Sep 11 00:29:27.819466 kernel: Linux version 6.12.46-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.44 p1) 2.44.0) #1 SMP PREEMPT_DYNAMIC Wed Sep 10 22:25:29 -00 2025 Sep 11 00:29:27.819488 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=24178014e7d1a618b6c727661dc98ca9324f7f5aeefcaa5f4996d4d839e6e63a Sep 11 00:29:27.819499 kernel: BIOS-provided physical RAM map: Sep 11 00:29:27.819506 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000002ffff] usable Sep 11 00:29:27.819512 kernel: BIOS-e820: [mem 0x0000000000030000-0x000000000004ffff] reserved Sep 11 00:29:27.819519 kernel: BIOS-e820: [mem 0x0000000000050000-0x000000000009efff] usable Sep 11 00:29:27.819527 kernel: BIOS-e820: [mem 0x000000000009f000-0x000000000009ffff] reserved Sep 11 00:29:27.819533 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000009b8ecfff] usable Sep 11 00:29:27.819540 kernel: BIOS-e820: [mem 0x000000009b8ed000-0x000000009bb6cfff] reserved Sep 11 00:29:27.819546 kernel: BIOS-e820: [mem 0x000000009bb6d000-0x000000009bb7efff] ACPI data Sep 11 00:29:27.819553 kernel: BIOS-e820: [mem 0x000000009bb7f000-0x000000009bbfefff] ACPI NVS Sep 11 00:29:27.819562 kernel: BIOS-e820: [mem 0x000000009bbff000-0x000000009bfb0fff] usable Sep 11 00:29:27.819569 kernel: BIOS-e820: [mem 0x000000009bfb1000-0x000000009bfb4fff] reserved Sep 11 00:29:27.819575 kernel: BIOS-e820: [mem 0x000000009bfb5000-0x000000009bfb6fff] ACPI NVS Sep 11 00:29:27.819583 kernel: BIOS-e820: [mem 0x000000009bfb7000-0x000000009bffffff] usable Sep 11 00:29:27.819590 kernel: BIOS-e820: [mem 0x000000009c000000-0x000000009cffffff] reserved Sep 11 00:29:27.819600 kernel: BIOS-e820: [mem 0x00000000e0000000-0x00000000efffffff] reserved Sep 11 00:29:27.819607 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Sep 11 00:29:27.819614 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Sep 11 00:29:27.819621 kernel: NX (Execute Disable) protection: active Sep 11 00:29:27.819628 kernel: APIC: Static calls initialized Sep 11 00:29:27.819635 kernel: e820: update [mem 0x9a13e018-0x9a147c57] usable ==> usable Sep 11 00:29:27.819642 kernel: e820: update [mem 0x9a101018-0x9a13de57] usable ==> usable Sep 11 00:29:27.819649 kernel: extended physical RAM map: Sep 11 00:29:27.819656 kernel: reserve setup_data: [mem 0x0000000000000000-0x000000000002ffff] usable Sep 11 00:29:27.819663 kernel: reserve setup_data: [mem 0x0000000000030000-0x000000000004ffff] reserved Sep 11 00:29:27.819671 kernel: reserve setup_data: [mem 0x0000000000050000-0x000000000009efff] usable Sep 11 00:29:27.819680 kernel: reserve setup_data: [mem 0x000000000009f000-0x000000000009ffff] reserved Sep 11 00:29:27.819687 kernel: reserve setup_data: [mem 0x0000000000100000-0x000000009a101017] usable Sep 11 00:29:27.819694 kernel: reserve setup_data: [mem 0x000000009a101018-0x000000009a13de57] usable Sep 11 00:29:27.819701 kernel: reserve setup_data: [mem 0x000000009a13de58-0x000000009a13e017] usable Sep 11 00:29:27.819708 kernel: reserve setup_data: [mem 0x000000009a13e018-0x000000009a147c57] usable Sep 11 00:29:27.819714 kernel: reserve setup_data: [mem 0x000000009a147c58-0x000000009b8ecfff] usable Sep 11 00:29:27.819721 kernel: reserve setup_data: [mem 0x000000009b8ed000-0x000000009bb6cfff] reserved Sep 11 00:29:27.819728 kernel: reserve setup_data: [mem 0x000000009bb6d000-0x000000009bb7efff] ACPI data Sep 11 00:29:27.819735 kernel: reserve setup_data: [mem 0x000000009bb7f000-0x000000009bbfefff] ACPI NVS Sep 11 00:29:27.819752 kernel: reserve setup_data: [mem 0x000000009bbff000-0x000000009bfb0fff] usable Sep 11 00:29:27.819759 kernel: reserve setup_data: [mem 0x000000009bfb1000-0x000000009bfb4fff] reserved Sep 11 00:29:27.819769 kernel: reserve setup_data: [mem 0x000000009bfb5000-0x000000009bfb6fff] ACPI NVS Sep 11 00:29:27.819776 kernel: reserve setup_data: [mem 0x000000009bfb7000-0x000000009bffffff] usable Sep 11 00:29:27.819787 kernel: reserve setup_data: [mem 0x000000009c000000-0x000000009cffffff] reserved Sep 11 00:29:27.819794 kernel: reserve setup_data: [mem 0x00000000e0000000-0x00000000efffffff] reserved Sep 11 00:29:27.819801 kernel: reserve setup_data: [mem 0x00000000feffc000-0x00000000feffffff] reserved Sep 11 00:29:27.819809 kernel: reserve setup_data: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Sep 11 00:29:27.819818 kernel: efi: EFI v2.7 by EDK II Sep 11 00:29:27.819825 kernel: efi: SMBIOS=0x9b9d5000 ACPI=0x9bb7e000 ACPI 2.0=0x9bb7e014 MEMATTR=0x9a1af018 RNG=0x9bb73018 Sep 11 00:29:27.819843 kernel: random: crng init done Sep 11 00:29:27.819858 kernel: Kernel is locked down from EFI Secure Boot; see man kernel_lockdown.7 Sep 11 00:29:27.819873 kernel: secureboot: Secure boot enabled Sep 11 00:29:27.819881 kernel: SMBIOS 2.8 present. Sep 11 00:29:27.819916 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS unknown 02/02/2022 Sep 11 00:29:27.819924 kernel: DMI: Memory slots populated: 1/1 Sep 11 00:29:27.819931 kernel: Hypervisor detected: KVM Sep 11 00:29:27.819938 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Sep 11 00:29:27.819949 kernel: kvm-clock: using sched offset of 4950272708 cycles Sep 11 00:29:27.819956 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Sep 11 00:29:27.819964 kernel: tsc: Detected 2794.750 MHz processor Sep 11 00:29:27.819972 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Sep 11 00:29:27.819980 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Sep 11 00:29:27.819991 kernel: last_pfn = 0x9c000 max_arch_pfn = 0x400000000 Sep 11 00:29:27.819999 kernel: MTRR map: 4 entries (2 fixed + 2 variable; max 18), built from 8 variable MTRRs Sep 11 00:29:27.820007 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Sep 11 00:29:27.820015 kernel: Using GB pages for direct mapping Sep 11 00:29:27.820022 kernel: ACPI: Early table checksum verification disabled Sep 11 00:29:27.820033 kernel: ACPI: RSDP 0x000000009BB7E014 000024 (v02 BOCHS ) Sep 11 00:29:27.820040 kernel: ACPI: XSDT 0x000000009BB7D0E8 000054 (v01 BOCHS BXPC 00000001 01000013) Sep 11 00:29:27.820048 kernel: ACPI: FACP 0x000000009BB79000 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) Sep 11 00:29:27.820055 kernel: ACPI: DSDT 0x000000009BB7A000 002237 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 11 00:29:27.820063 kernel: ACPI: FACS 0x000000009BBDD000 000040 Sep 11 00:29:27.820070 kernel: ACPI: APIC 0x000000009BB78000 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 11 00:29:27.820078 kernel: ACPI: HPET 0x000000009BB77000 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 11 00:29:27.820085 kernel: ACPI: MCFG 0x000000009BB76000 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 11 00:29:27.820095 kernel: ACPI: WAET 0x000000009BB75000 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 11 00:29:27.820103 kernel: ACPI: BGRT 0x000000009BB74000 000038 (v01 INTEL EDK2 00000002 01000013) Sep 11 00:29:27.820110 kernel: ACPI: Reserving FACP table memory at [mem 0x9bb79000-0x9bb790f3] Sep 11 00:29:27.820118 kernel: ACPI: Reserving DSDT table memory at [mem 0x9bb7a000-0x9bb7c236] Sep 11 00:29:27.820125 kernel: ACPI: Reserving FACS table memory at [mem 0x9bbdd000-0x9bbdd03f] Sep 11 00:29:27.820133 kernel: ACPI: Reserving APIC table memory at [mem 0x9bb78000-0x9bb7808f] Sep 11 00:29:27.820140 kernel: ACPI: Reserving HPET table memory at [mem 0x9bb77000-0x9bb77037] Sep 11 00:29:27.820147 kernel: ACPI: Reserving MCFG table memory at [mem 0x9bb76000-0x9bb7603b] Sep 11 00:29:27.820155 kernel: ACPI: Reserving WAET table memory at [mem 0x9bb75000-0x9bb75027] Sep 11 00:29:27.820162 kernel: ACPI: Reserving BGRT table memory at [mem 0x9bb74000-0x9bb74037] Sep 11 00:29:27.820172 kernel: No NUMA configuration found Sep 11 00:29:27.820180 kernel: Faking a node at [mem 0x0000000000000000-0x000000009bffffff] Sep 11 00:29:27.820187 kernel: NODE_DATA(0) allocated [mem 0x9bf57dc0-0x9bf5efff] Sep 11 00:29:27.820195 kernel: Zone ranges: Sep 11 00:29:27.820202 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Sep 11 00:29:27.820210 kernel: DMA32 [mem 0x0000000001000000-0x000000009bffffff] Sep 11 00:29:27.820217 kernel: Normal empty Sep 11 00:29:27.820224 kernel: Device empty Sep 11 00:29:27.820232 kernel: Movable zone start for each node Sep 11 00:29:27.820241 kernel: Early memory node ranges Sep 11 00:29:27.820249 kernel: node 0: [mem 0x0000000000001000-0x000000000002ffff] Sep 11 00:29:27.820256 kernel: node 0: [mem 0x0000000000050000-0x000000000009efff] Sep 11 00:29:27.820264 kernel: node 0: [mem 0x0000000000100000-0x000000009b8ecfff] Sep 11 00:29:27.820271 kernel: node 0: [mem 0x000000009bbff000-0x000000009bfb0fff] Sep 11 00:29:27.820278 kernel: node 0: [mem 0x000000009bfb7000-0x000000009bffffff] Sep 11 00:29:27.820286 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009bffffff] Sep 11 00:29:27.820293 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 11 00:29:27.820301 kernel: On node 0, zone DMA: 32 pages in unavailable ranges Sep 11 00:29:27.820308 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Sep 11 00:29:27.820318 kernel: On node 0, zone DMA32: 786 pages in unavailable ranges Sep 11 00:29:27.820325 kernel: On node 0, zone DMA32: 6 pages in unavailable ranges Sep 11 00:29:27.820333 kernel: On node 0, zone DMA32: 16384 pages in unavailable ranges Sep 11 00:29:27.820340 kernel: ACPI: PM-Timer IO Port: 0x608 Sep 11 00:29:27.820348 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Sep 11 00:29:27.820355 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Sep 11 00:29:27.820362 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Sep 11 00:29:27.820370 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Sep 11 00:29:27.820378 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Sep 11 00:29:27.820387 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Sep 11 00:29:27.820395 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Sep 11 00:29:27.820402 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Sep 11 00:29:27.820410 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Sep 11 00:29:27.820417 kernel: TSC deadline timer available Sep 11 00:29:27.820424 kernel: CPU topo: Max. logical packages: 1 Sep 11 00:29:27.820432 kernel: CPU topo: Max. logical dies: 1 Sep 11 00:29:27.820439 kernel: CPU topo: Max. dies per package: 1 Sep 11 00:29:27.820456 kernel: CPU topo: Max. threads per core: 1 Sep 11 00:29:27.820464 kernel: CPU topo: Num. cores per package: 4 Sep 11 00:29:27.820471 kernel: CPU topo: Num. threads per package: 4 Sep 11 00:29:27.820479 kernel: CPU topo: Allowing 4 present CPUs plus 0 hotplug CPUs Sep 11 00:29:27.820489 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Sep 11 00:29:27.820497 kernel: kvm-guest: KVM setup pv remote TLB flush Sep 11 00:29:27.820505 kernel: kvm-guest: setup PV sched yield Sep 11 00:29:27.820513 kernel: [mem 0x9d000000-0xdfffffff] available for PCI devices Sep 11 00:29:27.820520 kernel: Booting paravirtualized kernel on KVM Sep 11 00:29:27.820531 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Sep 11 00:29:27.820539 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 Sep 11 00:29:27.820547 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u524288 Sep 11 00:29:27.820555 kernel: pcpu-alloc: s207832 r8192 d29736 u524288 alloc=1*2097152 Sep 11 00:29:27.820562 kernel: pcpu-alloc: [0] 0 1 2 3 Sep 11 00:29:27.820570 kernel: kvm-guest: PV spinlocks enabled Sep 11 00:29:27.820578 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Sep 11 00:29:27.820587 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=24178014e7d1a618b6c727661dc98ca9324f7f5aeefcaa5f4996d4d839e6e63a Sep 11 00:29:27.820597 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 11 00:29:27.820605 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 11 00:29:27.820613 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 11 00:29:27.820621 kernel: Fallback order for Node 0: 0 Sep 11 00:29:27.820629 kernel: Built 1 zonelists, mobility grouping on. Total pages: 638054 Sep 11 00:29:27.820636 kernel: Policy zone: DMA32 Sep 11 00:29:27.820644 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 11 00:29:27.820652 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Sep 11 00:29:27.820660 kernel: ftrace: allocating 40103 entries in 157 pages Sep 11 00:29:27.820670 kernel: ftrace: allocated 157 pages with 5 groups Sep 11 00:29:27.820678 kernel: Dynamic Preempt: voluntary Sep 11 00:29:27.820686 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 11 00:29:27.820698 kernel: rcu: RCU event tracing is enabled. Sep 11 00:29:27.820706 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Sep 11 00:29:27.820714 kernel: Trampoline variant of Tasks RCU enabled. Sep 11 00:29:27.820722 kernel: Rude variant of Tasks RCU enabled. Sep 11 00:29:27.820730 kernel: Tracing variant of Tasks RCU enabled. Sep 11 00:29:27.820745 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 11 00:29:27.820753 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Sep 11 00:29:27.820764 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 11 00:29:27.820780 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:29:27.820797 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:29:27.820805 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 Sep 11 00:29:27.820821 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 11 00:29:27.820829 kernel: Console: colour dummy device 80x25 Sep 11 00:29:27.820837 kernel: printk: legacy console [ttyS0] enabled Sep 11 00:29:27.820845 kernel: ACPI: Core revision 20240827 Sep 11 00:29:27.820856 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Sep 11 00:29:27.820864 kernel: APIC: Switch to symmetric I/O mode setup Sep 11 00:29:27.820876 kernel: x2apic enabled Sep 11 00:29:27.820884 kernel: APIC: Switched APIC routing to: physical x2apic Sep 11 00:29:27.820892 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() Sep 11 00:29:27.820900 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() Sep 11 00:29:27.820927 kernel: kvm-guest: setup PV IPIs Sep 11 00:29:27.820936 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Sep 11 00:29:27.820944 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x2848e100549, max_idle_ns: 440795215505 ns Sep 11 00:29:27.820955 kernel: Calibrating delay loop (skipped) preset value.. 5589.50 BogoMIPS (lpj=2794750) Sep 11 00:29:27.820963 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Sep 11 00:29:27.820971 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 Sep 11 00:29:27.820979 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 Sep 11 00:29:27.820987 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Sep 11 00:29:27.820994 kernel: Spectre V2 : Mitigation: Retpolines Sep 11 00:29:27.821002 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Sep 11 00:29:27.821010 kernel: Spectre V2 : Enabling Speculation Barrier for firmware calls Sep 11 00:29:27.821018 kernel: active return thunk: retbleed_return_thunk Sep 11 00:29:27.821028 kernel: RETBleed: Mitigation: untrained return thunk Sep 11 00:29:27.821036 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Sep 11 00:29:27.821044 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Sep 11 00:29:27.821052 kernel: Speculative Return Stack Overflow: IBPB-extending microcode not applied! Sep 11 00:29:27.821061 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:29:27.821069 kernel: active return thunk: srso_return_thunk Sep 11 00:29:27.821076 kernel: Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode Sep 11 00:29:27.821084 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Sep 11 00:29:27.821095 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Sep 11 00:29:27.821103 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Sep 11 00:29:27.821110 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Sep 11 00:29:27.821118 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format. Sep 11 00:29:27.821126 kernel: Freeing SMP alternatives memory: 32K Sep 11 00:29:27.821134 kernel: pid_max: default: 32768 minimum: 301 Sep 11 00:29:27.821142 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Sep 11 00:29:27.821150 kernel: landlock: Up and running. Sep 11 00:29:27.821157 kernel: SELinux: Initializing. Sep 11 00:29:27.821168 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 11 00:29:27.821176 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 11 00:29:27.821184 kernel: smpboot: CPU0: AMD EPYC 7402P 24-Core Processor (family: 0x17, model: 0x31, stepping: 0x0) Sep 11 00:29:27.821191 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver. Sep 11 00:29:27.821199 kernel: ... version: 0 Sep 11 00:29:27.821207 kernel: ... bit width: 48 Sep 11 00:29:27.821215 kernel: ... generic registers: 6 Sep 11 00:29:27.821223 kernel: ... value mask: 0000ffffffffffff Sep 11 00:29:27.821230 kernel: ... max period: 00007fffffffffff Sep 11 00:29:27.821240 kernel: ... fixed-purpose events: 0 Sep 11 00:29:27.821248 kernel: ... event mask: 000000000000003f Sep 11 00:29:27.821256 kernel: signal: max sigframe size: 1776 Sep 11 00:29:27.821263 kernel: rcu: Hierarchical SRCU implementation. Sep 11 00:29:27.821271 kernel: rcu: Max phase no-delay instances is 400. Sep 11 00:29:27.821280 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Sep 11 00:29:27.821287 kernel: smp: Bringing up secondary CPUs ... Sep 11 00:29:27.821295 kernel: smpboot: x86: Booting SMP configuration: Sep 11 00:29:27.821303 kernel: .... node #0, CPUs: #1 #2 #3 Sep 11 00:29:27.821311 kernel: smp: Brought up 1 node, 4 CPUs Sep 11 00:29:27.821320 kernel: smpboot: Total of 4 processors activated (22358.00 BogoMIPS) Sep 11 00:29:27.821328 kernel: Memory: 2411272K/2552216K available (14336K kernel code, 2429K rwdata, 9960K rodata, 53832K init, 1088K bss, 135016K reserved, 0K cma-reserved) Sep 11 00:29:27.821336 kernel: devtmpfs: initialized Sep 11 00:29:27.821344 kernel: x86/mm: Memory block size: 128MB Sep 11 00:29:27.821352 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9bb7f000-0x9bbfefff] (524288 bytes) Sep 11 00:29:27.821360 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9bfb5000-0x9bfb6fff] (8192 bytes) Sep 11 00:29:27.821368 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 11 00:29:27.821376 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Sep 11 00:29:27.821385 kernel: pinctrl core: initialized pinctrl subsystem Sep 11 00:29:27.821393 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 11 00:29:27.821401 kernel: audit: initializing netlink subsys (disabled) Sep 11 00:29:27.821409 kernel: audit: type=2000 audit(1757550565.641:1): state=initialized audit_enabled=0 res=1 Sep 11 00:29:27.821417 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 11 00:29:27.821425 kernel: thermal_sys: Registered thermal governor 'user_space' Sep 11 00:29:27.821433 kernel: cpuidle: using governor menu Sep 11 00:29:27.821440 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 11 00:29:27.821448 kernel: dca service started, version 1.12.1 Sep 11 00:29:27.821458 kernel: PCI: ECAM [mem 0xe0000000-0xefffffff] (base 0xe0000000) for domain 0000 [bus 00-ff] Sep 11 00:29:27.821466 kernel: PCI: Using configuration type 1 for base access Sep 11 00:29:27.821474 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Sep 11 00:29:27.821482 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 11 00:29:27.821490 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Sep 11 00:29:27.821497 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 11 00:29:27.821505 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Sep 11 00:29:27.821513 kernel: ACPI: Added _OSI(Module Device) Sep 11 00:29:27.821521 kernel: ACPI: Added _OSI(Processor Device) Sep 11 00:29:27.821531 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 11 00:29:27.821539 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 11 00:29:27.821546 kernel: ACPI: Interpreter enabled Sep 11 00:29:27.821554 kernel: ACPI: PM: (supports S0 S5) Sep 11 00:29:27.821562 kernel: ACPI: Using IOAPIC for interrupt routing Sep 11 00:29:27.821570 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Sep 11 00:29:27.821577 kernel: PCI: Using E820 reservations for host bridge windows Sep 11 00:29:27.821585 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F Sep 11 00:29:27.821593 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Sep 11 00:29:27.821778 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Sep 11 00:29:27.821902 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] Sep 11 00:29:27.822035 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] Sep 11 00:29:27.822046 kernel: PCI host bridge to bus 0000:00 Sep 11 00:29:27.822165 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Sep 11 00:29:27.822272 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Sep 11 00:29:27.822384 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Sep 11 00:29:27.822489 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xdfffffff window] Sep 11 00:29:27.822594 kernel: pci_bus 0000:00: root bus resource [mem 0xf0000000-0xfebfffff window] Sep 11 00:29:27.822699 kernel: pci_bus 0000:00: root bus resource [mem 0x380000000000-0x3807ffffffff window] Sep 11 00:29:27.822814 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Sep 11 00:29:27.822969 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint Sep 11 00:29:27.823095 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint Sep 11 00:29:27.823216 kernel: pci 0000:00:01.0: BAR 0 [mem 0xc0000000-0xc0ffffff pref] Sep 11 00:29:27.823332 kernel: pci 0000:00:01.0: BAR 2 [mem 0xc1044000-0xc1044fff] Sep 11 00:29:27.823447 kernel: pci 0000:00:01.0: ROM [mem 0xffff0000-0xffffffff pref] Sep 11 00:29:27.823567 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Sep 11 00:29:27.823701 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Sep 11 00:29:27.823831 kernel: pci 0000:00:02.0: BAR 0 [io 0x6100-0x611f] Sep 11 00:29:27.823986 kernel: pci 0000:00:02.0: BAR 1 [mem 0xc1043000-0xc1043fff] Sep 11 00:29:27.824110 kernel: pci 0000:00:02.0: BAR 4 [mem 0x380000000000-0x380000003fff 64bit pref] Sep 11 00:29:27.824234 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint Sep 11 00:29:27.824350 kernel: pci 0000:00:03.0: BAR 0 [io 0x6000-0x607f] Sep 11 00:29:27.824468 kernel: pci 0000:00:03.0: BAR 1 [mem 0xc1042000-0xc1042fff] Sep 11 00:29:27.824595 kernel: pci 0000:00:03.0: BAR 4 [mem 0x380000004000-0x380000007fff 64bit pref] Sep 11 00:29:27.824725 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint Sep 11 00:29:27.824859 kernel: pci 0000:00:04.0: BAR 0 [io 0x60e0-0x60ff] Sep 11 00:29:27.825008 kernel: pci 0000:00:04.0: BAR 1 [mem 0xc1041000-0xc1041fff] Sep 11 00:29:27.825129 kernel: pci 0000:00:04.0: BAR 4 [mem 0x380000008000-0x38000000bfff 64bit pref] Sep 11 00:29:27.825245 kernel: pci 0000:00:04.0: ROM [mem 0xfffc0000-0xffffffff pref] Sep 11 00:29:27.825369 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint Sep 11 00:29:27.825486 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO Sep 11 00:29:27.825610 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint Sep 11 00:29:27.825731 kernel: pci 0000:00:1f.2: BAR 4 [io 0x60c0-0x60df] Sep 11 00:29:27.825863 kernel: pci 0000:00:1f.2: BAR 5 [mem 0xc1040000-0xc1040fff] Sep 11 00:29:27.826015 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint Sep 11 00:29:27.826134 kernel: pci 0000:00:1f.3: BAR 4 [io 0x6080-0x60bf] Sep 11 00:29:27.826144 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Sep 11 00:29:27.826152 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Sep 11 00:29:27.826160 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Sep 11 00:29:27.826172 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Sep 11 00:29:27.826180 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 Sep 11 00:29:27.826188 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 Sep 11 00:29:27.826196 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 Sep 11 00:29:27.826204 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 Sep 11 00:29:27.826212 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 Sep 11 00:29:27.826220 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 Sep 11 00:29:27.826227 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 Sep 11 00:29:27.826235 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 Sep 11 00:29:27.826245 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 Sep 11 00:29:27.826253 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 Sep 11 00:29:27.826261 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 Sep 11 00:29:27.826269 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 Sep 11 00:29:27.826277 kernel: iommu: Default domain type: Translated Sep 11 00:29:27.826285 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Sep 11 00:29:27.826293 kernel: efivars: Registered efivars operations Sep 11 00:29:27.826301 kernel: PCI: Using ACPI for IRQ routing Sep 11 00:29:27.826308 kernel: PCI: pci_cache_line_size set to 64 bytes Sep 11 00:29:27.826316 kernel: e820: reserve RAM buffer [mem 0x0009f000-0x0009ffff] Sep 11 00:29:27.826326 kernel: e820: reserve RAM buffer [mem 0x9a101018-0x9bffffff] Sep 11 00:29:27.826333 kernel: e820: reserve RAM buffer [mem 0x9a13e018-0x9bffffff] Sep 11 00:29:27.826341 kernel: e820: reserve RAM buffer [mem 0x9b8ed000-0x9bffffff] Sep 11 00:29:27.826349 kernel: e820: reserve RAM buffer [mem 0x9bfb1000-0x9bffffff] Sep 11 00:29:27.826465 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device Sep 11 00:29:27.826580 kernel: pci 0000:00:01.0: vgaarb: bridge control possible Sep 11 00:29:27.826695 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Sep 11 00:29:27.826706 kernel: vgaarb: loaded Sep 11 00:29:27.826717 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Sep 11 00:29:27.826724 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Sep 11 00:29:27.826732 kernel: clocksource: Switched to clocksource kvm-clock Sep 11 00:29:27.826750 kernel: VFS: Disk quotas dquot_6.6.0 Sep 11 00:29:27.826757 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 11 00:29:27.826765 kernel: pnp: PnP ACPI init Sep 11 00:29:27.826919 kernel: system 00:05: [mem 0xe0000000-0xefffffff window] has been reserved Sep 11 00:29:27.826932 kernel: pnp: PnP ACPI: found 6 devices Sep 11 00:29:27.826943 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Sep 11 00:29:27.826951 kernel: NET: Registered PF_INET protocol family Sep 11 00:29:27.826959 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Sep 11 00:29:27.826967 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Sep 11 00:29:27.826975 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 11 00:29:27.826983 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Sep 11 00:29:27.826991 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Sep 11 00:29:27.826999 kernel: TCP: Hash tables configured (established 32768 bind 32768) Sep 11 00:29:27.827007 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 11 00:29:27.827017 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 11 00:29:27.827025 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 11 00:29:27.827032 kernel: NET: Registered PF_XDP protocol family Sep 11 00:29:27.827155 kernel: pci 0000:00:04.0: ROM [mem 0xfffc0000-0xffffffff pref]: can't claim; no compatible bridge window Sep 11 00:29:27.827294 kernel: pci 0000:00:04.0: ROM [mem 0x9d000000-0x9d03ffff pref]: assigned Sep 11 00:29:27.827404 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Sep 11 00:29:27.827510 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Sep 11 00:29:27.827616 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Sep 11 00:29:27.827725 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xdfffffff window] Sep 11 00:29:27.827843 kernel: pci_bus 0000:00: resource 8 [mem 0xf0000000-0xfebfffff window] Sep 11 00:29:27.827964 kernel: pci_bus 0000:00: resource 9 [mem 0x380000000000-0x3807ffffffff window] Sep 11 00:29:27.827975 kernel: PCI: CLS 0 bytes, default 64 Sep 11 00:29:27.827984 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x2848e100549, max_idle_ns: 440795215505 ns Sep 11 00:29:27.827992 kernel: Initialise system trusted keyrings Sep 11 00:29:27.828000 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Sep 11 00:29:27.828007 kernel: Key type asymmetric registered Sep 11 00:29:27.828016 kernel: Asymmetric key parser 'x509' registered Sep 11 00:29:27.828038 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Sep 11 00:29:27.828048 kernel: io scheduler mq-deadline registered Sep 11 00:29:27.828058 kernel: io scheduler kyber registered Sep 11 00:29:27.828067 kernel: io scheduler bfq registered Sep 11 00:29:27.828075 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Sep 11 00:29:27.828083 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 Sep 11 00:29:27.828091 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 Sep 11 00:29:27.828102 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 Sep 11 00:29:27.828110 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 11 00:29:27.828120 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Sep 11 00:29:27.828128 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Sep 11 00:29:27.828136 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Sep 11 00:29:27.828144 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Sep 11 00:29:27.828267 kernel: rtc_cmos 00:04: RTC can wake from S4 Sep 11 00:29:27.828280 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Sep 11 00:29:27.828391 kernel: rtc_cmos 00:04: registered as rtc0 Sep 11 00:29:27.828502 kernel: rtc_cmos 00:04: setting system clock to 2025-09-11T00:29:27 UTC (1757550567) Sep 11 00:29:27.828616 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram Sep 11 00:29:27.828627 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled Sep 11 00:29:27.828635 kernel: efifb: probing for efifb Sep 11 00:29:27.828643 kernel: efifb: framebuffer at 0xc0000000, using 4000k, total 4000k Sep 11 00:29:27.828652 kernel: efifb: mode is 1280x800x32, linelength=5120, pages=1 Sep 11 00:29:27.828660 kernel: efifb: scrolling: redraw Sep 11 00:29:27.828668 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Sep 11 00:29:27.828676 kernel: Console: switching to colour frame buffer device 160x50 Sep 11 00:29:27.828687 kernel: fb0: EFI VGA frame buffer device Sep 11 00:29:27.828697 kernel: pstore: Using crash dump compression: deflate Sep 11 00:29:27.828705 kernel: pstore: Registered efi_pstore as persistent store backend Sep 11 00:29:27.828713 kernel: NET: Registered PF_INET6 protocol family Sep 11 00:29:27.828721 kernel: Segment Routing with IPv6 Sep 11 00:29:27.828730 kernel: In-situ OAM (IOAM) with IPv6 Sep 11 00:29:27.828749 kernel: NET: Registered PF_PACKET protocol family Sep 11 00:29:27.828758 kernel: Key type dns_resolver registered Sep 11 00:29:27.828767 kernel: IPI shorthand broadcast: enabled Sep 11 00:29:27.828775 kernel: sched_clock: Marking stable (2699002824, 138636163)->(2888615743, -50976756) Sep 11 00:29:27.828783 kernel: registered taskstats version 1 Sep 11 00:29:27.828792 kernel: Loading compiled-in X.509 certificates Sep 11 00:29:27.828800 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.46-flatcar: 8138ce5002a1b572fd22b23ac238f29bab3f249f' Sep 11 00:29:27.828811 kernel: Demotion targets for Node 0: null Sep 11 00:29:27.828819 kernel: Key type .fscrypt registered Sep 11 00:29:27.828829 kernel: Key type fscrypt-provisioning registered Sep 11 00:29:27.828838 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 11 00:29:27.828846 kernel: ima: Allocated hash algorithm: sha1 Sep 11 00:29:27.828854 kernel: ima: No architecture policies found Sep 11 00:29:27.828862 kernel: clk: Disabling unused clocks Sep 11 00:29:27.828870 kernel: Warning: unable to open an initial console. Sep 11 00:29:27.828878 kernel: Freeing unused kernel image (initmem) memory: 53832K Sep 11 00:29:27.828886 kernel: Write protecting the kernel read-only data: 24576k Sep 11 00:29:27.828894 kernel: Freeing unused kernel image (rodata/data gap) memory: 280K Sep 11 00:29:27.828916 kernel: Run /init as init process Sep 11 00:29:27.828924 kernel: with arguments: Sep 11 00:29:27.828932 kernel: /init Sep 11 00:29:27.828940 kernel: with environment: Sep 11 00:29:27.828948 kernel: HOME=/ Sep 11 00:29:27.828956 kernel: TERM=linux Sep 11 00:29:27.828964 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 11 00:29:27.828973 systemd[1]: Successfully made /usr/ read-only. Sep 11 00:29:27.828987 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:29:27.828996 systemd[1]: Detected virtualization kvm. Sep 11 00:29:27.829005 systemd[1]: Detected architecture x86-64. Sep 11 00:29:27.829013 systemd[1]: Running in initrd. Sep 11 00:29:27.829022 systemd[1]: No hostname configured, using default hostname. Sep 11 00:29:27.829030 systemd[1]: Hostname set to . Sep 11 00:29:27.829039 systemd[1]: Initializing machine ID from VM UUID. Sep 11 00:29:27.829047 systemd[1]: Queued start job for default target initrd.target. Sep 11 00:29:27.829058 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 11 00:29:27.829067 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 11 00:29:27.829076 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 11 00:29:27.829085 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 11 00:29:27.829094 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 11 00:29:27.829103 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 11 00:29:27.829130 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:29:27.829139 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 11 00:29:27.829148 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 11 00:29:27.829156 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 11 00:29:27.829165 systemd[1]: Reached target paths.target - Path Units. Sep 11 00:29:27.829173 systemd[1]: Reached target slices.target - Slice Units. Sep 11 00:29:27.829182 systemd[1]: Reached target swap.target - Swaps. Sep 11 00:29:27.829190 systemd[1]: Reached target timers.target - Timer Units. Sep 11 00:29:27.829199 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 11 00:29:27.829210 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 11 00:29:27.829218 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 11 00:29:27.829227 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Sep 11 00:29:27.829236 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 11 00:29:27.829244 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 11 00:29:27.829253 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 11 00:29:27.829261 systemd[1]: Reached target sockets.target - Socket Units. Sep 11 00:29:27.829270 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 11 00:29:27.829281 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 11 00:29:27.829289 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 11 00:29:27.829298 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:29:27.829307 systemd[1]: Starting systemd-fsck-usr.service... Sep 11 00:29:27.829315 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 11 00:29:27.829324 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 11 00:29:27.829332 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 11 00:29:27.829341 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 11 00:29:27.829352 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 11 00:29:27.829361 systemd[1]: Finished systemd-fsck-usr.service. Sep 11 00:29:27.829370 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 11 00:29:27.829398 systemd-journald[219]: Collecting audit messages is disabled. Sep 11 00:29:27.829420 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:29:27.829428 systemd-journald[219]: Journal started Sep 11 00:29:27.829453 systemd-journald[219]: Runtime Journal (/run/log/journal/d95fca4e382d4c7382f6bd8db224dfa9) is 6M, max 48.2M, 42.2M free. Sep 11 00:29:27.818717 systemd-modules-load[221]: Inserted module 'overlay' Sep 11 00:29:27.831989 systemd[1]: Started systemd-journald.service - Journal Service. Sep 11 00:29:27.832703 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 11 00:29:27.836346 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 11 00:29:27.839336 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 11 00:29:27.840367 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 11 00:29:27.850928 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:29:27.852923 kernel: Bridge firewalling registered Sep 11 00:29:27.852923 systemd-modules-load[221]: Inserted module 'br_netfilter' Sep 11 00:29:27.854523 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 11 00:29:27.858050 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 11 00:29:27.864488 systemd-tmpfiles[240]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Sep 11 00:29:27.866434 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 11 00:29:27.871720 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 11 00:29:27.876058 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 11 00:29:27.877337 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 11 00:29:27.879878 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 11 00:29:27.882368 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 11 00:29:27.913098 dracut-cmdline[260]: 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=24178014e7d1a618b6c727661dc98ca9324f7f5aeefcaa5f4996d4d839e6e63a Sep 11 00:29:27.935919 systemd-resolved[261]: Positive Trust Anchors: Sep 11 00:29:27.935931 systemd-resolved[261]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 11 00:29:27.935960 systemd-resolved[261]: 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:29:27.938367 systemd-resolved[261]: Defaulting to hostname 'linux'. Sep 11 00:29:27.939347 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 11 00:29:27.945242 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 11 00:29:28.010938 kernel: SCSI subsystem initialized Sep 11 00:29:28.019927 kernel: Loading iSCSI transport class v2.0-870. Sep 11 00:29:28.029929 kernel: iscsi: registered transport (tcp) Sep 11 00:29:28.050929 kernel: iscsi: registered transport (qla4xxx) Sep 11 00:29:28.050953 kernel: QLogic iSCSI HBA Driver Sep 11 00:29:28.068553 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 11 00:29:28.089760 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 11 00:29:28.091237 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 11 00:29:28.137302 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 11 00:29:28.140049 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 11 00:29:28.209963 kernel: raid6: avx2x4 gen() 29103 MB/s Sep 11 00:29:28.226949 kernel: raid6: avx2x2 gen() 30701 MB/s Sep 11 00:29:28.243982 kernel: raid6: avx2x1 gen() 25485 MB/s Sep 11 00:29:28.244004 kernel: raid6: using algorithm avx2x2 gen() 30701 MB/s Sep 11 00:29:28.261982 kernel: raid6: .... xor() 19735 MB/s, rmw enabled Sep 11 00:29:28.262010 kernel: raid6: using avx2x2 recovery algorithm Sep 11 00:29:28.285936 kernel: xor: automatically using best checksumming function avx Sep 11 00:29:28.460947 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 11 00:29:28.469358 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 11 00:29:28.471740 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 11 00:29:28.504505 systemd-udevd[470]: Using default interface naming scheme 'v255'. Sep 11 00:29:28.510966 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 11 00:29:28.515027 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 11 00:29:28.541545 dracut-pre-trigger[475]: rd.md=0: removing MD RAID activation Sep 11 00:29:28.572202 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 11 00:29:28.575101 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 11 00:29:28.653444 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 11 00:29:28.656030 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 11 00:29:28.714433 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input2 Sep 11 00:29:28.719937 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues Sep 11 00:29:28.724211 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Sep 11 00:29:28.724438 kernel: cryptd: max_cpu_qlen set to 1000 Sep 11 00:29:28.728935 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 11 00:29:28.734514 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 11 00:29:28.735966 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:29:28.738789 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 11 00:29:28.744932 kernel: AES CTR mode by8 optimization enabled Sep 11 00:29:28.745007 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 11 00:29:28.750929 kernel: libata version 3.00 loaded. Sep 11 00:29:28.755075 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 11 00:29:28.755205 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:29:28.758122 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 11 00:29:28.775986 kernel: ahci 0000:00:1f.2: version 3.0 Sep 11 00:29:28.776227 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 Sep 11 00:29:28.777040 kernel: ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode Sep 11 00:29:28.779470 kernel: ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f) Sep 11 00:29:28.779652 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only Sep 11 00:29:28.802628 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Sep 11 00:29:28.814424 kernel: scsi host0: ahci Sep 11 00:29:28.814611 kernel: scsi host1: ahci Sep 11 00:29:28.814764 kernel: scsi host2: ahci Sep 11 00:29:28.814986 kernel: scsi host3: ahci Sep 11 00:29:28.815154 kernel: scsi host4: ahci Sep 11 00:29:28.815290 kernel: scsi host5: ahci Sep 11 00:29:28.816597 kernel: ata1: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040100 irq 34 lpm-pol 1 Sep 11 00:29:28.816611 kernel: ata2: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040180 irq 34 lpm-pol 1 Sep 11 00:29:28.818391 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:29:28.822450 kernel: ata3: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040200 irq 34 lpm-pol 1 Sep 11 00:29:28.822465 kernel: ata4: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040280 irq 34 lpm-pol 1 Sep 11 00:29:28.822479 kernel: ata5: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040300 irq 34 lpm-pol 1 Sep 11 00:29:28.822489 kernel: ata6: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040380 irq 34 lpm-pol 1 Sep 11 00:29:28.837831 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:29:28.838206 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Sep 11 00:29:28.847611 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Sep 11 00:29:28.858749 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 11 00:29:28.865682 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 11 00:29:29.029948 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 11 00:29:29.127938 kernel: ata4: SATA link down (SStatus 0 SControl 300) Sep 11 00:29:29.128021 kernel: ata2: SATA link down (SStatus 0 SControl 300) Sep 11 00:29:29.128932 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Sep 11 00:29:29.129943 kernel: ata1: SATA link down (SStatus 0 SControl 300) Sep 11 00:29:29.130012 kernel: ata3.00: LPM support broken, forcing max_power Sep 11 00:29:29.131404 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 Sep 11 00:29:29.131425 kernel: ata3.00: applying bridge limits Sep 11 00:29:29.132096 kernel: ata3.00: LPM support broken, forcing max_power Sep 11 00:29:29.132108 kernel: ata3.00: configured for UDMA/100 Sep 11 00:29:29.132932 kernel: scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 Sep 11 00:29:29.137937 kernel: ata5: SATA link down (SStatus 0 SControl 300) Sep 11 00:29:29.137950 kernel: ata6: SATA link down (SStatus 0 SControl 300) Sep 11 00:29:29.154928 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 11 00:29:29.189933 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray Sep 11 00:29:29.190218 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Sep 11 00:29:29.202927 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 Sep 11 00:29:29.469668 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 11 00:29:29.470857 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 11 00:29:29.472311 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 11 00:29:29.472644 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 11 00:29:29.478319 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 11 00:29:29.506047 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 11 00:29:30.159938 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 11 00:29:30.160347 disk-uuid[633]: The operation has completed successfully. Sep 11 00:29:30.193423 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 11 00:29:30.193545 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 11 00:29:30.224540 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 11 00:29:30.251653 sh[661]: Success Sep 11 00:29:30.270731 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 11 00:29:30.270759 kernel: device-mapper: uevent: version 1.0.3 Sep 11 00:29:30.270770 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Sep 11 00:29:30.280935 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 11 00:29:30.311724 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 11 00:29:30.314720 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 11 00:29:30.331583 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 11 00:29:30.338946 kernel: BTRFS: device fsid f1eb5eb7-34cc-49c0-9f2b-e603bd772d66 devid 1 transid 39 /dev/mapper/usr (253:0) scanned by mount (673) Sep 11 00:29:30.341114 kernel: BTRFS info (device dm-0): first mount of filesystem f1eb5eb7-34cc-49c0-9f2b-e603bd772d66 Sep 11 00:29:30.341137 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Sep 11 00:29:30.345930 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 11 00:29:30.345958 kernel: BTRFS info (device dm-0): enabling free space tree Sep 11 00:29:30.347183 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 11 00:29:30.348530 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Sep 11 00:29:30.350073 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 11 00:29:30.350827 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 11 00:29:30.352639 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 11 00:29:30.379943 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (705) Sep 11 00:29:30.381936 kernel: BTRFS info (device vda6): first mount of filesystem a5de7b5e-e14d-4c62-883d-af7ea22fae7e Sep 11 00:29:30.381961 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 11 00:29:30.385005 kernel: BTRFS info (device vda6): turning on async discard Sep 11 00:29:30.385084 kernel: BTRFS info (device vda6): enabling free space tree Sep 11 00:29:30.389943 kernel: BTRFS info (device vda6): last unmount of filesystem a5de7b5e-e14d-4c62-883d-af7ea22fae7e Sep 11 00:29:30.391042 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 11 00:29:30.394188 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 11 00:29:30.471761 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 11 00:29:30.473772 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 11 00:29:30.588982 systemd-networkd[842]: lo: Link UP Sep 11 00:29:30.588995 systemd-networkd[842]: lo: Gained carrier Sep 11 00:29:30.590597 systemd-networkd[842]: Enumeration completed Sep 11 00:29:30.590718 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 11 00:29:30.591898 systemd[1]: Reached target network.target - Network. Sep 11 00:29:30.593224 systemd-networkd[842]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 11 00:29:30.593228 systemd-networkd[842]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 11 00:29:30.593643 systemd-networkd[842]: eth0: Link UP Sep 11 00:29:30.691660 systemd-networkd[842]: eth0: Gained carrier Sep 11 00:29:30.691690 systemd-networkd[842]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 11 00:29:30.703992 systemd-networkd[842]: eth0: DHCPv4 address 10.0.0.130/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 11 00:29:30.775971 ignition[748]: Ignition 2.21.0 Sep 11 00:29:30.775987 ignition[748]: Stage: fetch-offline Sep 11 00:29:30.776038 ignition[748]: no configs at "/usr/lib/ignition/base.d" Sep 11 00:29:30.776058 ignition[748]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 11 00:29:30.776175 ignition[748]: parsed url from cmdline: "" Sep 11 00:29:30.776180 ignition[748]: no config URL provided Sep 11 00:29:30.776190 ignition[748]: reading system config file "/usr/lib/ignition/user.ign" Sep 11 00:29:30.776202 ignition[748]: no config at "/usr/lib/ignition/user.ign" Sep 11 00:29:30.776240 ignition[748]: op(1): [started] loading QEMU firmware config module Sep 11 00:29:30.776246 ignition[748]: op(1): executing: "modprobe" "qemu_fw_cfg" Sep 11 00:29:30.789160 ignition[748]: op(1): [finished] loading QEMU firmware config module Sep 11 00:29:30.790435 ignition[748]: parsing config with SHA512: 54a848ad41a7b2ab0635ad47d42ea96f04cbfe067d1808876021899fb88aab700b8fece5d3705e74af68b1d9dc76f4477f127d3a0b8678911e75985dd938a3c9 Sep 11 00:29:30.798281 unknown[748]: fetched base config from "system" Sep 11 00:29:30.798294 unknown[748]: fetched user config from "qemu" Sep 11 00:29:30.798439 ignition[748]: fetch-offline: fetch-offline passed Sep 11 00:29:30.798510 ignition[748]: Ignition finished successfully Sep 11 00:29:30.804499 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 11 00:29:30.806896 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Sep 11 00:29:30.807899 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 11 00:29:30.869053 ignition[856]: Ignition 2.21.0 Sep 11 00:29:30.869067 ignition[856]: Stage: kargs Sep 11 00:29:30.869212 ignition[856]: no configs at "/usr/lib/ignition/base.d" Sep 11 00:29:30.869223 ignition[856]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 11 00:29:30.869874 ignition[856]: kargs: kargs passed Sep 11 00:29:30.869950 ignition[856]: Ignition finished successfully Sep 11 00:29:30.875142 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 11 00:29:30.878218 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 11 00:29:30.914448 ignition[864]: Ignition 2.21.0 Sep 11 00:29:30.914463 ignition[864]: Stage: disks Sep 11 00:29:30.914644 ignition[864]: no configs at "/usr/lib/ignition/base.d" Sep 11 00:29:30.914657 ignition[864]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 11 00:29:30.918168 ignition[864]: disks: disks passed Sep 11 00:29:30.918239 ignition[864]: Ignition finished successfully Sep 11 00:29:30.921204 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 11 00:29:30.923284 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 11 00:29:30.923548 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 11 00:29:30.925596 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 11 00:29:30.927855 systemd[1]: Reached target sysinit.target - System Initialization. Sep 11 00:29:30.929659 systemd[1]: Reached target basic.target - Basic System. Sep 11 00:29:30.933954 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 11 00:29:31.027020 systemd-fsck[874]: ROOT: clean, 15/553520 files, 52789/553472 blocks Sep 11 00:29:31.034368 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 11 00:29:31.035839 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 11 00:29:31.156935 kernel: EXT4-fs (vda9): mounted filesystem 6a9ce0af-81d0-4628-9791-e47488ed2744 r/w with ordered data mode. Quota mode: none. Sep 11 00:29:31.157519 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 11 00:29:31.158629 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 11 00:29:31.161445 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 11 00:29:31.163391 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 11 00:29:31.164454 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Sep 11 00:29:31.164503 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:29:31.164531 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 11 00:29:31.181012 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 11 00:29:31.183432 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 11 00:29:31.186528 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (883) Sep 11 00:29:31.188934 kernel: BTRFS info (device vda6): first mount of filesystem a5de7b5e-e14d-4c62-883d-af7ea22fae7e Sep 11 00:29:31.188956 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 11 00:29:31.191923 kernel: BTRFS info (device vda6): turning on async discard Sep 11 00:29:31.191951 kernel: BTRFS info (device vda6): enabling free space tree Sep 11 00:29:31.193965 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 11 00:29:31.225597 initrd-setup-root[909]: cut: /sysroot/etc/passwd: No such file or directory Sep 11 00:29:31.229789 initrd-setup-root[916]: cut: /sysroot/etc/group: No such file or directory Sep 11 00:29:31.233584 initrd-setup-root[923]: cut: /sysroot/etc/shadow: No such file or directory Sep 11 00:29:31.237475 initrd-setup-root[930]: cut: /sysroot/etc/gshadow: No such file or directory Sep 11 00:29:31.323269 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 11 00:29:31.325475 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 11 00:29:31.327127 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 11 00:29:31.350639 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 11 00:29:31.352108 kernel: BTRFS info (device vda6): last unmount of filesystem a5de7b5e-e14d-4c62-883d-af7ea22fae7e Sep 11 00:29:31.364739 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 11 00:29:31.392918 ignition[998]: INFO : Ignition 2.21.0 Sep 11 00:29:31.392918 ignition[998]: INFO : Stage: mount Sep 11 00:29:31.394711 ignition[998]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 11 00:29:31.394711 ignition[998]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 11 00:29:31.394711 ignition[998]: INFO : mount: mount passed Sep 11 00:29:31.394711 ignition[998]: INFO : Ignition finished successfully Sep 11 00:29:31.396605 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 11 00:29:31.399665 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 11 00:29:31.433336 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 11 00:29:31.464580 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (1011) Sep 11 00:29:31.464604 kernel: BTRFS info (device vda6): first mount of filesystem a5de7b5e-e14d-4c62-883d-af7ea22fae7e Sep 11 00:29:31.464616 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 11 00:29:31.468655 kernel: BTRFS info (device vda6): turning on async discard Sep 11 00:29:31.468671 kernel: BTRFS info (device vda6): enabling free space tree Sep 11 00:29:31.470452 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 11 00:29:31.499689 ignition[1028]: INFO : Ignition 2.21.0 Sep 11 00:29:31.499689 ignition[1028]: INFO : Stage: files Sep 11 00:29:31.501285 ignition[1028]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 11 00:29:31.501285 ignition[1028]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 11 00:29:31.503527 ignition[1028]: DEBUG : files: compiled without relabeling support, skipping Sep 11 00:29:31.504617 ignition[1028]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 11 00:29:31.504617 ignition[1028]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 11 00:29:31.507450 ignition[1028]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 11 00:29:31.507450 ignition[1028]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 11 00:29:31.507450 ignition[1028]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 11 00:29:31.506849 unknown[1028]: wrote ssh authorized keys file for user: core Sep 11 00:29:31.512677 ignition[1028]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 11 00:29:31.512677 ignition[1028]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 11 00:29:31.512677 ignition[1028]: INFO : files: op(4): [started] processing unit "etcd-member.service" Sep 11 00:29:31.517612 ignition[1028]: INFO : files: op(4): op(5): [started] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Sep 11 00:29:31.519981 ignition[1028]: INFO : files: op(4): op(5): [finished] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Sep 11 00:29:31.519981 ignition[1028]: INFO : files: op(4): [finished] processing unit "etcd-member.service" Sep 11 00:29:31.519981 ignition[1028]: INFO : files: op(6): [started] processing unit "coreos-metadata.service" Sep 11 00:29:31.519981 ignition[1028]: INFO : files: op(6): op(7): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 11 00:29:31.519981 ignition[1028]: INFO : files: op(6): op(7): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 11 00:29:31.519981 ignition[1028]: INFO : files: op(6): [finished] processing unit "coreos-metadata.service" Sep 11 00:29:31.519981 ignition[1028]: INFO : files: op(8): [started] setting preset to disabled for "coreos-metadata.service" Sep 11 00:29:31.540323 ignition[1028]: INFO : files: op(8): op(9): [started] removing enablement symlink(s) for "coreos-metadata.service" Sep 11 00:29:31.544667 ignition[1028]: INFO : files: op(8): op(9): [finished] removing enablement symlink(s) for "coreos-metadata.service" Sep 11 00:29:31.546376 ignition[1028]: INFO : files: op(8): [finished] setting preset to disabled for "coreos-metadata.service" Sep 11 00:29:31.547813 ignition[1028]: INFO : files: op(a): [started] setting preset to enabled for "etcd-member.service" Sep 11 00:29:31.547813 ignition[1028]: INFO : files: op(a): [finished] setting preset to enabled for "etcd-member.service" Sep 11 00:29:31.550460 ignition[1028]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 11 00:29:31.550460 ignition[1028]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 11 00:29:31.550460 ignition[1028]: INFO : files: files passed Sep 11 00:29:31.550460 ignition[1028]: INFO : Ignition finished successfully Sep 11 00:29:31.557133 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 11 00:29:31.558389 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 11 00:29:31.561153 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 11 00:29:31.577335 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 11 00:29:31.577773 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 11 00:29:31.581481 initrd-setup-root-after-ignition[1057]: grep: /sysroot/oem/oem-release: No such file or directory Sep 11 00:29:31.584527 initrd-setup-root-after-ignition[1059]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 11 00:29:31.586296 initrd-setup-root-after-ignition[1063]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 11 00:29:31.587879 initrd-setup-root-after-ignition[1059]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 11 00:29:31.590730 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 11 00:29:31.593278 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 11 00:29:31.594245 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 11 00:29:31.651731 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 11 00:29:31.651853 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 11 00:29:31.652806 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 11 00:29:31.655751 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 11 00:29:31.656324 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 11 00:29:31.657237 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 11 00:29:31.702987 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 11 00:29:31.705382 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 11 00:29:31.735752 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 11 00:29:31.736455 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 11 00:29:31.738468 systemd[1]: Stopped target timers.target - Timer Units. Sep 11 00:29:31.741020 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 11 00:29:31.741183 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 11 00:29:31.741939 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 11 00:29:31.742446 systemd[1]: Stopped target basic.target - Basic System. Sep 11 00:29:31.742812 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 11 00:29:31.743330 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 11 00:29:31.743635 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 11 00:29:31.743990 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Sep 11 00:29:31.744511 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 11 00:29:31.744859 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 11 00:29:31.745346 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 11 00:29:31.745725 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 11 00:29:31.746283 systemd[1]: Stopped target swap.target - Swaps. Sep 11 00:29:31.746584 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 11 00:29:31.746729 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 11 00:29:31.768246 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 11 00:29:31.768823 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 11 00:29:31.769334 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 11 00:29:31.773383 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 11 00:29:31.774165 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 11 00:29:31.774271 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 11 00:29:31.779617 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 11 00:29:31.779795 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 11 00:29:31.780552 systemd[1]: Stopped target paths.target - Path Units. Sep 11 00:29:31.780806 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 11 00:29:31.787985 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 11 00:29:31.788339 systemd[1]: Stopped target slices.target - Slice Units. Sep 11 00:29:31.791337 systemd[1]: Stopped target sockets.target - Socket Units. Sep 11 00:29:31.791754 systemd[1]: iscsid.socket: Deactivated successfully. Sep 11 00:29:31.791874 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 11 00:29:31.794305 systemd-networkd[842]: eth0: Gained IPv6LL Sep 11 00:29:31.795056 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 11 00:29:31.795194 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 11 00:29:31.796700 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 11 00:29:31.796828 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 11 00:29:31.798438 systemd[1]: ignition-files.service: Deactivated successfully. Sep 11 00:29:31.798543 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 11 00:29:31.801166 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 11 00:29:31.804035 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 11 00:29:31.804659 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 11 00:29:31.804779 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 11 00:29:31.805383 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 11 00:29:31.805488 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 11 00:29:31.819021 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 11 00:29:31.819132 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 11 00:29:31.839813 ignition[1083]: INFO : Ignition 2.21.0 Sep 11 00:29:31.839813 ignition[1083]: INFO : Stage: umount Sep 11 00:29:31.842865 ignition[1083]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 11 00:29:31.842865 ignition[1083]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 11 00:29:31.842865 ignition[1083]: INFO : umount: umount passed Sep 11 00:29:31.842865 ignition[1083]: INFO : Ignition finished successfully Sep 11 00:29:31.842545 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 11 00:29:31.843273 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 11 00:29:31.843390 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 11 00:29:31.846671 systemd[1]: Stopped target network.target - Network. Sep 11 00:29:31.847201 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 11 00:29:31.847296 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 11 00:29:31.848856 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 11 00:29:31.848922 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 11 00:29:31.849315 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 11 00:29:31.849367 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 11 00:29:31.849628 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 11 00:29:31.849677 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 11 00:29:31.850306 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 11 00:29:31.863700 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 11 00:29:31.864502 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 11 00:29:31.864622 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 11 00:29:31.866669 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 11 00:29:31.866760 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 11 00:29:31.871874 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 11 00:29:31.872079 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 11 00:29:31.875187 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 11 00:29:31.875330 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 11 00:29:31.878816 systemd[1]: Stopped target network-pre.target - Preparation for Network. Sep 11 00:29:31.879315 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 11 00:29:31.879371 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 11 00:29:31.880435 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 11 00:29:31.883198 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 11 00:29:31.883249 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 11 00:29:31.883557 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 11 00:29:31.883605 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 11 00:29:31.889450 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 11 00:29:31.889518 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 11 00:29:31.889954 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 11 00:29:31.890000 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 11 00:29:31.895119 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 11 00:29:31.905086 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 11 00:29:31.905223 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 11 00:29:31.921773 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 11 00:29:31.921994 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 11 00:29:31.922745 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 11 00:29:31.922791 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 11 00:29:31.925845 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 11 00:29:31.925882 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 11 00:29:31.928293 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 11 00:29:31.928341 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 11 00:29:31.929257 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 11 00:29:31.929299 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 11 00:29:31.934267 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 11 00:29:31.934317 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 11 00:29:31.939480 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 11 00:29:31.940286 systemd[1]: systemd-network-generator.service: Deactivated successfully. Sep 11 00:29:31.940336 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Sep 11 00:29:31.944882 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 11 00:29:31.944952 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 11 00:29:31.948540 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Sep 11 00:29:31.948592 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 11 00:29:31.952500 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 11 00:29:31.952545 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 11 00:29:31.953458 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 11 00:29:31.953500 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:29:31.968701 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 11 00:29:31.968817 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 11 00:29:31.971402 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 11 00:29:31.972531 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 11 00:29:32.003620 systemd[1]: Switching root. Sep 11 00:29:32.047471 systemd-journald[219]: Journal stopped Sep 11 00:29:32.933982 systemd-journald[219]: Received SIGTERM from PID 1 (systemd). Sep 11 00:29:32.934039 kernel: SELinux: policy capability network_peer_controls=1 Sep 11 00:29:32.934055 kernel: SELinux: policy capability open_perms=1 Sep 11 00:29:32.934067 kernel: SELinux: policy capability extended_socket_class=1 Sep 11 00:29:32.934086 kernel: SELinux: policy capability always_check_network=0 Sep 11 00:29:32.934097 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 11 00:29:32.934109 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 11 00:29:32.934120 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 11 00:29:32.934132 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 11 00:29:32.934143 kernel: SELinux: policy capability userspace_initial_context=0 Sep 11 00:29:32.934156 kernel: audit: type=1403 audit(1757550572.147:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 11 00:29:32.934173 systemd[1]: Successfully loaded SELinux policy in 53.261ms. Sep 11 00:29:32.934203 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 12.556ms. Sep 11 00:29:32.934216 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:29:32.934274 systemd[1]: Detected virtualization kvm. Sep 11 00:29:32.934288 systemd[1]: Detected architecture x86-64. Sep 11 00:29:32.934299 systemd[1]: Detected first boot. Sep 11 00:29:32.934346 systemd[1]: Initializing machine ID from VM UUID. Sep 11 00:29:32.934361 zram_generator::config[1127]: No configuration found. Sep 11 00:29:32.934374 kernel: Guest personality initialized and is inactive Sep 11 00:29:32.934385 kernel: VMCI host device registered (name=vmci, major=10, minor=125) Sep 11 00:29:32.934396 kernel: Initialized host personality Sep 11 00:29:32.934407 kernel: NET: Registered PF_VSOCK protocol family Sep 11 00:29:32.934423 systemd[1]: Populated /etc with preset unit settings. Sep 11 00:29:32.934438 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Sep 11 00:29:32.934450 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 11 00:29:32.934462 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 11 00:29:32.934474 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 11 00:29:32.934486 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 11 00:29:32.934498 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 11 00:29:32.934510 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 11 00:29:32.934522 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 11 00:29:32.934534 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 11 00:29:32.934550 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 11 00:29:32.934562 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 11 00:29:32.934574 systemd[1]: Created slice user.slice - User and Session Slice. Sep 11 00:29:32.934585 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 11 00:29:32.934598 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 11 00:29:32.934619 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 11 00:29:32.934631 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 11 00:29:32.934644 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 11 00:29:32.934670 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 11 00:29:32.934690 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Sep 11 00:29:32.934706 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 11 00:29:32.934722 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 11 00:29:32.934734 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 11 00:29:32.934746 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 11 00:29:32.934758 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 11 00:29:32.934770 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 11 00:29:32.934786 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 11 00:29:32.934798 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 11 00:29:32.934810 systemd[1]: Reached target slices.target - Slice Units. Sep 11 00:29:32.934822 systemd[1]: Reached target swap.target - Swaps. Sep 11 00:29:32.934833 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 11 00:29:32.934847 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 11 00:29:32.934858 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Sep 11 00:29:32.934870 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 11 00:29:32.934882 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 11 00:29:32.934893 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 11 00:29:32.934962 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 11 00:29:32.934976 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 11 00:29:32.934988 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 11 00:29:32.935001 systemd[1]: Mounting media.mount - External Media Directory... Sep 11 00:29:32.935012 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 11 00:29:32.935024 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 11 00:29:32.935035 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 11 00:29:32.935047 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 11 00:29:32.935062 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:29:32.935075 systemd[1]: Reached target machines.target - Containers. Sep 11 00:29:32.935086 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 11 00:29:32.935098 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 11 00:29:32.935110 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 11 00:29:32.935122 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 11 00:29:32.935133 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 11 00:29:32.935145 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 11 00:29:32.935157 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 11 00:29:32.935171 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 11 00:29:32.935182 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 11 00:29:32.935196 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 11 00:29:32.935208 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 11 00:29:32.935220 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 11 00:29:32.935232 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 11 00:29:32.935244 systemd[1]: Stopped systemd-fsck-usr.service. Sep 11 00:29:32.935256 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:29:32.935270 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 11 00:29:32.935282 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 11 00:29:32.935293 kernel: loop: module loaded Sep 11 00:29:32.935305 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 11 00:29:32.935317 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 11 00:29:32.935329 kernel: fuse: init (API version 7.41) Sep 11 00:29:32.935340 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Sep 11 00:29:32.935352 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 11 00:29:32.935367 systemd[1]: verity-setup.service: Deactivated successfully. Sep 11 00:29:32.935378 systemd[1]: Stopped verity-setup.service. Sep 11 00:29:32.935390 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:29:32.935425 systemd-journald[1198]: Collecting audit messages is disabled. Sep 11 00:29:32.935454 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 11 00:29:32.935466 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 11 00:29:32.935478 systemd[1]: Mounted media.mount - External Media Directory. Sep 11 00:29:32.935491 systemd-journald[1198]: Journal started Sep 11 00:29:32.935513 systemd-journald[1198]: Runtime Journal (/run/log/journal/d95fca4e382d4c7382f6bd8db224dfa9) is 6M, max 48.2M, 42.2M free. Sep 11 00:29:32.703072 systemd[1]: Queued start job for default target multi-user.target. Sep 11 00:29:32.724851 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Sep 11 00:29:32.725328 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 11 00:29:32.938288 systemd[1]: Started systemd-journald.service - Journal Service. Sep 11 00:29:32.941888 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 11 00:29:32.943146 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 11 00:29:32.944569 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 11 00:29:32.946251 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 11 00:29:32.948118 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 11 00:29:32.948384 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 11 00:29:32.950273 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 11 00:29:32.950524 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 11 00:29:32.952534 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 11 00:29:32.954383 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 11 00:29:32.954660 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 11 00:29:32.956422 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 11 00:29:32.956695 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 11 00:29:32.958444 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 11 00:29:32.958683 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 11 00:29:32.960204 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 11 00:29:32.961831 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 11 00:29:32.964010 kernel: ACPI: bus type drm_connector registered Sep 11 00:29:32.964014 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 11 00:29:32.965623 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 11 00:29:32.965838 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 11 00:29:32.967695 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Sep 11 00:29:32.982136 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 11 00:29:32.985168 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 11 00:29:32.987280 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 11 00:29:32.988491 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 11 00:29:32.988582 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 11 00:29:32.990647 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Sep 11 00:29:33.004856 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 11 00:29:33.007213 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 11 00:29:33.008661 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 11 00:29:33.012796 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 11 00:29:33.014204 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 11 00:29:33.015349 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 11 00:29:33.016615 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 11 00:29:33.017759 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 11 00:29:33.022027 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 11 00:29:33.025130 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 11 00:29:33.028815 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 11 00:29:33.030329 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 11 00:29:33.031434 systemd-journald[1198]: Time spent on flushing to /var/log/journal/d95fca4e382d4c7382f6bd8db224dfa9 is 17.154ms for 1013 entries. Sep 11 00:29:33.031434 systemd-journald[1198]: System Journal (/var/log/journal/d95fca4e382d4c7382f6bd8db224dfa9) is 8M, max 195.6M, 187.6M free. Sep 11 00:29:33.070277 systemd-journald[1198]: Received client request to flush runtime journal. Sep 11 00:29:33.031582 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 11 00:29:33.041011 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 11 00:29:33.046650 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 11 00:29:33.051287 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Sep 11 00:29:33.065980 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 11 00:29:33.073040 kernel: loop0: detected capacity change from 0 to 113872 Sep 11 00:29:33.073650 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 11 00:29:33.074862 systemd-tmpfiles[1247]: ACLs are not supported, ignoring. Sep 11 00:29:33.074882 systemd-tmpfiles[1247]: ACLs are not supported, ignoring. Sep 11 00:29:33.081469 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 11 00:29:33.086056 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 11 00:29:33.095932 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 11 00:29:33.100083 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Sep 11 00:29:33.116966 kernel: loop1: detected capacity change from 0 to 146240 Sep 11 00:29:33.129719 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 11 00:29:33.133191 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 11 00:29:33.149948 kernel: loop2: detected capacity change from 0 to 113872 Sep 11 00:29:33.167102 kernel: loop3: detected capacity change from 0 to 146240 Sep 11 00:29:33.172175 systemd-tmpfiles[1267]: ACLs are not supported, ignoring. Sep 11 00:29:33.172195 systemd-tmpfiles[1267]: ACLs are not supported, ignoring. Sep 11 00:29:33.178923 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 11 00:29:33.180338 (sd-merge)[1268]: Using extensions 'containerd-flatcar', 'docker-flatcar'. Sep 11 00:29:33.181195 (sd-merge)[1268]: Merged extensions into '/usr'. Sep 11 00:29:33.184144 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 11 00:29:33.188493 systemd[1]: Starting ensure-sysext.service... Sep 11 00:29:33.191991 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 11 00:29:33.211433 systemd[1]: Reload requested from client PID 1272 ('systemctl') (unit ensure-sysext.service)... Sep 11 00:29:33.211584 systemd[1]: Reloading... Sep 11 00:29:33.219476 systemd-tmpfiles[1273]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Sep 11 00:29:33.219519 systemd-tmpfiles[1273]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Sep 11 00:29:33.219809 systemd-tmpfiles[1273]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 11 00:29:33.220077 systemd-tmpfiles[1273]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 11 00:29:33.222449 systemd-tmpfiles[1273]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 11 00:29:33.222727 systemd-tmpfiles[1273]: ACLs are not supported, ignoring. Sep 11 00:29:33.222798 systemd-tmpfiles[1273]: ACLs are not supported, ignoring. Sep 11 00:29:33.229002 systemd-tmpfiles[1273]: Detected autofs mount point /boot during canonicalization of boot. Sep 11 00:29:33.229013 systemd-tmpfiles[1273]: Skipping /boot Sep 11 00:29:33.242590 systemd-tmpfiles[1273]: Detected autofs mount point /boot during canonicalization of boot. Sep 11 00:29:33.242615 systemd-tmpfiles[1273]: Skipping /boot Sep 11 00:29:33.267969 zram_generator::config[1298]: No configuration found. Sep 11 00:29:33.364185 ldconfig[1241]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 11 00:29:33.400838 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Sep 11 00:29:33.483226 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 11 00:29:33.483775 systemd[1]: Reloading finished in 271 ms. Sep 11 00:29:33.517864 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 11 00:29:33.531271 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 11 00:29:33.540492 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 11 00:29:33.545299 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 11 00:29:33.562133 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 11 00:29:33.566106 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 11 00:29:33.568987 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 11 00:29:33.573678 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 11 00:29:33.575126 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 11 00:29:33.579190 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 11 00:29:33.589194 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 11 00:29:33.590472 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 11 00:29:33.590582 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:29:33.593206 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 11 00:29:33.594189 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 11 00:29:33.596414 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 11 00:29:33.599307 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 11 00:29:33.599580 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 11 00:29:33.611428 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 11 00:29:33.613753 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 11 00:29:33.618146 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 11 00:29:33.619606 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 11 00:29:33.619836 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:29:33.621933 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 11 00:29:33.624617 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 11 00:29:33.624874 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 11 00:29:33.628014 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 11 00:29:33.634425 augenrules[1374]: No rules Sep 11 00:29:33.635163 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 11 00:29:33.637311 systemd[1]: audit-rules.service: Deactivated successfully. Sep 11 00:29:33.637570 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 11 00:29:33.639347 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 11 00:29:33.639558 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 11 00:29:33.643394 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 11 00:29:33.653715 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 11 00:29:33.654970 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 11 00:29:33.656290 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 11 00:29:33.659358 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 11 00:29:33.662958 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 11 00:29:33.668254 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 11 00:29:33.669704 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 11 00:29:33.669818 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:29:33.671207 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 11 00:29:33.672903 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 11 00:29:33.673140 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 11 00:29:33.682937 systemd[1]: Finished ensure-sysext.service. Sep 11 00:29:33.689254 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Sep 11 00:29:33.691990 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:29:33.692277 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 11 00:29:33.693702 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 11 00:29:33.693976 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 11 00:29:33.695340 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 11 00:29:33.695555 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 11 00:29:33.697089 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 11 00:29:33.699576 augenrules[1384]: /sbin/augenrules: No change Sep 11 00:29:33.702115 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 11 00:29:33.706029 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 11 00:29:33.706236 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 11 00:29:33.711297 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 11 00:29:33.711496 augenrules[1416]: No rules Sep 11 00:29:33.713348 systemd[1]: audit-rules.service: Deactivated successfully. Sep 11 00:29:33.713672 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 11 00:29:33.717160 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 11 00:29:33.720024 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 11 00:29:33.727193 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 11 00:29:33.727211 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:29:33.745610 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 11 00:29:33.763515 systemd-udevd[1424]: Using default interface naming scheme 'v255'. Sep 11 00:29:33.767617 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Sep 11 00:29:33.769824 systemd[1]: Reached target time-set.target - System Time Set. Sep 11 00:29:33.776075 systemd-resolved[1343]: Positive Trust Anchors: Sep 11 00:29:33.776090 systemd-resolved[1343]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 11 00:29:33.776122 systemd-resolved[1343]: 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:29:33.780004 systemd-resolved[1343]: Defaulting to hostname 'linux'. Sep 11 00:29:33.781463 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 11 00:29:33.782653 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 11 00:29:33.787172 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 11 00:29:33.789702 systemd[1]: Reached target sysinit.target - System Initialization. Sep 11 00:29:33.790886 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 11 00:29:33.792142 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:29:33.793958 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Sep 11 00:29:33.795269 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 11 00:29:33.796422 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 11 00:29:33.797988 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 11 00:29:33.799308 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 11 00:29:33.799337 systemd[1]: Reached target paths.target - Path Units. Sep 11 00:29:33.800251 systemd[1]: Reached target timers.target - Timer Units. Sep 11 00:29:33.801875 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 11 00:29:33.805312 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 11 00:29:33.811051 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Sep 11 00:29:33.812612 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Sep 11 00:29:33.934989 systemd[1]: Reached target ssh-access.target - SSH Access Available. Sep 11 00:29:33.948461 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 11 00:29:33.951383 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Sep 11 00:29:33.954604 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 11 00:29:33.956293 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 11 00:29:33.962307 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Sep 11 00:29:33.964619 systemd[1]: Reached target sockets.target - Socket Units. Sep 11 00:29:33.965677 systemd[1]: Reached target basic.target - Basic System. Sep 11 00:29:33.966716 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 11 00:29:33.966818 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 11 00:29:33.972317 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 11 00:29:33.974211 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 11 00:29:33.976307 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 11 00:29:33.979296 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 11 00:29:33.980958 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:29:33.982452 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Sep 11 00:29:33.989733 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 11 00:29:33.991659 jq[1467]: false Sep 11 00:29:33.992083 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 11 00:29:33.995096 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 11 00:29:33.997922 google_oslogin_nss_cache[1469]: oslogin_cache_refresh[1469]: Refreshing passwd entry cache Sep 11 00:29:33.997284 oslogin_cache_refresh[1469]: Refreshing passwd entry cache Sep 11 00:29:34.000481 google_oslogin_nss_cache[1469]: oslogin_cache_refresh[1469]: Failure getting users, quitting Sep 11 00:29:34.000481 google_oslogin_nss_cache[1469]: oslogin_cache_refresh[1469]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 11 00:29:34.000481 google_oslogin_nss_cache[1469]: oslogin_cache_refresh[1469]: Refreshing group entry cache Sep 11 00:29:34.000481 google_oslogin_nss_cache[1469]: oslogin_cache_refresh[1469]: Failure getting groups, quitting Sep 11 00:29:34.000481 google_oslogin_nss_cache[1469]: oslogin_cache_refresh[1469]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 11 00:29:33.999341 oslogin_cache_refresh[1469]: Failure getting users, quitting Sep 11 00:29:33.999353 oslogin_cache_refresh[1469]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 11 00:29:33.999397 oslogin_cache_refresh[1469]: Refreshing group entry cache Sep 11 00:29:33.999858 oslogin_cache_refresh[1469]: Failure getting groups, quitting Sep 11 00:29:33.999866 oslogin_cache_refresh[1469]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 11 00:29:34.008858 kernel: mousedev: PS/2 mouse device common for all mice Sep 11 00:29:34.009288 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 11 00:29:34.011416 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 11 00:29:34.012324 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 11 00:29:34.016078 systemd[1]: Starting update-engine.service - Update Engine... Sep 11 00:29:34.025618 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 11 00:29:34.029484 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 11 00:29:34.031229 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 11 00:29:34.031461 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 11 00:29:34.031763 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Sep 11 00:29:34.032082 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Sep 11 00:29:34.034406 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 11 00:29:34.034649 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 11 00:29:34.036213 systemd[1]: motdgen.service: Deactivated successfully. Sep 11 00:29:34.036444 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 11 00:29:34.045546 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 Sep 11 00:29:34.055719 kernel: ACPI: button: Power Button [PWRF] Sep 11 00:29:34.057515 update_engine[1475]: I20250911 00:29:34.057101 1475 main.cc:92] Flatcar Update Engine starting Sep 11 00:29:34.065076 extend-filesystems[1468]: Found /dev/vda6 Sep 11 00:29:34.068226 extend-filesystems[1468]: Found /dev/vda9 Sep 11 00:29:34.068402 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 11 00:29:34.072964 extend-filesystems[1468]: Checking size of /dev/vda9 Sep 11 00:29:34.074031 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 11 00:29:34.080608 jq[1487]: true Sep 11 00:29:34.076826 dbus-daemon[1465]: [system] SELinux support is enabled Sep 11 00:29:34.077008 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 11 00:29:34.084111 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:29:34.084247 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 11 00:29:34.087074 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:29:34.089953 update_engine[1475]: I20250911 00:29:34.087538 1475 update_check_scheduler.cc:74] Next update check in 8m49s Sep 11 00:29:34.087094 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 11 00:29:34.088635 systemd[1]: Started update-engine.service - Update Engine. Sep 11 00:29:34.093106 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 11 00:29:34.095322 jq[1499]: true Sep 11 00:29:34.107617 extend-filesystems[1468]: Old size kept for /dev/vda9 Sep 11 00:29:34.109598 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 11 00:29:34.109893 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 11 00:29:34.124352 systemd-networkd[1464]: lo: Link UP Sep 11 00:29:34.124938 systemd-networkd[1464]: lo: Gained carrier Sep 11 00:29:34.125762 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 11 00:29:34.128556 systemd-networkd[1464]: Enumeration completed Sep 11 00:29:34.128658 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 11 00:29:34.129348 systemd-networkd[1464]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 11 00:29:34.129353 systemd-networkd[1464]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 11 00:29:34.129849 systemd[1]: Reached target network.target - Network. Sep 11 00:29:34.131045 systemd-networkd[1464]: eth0: Link UP Sep 11 00:29:34.131295 systemd-networkd[1464]: eth0: Gained carrier Sep 11 00:29:34.131317 systemd-networkd[1464]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 11 00:29:34.137226 systemd[1]: Starting containerd.service - containerd container runtime... Sep 11 00:29:34.158766 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Sep 11 00:29:34.161852 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 11 00:29:34.163963 systemd-networkd[1464]: eth0: DHCPv4 address 10.0.0.130/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 11 00:29:34.164992 systemd-timesyncd[1403]: Network configuration changed, trying to establish connection. Sep 11 00:29:35.062506 systemd-timesyncd[1403]: Contacted time server 10.0.0.1:123 (10.0.0.1). Sep 11 00:29:35.062546 systemd-timesyncd[1403]: Initial clock synchronization to Thu 2025-09-11 00:29:35.062399 UTC. Sep 11 00:29:35.065291 systemd-resolved[1343]: Clock change detected. Flushing caches. Sep 11 00:29:35.066967 bash[1524]: Updated "/home/core/.ssh/authorized_keys" Sep 11 00:29:35.069380 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 11 00:29:35.071303 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 11 00:29:35.076124 systemd-logind[1474]: New seat seat0. Sep 11 00:29:35.077871 systemd[1]: Started systemd-logind.service - User Login Management. Sep 11 00:29:35.101591 kernel: i801_smbus 0000:00:1f.3: Enabling SMBus device Sep 11 00:29:35.101877 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Sep 11 00:29:35.102057 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Sep 11 00:29:35.109507 (ntainerd)[1543]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 11 00:29:35.127888 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Sep 11 00:29:35.134373 sshd_keygen[1494]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 11 00:29:35.152170 locksmithd[1502]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 11 00:29:35.163404 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 11 00:29:35.167010 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 11 00:29:35.188560 systemd[1]: issuegen.service: Deactivated successfully. Sep 11 00:29:35.188927 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 11 00:29:35.194265 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 11 00:29:35.198889 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 11 00:29:35.215326 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 11 00:29:35.215594 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:29:35.219067 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 11 00:29:35.221966 systemd-logind[1474]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Sep 11 00:29:35.289061 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 11 00:29:35.298637 systemd-logind[1474]: Watching system buttons on /dev/input/event2 (Power Button) Sep 11 00:29:35.307266 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 11 00:29:35.312304 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Sep 11 00:29:35.313819 systemd[1]: Reached target getty.target - Login Prompts. Sep 11 00:29:35.345984 kernel: kvm_amd: TSC scaling supported Sep 11 00:29:35.346032 kernel: kvm_amd: Nested Virtualization enabled Sep 11 00:29:35.346046 kernel: kvm_amd: Nested Paging enabled Sep 11 00:29:35.346058 kernel: kvm_amd: LBR virtualization supported Sep 11 00:29:35.347122 kernel: kvm_amd: Virtual VMLOAD VMSAVE supported Sep 11 00:29:35.347145 kernel: kvm_amd: Virtual GIF supported Sep 11 00:29:35.380790 kernel: EDAC MC: Ver: 3.0.0 Sep 11 00:29:35.388595 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:29:35.489743 containerd[1543]: time="2025-09-11T00:29:35Z" 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:29:35.490637 containerd[1543]: time="2025-09-11T00:29:35.490601946Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 Sep 11 00:29:35.502249 containerd[1543]: time="2025-09-11T00:29:35.502188611Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="11.922µs" Sep 11 00:29:35.502249 containerd[1543]: time="2025-09-11T00:29:35.502228505Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Sep 11 00:29:35.502249 containerd[1543]: time="2025-09-11T00:29:35.502249014Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Sep 11 00:29:35.502516 containerd[1543]: time="2025-09-11T00:29:35.502477973Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Sep 11 00:29:35.502516 containerd[1543]: time="2025-09-11T00:29:35.502504042Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Sep 11 00:29:35.502582 containerd[1543]: time="2025-09-11T00:29:35.502536212Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 11 00:29:35.502623 containerd[1543]: time="2025-09-11T00:29:35.502601194Z" 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:29:35.502623 containerd[1543]: time="2025-09-11T00:29:35.502613878Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 11 00:29:35.502946 containerd[1543]: time="2025-09-11T00:29:35.502912388Z" 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:29:35.502946 containerd[1543]: time="2025-09-11T00:29:35.502932215Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 11 00:29:35.502946 containerd[1543]: time="2025-09-11T00:29:35.502943005Z" 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:29:35.503013 containerd[1543]: time="2025-09-11T00:29:35.502951561Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Sep 11 00:29:35.503073 containerd[1543]: time="2025-09-11T00:29:35.503045718Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Sep 11 00:29:35.503305 containerd[1543]: time="2025-09-11T00:29:35.503275659Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 11 00:29:35.503334 containerd[1543]: time="2025-09-11T00:29:35.503310063Z" 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:29:35.503334 containerd[1543]: time="2025-09-11T00:29:35.503321525Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Sep 11 00:29:35.503372 containerd[1543]: time="2025-09-11T00:29:35.503363604Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Sep 11 00:29:35.503902 containerd[1543]: time="2025-09-11T00:29:35.503871075Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Sep 11 00:29:35.503991 containerd[1543]: time="2025-09-11T00:29:35.503972155Z" level=info msg="metadata content store policy set" policy=shared Sep 11 00:29:35.508637 containerd[1543]: time="2025-09-11T00:29:35.508599571Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Sep 11 00:29:35.508677 containerd[1543]: time="2025-09-11T00:29:35.508651408Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Sep 11 00:29:35.508677 containerd[1543]: time="2025-09-11T00:29:35.508667188Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Sep 11 00:29:35.508726 containerd[1543]: time="2025-09-11T00:29:35.508679631Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Sep 11 00:29:35.508726 containerd[1543]: time="2025-09-11T00:29:35.508695100Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Sep 11 00:29:35.508726 containerd[1543]: time="2025-09-11T00:29:35.508705971Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Sep 11 00:29:35.508726 containerd[1543]: time="2025-09-11T00:29:35.508717993Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Sep 11 00:29:35.508830 containerd[1543]: time="2025-09-11T00:29:35.508729204Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Sep 11 00:29:35.508830 containerd[1543]: time="2025-09-11T00:29:35.508744443Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Sep 11 00:29:35.508830 containerd[1543]: time="2025-09-11T00:29:35.508788105Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Sep 11 00:29:35.508830 containerd[1543]: time="2025-09-11T00:29:35.508800418Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Sep 11 00:29:35.508830 containerd[1543]: time="2025-09-11T00:29:35.508813202Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Sep 11 00:29:35.508973 containerd[1543]: time="2025-09-11T00:29:35.508943787Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Sep 11 00:29:35.508973 containerd[1543]: time="2025-09-11T00:29:35.508968353Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Sep 11 00:29:35.509012 containerd[1543]: time="2025-09-11T00:29:35.508982159Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Sep 11 00:29:35.509012 containerd[1543]: time="2025-09-11T00:29:35.508993239Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Sep 11 00:29:35.509012 containerd[1543]: time="2025-09-11T00:29:35.509003469Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Sep 11 00:29:35.509070 containerd[1543]: time="2025-09-11T00:29:35.509013557Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Sep 11 00:29:35.509070 containerd[1543]: time="2025-09-11T00:29:35.509024819Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Sep 11 00:29:35.509070 containerd[1543]: time="2025-09-11T00:29:35.509034437Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Sep 11 00:29:35.509070 containerd[1543]: time="2025-09-11T00:29:35.509047000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Sep 11 00:29:35.509070 containerd[1543]: time="2025-09-11T00:29:35.509069021Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Sep 11 00:29:35.509171 containerd[1543]: time="2025-09-11T00:29:35.509080112Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Sep 11 00:29:35.509171 containerd[1543]: time="2025-09-11T00:29:35.509152758Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Sep 11 00:29:35.509171 containerd[1543]: time="2025-09-11T00:29:35.509165392Z" level=info msg="Start snapshots syncer" Sep 11 00:29:35.509224 containerd[1543]: time="2025-09-11T00:29:35.509190419Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Sep 11 00:29:35.509541 containerd[1543]: time="2025-09-11T00:29:35.509489500Z" 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:29:35.509690 containerd[1543]: time="2025-09-11T00:29:35.509540816Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Sep 11 00:29:35.509690 containerd[1543]: time="2025-09-11T00:29:35.509625214Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Sep 11 00:29:35.509746 containerd[1543]: time="2025-09-11T00:29:35.509727757Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Sep 11 00:29:35.509781 containerd[1543]: time="2025-09-11T00:29:35.509748055Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Sep 11 00:29:35.509802 containerd[1543]: time="2025-09-11T00:29:35.509792899Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Sep 11 00:29:35.509823 containerd[1543]: time="2025-09-11T00:29:35.509813127Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Sep 11 00:29:35.509851 containerd[1543]: time="2025-09-11T00:29:35.509839907Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Sep 11 00:29:35.509880 containerd[1543]: time="2025-09-11T00:29:35.509852751Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Sep 11 00:29:35.509880 containerd[1543]: time="2025-09-11T00:29:35.509862920Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Sep 11 00:29:35.509917 containerd[1543]: time="2025-09-11T00:29:35.509884961Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Sep 11 00:29:35.509917 containerd[1543]: time="2025-09-11T00:29:35.509896232Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Sep 11 00:29:35.509917 containerd[1543]: time="2025-09-11T00:29:35.509906271Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Sep 11 00:29:35.509969 containerd[1543]: time="2025-09-11T00:29:35.509962747Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 11 00:29:35.509994 containerd[1543]: time="2025-09-11T00:29:35.509975601Z" 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:29:35.509994 containerd[1543]: time="2025-09-11T00:29:35.509984969Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 11 00:29:35.510032 containerd[1543]: time="2025-09-11T00:29:35.509994216Z" 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:29:35.510032 containerd[1543]: time="2025-09-11T00:29:35.510002432Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Sep 11 00:29:35.510032 containerd[1543]: time="2025-09-11T00:29:35.510011609Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Sep 11 00:29:35.510032 containerd[1543]: time="2025-09-11T00:29:35.510022830Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Sep 11 00:29:35.510106 containerd[1543]: time="2025-09-11T00:29:35.510040643Z" level=info msg="runtime interface created" Sep 11 00:29:35.510106 containerd[1543]: time="2025-09-11T00:29:35.510047226Z" level=info msg="created NRI interface" Sep 11 00:29:35.510106 containerd[1543]: time="2025-09-11T00:29:35.510071842Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Sep 11 00:29:35.510106 containerd[1543]: time="2025-09-11T00:29:35.510082842Z" level=info msg="Connect containerd service" Sep 11 00:29:35.510174 containerd[1543]: time="2025-09-11T00:29:35.510131944Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 11 00:29:35.511054 containerd[1543]: time="2025-09-11T00:29:35.511017495Z" 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:29:35.740627 containerd[1543]: time="2025-09-11T00:29:35.740469427Z" level=info msg="Start subscribing containerd event" Sep 11 00:29:35.740733 containerd[1543]: time="2025-09-11T00:29:35.740609049Z" level=info msg="Start recovering state" Sep 11 00:29:35.740892 containerd[1543]: time="2025-09-11T00:29:35.740852425Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 11 00:29:35.740923 containerd[1543]: time="2025-09-11T00:29:35.740854940Z" level=info msg="Start event monitor" Sep 11 00:29:35.740965 containerd[1543]: time="2025-09-11T00:29:35.740947473Z" level=info msg="Start cni network conf syncer for default" Sep 11 00:29:35.740990 containerd[1543]: time="2025-09-11T00:29:35.740912939Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 11 00:29:35.741029 containerd[1543]: time="2025-09-11T00:29:35.740966800Z" level=info msg="Start streaming server" Sep 11 00:29:35.741050 containerd[1543]: time="2025-09-11T00:29:35.741035138Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Sep 11 00:29:35.741050 containerd[1543]: time="2025-09-11T00:29:35.741043423Z" level=info msg="runtime interface starting up..." Sep 11 00:29:35.741050 containerd[1543]: time="2025-09-11T00:29:35.741049595Z" level=info msg="starting plugins..." Sep 11 00:29:35.741104 containerd[1543]: time="2025-09-11T00:29:35.741089089Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Sep 11 00:29:35.741981 containerd[1543]: time="2025-09-11T00:29:35.741286469Z" level=info msg="containerd successfully booted in 0.252080s" Sep 11 00:29:35.741469 systemd[1]: Started containerd.service - containerd container runtime. Sep 11 00:29:36.913986 systemd-networkd[1464]: eth0: Gained IPv6LL Sep 11 00:29:36.917668 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 11 00:29:36.919502 systemd[1]: Reached target network-online.target - Network is Online. Sep 11 00:29:36.922157 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 11 00:29:36.924253 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 11 00:29:36.961639 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 11 00:29:36.979647 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 11 00:29:36.979959 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 11 00:29:36.982941 systemd[1]: Starting etcd-member.service - etcd (System Application Container)... Sep 11 00:29:36.984071 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 11 00:29:37.006604 (-wrapper)[1617]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS Sep 11 00:29:37.319643 systemd[1]: Starting docker.service - Docker Application Container Engine... Sep 11 00:29:37.340222 (dockerd)[1629]: 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:29:37.955080 dockerd[1629]: time="2025-09-11T00:29:37.954984282Z" level=info msg="Starting up" Sep 11 00:29:37.957006 dockerd[1629]: time="2025-09-11T00:29:37.956981366Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Sep 11 00:29:38.157803 dockerd[1629]: time="2025-09-11T00:29:38.157716324Z" level=info msg="Loading containers: start." Sep 11 00:29:38.240798 kernel: Initializing XFRM netlink socket Sep 11 00:29:38.523857 systemd-networkd[1464]: docker0: Link UP Sep 11 00:29:38.528729 dockerd[1629]: time="2025-09-11T00:29:38.528678358Z" level=info msg="Loading containers: done." Sep 11 00:29:38.547854 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck3726575088-merged.mount: Deactivated successfully. Sep 11 00:29:38.548620 dockerd[1629]: time="2025-09-11T00:29:38.548571117Z" 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:29:38.548724 dockerd[1629]: time="2025-09-11T00:29:38.548703836Z" level=info msg="Docker daemon" commit=bbd0a17ccc67e48d4a69393287b7fcc4f0578683 containerd-snapshotter=false storage-driver=overlay2 version=28.0.1 Sep 11 00:29:38.548907 dockerd[1629]: time="2025-09-11T00:29:38.548885607Z" level=info msg="Initializing buildkit" Sep 11 00:29:38.580331 dockerd[1629]: time="2025-09-11T00:29:38.580270473Z" level=info msg="Completed buildkit initialization" Sep 11 00:29:38.586109 dockerd[1629]: time="2025-09-11T00:29:38.586029562Z" level=info msg="Daemon has completed initialization" Sep 11 00:29:38.586271 dockerd[1629]: time="2025-09-11T00:29:38.586172790Z" level=info msg="API listen on /run/docker.sock" Sep 11 00:29:38.586402 systemd[1]: Started docker.service - Docker Application Container Engine. Sep 11 00:29:38.590185 etcd-wrapper[1621]: Error response from daemon: No such container: etcd-member Sep 11 00:29:38.612221 etcd-wrapper[1821]: Error response from daemon: No such container: etcd-member Sep 11 00:29:38.777668 etcd-wrapper[1841]: Unable to find image 'quay.io/coreos/etcd:v3.5.16' locally Sep 11 00:29:39.875279 etcd-wrapper[1841]: v3.5.16: Pulling from coreos/etcd Sep 11 00:29:40.111345 etcd-wrapper[1841]: 804c8aba2cc6: Pulling fs layer Sep 11 00:29:40.111345 etcd-wrapper[1841]: 2ae710cd8bfe: Pulling fs layer Sep 11 00:29:40.111345 etcd-wrapper[1841]: d462aa345367: Pulling fs layer Sep 11 00:29:40.111345 etcd-wrapper[1841]: 0f8b424aa0b9: Pulling fs layer Sep 11 00:29:40.111345 etcd-wrapper[1841]: d557676654e5: Pulling fs layer Sep 11 00:29:40.111345 etcd-wrapper[1841]: c8022d07192e: Pulling fs layer Sep 11 00:29:40.111345 etcd-wrapper[1841]: d858cbc252ad: Pulling fs layer Sep 11 00:29:40.111345 etcd-wrapper[1841]: 1069fc2daed1: Pulling fs layer Sep 11 00:29:40.111598 etcd-wrapper[1841]: b40161cd83fc: Pulling fs layer Sep 11 00:29:40.111598 etcd-wrapper[1841]: 5318d93a3a65: Pulling fs layer Sep 11 00:29:40.111598 etcd-wrapper[1841]: 307c1adadb60: Pulling fs layer Sep 11 00:29:40.111598 etcd-wrapper[1841]: fbb01d9e9dc9: Pulling fs layer Sep 11 00:29:40.111598 etcd-wrapper[1841]: fbfea02ac3cf: Pulling fs layer Sep 11 00:29:40.111598 etcd-wrapper[1841]: 8c26e4bf18e2: Pulling fs layer Sep 11 00:29:40.111598 etcd-wrapper[1841]: 1e59a65f8816: Pulling fs layer Sep 11 00:29:40.111598 etcd-wrapper[1841]: ffbd4ca5f0bd: Pulling fs layer Sep 11 00:29:40.111598 etcd-wrapper[1841]: d557676654e5: Waiting Sep 11 00:29:40.111598 etcd-wrapper[1841]: c8022d07192e: Waiting Sep 11 00:29:40.111598 etcd-wrapper[1841]: d858cbc252ad: Waiting Sep 11 00:29:40.111598 etcd-wrapper[1841]: 1069fc2daed1: Waiting Sep 11 00:29:40.111598 etcd-wrapper[1841]: b40161cd83fc: Waiting Sep 11 00:29:40.111598 etcd-wrapper[1841]: 5318d93a3a65: Waiting Sep 11 00:29:40.111598 etcd-wrapper[1841]: 307c1adadb60: Waiting Sep 11 00:29:40.111598 etcd-wrapper[1841]: fbb01d9e9dc9: Waiting Sep 11 00:29:40.111598 etcd-wrapper[1841]: fbfea02ac3cf: Waiting Sep 11 00:29:40.111598 etcd-wrapper[1841]: 8c26e4bf18e2: Waiting Sep 11 00:29:40.111598 etcd-wrapper[1841]: 1e59a65f8816: Waiting Sep 11 00:29:40.111598 etcd-wrapper[1841]: ffbd4ca5f0bd: Waiting Sep 11 00:29:40.111598 etcd-wrapper[1841]: 0f8b424aa0b9: Waiting Sep 11 00:29:40.405366 etcd-wrapper[1841]: d462aa345367: Download complete Sep 11 00:29:40.426705 etcd-wrapper[1841]: 2ae710cd8bfe: Verifying Checksum Sep 11 00:29:40.446432 etcd-wrapper[1841]: 804c8aba2cc6: Verifying Checksum Sep 11 00:29:40.446432 etcd-wrapper[1841]: 804c8aba2cc6: Download complete Sep 11 00:29:40.476626 etcd-wrapper[1841]: 804c8aba2cc6: Pull complete Sep 11 00:29:40.493404 systemd[1]: var-lib-docker-overlay2-ed5275dcf63509116c0edabc70b86999410bf1ab6cae29b29df14066a59f3afa-merged.mount: Deactivated successfully. Sep 11 00:29:40.512284 etcd-wrapper[1841]: 2ae710cd8bfe: Pull complete Sep 11 00:29:40.698921 etcd-wrapper[1841]: c8022d07192e: Download complete Sep 11 00:29:40.702594 etcd-wrapper[1841]: 0f8b424aa0b9: Verifying Checksum Sep 11 00:29:40.702594 etcd-wrapper[1841]: 0f8b424aa0b9: Download complete Sep 11 00:29:40.740413 etcd-wrapper[1841]: d557676654e5: Verifying Checksum Sep 11 00:29:40.740413 etcd-wrapper[1841]: d557676654e5: Download complete Sep 11 00:29:40.937597 etcd-wrapper[1841]: d462aa345367: Pull complete Sep 11 00:29:40.960283 etcd-wrapper[1841]: 0f8b424aa0b9: Pull complete Sep 11 00:29:40.973579 etcd-wrapper[1841]: 1069fc2daed1: Verifying Checksum Sep 11 00:29:40.973579 etcd-wrapper[1841]: 1069fc2daed1: Download complete Sep 11 00:29:40.984066 etcd-wrapper[1841]: d557676654e5: Pull complete Sep 11 00:29:41.002930 etcd-wrapper[1841]: d858cbc252ad: Verifying Checksum Sep 11 00:29:41.002930 etcd-wrapper[1841]: d858cbc252ad: Download complete Sep 11 00:29:41.011089 etcd-wrapper[1841]: c8022d07192e: Pull complete Sep 11 00:29:41.034600 etcd-wrapper[1841]: d858cbc252ad: Pull complete Sep 11 00:29:41.058908 etcd-wrapper[1841]: 1069fc2daed1: Pull complete Sep 11 00:29:41.065099 etcd-wrapper[1841]: b40161cd83fc: Verifying Checksum Sep 11 00:29:41.065099 etcd-wrapper[1841]: b40161cd83fc: Download complete Sep 11 00:29:41.083385 etcd-wrapper[1841]: b40161cd83fc: Pull complete Sep 11 00:29:41.226136 etcd-wrapper[1841]: 5318d93a3a65: Download complete Sep 11 00:29:41.245740 etcd-wrapper[1841]: 5318d93a3a65: Pull complete Sep 11 00:29:41.338080 etcd-wrapper[1841]: 307c1adadb60: Download complete Sep 11 00:29:41.352344 systemd[1]: var-lib-docker-overlay2-53e23684d2616c8cb193777992c03780b610f38fcf031fa522281c617f253b2b-merged.mount: Deactivated successfully. Sep 11 00:29:41.365247 etcd-wrapper[1841]: 307c1adadb60: Pull complete Sep 11 00:29:41.611302 etcd-wrapper[1841]: fbfea02ac3cf: Verifying Checksum Sep 11 00:29:41.611302 etcd-wrapper[1841]: fbfea02ac3cf: Download complete Sep 11 00:29:41.627486 etcd-wrapper[1841]: fbb01d9e9dc9: Verifying Checksum Sep 11 00:29:41.627486 etcd-wrapper[1841]: fbb01d9e9dc9: Download complete Sep 11 00:29:41.750920 systemd[1]: var-lib-docker-overlay2-7b7114805beacdf390009d51ece5a3d0212b492206900c80278019406c21190d-merged.mount: Deactivated successfully. Sep 11 00:29:41.834131 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 11 00:29:41.835499 systemd[1]: Started sshd@0-10.0.0.130:22-10.0.0.1:51200.service - OpenSSH per-connection server daemon (10.0.0.1:51200). Sep 11 00:29:41.926761 etcd-wrapper[1841]: 1e59a65f8816: Download complete Sep 11 00:29:41.929576 etcd-wrapper[1841]: 8c26e4bf18e2: Verifying Checksum Sep 11 00:29:41.929576 etcd-wrapper[1841]: 8c26e4bf18e2: Download complete Sep 11 00:29:41.985416 sshd[1919]: Accepted publickey for core from 10.0.0.1 port 51200 ssh2: RSA SHA256:2FKl6F/CXYpU0+lRtBl6FqtyyB7NBzEoeS8HPkzCick Sep 11 00:29:41.987236 sshd-session[1919]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:29:41.993556 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 11 00:29:41.994673 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 11 00:29:42.000530 systemd-logind[1474]: New session 1 of user core. Sep 11 00:29:42.001121 etcd-wrapper[1841]: ffbd4ca5f0bd: Verifying Checksum Sep 11 00:29:42.001121 etcd-wrapper[1841]: ffbd4ca5f0bd: Download complete Sep 11 00:29:42.026404 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 11 00:29:42.029398 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 11 00:29:42.083259 (systemd)[1923]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 11 00:29:42.085634 systemd-logind[1474]: New session c1 of user core. Sep 11 00:29:42.246445 systemd[1923]: Queued start job for default target default.target. Sep 11 00:29:42.263008 systemd[1923]: Created slice app.slice - User Application Slice. Sep 11 00:29:42.263034 systemd[1923]: Reached target paths.target - Paths. Sep 11 00:29:42.263075 systemd[1923]: Reached target timers.target - Timers. Sep 11 00:29:42.264591 systemd[1923]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 11 00:29:42.275407 systemd[1923]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 11 00:29:42.275528 systemd[1923]: Reached target sockets.target - Sockets. Sep 11 00:29:42.275569 systemd[1923]: Reached target basic.target - Basic System. Sep 11 00:29:42.275615 systemd[1923]: Reached target default.target - Main User Target. Sep 11 00:29:42.275651 systemd[1923]: Startup finished in 183ms. Sep 11 00:29:42.275995 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 11 00:29:42.277683 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 11 00:29:42.344249 systemd[1]: Started sshd@1-10.0.0.130:22-10.0.0.1:51202.service - OpenSSH per-connection server daemon (10.0.0.1:51202). Sep 11 00:29:42.359127 etcd-wrapper[1841]: fbb01d9e9dc9: Pull complete Sep 11 00:29:42.396306 sshd[1934]: Accepted publickey for core from 10.0.0.1 port 51202 ssh2: RSA SHA256:2FKl6F/CXYpU0+lRtBl6FqtyyB7NBzEoeS8HPkzCick Sep 11 00:29:42.397965 sshd-session[1934]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:29:42.402746 systemd-logind[1474]: New session 2 of user core. Sep 11 00:29:42.407901 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 11 00:29:42.464508 sshd[1941]: Connection closed by 10.0.0.1 port 51202 Sep 11 00:29:42.462922 sshd-session[1934]: pam_unix(sshd:session): session closed for user core Sep 11 00:29:42.473864 systemd[1]: var-lib-docker-overlay2-12f0dfb0f37505bbd3a58a6ac8a7714afb9dbd1c110a3d9a63fc4ee2e582badd-merged.mount: Deactivated successfully. Sep 11 00:29:42.474918 systemd[1]: sshd@1-10.0.0.130:22-10.0.0.1:51202.service: Deactivated successfully. Sep 11 00:29:42.476828 systemd[1]: session-2.scope: Deactivated successfully. Sep 11 00:29:42.478578 systemd-logind[1474]: Session 2 logged out. Waiting for processes to exit. Sep 11 00:29:42.481055 systemd[1]: Started sshd@2-10.0.0.130:22-10.0.0.1:51214.service - OpenSSH per-connection server daemon (10.0.0.1:51214). Sep 11 00:29:42.481949 systemd-logind[1474]: Removed session 2. Sep 11 00:29:42.534102 sshd[1947]: Accepted publickey for core from 10.0.0.1 port 51214 ssh2: RSA SHA256:2FKl6F/CXYpU0+lRtBl6FqtyyB7NBzEoeS8HPkzCick Sep 11 00:29:42.535693 sshd-session[1947]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:29:42.540081 systemd-logind[1474]: New session 3 of user core. Sep 11 00:29:42.549989 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 11 00:29:42.562034 etcd-wrapper[1841]: fbfea02ac3cf: Pull complete Sep 11 00:29:42.603063 sshd[1949]: Connection closed by 10.0.0.1 port 51214 Sep 11 00:29:42.603506 sshd-session[1947]: pam_unix(sshd:session): session closed for user core Sep 11 00:29:42.608616 systemd[1]: sshd@2-10.0.0.130:22-10.0.0.1:51214.service: Deactivated successfully. Sep 11 00:29:42.611398 systemd[1]: session-3.scope: Deactivated successfully. Sep 11 00:29:42.612624 systemd-logind[1474]: Session 3 logged out. Waiting for processes to exit. Sep 11 00:29:42.614032 systemd-logind[1474]: Removed session 3. Sep 11 00:29:42.651129 systemd[1]: var-lib-docker-overlay2-787b306870ac8a9fc47cc02e38f3cd56c1e8582414d43fdc9665babc6b3ab3f5-merged.mount: Deactivated successfully. Sep 11 00:29:42.746807 etcd-wrapper[1841]: 8c26e4bf18e2: Pull complete Sep 11 00:29:42.774647 etcd-wrapper[1841]: 1e59a65f8816: Pull complete Sep 11 00:29:42.796466 etcd-wrapper[1841]: ffbd4ca5f0bd: Pull complete Sep 11 00:29:42.806532 etcd-wrapper[1841]: Digest: sha256:d967d98a12dc220a1a290794711dba7eba04b8ce465e12b02383d1bfbb33e159 Sep 11 00:29:42.810544 etcd-wrapper[1841]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.16 Sep 11 00:29:42.866745 containerd[1543]: time="2025-09-11T00:29:42.866680892Z" level=info msg="connecting to shim e8ef8df004d5d91e9339895a092ccf6a83383fc99c978fcbe4ac27fc39743f4b" address="unix:///run/containerd/s/180ae1fb261840a87b5a2f7ffb00acfb340a08e8efd79c6564b2495d5e127611" namespace=moby protocol=ttrpc version=3 Sep 11 00:29:42.898936 systemd[1]: Started docker-e8ef8df004d5d91e9339895a092ccf6a83383fc99c978fcbe4ac27fc39743f4b.scope - libcontainer container e8ef8df004d5d91e9339895a092ccf6a83383fc99c978fcbe4ac27fc39743f4b. Sep 11 00:29:42.912193 systemd-resolved[1343]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 11 00:29:42.960055 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:42.959779Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} Sep 11 00:29:42.960055 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:42.959902Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"d95fca4e382d4c7382f6bd8db224dfa9"} Sep 11 00:29:42.960944 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:29:42.959926Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} Sep 11 00:29:42.960944 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:29:42.959943Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.16"} Sep 11 00:29:42.960944 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:29:42.959948Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} Sep 11 00:29:42.960944 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:29:42.959952Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} Sep 11 00:29:42.960944 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:29:42.960003Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Sep 11 00:29:42.960944 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:42.960017Z","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.130:2379"]} Sep 11 00:29:42.960944 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:29:42.960071Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Sep 11 00:29:42.960944 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:42.960078Z","caller":"embed/etcd.go:128","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]} Sep 11 00:29:42.960944 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:42.960522Z","caller":"embed/etcd.go:136","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} Sep 11 00:29:42.961134 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:42.960631Z","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":"d95fca4e382d4c7382f6bd8db224dfa9","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.130:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"d95fca4e382d4c7382f6bd8db224dfa9=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:29:42.963675 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:42.963501Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.50159ms"} Sep 11 00:29:42.968395 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:42.968176Z","caller":"etcdserver/raft.go:505","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"} Sep 11 00:29:42.968395 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:42.968230Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"} Sep 11 00:29:42.968395 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:42.968292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"} Sep 11 00:29:42.968562 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:42.968299Z","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:29:42.968562 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:42.968311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"} Sep 11 00:29:42.968562 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:42.968358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Sep 11 00:29:42.972436 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:29:42.972180Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} Sep 11 00:29:42.973466 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:42.973282Z","caller":"mvcc/kvstore.go:423","msg":"kvstore restored","current-rev":1} Sep 11 00:29:42.974388 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:42.974228Z","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:29:42.975214 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:42.975055Z","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:29:42.975407 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:42.975220Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Sep 11 00:29:42.975531 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:42.975275Z","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:29:42.975762 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:42.975581Z","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:29:42.975762 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:42.975629Z","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:29:42.975762 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:42.975638Z","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:29:42.977362 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:42.977198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Sep 11 00:29:42.977539 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:42.977400Z","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:29:42.978340 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:42.978192Z","caller":"embed/etcd.go:600","msg":"serving peer traffic","address":"127.0.0.1:2380"} Sep 11 00:29:42.978340 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:42.978219Z","caller":"embed/etcd.go:572","msg":"cmux::serve","address":"127.0.0.1:2380"} Sep 11 00:29:42.978340 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:42.978223Z","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.130:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} Sep 11 00:29:43.373555 systemd[1]: var-lib-docker-overlay2-622bb792e368363b49c0104eaa62c5be1e93a03294838751b2d5c006abe6fedc-merged.mount: Deactivated successfully. Sep 11 00:29:43.469935 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:43.469470Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"} Sep 11 00:29:43.469935 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:43.469525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"} Sep 11 00:29:43.469935 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:43.469559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"} Sep 11 00:29:43.469935 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:43.469572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"} Sep 11 00:29:43.469935 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:43.469578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"} Sep 11 00:29:43.469935 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:43.469587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"} Sep 11 00:29:43.469935 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:43.469595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"} Sep 11 00:29:43.471044 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:43.470500Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} Sep 11 00:29:43.471044 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:43.470494Z","caller":"etcdserver/server.go:2651","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} Sep 11 00:29:43.471044 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:43.470502Z","caller":"etcdserver/server.go:2140","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:d95fca4e382d4c7382f6bd8db224dfa9 ClientURLs:[http://10.0.0.130:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"} Sep 11 00:29:43.471044 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:43.470663Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} Sep 11 00:29:43.471044 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:43.470849Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} Sep 11 00:29:43.471435 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:43.470962Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Sep 11 00:29:43.471435 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:43.471217Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"} Sep 11 00:29:43.471435 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:43.471296Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} Sep 11 00:29:43.471250 systemd[1]: Started etcd-member.service - etcd (System Application Container). Sep 11 00:29:43.471562 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 11 00:29:43.471809 systemd[1]: Startup finished in 2.758s (kernel) + 4.517s (initrd) + 10.478s (userspace) = 17.754s. Sep 11 00:29:43.472000 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:43.471534Z","caller":"etcdserver/server.go:2675","msg":"cluster version is updated","cluster-version":"3.5"} Sep 11 00:29:43.472132 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:29:43.471931Z","caller":"embed/serve.go:187","msg":"serving client traffic insecurely; this is strongly discouraged!","traffic":"grpc+http","address":"[::]:2379"} Sep 11 00:29:52.619887 systemd[1]: Started sshd@3-10.0.0.130:22-10.0.0.1:35436.service - OpenSSH per-connection server daemon (10.0.0.1:35436). Sep 11 00:29:52.674027 sshd[2024]: Accepted publickey for core from 10.0.0.1 port 35436 ssh2: RSA SHA256:2FKl6F/CXYpU0+lRtBl6FqtyyB7NBzEoeS8HPkzCick Sep 11 00:29:52.675723 sshd-session[2024]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:29:52.680421 systemd-logind[1474]: New session 4 of user core. Sep 11 00:29:52.690011 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 11 00:29:52.744757 sshd[2026]: Connection closed by 10.0.0.1 port 35436 Sep 11 00:29:52.745113 sshd-session[2024]: pam_unix(sshd:session): session closed for user core Sep 11 00:29:52.762524 systemd[1]: sshd@3-10.0.0.130:22-10.0.0.1:35436.service: Deactivated successfully. Sep 11 00:29:52.764407 systemd[1]: session-4.scope: Deactivated successfully. Sep 11 00:29:52.765312 systemd-logind[1474]: Session 4 logged out. Waiting for processes to exit. Sep 11 00:29:52.768121 systemd[1]: Started sshd@4-10.0.0.130:22-10.0.0.1:35438.service - OpenSSH per-connection server daemon (10.0.0.1:35438). Sep 11 00:29:52.768895 systemd-logind[1474]: Removed session 4. Sep 11 00:29:52.821695 sshd[2032]: Accepted publickey for core from 10.0.0.1 port 35438 ssh2: RSA SHA256:2FKl6F/CXYpU0+lRtBl6FqtyyB7NBzEoeS8HPkzCick Sep 11 00:29:52.822967 sshd-session[2032]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:29:52.827591 systemd-logind[1474]: New session 5 of user core. Sep 11 00:29:52.840915 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 11 00:29:52.890290 sshd[2035]: Connection closed by 10.0.0.1 port 35438 Sep 11 00:29:52.890880 sshd-session[2032]: pam_unix(sshd:session): session closed for user core Sep 11 00:29:52.909176 systemd[1]: sshd@4-10.0.0.130:22-10.0.0.1:35438.service: Deactivated successfully. Sep 11 00:29:52.910656 systemd[1]: session-5.scope: Deactivated successfully. Sep 11 00:29:52.911453 systemd-logind[1474]: Session 5 logged out. Waiting for processes to exit. Sep 11 00:29:52.913688 systemd[1]: Started sshd@5-10.0.0.130:22-10.0.0.1:35454.service - OpenSSH per-connection server daemon (10.0.0.1:35454). Sep 11 00:29:52.914198 systemd-logind[1474]: Removed session 5. Sep 11 00:29:52.984742 sshd[2041]: Accepted publickey for core from 10.0.0.1 port 35454 ssh2: RSA SHA256:2FKl6F/CXYpU0+lRtBl6FqtyyB7NBzEoeS8HPkzCick Sep 11 00:29:52.986368 sshd-session[2041]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:29:52.990982 systemd-logind[1474]: New session 6 of user core. Sep 11 00:29:53.002941 systemd[1]: Started session-6.scope - Session 6 of User core. Sep 11 00:29:53.057026 sshd[2043]: Connection closed by 10.0.0.1 port 35454 Sep 11 00:29:53.057471 sshd-session[2041]: pam_unix(sshd:session): session closed for user core Sep 11 00:29:53.067399 systemd[1]: sshd@5-10.0.0.130:22-10.0.0.1:35454.service: Deactivated successfully. Sep 11 00:29:53.069284 systemd[1]: session-6.scope: Deactivated successfully. Sep 11 00:29:53.070127 systemd-logind[1474]: Session 6 logged out. Waiting for processes to exit. Sep 11 00:29:53.072608 systemd[1]: Started sshd@6-10.0.0.130:22-10.0.0.1:35460.service - OpenSSH per-connection server daemon (10.0.0.1:35460). Sep 11 00:29:53.073301 systemd-logind[1474]: Removed session 6. Sep 11 00:29:53.134265 sshd[2049]: Accepted publickey for core from 10.0.0.1 port 35460 ssh2: RSA SHA256:2FKl6F/CXYpU0+lRtBl6FqtyyB7NBzEoeS8HPkzCick Sep 11 00:29:53.135829 sshd-session[2049]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:29:53.140483 systemd-logind[1474]: New session 7 of user core. Sep 11 00:29:53.150981 systemd[1]: Started session-7.scope - Session 7 of User core. Sep 11 00:29:53.209193 sudo[2052]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Sep 11 00:29:53.209534 sudo[2052]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 11 00:29:53.232092 sudo[2052]: pam_unix(sudo:session): session closed for user root Sep 11 00:29:53.234677 sshd[2051]: Connection closed by 10.0.0.1 port 35460 Sep 11 00:29:53.235124 sshd-session[2049]: pam_unix(sshd:session): session closed for user core Sep 11 00:29:53.249719 systemd[1]: sshd@6-10.0.0.130:22-10.0.0.1:35460.service: Deactivated successfully. Sep 11 00:29:53.251594 systemd[1]: session-7.scope: Deactivated successfully. Sep 11 00:29:53.252591 systemd-logind[1474]: Session 7 logged out. Waiting for processes to exit. Sep 11 00:29:53.255148 systemd[1]: Started sshd@7-10.0.0.130:22-10.0.0.1:35466.service - OpenSSH per-connection server daemon (10.0.0.1:35466). Sep 11 00:29:53.255760 systemd-logind[1474]: Removed session 7. Sep 11 00:29:53.310525 sshd[2058]: Accepted publickey for core from 10.0.0.1 port 35466 ssh2: RSA SHA256:2FKl6F/CXYpU0+lRtBl6FqtyyB7NBzEoeS8HPkzCick Sep 11 00:29:53.312200 sshd-session[2058]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:29:53.317047 systemd-logind[1474]: New session 8 of user core. Sep 11 00:29:53.332097 systemd[1]: Started session-8.scope - Session 8 of User core. Sep 11 00:29:53.386467 sudo[2062]: 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:29:53.386816 sudo[2062]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 11 00:29:53.393744 sudo[2062]: pam_unix(sudo:session): session closed for user root Sep 11 00:29:53.401954 sudo[2061]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Sep 11 00:29:53.402356 sudo[2061]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 11 00:29:53.412430 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 11 00:29:53.464296 augenrules[2084]: No rules Sep 11 00:29:53.465961 systemd[1]: audit-rules.service: Deactivated successfully. Sep 11 00:29:53.466264 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 11 00:29:53.467434 sudo[2061]: pam_unix(sudo:session): session closed for user root Sep 11 00:29:53.469258 sshd[2060]: Connection closed by 10.0.0.1 port 35466 Sep 11 00:29:53.469520 sshd-session[2058]: pam_unix(sshd:session): session closed for user core Sep 11 00:29:53.478587 systemd[1]: sshd@7-10.0.0.130:22-10.0.0.1:35466.service: Deactivated successfully. Sep 11 00:29:53.480217 systemd[1]: session-8.scope: Deactivated successfully. Sep 11 00:29:53.481031 systemd-logind[1474]: Session 8 logged out. Waiting for processes to exit. Sep 11 00:29:53.483911 systemd[1]: Started sshd@8-10.0.0.130:22-10.0.0.1:35472.service - OpenSSH per-connection server daemon (10.0.0.1:35472). Sep 11 00:29:53.484702 systemd-logind[1474]: Removed session 8. Sep 11 00:29:53.549996 sshd[2093]: Accepted publickey for core from 10.0.0.1 port 35472 ssh2: RSA SHA256:2FKl6F/CXYpU0+lRtBl6FqtyyB7NBzEoeS8HPkzCick Sep 11 00:29:53.551473 sshd-session[2093]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:29:53.556212 systemd-logind[1474]: New session 9 of user core. Sep 11 00:29:53.566913 systemd[1]: Started session-9.scope - Session 9 of User core. Sep 11 00:29:53.618137 sshd[2095]: Connection closed by 10.0.0.1 port 35472 Sep 11 00:29:53.618525 sshd-session[2093]: pam_unix(sshd:session): session closed for user core Sep 11 00:29:53.631912 systemd[1]: sshd@8-10.0.0.130:22-10.0.0.1:35472.service: Deactivated successfully. Sep 11 00:29:53.633803 systemd[1]: session-9.scope: Deactivated successfully. Sep 11 00:29:53.634548 systemd-logind[1474]: Session 9 logged out. Waiting for processes to exit. Sep 11 00:29:53.637350 systemd[1]: Started sshd@9-10.0.0.130:22-10.0.0.1:35476.service - OpenSSH per-connection server daemon (10.0.0.1:35476). Sep 11 00:29:53.638069 systemd-logind[1474]: Removed session 9. Sep 11 00:29:53.690600 sshd[2103]: Accepted publickey for core from 10.0.0.1 port 35476 ssh2: RSA SHA256:2FKl6F/CXYpU0+lRtBl6FqtyyB7NBzEoeS8HPkzCick Sep 11 00:29:53.692164 sshd-session[2103]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:29:53.697546 systemd-logind[1474]: New session 10 of user core. Sep 11 00:29:53.707006 systemd[1]: Started session-10.scope - Session 10 of User core. Sep 11 00:29:53.877200 sshd[2105]: Connection closed by 10.0.0.1 port 35476 Sep 11 00:29:53.877517 sshd-session[2103]: pam_unix(sshd:session): session closed for user core Sep 11 00:29:53.880883 systemd[1]: sshd@9-10.0.0.130:22-10.0.0.1:35476.service: Deactivated successfully. Sep 11 00:29:53.882710 systemd[1]: session-10.scope: Deactivated successfully. Sep 11 00:29:53.884051 systemd-logind[1474]: Session 10 logged out. Waiting for processes to exit. Sep 11 00:29:53.885452 systemd-logind[1474]: Removed session 10. Sep 11 00:30:20.740787 update_engine[1475]: I20250911 00:30:20.740669 1475 update_attempter.cc:509] Updating boot flags... Sep 11 00:30:43.818217 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:30:43.817422Z","caller":"traceutil/trace.go:171","msg":"trace[886043519] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"105.438113ms","start":"2025-09-11T00:30:43.711956Z","end":"2025-09-11T00:30:43.817394Z","steps":["trace[886043519] 'process raft request' (duration: 105.375585ms)"],"step_count":1} Sep 11 00:30:43.818217 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:30:43.817415Z","caller":"traceutil/trace.go:171","msg":"trace[226455400] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"105.43655ms","start":"2025-09-11T00:30:43.711958Z","end":"2025-09-11T00:30:43.817395Z","steps":["trace[226455400] 'process raft request' (duration: 105.406093ms)"],"step_count":1} Sep 11 00:30:43.818217 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:30:43.817405Z","caller":"traceutil/trace.go:171","msg":"trace[2051193948] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"105.429598ms","start":"2025-09-11T00:30:43.711950Z","end":"2025-09-11T00:30:43.817380Z","steps":["trace[2051193948] 'process raft request' (duration: 89.51744ms)","trace[2051193948] 'compare' (duration: 15.767596ms)"],"step_count":2} Sep 11 00:30:43.906005 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:30:43.905532Z","caller":"traceutil/trace.go:171","msg":"trace[1488728659] transaction","detail":"{read_only:false; number_of_response:0; response_revision:282; }","duration":"171.131098ms","start":"2025-09-11T00:30:43.734385Z","end":"2025-09-11T00:30:43.905516Z","steps":["trace[1488728659] 'process raft request' (duration: 171.096353ms)"],"step_count":1} Sep 11 00:30:43.906005 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:30:43.905609Z","caller":"traceutil/trace.go:171","msg":"trace[1136734916] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"171.362993ms","start":"2025-09-11T00:30:43.734221Z","end":"2025-09-11T00:30:43.905584Z","steps":["trace[1136734916] 'process raft request' (duration: 171.180751ms)"],"step_count":1} Sep 11 00:30:44.038236 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:30:44.037989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.357552ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Sep 11 00:30:44.038236 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:30:44.038045Z","caller":"traceutil/trace.go:171","msg":"trace[445635629] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"123.218401ms","start":"2025-09-11T00:30:43.914815Z","end":"2025-09-11T00:30:44.038033Z","steps":["trace[445635629] 'marshal mvccpb.KeyValue' {req_type:put; key:/registry/events/default/localhost.186412ffa27a9a7b; req_size:640; } (duration: 120.270248ms)"],"step_count":1} Sep 11 00:30:44.104784 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:30:44.104348Z","caller":"traceutil/trace.go:171","msg":"trace[984969281] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"184.396516ms","start":"2025-09-11T00:30:43.919942Z","end":"2025-09-11T00:30:44.104339Z","steps":["trace[984969281] 'process raft request' (duration: 184.345461ms)"],"step_count":1} Sep 11 00:30:44.393626 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:30:44.393034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.217875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Sep 11 00:30:44.393626 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:30:44.393109Z","caller":"traceutil/trace.go:171","msg":"trace[793016045] linearizableReadLoop","detail":"{readStateIndex:295; appliedIndex:294; }","duration":"257.645838ms","start":"2025-09-11T00:30:44.135450Z","end":"2025-09-11T00:30:44.393096Z","steps":["trace[793016045] 'read index received' (duration: 18.378871ms)","trace[793016045] 'applied index is now lower than readState.Index' (duration: 239.266205ms)"],"step_count":2} Sep 11 00:30:44.393626 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:30:44.393160Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.705619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} Sep 11 00:30:44.393626 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:30:44.393181Z","caller":"traceutil/trace.go:171","msg":"trace[879926237] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:287; }","duration":"257.756035ms","start":"2025-09-11T00:30:44.135419Z","end":"2025-09-11T00:30:44.393175Z","steps":["trace[879926237] 'agreement among raft nodes before linearized reading' (duration: 257.70565ms)"],"step_count":1} Sep 11 00:30:44.394061 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:30:44.393173Z","caller":"traceutil/trace.go:171","msg":"trace[1565647153] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"272.174498ms","start":"2025-09-11T00:30:44.120970Z","end":"2025-09-11T00:30:44.393145Z","steps":["trace[1565647153] 'process raft request' (duration: 32.825197ms)","trace[1565647153] 'compare' (duration: 239.095995ms)"],"step_count":2} Sep 11 00:30:44.728796 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:30:44.728161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.714344ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Sep 11 00:30:44.728796 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:30:44.728249Z","caller":"traceutil/trace.go:171","msg":"trace[267955368] linearizableReadLoop","detail":"{readStateIndex:297; appliedIndex:296; }","duration":"241.053077ms","start":"2025-09-11T00:30:44.487182Z","end":"2025-09-11T00:30:44.728235Z","steps":["trace[267955368] 'read index received' (duration: 122.211573ms)","trace[267955368] 'applied index is now lower than readState.Index' (duration: 118.840662ms)"],"step_count":2} Sep 11 00:30:44.728796 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:30:44.728306Z","caller":"traceutil/trace.go:171","msg":"trace[17844250] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"332.589924ms","start":"2025-09-11T00:30:44.395674Z","end":"2025-09-11T00:30:44.728264Z","steps":["trace[17844250] 'process raft request' (duration: 213.720408ms)","trace[17844250] 'compare' (duration: 118.587726ms)"],"step_count":2} Sep 11 00:30:44.728796 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:30:44.728323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.136783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Sep 11 00:30:44.729098 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:30:44.728341Z","caller":"traceutil/trace.go:171","msg":"trace[1306420289] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:289; }","duration":"241.184142ms","start":"2025-09-11T00:30:44.487152Z","end":"2025-09-11T00:30:44.728336Z","steps":["trace[1306420289] 'agreement among raft nodes before linearized reading' (duration: 241.134219ms)"],"step_count":1} Sep 11 00:30:44.729098 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:30:44.728482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-11T00:30:44.395666Z","time spent":"332.675696ms","remote":"10.0.0.136:41716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":829,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} Sep 11 00:30:44.755532 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:30:44.755244Z","caller":"traceutil/trace.go:171","msg":"trace[1479933475] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"267.606333ms","start":"2025-09-11T00:30:44.487629Z","end":"2025-09-11T00:30:44.755235Z","steps":["trace[1479933475] 'process raft request' (duration: 267.566198ms)"],"step_count":1} Sep 11 00:30:44.755532 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:30:44.755287Z","caller":"traceutil/trace.go:171","msg":"trace[904784166] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"267.687115ms","start":"2025-09-11T00:30:44.487576Z","end":"2025-09-11T00:30:44.755263Z","steps":["trace[904784166] 'process raft request' (duration: 267.554886ms)"],"step_count":1} Sep 11 00:30:44.755885 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:30:44.755551Z","caller":"traceutil/trace.go:171","msg":"trace[664939401] transaction","detail":"{read_only:false; number_of_response:0; response_revision:291; }","duration":"154.781336ms","start":"2025-09-11T00:30:44.600762Z","end":"2025-09-11T00:30:44.755543Z","steps":["trace[664939401] 'process raft request' (duration: 154.678764ms)"],"step_count":1} Sep 11 00:30:44.755885 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:30:44.755604Z","caller":"traceutil/trace.go:171","msg":"trace[303250104] transaction","detail":"{read_only:false; number_of_response:0; response_revision:291; }","duration":"154.769875ms","start":"2025-09-11T00:30:44.600704Z","end":"2025-09-11T00:30:44.755473Z","steps":["trace[303250104] 'process raft request' (duration: 154.515547ms)"],"step_count":1} Sep 11 00:30:44.755885 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:30:44.755552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.498815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-localhost\" ","response":"range_response_count:1 size:4969"} Sep 11 00:30:44.755885 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:30:44.755723Z","caller":"traceutil/trace.go:171","msg":"trace[37462692] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-localhost; range_end:; response_count:1; response_revision:291; }","duration":"156.695395ms","start":"2025-09-11T00:30:44.599019Z","end":"2025-09-11T00:30:44.755715Z","steps":["trace[37462692] 'agreement among raft nodes before linearized reading' (duration: 156.494056ms)"],"step_count":1} Sep 11 00:30:49.835466 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:30:49.835105Z","caller":"traceutil/trace.go:171","msg":"trace[1001476432] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:371; }","duration":"101.493058ms","start":"2025-09-11T00:30:49.733590Z","end":"2025-09-11T00:30:49.835083Z","steps":["trace[1001476432] 'read index received' (duration: 99.488692ms)","trace[1001476432] 'applied index is now lower than readState.Index' (duration: 2.003715ms)"],"step_count":2} Sep 11 00:30:49.835466 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:30:49.835143Z","caller":"traceutil/trace.go:171","msg":"trace[496572632] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"115.279175ms","start":"2025-09-11T00:30:49.719842Z","end":"2025-09-11T00:30:49.835121Z","steps":["trace[496572632] 'process raft request' (duration: 113.229543ms)"],"step_count":1} Sep 11 00:30:49.835466 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:30:49.835226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.621459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/legacy-service-account-token-cleaner\" ","response":"range_response_count:1 size:238"} Sep 11 00:30:49.835466 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:30:49.835257Z","caller":"traceutil/trace.go:171","msg":"trace[1045723908] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/legacy-service-account-token-cleaner; range_end:; response_count:1; response_revision:362; }","duration":"101.690318ms","start":"2025-09-11T00:30:49.733554Z","end":"2025-09-11T00:30:49.835244Z","steps":["trace[1045723908] 'agreement among raft nodes before linearized reading' (duration: 101.624484ms)"],"step_count":1} Sep 11 00:31:04.003889 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:31:04.003467Z","caller":"traceutil/trace.go:171","msg":"trace[1075299178] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"344.640633ms","start":"2025-09-11T00:31:03.658800Z","end":"2025-09-11T00:31:04.003441Z","steps":["trace[1075299178] 'process raft request' (duration: 300.484167ms)","trace[1075299178] 'compare' (duration: 44.088539ms)"],"step_count":2} Sep 11 00:31:04.003889 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:31:04.003703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-11T00:31:03.658790Z","time spent":"344.730301ms","remote":"10.0.0.136:41854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17546,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Sep 11 00:31:04.045437 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:31:04.045192Z","caller":"traceutil/trace.go:171","msg":"trace[1372759879] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"217.19598ms","start":"2025-09-11T00:31:03.827975Z","end":"2025-09-11T00:31:04.045171Z","steps":["trace[1372759879] 'process raft request' (duration: 217.10012ms)"],"step_count":1} Sep 11 00:31:04.893358 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:31:04.893098Z","caller":"traceutil/trace.go:171","msg":"trace[930265655] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"193.288665ms","start":"2025-09-11T00:31:04.699790Z","end":"2025-09-11T00:31:04.893079Z","steps":["trace[930265655] 'process raft request' (duration: 133.66946ms)","trace[930265655] 'compare' (duration: 59.529798ms)"],"step_count":2} Sep 11 00:31:04.923656 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:31:04.923436Z","caller":"traceutil/trace.go:171","msg":"trace[1747869111] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"206.274561ms","start":"2025-09-11T00:31:04.717150Z","end":"2025-09-11T00:31:04.923425Z","steps":["trace[1747869111] 'process raft request' (duration: 206.225108ms)"],"step_count":1} Sep 11 00:31:06.177666 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:31:06.177321Z","caller":"traceutil/trace.go:171","msg":"trace[262869689] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"246.020853ms","start":"2025-09-11T00:31:05.931285Z","end":"2025-09-11T00:31:06.177306Z","steps":["trace[262869689] 'process raft request' (duration: 245.98725ms)"],"step_count":1} Sep 11 00:31:06.177666 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:31:06.177361Z","caller":"traceutil/trace.go:171","msg":"trace[100104434] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"246.791349ms","start":"2025-09-11T00:31:05.930558Z","end":"2025-09-11T00:31:06.177350Z","steps":["trace[100104434] 'process raft request' (duration: 246.694848ms)"],"step_count":1} Sep 11 00:31:06.177666 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:31:06.177406Z","caller":"traceutil/trace.go:171","msg":"trace[232635485] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"248.281535ms","start":"2025-09-11T00:31:05.929104Z","end":"2025-09-11T00:31:06.177386Z","steps":["trace[232635485] 'process raft request' (duration: 219.3373ms)","trace[232635485] 'compare' (duration: 28.730444ms)"],"step_count":2} Sep 11 00:31:06.902691 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:31:06.902244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.42182ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Sep 11 00:31:06.902691 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:31:06.902372Z","caller":"traceutil/trace.go:171","msg":"trace[535589415] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"887.396551ms","start":"2025-09-11T00:31:06.014953Z","end":"2025-09-11T00:31:06.902350Z","steps":["trace[535589415] 'process raft request' (duration: 608.831983ms)","trace[535589415] 'compare' (duration: 278.335157ms)"],"step_count":2} Sep 11 00:31:06.902691 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:31:06.902497Z","caller":"traceutil/trace.go:171","msg":"trace[2127746502] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"825.784717ms","start":"2025-09-11T00:31:06.076704Z","end":"2025-09-11T00:31:06.902489Z","steps":["trace[2127746502] 'process raft request' (duration: 825.699718ms)"],"step_count":1} Sep 11 00:31:06.902691 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:31:06.902484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-11T00:31:06.014927Z","time spent":"887.529561ms","remote":"10.0.0.136:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":932,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} Sep 11 00:31:06.903045 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:31:06.902511Z","caller":"traceutil/trace.go:171","msg":"trace[453630697] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"886.844346ms","start":"2025-09-11T00:31:06.015655Z","end":"2025-09-11T00:31:06.902499Z","steps":["trace[453630697] 'process raft request' (duration: 886.653087ms)"],"step_count":1} Sep 11 00:31:06.903045 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:31:06.902541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-11T00:31:06.076676Z","time spent":"825.844168ms","remote":"10.0.0.136:41692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":176922,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} Sep 11 00:31:06.903045 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:31:06.902587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-11T00:31:06.015638Z","time spent":"886.908055ms","remote":"10.0.0.136:41692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4649,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Sep 11 00:31:06.906637 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:31:06.906382Z","caller":"traceutil/trace.go:171","msg":"trace[874472999] linearizableReadLoop","detail":"{readStateIndex:518; appliedIndex:514; }","duration":"390.1716ms","start":"2025-09-11T00:31:06.516199Z","end":"2025-09-11T00:31:06.906370Z","steps":["trace[874472999] 'read index received' (duration: 107.317084ms)","trace[874472999] 'applied index is now lower than readState.Index' (duration: 282.853775ms)"],"step_count":2} Sep 11 00:31:06.906637 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:31:06.906410Z","caller":"traceutil/trace.go:171","msg":"trace[1097280524] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"620.429767ms","start":"2025-09-11T00:31:06.285971Z","end":"2025-09-11T00:31:06.906401Z","steps":["trace[1097280524] 'process raft request' (duration: 620.326243ms)"],"step_count":1} Sep 11 00:31:06.907132 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:31:06.906491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.285152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4664"} Sep 11 00:31:06.907132 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:31:06.906618Z","caller":"traceutil/trace.go:171","msg":"trace[2097405924] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:504; }","duration":"390.440185ms","start":"2025-09-11T00:31:06.516169Z","end":"2025-09-11T00:31:06.906609Z","steps":["trace[2097405924] 'agreement among raft nodes before linearized reading' (duration: 390.260567ms)"],"step_count":1} Sep 11 00:31:06.907132 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:31:06.906640Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-11T00:31:06.516149Z","time spent":"390.484818ms","remote":"10.0.0.136:41692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4688,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} Sep 11 00:31:06.907132 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:31:06.906580Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-11T00:31:06.285949Z","time spent":"620.600469ms","remote":"10.0.0.136:41692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":177131,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} Sep 11 00:31:06.913177 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:31:06.912739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.579104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:4595"} Sep 11 00:31:06.913360 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:31:06.912986Z","caller":"traceutil/trace.go:171","msg":"trace[2112445883] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:505; }","duration":"231.83809ms","start":"2025-09-11T00:31:06.681115Z","end":"2025-09-11T00:31:06.912953Z","steps":["trace[2112445883] 'agreement among raft nodes before linearized reading' (duration: 231.563124ms)"],"step_count":1} Sep 11 00:31:06.913360 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:31:06.912803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.359206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-operator-5d85765b45-xxbwk.18641301434ba50e\" ","response":"range_response_count:1 size:848"} Sep 11 00:31:06.913360 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:31:06.913162Z","caller":"traceutil/trace.go:171","msg":"trace[16791820] range","detail":"{range_begin:/registry/events/kube-system/cilium-operator-5d85765b45-xxbwk.18641301434ba50e; range_end:; response_count:1; response_revision:505; }","duration":"214.76164ms","start":"2025-09-11T00:31:06.698388Z","end":"2025-09-11T00:31:06.913150Z","steps":["trace[16791820] 'agreement among raft nodes before linearized reading' (duration: 214.343937ms)"],"step_count":1} Sep 11 00:31:06.913556 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:31:06.912847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.1341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-5d85765b45-xxbwk\" ","response":"range_response_count:1 size:4653"} Sep 11 00:31:06.913556 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:31:06.913224Z","caller":"traceutil/trace.go:171","msg":"trace[1785139148] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-5d85765b45-xxbwk; range_end:; response_count:1; response_revision:505; }","duration":"215.531306ms","start":"2025-09-11T00:31:06.697685Z","end":"2025-09-11T00:31:06.913216Z","steps":["trace[1785139148] 'agreement among raft nodes before linearized reading' (duration: 215.13952ms)"],"step_count":1} Sep 11 00:31:07.965105 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:31:07.964657Z","caller":"traceutil/trace.go:171","msg":"trace[704266918] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"104.160746ms","start":"2025-09-11T00:31:07.860481Z","end":"2025-09-11T00:31:07.964641Z","steps":["trace[704266918] 'process raft request' (duration: 104.073142ms)"],"step_count":1} Sep 11 00:31:26.133555 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:31:26.133165Z","caller":"traceutil/trace.go:171","msg":"trace[417398507] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"125.314483ms","start":"2025-09-11T00:31:26.007832Z","end":"2025-09-11T00:31:26.133146Z","steps":["trace[417398507] 'process raft request' (duration: 125.18766ms)"],"step_count":1} Sep 11 00:31:26.235760 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:31:26.235427Z","caller":"traceutil/trace.go:171","msg":"trace[82247559] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"220.164367ms","start":"2025-09-11T00:31:26.015244Z","end":"2025-09-11T00:31:26.235409Z","steps":["trace[82247559] 'process raft request' (duration: 217.084262ms)"],"step_count":1} Sep 11 00:31:27.193640 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:31:27.193308Z","caller":"traceutil/trace.go:171","msg":"trace[1783771597] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"175.010708ms","start":"2025-09-11T00:31:27.018280Z","end":"2025-09-11T00:31:27.193291Z","steps":["trace[1783771597] 'process raft request' (duration: 174.877013ms)"],"step_count":1} Sep 11 00:31:27.195439 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:31:27.195165Z","caller":"traceutil/trace.go:171","msg":"trace[1588578818] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"133.714032ms","start":"2025-09-11T00:31:27.061415Z","end":"2025-09-11T00:31:27.195129Z","steps":["trace[1588578818] 'process raft request' (duration: 133.621936ms)"],"step_count":1} Sep 11 00:31:31.604113 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:31:31.603649Z","caller":"traceutil/trace.go:171","msg":"trace[836058514] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"106.131339ms","start":"2025-09-11T00:31:31.497498Z","end":"2025-09-11T00:31:31.603629Z","steps":["trace[836058514] 'process raft request' (duration: 106.015758ms)"],"step_count":1} Sep 11 00:31:32.164171 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:31:32.163813Z","caller":"traceutil/trace.go:171","msg":"trace[1960075099] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"123.742941ms","start":"2025-09-11T00:31:32.040050Z","end":"2025-09-11T00:31:32.163793Z","steps":["trace[1960075099] 'process raft request' (duration: 67.591139ms)","trace[1960075099] 'compare' (duration: 56.04627ms)"],"step_count":2} Sep 11 00:31:32.183583 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:31:32.181005Z","caller":"traceutil/trace.go:171","msg":"trace[1529421187] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"138.074928ms","start":"2025-09-11T00:31:32.042914Z","end":"2025-09-11T00:31:32.180989Z","steps":["trace[1529421187] 'process raft request' (duration: 137.970839ms)"],"step_count":1} Sep 11 00:31:35.774612 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:31:35.774269Z","caller":"traceutil/trace.go:171","msg":"trace[765919413] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"161.559874ms","start":"2025-09-11T00:31:35.612692Z","end":"2025-09-11T00:31:35.774251Z","steps":["trace[765919413] 'process raft request' (duration: 146.155569ms)","trace[765919413] 'compare' (duration: 15.30196ms)"],"step_count":2} Sep 11 00:31:36.341723 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:31:36.341329Z","caller":"traceutil/trace.go:171","msg":"trace[1808272108] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"141.799012ms","start":"2025-09-11T00:31:36.199514Z","end":"2025-09-11T00:31:36.341313Z","steps":["trace[1808272108] 'process raft request' (duration: 141.698932ms)"],"step_count":1} Sep 11 00:31:36.985504 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:31:36.985102Z","caller":"traceutil/trace.go:171","msg":"trace[1777724607] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"183.602663ms","start":"2025-09-11T00:31:36.801485Z","end":"2025-09-11T00:31:36.985088Z","steps":["trace[1777724607] 'process raft request' (duration: 183.562246ms)"],"step_count":1} Sep 11 00:31:36.985504 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:31:36.985145Z","caller":"traceutil/trace.go:171","msg":"trace[1576227385] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"186.239085ms","start":"2025-09-11T00:31:36.798876Z","end":"2025-09-11T00:31:36.985115Z","steps":["trace[1576227385] 'process raft request' (duration: 90.492597ms)","trace[1576227385] 'compare' (duration: 95.574711ms)"],"step_count":2} Sep 11 00:31:43.122875 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:31:43.122493Z","caller":"traceutil/trace.go:171","msg":"trace[1837610987] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"146.989703ms","start":"2025-09-11T00:31:42.975483Z","end":"2025-09-11T00:31:43.122473Z","steps":["trace[1837610987] 'process raft request' (duration: 146.894803ms)"],"step_count":1} Sep 11 00:31:43.776064 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:31:43.775652Z","caller":"traceutil/trace.go:171","msg":"trace[831313037] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"111.075713ms","start":"2025-09-11T00:31:43.664558Z","end":"2025-09-11T00:31:43.775634Z","steps":["trace[831313037] 'process raft request' (duration: 110.971296ms)"],"step_count":1} Sep 11 00:31:53.706670 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:31:53.706269Z","caller":"traceutil/trace.go:171","msg":"trace[1941783393] linearizableReadLoop","detail":"{readStateIndex:831; appliedIndex:829; }","duration":"158.726243ms","start":"2025-09-11T00:31:53.547529Z","end":"2025-09-11T00:31:53.706255Z","steps":["trace[1941783393] 'read index received' (duration: 69.123171ms)","trace[1941783393] 'applied index is now lower than readState.Index' (duration: 89.602692ms)"],"step_count":2} Sep 11 00:31:53.706670 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:31:53.706295Z","caller":"traceutil/trace.go:171","msg":"trace[1056842237] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"180.497491ms","start":"2025-09-11T00:31:53.525781Z","end":"2025-09-11T00:31:53.706279Z","steps":["trace[1056842237] 'process raft request' (duration: 90.865194ms)","trace[1056842237] 'compare' (duration: 89.447297ms)"],"step_count":2} Sep 11 00:31:53.706670 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:31:53.706304Z","caller":"traceutil/trace.go:171","msg":"trace[2120842035] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"161.396905ms","start":"2025-09-11T00:31:53.544886Z","end":"2025-09-11T00:31:53.706283Z","steps":["trace[2120842035] 'process raft request' (duration: 161.299341ms)"],"step_count":1} Sep 11 00:31:53.706670 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:31:53.706349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.813498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} Sep 11 00:31:53.707183 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:31:53.706369Z","caller":"traceutil/trace.go:171","msg":"trace[1998975233] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:805; }","duration":"158.870968ms","start":"2025-09-11T00:31:53.547491Z","end":"2025-09-11T00:31:53.706362Z","steps":["trace[1998975233] 'agreement among raft nodes before linearized reading' (duration: 158.823869ms)"],"step_count":1} Sep 11 00:31:55.419990 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:31:55.419604Z","caller":"traceutil/trace.go:171","msg":"trace[1689533685] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"113.024721ms","start":"2025-09-11T00:31:55.306565Z","end":"2025-09-11T00:31:55.419590Z","steps":["trace[1689533685] 'process raft request' (duration: 99.895942ms)","trace[1689533685] 'compare' (duration: 13.02915ms)"],"step_count":2} Sep 11 00:32:02.092970 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:32:02.092618Z","caller":"traceutil/trace.go:171","msg":"trace[1937493493] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"122.856869ms","start":"2025-09-11T00:32:01.969745Z","end":"2025-09-11T00:32:02.092602Z","steps":["trace[1937493493] 'process raft request' (duration: 122.750128ms)"],"step_count":1} Sep 11 00:32:04.015304 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:32:04.014927Z","caller":"traceutil/trace.go:171","msg":"trace[1769583649] linearizableReadLoop","detail":"{readStateIndex:866; appliedIndex:864; }","duration":"100.921981ms","start":"2025-09-11T00:32:03.913989Z","end":"2025-09-11T00:32:04.014911Z","steps":["trace[1769583649] 'read index received' (duration: 39.082377ms)","trace[1769583649] 'applied index is now lower than readState.Index' (duration: 61.838833ms)"],"step_count":2} Sep 11 00:32:04.015304 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:32:04.014972Z","caller":"traceutil/trace.go:171","msg":"trace[1116280592] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"168.417076ms","start":"2025-09-11T00:32:03.846539Z","end":"2025-09-11T00:32:04.014956Z","steps":["trace[1116280592] 'process raft request' (duration: 106.571851ms)","trace[1116280592] 'compare' (duration: 61.702184ms)"],"step_count":2} Sep 11 00:32:04.015304 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:32:04.014998Z","caller":"traceutil/trace.go:171","msg":"trace[275922795] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"115.944855ms","start":"2025-09-11T00:32:03.899040Z","end":"2025-09-11T00:32:04.014985Z","steps":["trace[275922795] 'process raft request' (duration: 115.846508ms)"],"step_count":1} Sep 11 00:32:04.015304 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:32:04.015042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.042408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pod-1\" ","response":"range_response_count:1 size:2339"} Sep 11 00:32:04.015949 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:32:04.015064Z","caller":"traceutil/trace.go:171","msg":"trace[371785160] range","detail":"{range_begin:/registry/pods/default/test-pod-1; range_end:; response_count:1; response_revision:838; }","duration":"101.091031ms","start":"2025-09-11T00:32:03.913968Z","end":"2025-09-11T00:32:04.015059Z","steps":["trace[371785160] 'agreement among raft nodes before linearized reading' (duration: 101.003185ms)"],"step_count":1} Sep 11 00:32:05.715553 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:32:05.715237Z","caller":"traceutil/trace.go:171","msg":"trace[465229669] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"139.796433ms","start":"2025-09-11T00:32:05.575426Z","end":"2025-09-11T00:32:05.715222Z","steps":["trace[465229669] 'process raft request' (duration: 139.702946ms)"],"step_count":1} Sep 11 00:32:06.047343 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:32:06.046751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.925783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} Sep 11 00:32:06.047343 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:32:06.046827Z","caller":"traceutil/trace.go:171","msg":"trace[781386300] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:843; }","duration":"250.05748ms","start":"2025-09-11T00:32:05.796758Z","end":"2025-09-11T00:32:06.046815Z","steps":["trace[781386300] 'count revisions from in-memory index tree' (duration: 249.833888ms)"],"step_count":1} Sep 11 00:32:06.047343 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:32:06.046949Z","caller":"traceutil/trace.go:171","msg":"trace[1593746410] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"238.501673ms","start":"2025-09-11T00:32:05.808441Z","end":"2025-09-11T00:32:06.046943Z","steps":["trace[1593746410] 'process raft request' (duration: 185.193004ms)","trace[1593746410] 'compare' (duration: 53.123398ms)"],"step_count":2} Sep 11 00:32:11.373499 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:32:11.373151Z","caller":"traceutil/trace.go:171","msg":"trace[142905397] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"150.473718ms","start":"2025-09-11T00:32:11.222657Z","end":"2025-09-11T00:32:11.373130Z","steps":["trace[142905397] 'process raft request' (duration: 147.53716ms)"],"step_count":1} Sep 11 00:32:12.004716 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:32:12.004320Z","caller":"traceutil/trace.go:171","msg":"trace[1119225629] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"110.665773ms","start":"2025-09-11T00:32:11.893632Z","end":"2025-09-11T00:32:12.004297Z","steps":["trace[1119225629] 'process raft request' (duration: 110.541248ms)"],"step_count":1} Sep 11 00:32:16.442436 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:32:16.441895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.530568ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Sep 11 00:32:16.442436 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:32:16.441994Z","caller":"traceutil/trace.go:171","msg":"trace[1793939958] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:1001; }","duration":"312.120331ms","start":"2025-09-11T00:32:16.129864Z","end":"2025-09-11T00:32:16.441984Z","steps":["trace[1793939958] 'read index received' (duration: 82.403331ms)","trace[1793939958] 'applied index is now lower than readState.Index' (duration: 229.716459ms)"],"step_count":2} Sep 11 00:32:16.442436 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:32:16.442088Z","caller":"traceutil/trace.go:171","msg":"trace[97320809] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"359.957945ms","start":"2025-09-11T00:32:16.082097Z","end":"2025-09-11T00:32:16.442055Z","steps":["trace[97320809] 'process raft request' (duration: 130.220576ms)","trace[97320809] 'compare' (duration: 229.42529ms)"],"step_count":2} Sep 11 00:32:16.442436 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:32:16.442248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.390982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zd5rb\" ","response":"range_response_count:1 size:17540"} Sep 11 00:32:16.442999 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:32:16.442256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-11T00:32:16.082078Z","time spent":"360.099593ms","remote":"10.0.0.136:41936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Sep 11 00:32:16.442999 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:32:16.442578Z","caller":"traceutil/trace.go:171","msg":"trace[1438670906] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zd5rb; range_end:; response_count:1; response_revision:970; }","duration":"312.752204ms","start":"2025-09-11T00:32:16.129816Z","end":"2025-09-11T00:32:16.442569Z","steps":["trace[1438670906] 'agreement among raft nodes before linearized reading' (duration: 312.223305ms)"],"step_count":1} Sep 11 00:32:16.442999 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:32:16.442613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-11T00:32:16.129798Z","time spent":"312.806075ms","remote":"10.0.0.136:41854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17564,"request content":"key:\"/registry/pods/kube-system/cilium-zd5rb\" "} Sep 11 00:32:16.442999 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:32:16.442128Z","caller":"traceutil/trace.go:171","msg":"trace[1543860715] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"190.851784ms","start":"2025-09-11T00:32:16.251269Z","end":"2025-09-11T00:32:16.442121Z","steps":["trace[1543860715] 'process raft request' (duration: 190.693275ms)"],"step_count":1} Sep 11 00:32:16.443113 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:32:16.442293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.442359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-zd5rb.18641314b0209dd9\" ","response":"range_response_count:1 size:790"} Sep 11 00:32:16.443113 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:32:16.442709Z","caller":"traceutil/trace.go:171","msg":"trace[1032495260] range","detail":"{range_begin:/registry/events/kube-system/cilium-zd5rb.18641314b0209dd9; range_end:; response_count:1; response_revision:970; }","duration":"312.883913ms","start":"2025-09-11T00:32:16.129816Z","end":"2025-09-11T00:32:16.442700Z","steps":["trace[1032495260] 'agreement among raft nodes before linearized reading' (duration: 312.220079ms)"],"step_count":1} Sep 11 00:32:16.443164 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:32:16.442741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-11T00:32:16.129798Z","time spent":"312.931502ms","remote":"10.0.0.136:41716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":814,"request content":"key:\"/registry/events/kube-system/cilium-zd5rb.18641314b0209dd9\" "} Sep 11 00:32:16.492502 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:32:16.492110Z","caller":"traceutil/trace.go:171","msg":"trace[1614549784] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"131.258918ms","start":"2025-09-11T00:32:16.360832Z","end":"2025-09-11T00:32:16.492091Z","steps":["trace[1614549784] 'process raft request' (duration: 131.156214ms)"],"step_count":1} Sep 11 00:32:16.492502 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:32:16.492120Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.819483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} Sep 11 00:32:16.492502 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:32:16.492255Z","caller":"traceutil/trace.go:171","msg":"trace[1500175756] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:971; }","duration":"283.988462ms","start":"2025-09-11T00:32:16.208257Z","end":"2025-09-11T00:32:16.492246Z","steps":["trace[1500175756] 'agreement among raft nodes before linearized reading' (duration: 283.817379ms)"],"step_count":1} Sep 11 00:32:16.492502 etcd-wrapper[1841]: {"level":"warn","ts":"2025-09-11T00:32:16.492159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.73108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods\" limit:1 ","response":"range_response_count:0 size:5"} Sep 11 00:32:16.492502 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:32:16.492338Z","caller":"traceutil/trace.go:171","msg":"trace[102234940] range","detail":"{range_begin:/registry/pods; range_end:; response_count:0; response_revision:971; }","duration":"227.919076ms","start":"2025-09-11T00:32:16.264413Z","end":"2025-09-11T00:32:16.492332Z","steps":["trace[102234940] 'agreement among raft nodes before linearized reading' (duration: 227.73073ms)"],"step_count":1} Sep 11 00:32:19.116178 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:32:19.115824Z","caller":"traceutil/trace.go:171","msg":"trace[1681643853] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"136.211944ms","start":"2025-09-11T00:32:18.979594Z","end":"2025-09-11T00:32:19.115806Z","steps":["trace[1681643853] 'process raft request' (duration: 62.680847ms)","trace[1681643853] 'compare' (duration: 73.439174ms)"],"step_count":2} Sep 11 00:32:22.364470 etcd-wrapper[1841]: {"level":"info","ts":"2025-09-11T00:32:22.364172Z","caller":"traceutil/trace.go:171","msg":"trace[595160397] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"133.859443ms","start":"2025-09-11T00:32:22.230296Z","end":"2025-09-11T00:32:22.364155Z","steps":["trace[595160397] 'process raft request' (duration: 131.53117ms)"],"step_count":1}