Sep 5 00:38:11.211804 kernel: Linux version 6.6.156-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p17) 13.3.1 20240614, GNU ld (Gentoo 2.42 p3) 2.42.0) #1 SMP PREEMPT_DYNAMIC Fri Sep 4 22:21:59 -00 2026 Sep 5 00:38:11.211871 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=9215c603110941002c0250d1c5b0629b89b2cc5fa29e7b45d5a5566f7e6b6ee1 Sep 5 00:38:11.211885 kernel: BIOS-provided physical RAM map: Sep 5 00:38:11.211892 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Sep 5 00:38:11.211917 kernel: BIOS-e820: [mem 0x0000000000100000-0x00000000007fffff] usable Sep 5 00:38:11.211924 kernel: BIOS-e820: [mem 0x0000000000800000-0x0000000000807fff] ACPI NVS Sep 5 00:38:11.211932 kernel: BIOS-e820: [mem 0x0000000000808000-0x000000000080afff] usable Sep 5 00:38:11.211938 kernel: BIOS-e820: [mem 0x000000000080b000-0x000000000080bfff] ACPI NVS Sep 5 00:38:11.211945 kernel: BIOS-e820: [mem 0x000000000080c000-0x000000000080ffff] usable Sep 5 00:38:11.211953 kernel: BIOS-e820: [mem 0x0000000000810000-0x00000000008fffff] ACPI NVS Sep 5 00:38:11.211961 kernel: BIOS-e820: [mem 0x0000000000900000-0x000000009c8eefff] usable Sep 5 00:38:11.211971 kernel: BIOS-e820: [mem 0x000000009c8ef000-0x000000009c9eefff] reserved Sep 5 00:38:11.211988 kernel: BIOS-e820: [mem 0x000000009c9ef000-0x000000009caeefff] type 20 Sep 5 00:38:11.211997 kernel: BIOS-e820: [mem 0x000000009caef000-0x000000009cb6efff] reserved Sep 5 00:38:11.212007 kernel: BIOS-e820: [mem 0x000000009cb6f000-0x000000009cb7efff] ACPI data Sep 5 00:38:11.212015 kernel: BIOS-e820: [mem 0x000000009cb7f000-0x000000009cbfefff] ACPI NVS Sep 5 00:38:11.212026 kernel: BIOS-e820: [mem 0x000000009cbff000-0x000000009cf3ffff] usable Sep 5 00:38:11.212034 kernel: BIOS-e820: [mem 0x000000009cf40000-0x000000009cf5ffff] reserved Sep 5 00:38:11.212041 kernel: BIOS-e820: [mem 0x000000009cf60000-0x000000009cffffff] ACPI NVS Sep 5 00:38:11.212047 kernel: BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved Sep 5 00:38:11.212055 kernel: NX (Execute Disable) protection: active Sep 5 00:38:11.212063 kernel: APIC: Static calls initialized Sep 5 00:38:11.212072 kernel: efi: EFI v2.7 by EDK II Sep 5 00:38:11.212080 kernel: efi: SMBIOS=0x9c9ab000 ACPI=0x9cb7e000 ACPI 2.0=0x9cb7e014 MEMATTR=0x9b675198 Sep 5 00:38:11.212089 kernel: SMBIOS 2.8 present. Sep 5 00:38:11.212098 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 0.0.0 02/06/2015 Sep 5 00:38:11.212106 kernel: Hypervisor detected: KVM Sep 5 00:38:11.212114 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Sep 5 00:38:11.212125 kernel: kvm-clock: using sched offset of 21355303423 cycles Sep 5 00:38:11.212134 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Sep 5 00:38:11.212142 kernel: tsc: Detected 2793.438 MHz processor Sep 5 00:38:11.212150 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Sep 5 00:38:11.212158 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Sep 5 00:38:11.212166 kernel: last_pfn = 0x9cf40 max_arch_pfn = 0x10000000000 Sep 5 00:38:11.212174 kernel: MTRR map: 4 entries (2 fixed + 2 variable; max 18), built from 8 variable MTRRs Sep 5 00:38:11.212183 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Sep 5 00:38:11.212195 kernel: Using GB pages for direct mapping Sep 5 00:38:11.212204 kernel: Secure boot disabled Sep 5 00:38:11.212213 kernel: ACPI: Early table checksum verification disabled Sep 5 00:38:11.212223 kernel: ACPI: RSDP 0x000000009CB7E014 000024 (v02 BOCHS ) Sep 5 00:38:11.212236 kernel: ACPI: XSDT 0x000000009CB7D0E8 000054 (v01 BOCHS BXPC 00000001 01000013) Sep 5 00:38:11.212275 kernel: ACPI: FACP 0x000000009CB79000 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) Sep 5 00:38:11.212286 kernel: ACPI: DSDT 0x000000009CB7A000 0021BA (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 5 00:38:11.212299 kernel: ACPI: FACS 0x000000009CBDD000 000040 Sep 5 00:38:11.212320 kernel: ACPI: APIC 0x000000009CB78000 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 5 00:38:11.212331 kernel: ACPI: HPET 0x000000009CB77000 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 5 00:38:11.212341 kernel: ACPI: MCFG 0x000000009CB76000 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 5 00:38:11.212351 kernel: ACPI: WAET 0x000000009CB75000 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 5 00:38:11.212361 kernel: ACPI: BGRT 0x000000009CB74000 000038 (v01 INTEL EDK2 00000002 01000013) Sep 5 00:38:11.212371 kernel: ACPI: Reserving FACP table memory at [mem 0x9cb79000-0x9cb790f3] Sep 5 00:38:11.212383 kernel: ACPI: Reserving DSDT table memory at [mem 0x9cb7a000-0x9cb7c1b9] Sep 5 00:38:11.212393 kernel: ACPI: Reserving FACS table memory at [mem 0x9cbdd000-0x9cbdd03f] Sep 5 00:38:11.212403 kernel: ACPI: Reserving APIC table memory at [mem 0x9cb78000-0x9cb7808f] Sep 5 00:38:11.212412 kernel: ACPI: Reserving HPET table memory at [mem 0x9cb77000-0x9cb77037] Sep 5 00:38:11.212422 kernel: ACPI: Reserving MCFG table memory at [mem 0x9cb76000-0x9cb7603b] Sep 5 00:38:11.212432 kernel: ACPI: Reserving WAET table memory at [mem 0x9cb75000-0x9cb75027] Sep 5 00:38:11.212441 kernel: ACPI: Reserving BGRT table memory at [mem 0x9cb74000-0x9cb74037] Sep 5 00:38:11.212451 kernel: No NUMA configuration found Sep 5 00:38:11.212470 kernel: Faking a node at [mem 0x0000000000000000-0x000000009cf3ffff] Sep 5 00:38:11.212481 kernel: NODE_DATA(0) allocated [mem 0x9cea6000-0x9ceabfff] Sep 5 00:38:11.212494 kernel: Zone ranges: Sep 5 00:38:11.212504 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Sep 5 00:38:11.212513 kernel: DMA32 [mem 0x0000000001000000-0x000000009cf3ffff] Sep 5 00:38:11.212523 kernel: Normal empty Sep 5 00:38:11.212533 kernel: Movable zone start for each node Sep 5 00:38:11.212543 kernel: Early memory node ranges Sep 5 00:38:11.212553 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Sep 5 00:38:11.212562 kernel: node 0: [mem 0x0000000000100000-0x00000000007fffff] Sep 5 00:38:11.212572 kernel: node 0: [mem 0x0000000000808000-0x000000000080afff] Sep 5 00:38:11.212585 kernel: node 0: [mem 0x000000000080c000-0x000000000080ffff] Sep 5 00:38:11.212594 kernel: node 0: [mem 0x0000000000900000-0x000000009c8eefff] Sep 5 00:38:11.212604 kernel: node 0: [mem 0x000000009cbff000-0x000000009cf3ffff] Sep 5 00:38:11.212614 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009cf3ffff] Sep 5 00:38:11.212623 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 5 00:38:11.212633 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Sep 5 00:38:11.212643 kernel: On node 0, zone DMA: 8 pages in unavailable ranges Sep 5 00:38:11.212653 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 5 00:38:11.212662 kernel: On node 0, zone DMA: 240 pages in unavailable ranges Sep 5 00:38:11.212675 kernel: On node 0, zone DMA32: 784 pages in unavailable ranges Sep 5 00:38:11.212684 kernel: On node 0, zone DMA32: 12480 pages in unavailable ranges Sep 5 00:38:11.212694 kernel: ACPI: PM-Timer IO Port: 0x608 Sep 5 00:38:11.212704 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Sep 5 00:38:11.212714 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Sep 5 00:38:11.212724 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Sep 5 00:38:11.212734 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Sep 5 00:38:11.212744 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Sep 5 00:38:11.212754 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Sep 5 00:38:11.212766 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Sep 5 00:38:11.212776 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Sep 5 00:38:11.212786 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Sep 5 00:38:11.212795 kernel: TSC deadline timer available Sep 5 00:38:11.212805 kernel: smpboot: Allowing 4 CPUs, 0 hotplug CPUs Sep 5 00:38:11.212815 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Sep 5 00:38:11.212824 kernel: kvm-guest: KVM setup pv remote TLB flush Sep 5 00:38:11.212834 kernel: kvm-guest: setup PV sched yield Sep 5 00:38:11.212844 kernel: [mem 0xc0000000-0xffffffff] available for PCI devices Sep 5 00:38:11.212856 kernel: Booting paravirtualized kernel on KVM Sep 5 00:38:11.212866 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Sep 5 00:38:11.212876 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 Sep 5 00:38:11.212886 kernel: percpu: Embedded 57 pages/cpu s196392 r8192 d28888 u524288 Sep 5 00:38:11.214578 kernel: pcpu-alloc: s196392 r8192 d28888 u524288 alloc=1*2097152 Sep 5 00:38:11.214702 kernel: pcpu-alloc: [0] 0 1 2 3 Sep 5 00:38:11.214711 kernel: kvm-guest: PV spinlocks enabled Sep 5 00:38:11.214720 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Sep 5 00:38:11.214730 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=9215c603110941002c0250d1c5b0629b89b2cc5fa29e7b45d5a5566f7e6b6ee1 Sep 5 00:38:11.214762 kernel: random: crng init done Sep 5 00:38:11.214770 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 5 00:38:11.214778 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 5 00:38:11.214787 kernel: Fallback order for Node 0: 0 Sep 5 00:38:11.214795 kernel: Built 1 zonelists, mobility grouping on. Total pages: 629759 Sep 5 00:38:11.214804 kernel: Policy zone: DMA32 Sep 5 00:38:11.214812 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 5 00:38:11.214821 kernel: Memory: 2397612K/2567000K available (14336K kernel code, 2288K rwdata, 22772K rodata, 42920K init, 2236K bss, 169184K reserved, 0K cma-reserved) Sep 5 00:38:11.214835 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Sep 5 00:38:11.214844 kernel: ftrace: allocating 37502 entries in 148 pages Sep 5 00:38:11.214853 kernel: ftrace: allocated 148 pages with 3 groups Sep 5 00:38:11.214861 kernel: Dynamic Preempt: voluntary Sep 5 00:38:11.214872 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 5 00:38:11.214892 kernel: rcu: RCU event tracing is enabled. Sep 5 00:38:11.214922 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Sep 5 00:38:11.214930 kernel: Trampoline variant of Tasks RCU enabled. Sep 5 00:38:11.214938 kernel: Rude variant of Tasks RCU enabled. Sep 5 00:38:11.214946 kernel: Tracing variant of Tasks RCU enabled. Sep 5 00:38:11.214954 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 5 00:38:11.214962 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Sep 5 00:38:11.214972 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 Sep 5 00:38:11.214980 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 5 00:38:11.214988 kernel: Console: colour dummy device 80x25 Sep 5 00:38:11.214996 kernel: printk: console [ttyS0] enabled Sep 5 00:38:11.215004 kernel: ACPI: Core revision 20230628 Sep 5 00:38:11.215015 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Sep 5 00:38:11.215023 kernel: APIC: Switch to symmetric I/O mode setup Sep 5 00:38:11.215030 kernel: x2apic enabled Sep 5 00:38:11.215038 kernel: APIC: Switched APIC routing to: physical x2apic Sep 5 00:38:11.215047 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() Sep 5 00:38:11.215055 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() Sep 5 00:38:11.215063 kernel: kvm-guest: setup PV IPIs Sep 5 00:38:11.215071 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Sep 5 00:38:11.215079 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x284409db922, max_idle_ns: 440795228871 ns Sep 5 00:38:11.215089 kernel: Calibrating delay loop (skipped) preset value.. 5586.87 BogoMIPS (lpj=2793438) Sep 5 00:38:11.215097 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Sep 5 00:38:11.215105 kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 Sep 5 00:38:11.215113 kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 Sep 5 00:38:11.215121 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Sep 5 00:38:11.215129 kernel: Spectre V2 : Mitigation: Retpolines Sep 5 00:38:11.215137 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Sep 5 00:38:11.215145 kernel: RETBleed: WARNING: Spectre v2 mitigation leaves CPU vulnerable to RETBleed attacks, data leaks possible! Sep 5 00:38:11.215153 kernel: RETBleed: Vulnerable Sep 5 00:38:11.215163 kernel: Speculative Store Bypass: Vulnerable Sep 5 00:38:11.215171 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Sep 5 00:38:11.215179 kernel: GDS: Unknown: Dependent on hypervisor status Sep 5 00:38:11.215764 kernel: active return thunk: its_return_thunk Sep 5 00:38:11.224034 kernel: ITS: Mitigation: Aligned branch/return thunks Sep 5 00:38:11.224188 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Sep 5 00:38:11.224199 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Sep 5 00:38:11.224208 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Sep 5 00:38:11.224223 kernel: x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' Sep 5 00:38:11.224279 kernel: x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' Sep 5 00:38:11.224288 kernel: x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' Sep 5 00:38:11.224304 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Sep 5 00:38:11.224313 kernel: x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64 Sep 5 00:38:11.224321 kernel: x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512 Sep 5 00:38:11.224330 kernel: x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024 Sep 5 00:38:11.224340 kernel: x86/fpu: Enabled xstate features 0xe7, context size is 2432 bytes, using 'compacted' format. Sep 5 00:38:11.224350 kernel: Freeing SMP alternatives memory: 32K Sep 5 00:38:11.224359 kernel: pid_max: default: 32768 minimum: 301 Sep 5 00:38:11.224371 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Sep 5 00:38:11.224380 kernel: landlock: Up and running. Sep 5 00:38:11.224389 kernel: SELinux: Initializing. Sep 5 00:38:11.224399 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 5 00:38:11.224408 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 5 00:38:11.224418 kernel: smpboot: CPU0: Intel(R) Xeon(R) Platinum 8370C CPU @ 2.80GHz (family: 0x6, model: 0x6a, stepping: 0x6) Sep 5 00:38:11.224428 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 5 00:38:11.224437 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 5 00:38:11.224449 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 5 00:38:11.224459 kernel: Performance Events: unsupported p6 CPU model 106 no PMU driver, software events only. Sep 5 00:38:11.224469 kernel: signal: max sigframe size: 3632 Sep 5 00:38:11.224477 kernel: rcu: Hierarchical SRCU implementation. Sep 5 00:38:11.224487 kernel: rcu: Max phase no-delay instances is 400. Sep 5 00:38:11.224496 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Sep 5 00:38:11.224505 kernel: smp: Bringing up secondary CPUs ... Sep 5 00:38:11.224514 kernel: smpboot: x86: Booting SMP configuration: Sep 5 00:38:11.224522 kernel: .... node #0, CPUs: #1 #2 #3 Sep 5 00:38:11.224533 kernel: smp: Brought up 1 node, 4 CPUs Sep 5 00:38:11.224542 kernel: smpboot: Max logical packages: 1 Sep 5 00:38:11.224551 kernel: smpboot: Total of 4 processors activated (22347.50 BogoMIPS) Sep 5 00:38:11.224559 kernel: devtmpfs: initialized Sep 5 00:38:11.224569 kernel: x86/mm: Memory block size: 128MB Sep 5 00:38:11.224578 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x00800000-0x00807fff] (32768 bytes) Sep 5 00:38:11.224587 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x0080b000-0x0080bfff] (4096 bytes) Sep 5 00:38:11.224596 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x00810000-0x008fffff] (983040 bytes) Sep 5 00:38:11.224604 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9cb7f000-0x9cbfefff] (524288 bytes) Sep 5 00:38:11.224613 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9cf60000-0x9cffffff] (655360 bytes) Sep 5 00:38:11.224624 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 5 00:38:11.224633 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Sep 5 00:38:11.224642 kernel: pinctrl core: initialized pinctrl subsystem Sep 5 00:38:11.224650 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 5 00:38:11.224660 kernel: audit: initializing netlink subsys (disabled) Sep 5 00:38:11.224669 kernel: audit: type=2000 audit(1788568677.509:1): state=initialized audit_enabled=0 res=1 Sep 5 00:38:11.224679 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 5 00:38:11.224688 kernel: thermal_sys: Registered thermal governor 'user_space' Sep 5 00:38:11.224700 kernel: cpuidle: using governor menu Sep 5 00:38:11.224710 kernel: efi: Freeing EFI boot services memory: 29232K Sep 5 00:38:11.224720 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 5 00:38:11.224729 kernel: dca service started, version 1.12.1 Sep 5 00:38:11.224739 kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xb0000000-0xbfffffff] (base 0xb0000000) Sep 5 00:38:11.224748 kernel: PCI: MMCONFIG at [mem 0xb0000000-0xbfffffff] reserved as E820 entry Sep 5 00:38:11.224757 kernel: PCI: Using configuration type 1 for base access Sep 5 00:38:11.224766 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Sep 5 00:38:11.224774 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 5 00:38:11.224786 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Sep 5 00:38:11.224796 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 5 00:38:11.224805 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Sep 5 00:38:11.224815 kernel: ACPI: Added _OSI(Module Device) Sep 5 00:38:11.224825 kernel: ACPI: Added _OSI(Processor Device) Sep 5 00:38:11.224835 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 5 00:38:11.224845 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 5 00:38:11.224854 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Sep 5 00:38:11.224863 kernel: ACPI: Interpreter enabled Sep 5 00:38:11.224876 kernel: ACPI: PM: (supports S0 S3 S5) Sep 5 00:38:11.224885 kernel: ACPI: Using IOAPIC for interrupt routing Sep 5 00:38:11.225966 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Sep 5 00:38:11.226000 kernel: PCI: Using E820 reservations for host bridge windows Sep 5 00:38:11.226010 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F Sep 5 00:38:11.226019 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Sep 5 00:38:11.226487 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Sep 5 00:38:11.226623 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] Sep 5 00:38:11.226730 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] Sep 5 00:38:11.226745 kernel: PCI host bridge to bus 0000:00 Sep 5 00:38:11.226862 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Sep 5 00:38:11.226975 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Sep 5 00:38:11.227063 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Sep 5 00:38:11.227153 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xafffffff window] Sep 5 00:38:11.234105 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] Sep 5 00:38:11.234673 kernel: pci_bus 0000:00: root bus resource [mem 0x800000000-0xfffffffff window] Sep 5 00:38:11.234759 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Sep 5 00:38:11.246967 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 Sep 5 00:38:11.247563 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 Sep 5 00:38:11.247684 kernel: pci 0000:00:01.0: BAR 0 [mem 0xc0000000-0xc0ffffff pref] Sep 5 00:38:11.247789 kernel: pci 0000:00:01.0: BAR 2 [mem 0xc1044000-0xc1044fff] Sep 5 00:38:11.252981 kernel: pci 0000:00:01.0: ROM [mem 0xffff0000-0xffffffff pref] Sep 5 00:38:11.311831 kernel: pci 0000:00:01.0: BAR 0: assigned to efifb Sep 5 00:38:11.313648 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Sep 5 00:38:11.320818 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 Sep 5 00:38:11.322964 kernel: pci 0000:00:02.0: BAR 0 [io 0x6100-0x611f] Sep 5 00:38:11.323076 kernel: pci 0000:00:02.0: BAR 1 [mem 0xc1043000-0xc1043fff] Sep 5 00:38:11.323172 kernel: pci 0000:00:02.0: BAR 4 [mem 0x800000000-0x800003fff 64bit pref] Sep 5 00:38:11.323367 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 Sep 5 00:38:11.323466 kernel: pci 0000:00:03.0: BAR 0 [io 0x6000-0x607f] Sep 5 00:38:11.323557 kernel: pci 0000:00:03.0: BAR 1 [mem 0xc1042000-0xc1042fff] Sep 5 00:38:11.323641 kernel: pci 0000:00:03.0: BAR 4 [mem 0x800004000-0x800007fff 64bit pref] Sep 5 00:38:11.323838 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 Sep 5 00:38:11.323958 kernel: pci 0000:00:04.0: BAR 0 [io 0x60e0-0x60ff] Sep 5 00:38:11.324069 kernel: pci 0000:00:04.0: BAR 1 [mem 0xc1041000-0xc1041fff] Sep 5 00:38:11.324174 kernel: pci 0000:00:04.0: BAR 4 [mem 0x800008000-0x80000bfff 64bit pref] Sep 5 00:38:11.324312 kernel: pci 0000:00:04.0: ROM [mem 0xfffc0000-0xffffffff pref] Sep 5 00:38:11.324462 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 Sep 5 00:38:11.324557 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO Sep 5 00:38:11.324760 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 Sep 5 00:38:11.324870 kernel: pci 0000:00:1f.2: BAR 4 [io 0x60c0-0x60df] Sep 5 00:38:11.332819 kernel: pci 0000:00:1f.2: BAR 5 [mem 0xc1040000-0xc1040fff] Sep 5 00:38:11.336638 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 Sep 5 00:38:11.350114 kernel: pci 0000:00:1f.3: BAR 4 [io 0x6080-0x60bf] Sep 5 00:38:11.361281 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Sep 5 00:38:11.361551 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Sep 5 00:38:11.361564 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Sep 5 00:38:11.361577 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Sep 5 00:38:11.361587 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 Sep 5 00:38:11.361597 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 Sep 5 00:38:11.361624 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 Sep 5 00:38:11.361690 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 Sep 5 00:38:11.361699 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 Sep 5 00:38:11.361710 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 Sep 5 00:38:11.361721 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 Sep 5 00:38:11.361731 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 Sep 5 00:38:11.361741 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 Sep 5 00:38:11.361750 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 Sep 5 00:38:11.361759 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 Sep 5 00:38:11.361772 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 Sep 5 00:38:11.361782 kernel: iommu: Default domain type: Translated Sep 5 00:38:11.361792 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Sep 5 00:38:11.361802 kernel: efivars: Registered efivars operations Sep 5 00:38:11.361812 kernel: PCI: Using ACPI for IRQ routing Sep 5 00:38:11.361821 kernel: PCI: pci_cache_line_size set to 64 bytes Sep 5 00:38:11.361831 kernel: e820: reserve RAM buffer [mem 0x0080b000-0x008fffff] Sep 5 00:38:11.361841 kernel: e820: reserve RAM buffer [mem 0x00810000-0x008fffff] Sep 5 00:38:11.361850 kernel: e820: reserve RAM buffer [mem 0x9c8ef000-0x9fffffff] Sep 5 00:38:11.361861 kernel: e820: reserve RAM buffer [mem 0x9cf40000-0x9fffffff] Sep 5 00:38:11.372144 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device Sep 5 00:38:11.386657 kernel: pci 0000:00:01.0: vgaarb: bridge control possible Sep 5 00:38:11.387749 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Sep 5 00:38:11.387880 kernel: vgaarb: loaded Sep 5 00:38:11.387891 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Sep 5 00:38:11.390215 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Sep 5 00:38:11.403142 kernel: clocksource: Switched to clocksource kvm-clock Sep 5 00:38:11.403382 kernel: VFS: Disk quotas dquot_6.6.0 Sep 5 00:38:11.403411 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 5 00:38:11.403422 kernel: pnp: PnP ACPI init Sep 5 00:38:11.408636 kernel: system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved Sep 5 00:38:11.408681 kernel: pnp: PnP ACPI: found 6 devices Sep 5 00:38:11.408692 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Sep 5 00:38:11.408701 kernel: NET: Registered PF_INET protocol family Sep 5 00:38:11.408710 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Sep 5 00:38:11.408721 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Sep 5 00:38:11.408741 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 5 00:38:11.408751 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Sep 5 00:38:11.408760 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Sep 5 00:38:11.408770 kernel: TCP: Hash tables configured (established 32768 bind 32768) Sep 5 00:38:11.408781 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 5 00:38:11.408790 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 5 00:38:11.408855 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 5 00:38:11.408865 kernel: NET: Registered PF_XDP protocol family Sep 5 00:38:11.409066 kernel: pci 0000:00:04.0: ROM [mem 0xfffc0000-0xffffffff pref]: can't claim; no compatible bridge window Sep 5 00:38:11.409186 kernel: pci 0000:00:04.0: ROM [mem 0x9d000000-0x9d03ffff pref]: assigned Sep 5 00:38:11.421025 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Sep 5 00:38:11.421433 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Sep 5 00:38:11.421541 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Sep 5 00:38:11.421632 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xafffffff window] Sep 5 00:38:11.421718 kernel: pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] Sep 5 00:38:11.421808 kernel: pci_bus 0000:00: resource 9 [mem 0x800000000-0xfffffffff window] Sep 5 00:38:11.421824 kernel: PCI: CLS 0 bytes, default 64 Sep 5 00:38:11.421849 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Sep 5 00:38:11.421861 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x284409db922, max_idle_ns: 440795228871 ns Sep 5 00:38:11.421872 kernel: Initialise system trusted keyrings Sep 5 00:38:11.421884 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Sep 5 00:38:11.423269 kernel: Key type asymmetric registered Sep 5 00:38:11.423579 kernel: Asymmetric key parser 'x509' registered Sep 5 00:38:11.423591 kernel: hrtimer: interrupt took 10766817 ns Sep 5 00:38:11.423602 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Sep 5 00:38:11.423626 kernel: io scheduler mq-deadline registered Sep 5 00:38:11.423636 kernel: io scheduler kyber registered Sep 5 00:38:11.423646 kernel: io scheduler bfq registered Sep 5 00:38:11.423655 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Sep 5 00:38:11.423666 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 Sep 5 00:38:11.423675 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 Sep 5 00:38:11.423684 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 Sep 5 00:38:11.423694 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 5 00:38:11.423703 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Sep 5 00:38:11.423713 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Sep 5 00:38:11.423726 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Sep 5 00:38:11.423736 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Sep 5 00:38:11.423746 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Sep 5 00:38:11.430226 kernel: rtc_cmos 00:04: RTC can wake from S4 Sep 5 00:38:11.430623 kernel: rtc_cmos 00:04: registered as rtc0 Sep 5 00:38:11.430718 kernel: rtc_cmos 00:04: setting system clock to 2026-09-05T00:38:07 UTC (1788568687) Sep 5 00:38:11.430803 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram Sep 5 00:38:11.430827 kernel: intel_pstate: CPU model not supported Sep 5 00:38:11.430837 kernel: efifb: probing for efifb Sep 5 00:38:11.430846 kernel: efifb: framebuffer at 0xc0000000, using 1408k, total 1408k Sep 5 00:38:11.430854 kernel: efifb: mode is 800x600x24, linelength=2400, pages=1 Sep 5 00:38:11.430864 kernel: efifb: scrolling: redraw Sep 5 00:38:11.430875 kernel: efifb: Truecolor: size=0:8:8:8, shift=0:16:8:0 Sep 5 00:38:11.430930 kernel: Console: switching to colour frame buffer device 100x37 Sep 5 00:38:11.430942 kernel: fb0: EFI VGA frame buffer device Sep 5 00:38:11.430951 kernel: pstore: Using crash dump compression: deflate Sep 5 00:38:11.430963 kernel: pstore: Registered efi_pstore as persistent store backend Sep 5 00:38:11.430973 kernel: NET: Registered PF_INET6 protocol family Sep 5 00:38:11.430983 kernel: Segment Routing with IPv6 Sep 5 00:38:11.430994 kernel: In-situ OAM (IOAM) with IPv6 Sep 5 00:38:11.431005 kernel: NET: Registered PF_PACKET protocol family Sep 5 00:38:11.431015 kernel: Key type dns_resolver registered Sep 5 00:38:11.431026 kernel: IPI shorthand broadcast: enabled Sep 5 00:38:11.431037 kernel: sched_clock: Marking stable (10036243177, 530518310)->(11951191435, -1384429948) Sep 5 00:38:11.431047 kernel: registered taskstats version 1 Sep 5 00:38:11.431056 kernel: Loading compiled-in X.509 certificates Sep 5 00:38:11.431070 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.156-flatcar: b30541ee20a7c2326c701c18741253f662a6172a' Sep 5 00:38:11.431081 kernel: Key type .fscrypt registered Sep 5 00:38:11.431092 kernel: Key type fscrypt-provisioning registered Sep 5 00:38:11.431101 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 5 00:38:11.431110 kernel: ima: Allocated hash algorithm: sha1 Sep 5 00:38:11.431120 kernel: ima: No architecture policies found Sep 5 00:38:11.431130 kernel: clk: Disabling unused clocks Sep 5 00:38:11.431142 kernel: Freeing unused kernel image (initmem) memory: 42920K Sep 5 00:38:11.431154 kernel: Write protecting the kernel read-only data: 38912k Sep 5 00:38:11.431168 kernel: Freeing unused kernel image (rodata/data gap) memory: 1804K Sep 5 00:38:11.431180 kernel: Run /init as init process Sep 5 00:38:11.431192 kernel: with arguments: Sep 5 00:38:11.431211 kernel: /init Sep 5 00:38:11.431223 kernel: with environment: Sep 5 00:38:11.431234 kernel: HOME=/ Sep 5 00:38:11.431281 kernel: TERM=linux Sep 5 00:38:11.431298 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Sep 5 00:38:11.431313 systemd[1]: Detected virtualization kvm. Sep 5 00:38:11.431326 systemd[1]: Detected architecture x86-64. Sep 5 00:38:11.431342 systemd[1]: Running in initrd. Sep 5 00:38:11.431354 systemd[1]: No hostname configured, using default hostname. Sep 5 00:38:11.431366 systemd[1]: Hostname set to . Sep 5 00:38:11.431382 systemd[1]: Initializing machine ID from VM UUID. Sep 5 00:38:11.431394 systemd[1]: Queued start job for default target initrd.target. Sep 5 00:38:11.431407 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 5 00:38:11.431420 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 5 00:38:11.431434 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 5 00:38:11.431449 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 5 00:38:11.431462 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 5 00:38:11.431477 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 5 00:38:11.431493 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 5 00:38:11.431506 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 5 00:38:11.431517 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 5 00:38:11.431529 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 5 00:38:11.431541 systemd[1]: Reached target paths.target - Path Units. Sep 5 00:38:11.431553 systemd[1]: Reached target slices.target - Slice Units. Sep 5 00:38:11.431567 systemd[1]: Reached target swap.target - Swaps. Sep 5 00:38:11.431579 systemd[1]: Reached target timers.target - Timer Units. Sep 5 00:38:11.431591 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 5 00:38:11.431603 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 5 00:38:11.431615 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 5 00:38:11.431628 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Sep 5 00:38:11.431640 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 5 00:38:11.431653 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 5 00:38:11.431665 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 5 00:38:11.431681 systemd[1]: Reached target sockets.target - Socket Units. Sep 5 00:38:11.431693 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 5 00:38:11.431706 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 5 00:38:11.431718 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 5 00:38:11.431731 systemd[1]: Starting systemd-fsck-usr.service... Sep 5 00:38:11.431744 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 5 00:38:11.431757 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 5 00:38:11.431769 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 5 00:38:11.431871 systemd-journald[195]: Collecting audit messages is disabled. Sep 5 00:38:11.436089 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 5 00:38:11.436117 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 5 00:38:11.436130 systemd[1]: Finished systemd-fsck-usr.service. Sep 5 00:38:11.436157 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 5 00:38:11.436170 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 5 00:38:11.436186 systemd-journald[195]: Journal started Sep 5 00:38:11.436219 systemd-journald[195]: Runtime Journal (/run/log/journal/63172615560e4f86aceb290308085849) is 6.0M, max 48.2M, 42.2M free. Sep 5 00:38:11.190856 systemd-modules-load[196]: Inserted module 'overlay' Sep 5 00:38:11.531382 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 5 00:38:11.570965 systemd[1]: Started systemd-journald.service - Journal Service. Sep 5 00:38:11.600064 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 5 00:38:11.642310 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 5 00:38:11.645558 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 5 00:38:11.671430 kernel: Bridge firewalling registered Sep 5 00:38:11.670682 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 5 00:38:11.672117 systemd-modules-load[196]: Inserted module 'br_netfilter' Sep 5 00:38:11.776055 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 5 00:38:11.833396 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 5 00:38:11.935718 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 5 00:38:11.957316 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 5 00:38:11.967778 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 5 00:38:12.120445 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 5 00:38:12.135825 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 5 00:38:12.165833 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 5 00:38:12.203618 dracut-cmdline[228]: dracut-dracut-053 Sep 5 00:38:12.224789 dracut-cmdline[228]: Using kernel command line parameters: rd.driver.pre=btrfs rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected verity.usrhash=9215c603110941002c0250d1c5b0629b89b2cc5fa29e7b45d5a5566f7e6b6ee1 Sep 5 00:38:12.502855 systemd-resolved[238]: Positive Trust Anchors: Sep 5 00:38:12.504713 systemd-resolved[238]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 5 00:38:12.504750 systemd-resolved[238]: 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 5 00:38:12.555005 systemd-resolved[238]: Defaulting to hostname 'linux'. Sep 5 00:38:12.564659 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 5 00:38:12.615029 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 5 00:38:13.337108 kernel: SCSI subsystem initialized Sep 5 00:38:13.418370 kernel: Loading iSCSI transport class v2.0-870. Sep 5 00:38:13.486005 kernel: iscsi: registered transport (tcp) Sep 5 00:38:13.625166 kernel: iscsi: registered transport (qla4xxx) Sep 5 00:38:13.639417 kernel: QLogic iSCSI HBA Driver Sep 5 00:38:14.715064 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 5 00:38:14.773562 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 5 00:38:15.237955 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 5 00:38:15.241603 kernel: device-mapper: uevent: version 1.0.3 Sep 5 00:38:15.247588 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Sep 5 00:38:15.697181 kernel: raid6: avx512x4 gen() 22904 MB/s Sep 5 00:38:15.718050 kernel: raid6: avx512x2 gen() 17056 MB/s Sep 5 00:38:15.741970 kernel: raid6: avx512x1 gen() 4829 MB/s Sep 5 00:38:15.763418 kernel: raid6: avx2x4 gen() 14862 MB/s Sep 5 00:38:15.826541 kernel: raid6: avx2x2 gen() 8614 MB/s Sep 5 00:38:15.849100 kernel: raid6: avx2x1 gen() 5175 MB/s Sep 5 00:38:15.851855 kernel: raid6: using algorithm avx512x4 gen() 22904 MB/s Sep 5 00:38:15.867787 kernel: raid6: .... xor() 2929 MB/s, rmw enabled Sep 5 00:38:15.868602 kernel: raid6: using avx512x2 recovery algorithm Sep 5 00:38:16.047003 kernel: xor: automatically using best checksumming function avx Sep 5 00:38:17.362490 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 5 00:38:17.701147 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 5 00:38:17.858800 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 5 00:38:18.149483 systemd-udevd[418]: Using default interface naming scheme 'v255'. Sep 5 00:38:18.240428 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 5 00:38:18.471850 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 5 00:38:19.697925 dracut-pre-trigger[423]: rd.md=0: removing MD RAID activation Sep 5 00:38:21.058477 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 5 00:38:21.226045 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 5 00:38:22.531307 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 5 00:38:22.575508 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 5 00:38:22.762789 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 5 00:38:22.831812 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 5 00:38:22.889459 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 5 00:38:22.899574 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 5 00:38:22.955297 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 5 00:38:23.117720 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 5 00:38:23.545908 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 5 00:38:23.546303 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 5 00:38:23.567719 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 5 00:38:23.568090 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 5 00:38:23.584662 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 5 00:38:23.652472 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues Sep 5 00:38:23.653288 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Sep 5 00:38:23.653399 kernel: cryptd: max_cpu_qlen set to 1000 Sep 5 00:38:23.603894 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 5 00:38:23.709152 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 5 00:38:23.834010 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 5 00:38:24.014536 kernel: libata version 3.00 loaded. Sep 5 00:38:24.021219 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 5 00:38:24.189917 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 5 00:38:24.343427 kernel: BTRFS: device fsid 7334055a-467a-40fa-9177-3daf8468e49a devid 1 transid 31 /dev/vda3 scanned by (udev-worker) (474) Sep 5 00:38:24.633413 kernel: ahci 0000:00:1f.2: version 3.0 Sep 5 00:38:24.647543 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 Sep 5 00:38:24.648020 kernel: AVX2 version of gcm_enc/dec engaged. Sep 5 00:38:24.644371 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Sep 5 00:38:24.668933 kernel: BTRFS: device label OEM devid 1 transid 9 /dev/vda6 scanned by (udev-worker) (465) Sep 5 00:38:24.672165 kernel: ahci 0000:00:1f.2: AHCI 0001.0000 32 slots 6 ports 1.5 Gbps 0x3f impl SATA mode Sep 5 00:38:24.672665 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only Sep 5 00:38:24.677222 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 5 00:38:24.757367 kernel: AES CTR mode by8 optimization enabled Sep 5 00:38:24.765660 kernel: scsi host0: ahci Sep 5 00:38:24.822234 kernel: scsi host1: ahci Sep 5 00:38:24.839591 kernel: scsi host2: ahci Sep 5 00:38:24.851148 kernel: scsi host3: ahci Sep 5 00:38:24.894424 kernel: scsi host4: ahci Sep 5 00:38:24.900551 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Sep 5 00:38:24.944542 kernel: scsi host5: ahci Sep 5 00:38:24.945009 kernel: ata1: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040100 irq 34 Sep 5 00:38:24.945025 kernel: ata2: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040180 irq 34 Sep 5 00:38:24.945035 kernel: ata3: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040200 irq 34 Sep 5 00:38:24.945057 kernel: ata4: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040280 irq 34 Sep 5 00:38:24.951332 kernel: ata5: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040300 irq 34 Sep 5 00:38:24.962791 kernel: ata6: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040380 irq 34 Sep 5 00:38:25.100792 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 5 00:38:25.146951 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Sep 5 00:38:25.169480 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Sep 5 00:38:25.243480 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 5 00:38:25.303848 kernel: ata2: SATA link down (SStatus 0 SControl 300) Sep 5 00:38:25.304487 kernel: ata1: SATA link down (SStatus 0 SControl 300) Sep 5 00:38:25.330404 kernel: ata4: SATA link down (SStatus 0 SControl 300) Sep 5 00:38:25.333783 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Sep 5 00:38:25.339132 kernel: ata5: SATA link down (SStatus 0 SControl 300) Sep 5 00:38:25.347148 kernel: ata6: SATA link down (SStatus 0 SControl 300) Sep 5 00:38:25.347623 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 Sep 5 00:38:25.347653 kernel: ata3.00: applying bridge limits Sep 5 00:38:25.372882 kernel: ata3.00: configured for UDMA/100 Sep 5 00:38:25.389764 kernel: scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 Sep 5 00:38:25.516723 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 5 00:38:25.553681 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 5 00:38:26.073371 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray Sep 5 00:38:26.225082 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Sep 5 00:38:26.290597 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 Sep 5 00:38:26.620379 disk-uuid[569]: Warning: The kernel is still using the old partition table. Sep 5 00:38:26.620379 disk-uuid[569]: The new table will be used at the next reboot or after you Sep 5 00:38:26.620379 disk-uuid[569]: run partprobe(8) or kpartx(8) Sep 5 00:38:26.620379 disk-uuid[569]: The operation has completed successfully. Sep 5 00:38:27.652685 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 5 00:38:27.652869 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 5 00:38:27.752486 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 5 00:38:27.949823 sh[595]: Success Sep 5 00:38:28.203191 kernel: device-mapper: verity: sha256 using implementation "sha256-ni" Sep 5 00:38:29.170775 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 5 00:38:29.360641 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 5 00:38:29.542820 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 5 00:38:30.097612 kernel: BTRFS info (device dm-0): first mount of filesystem 7334055a-467a-40fa-9177-3daf8468e49a Sep 5 00:38:30.101730 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Sep 5 00:38:30.111386 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Sep 5 00:38:30.111947 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 5 00:38:30.131577 kernel: BTRFS info (device dm-0): using free space tree Sep 5 00:38:30.400155 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 5 00:38:30.455378 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 5 00:38:30.603941 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 5 00:38:30.707860 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 5 00:38:31.018512 kernel: BTRFS info (device vda6): first mount of filesystem 40ce7b63-c029-41bb-9ac2-b503fd73bd4d Sep 5 00:38:31.019751 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 5 00:38:31.019936 kernel: BTRFS info (device vda6): using free space tree Sep 5 00:38:31.157779 kernel: BTRFS info (device vda6): auto enabling async discard Sep 5 00:38:31.372442 kernel: BTRFS info (device vda6): last unmount of filesystem 40ce7b63-c029-41bb-9ac2-b503fd73bd4d Sep 5 00:38:31.373664 systemd[1]: mnt-oem.mount: Deactivated successfully. Sep 5 00:38:31.615161 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 5 00:38:31.766168 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 5 00:38:33.312150 ignition[689]: Ignition 2.19.0 Sep 5 00:38:33.313790 ignition[689]: Stage: fetch-offline Sep 5 00:38:33.315054 ignition[689]: no configs at "/usr/lib/ignition/base.d" Sep 5 00:38:33.315149 ignition[689]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 5 00:38:33.319050 ignition[689]: parsed url from cmdline: "" Sep 5 00:38:33.319059 ignition[689]: no config URL provided Sep 5 00:38:33.319070 ignition[689]: reading system config file "/usr/lib/ignition/user.ign" Sep 5 00:38:33.319211 ignition[689]: no config at "/usr/lib/ignition/user.ign" Sep 5 00:38:33.320140 ignition[689]: op(1): [started] loading QEMU firmware config module Sep 5 00:38:33.320159 ignition[689]: op(1): executing: "modprobe" "qemu_fw_cfg" Sep 5 00:38:33.407167 ignition[689]: op(1): [finished] loading QEMU firmware config module Sep 5 00:38:33.413896 ignition[689]: parsing config with SHA512: 62595efe28dc3b49f284f4a9943d122254c901b710af412656a2b8e1478f5029ab17d3d3d6e1f32d6ab3598d3d0aabda56b34970288747cba30205362059248d Sep 5 00:38:33.615230 unknown[689]: fetched base config from "system" Sep 5 00:38:33.617931 ignition[689]: fetch-offline: fetch-offline passed Sep 5 00:38:33.616237 unknown[689]: fetched user config from "qemu" Sep 5 00:38:33.618729 ignition[689]: Ignition finished successfully Sep 5 00:38:33.638584 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 5 00:38:35.338199 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 5 00:38:35.494428 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 5 00:38:35.868592 systemd-networkd[784]: lo: Link UP Sep 5 00:38:35.868621 systemd-networkd[784]: lo: Gained carrier Sep 5 00:38:35.906404 systemd-networkd[784]: Enumeration completed Sep 5 00:38:35.913493 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 5 00:38:35.914606 systemd-networkd[784]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 5 00:38:35.914611 systemd-networkd[784]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 5 00:38:35.953646 systemd-networkd[784]: eth0: Link UP Sep 5 00:38:35.953661 systemd-networkd[784]: eth0: Gained carrier Sep 5 00:38:35.953691 systemd-networkd[784]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 5 00:38:35.963841 systemd[1]: Reached target network.target - Network. Sep 5 00:38:36.017405 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Sep 5 00:38:36.037358 systemd-networkd[784]: eth0: DHCPv4 address 10.0.0.104/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 5 00:38:36.076834 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 5 00:38:36.443869 ignition[786]: Ignition 2.19.0 Sep 5 00:38:36.443954 ignition[786]: Stage: kargs Sep 5 00:38:36.447440 ignition[786]: no configs at "/usr/lib/ignition/base.d" Sep 5 00:38:36.447471 ignition[786]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 5 00:38:36.451517 ignition[786]: kargs: kargs passed Sep 5 00:38:36.451616 ignition[786]: Ignition finished successfully Sep 5 00:38:36.533492 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 5 00:38:36.586009 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 5 00:38:36.889534 ignition[795]: Ignition 2.19.0 Sep 5 00:38:36.891391 ignition[795]: Stage: disks Sep 5 00:38:36.892118 ignition[795]: no configs at "/usr/lib/ignition/base.d" Sep 5 00:38:36.892136 ignition[795]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 5 00:38:36.929015 ignition[795]: disks: disks passed Sep 5 00:38:36.933530 ignition[795]: Ignition finished successfully Sep 5 00:38:36.973808 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 5 00:38:37.031013 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 5 00:38:37.040980 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 5 00:38:37.044927 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 5 00:38:37.049003 systemd[1]: Reached target sysinit.target - System Initialization. Sep 5 00:38:37.058489 systemd[1]: Reached target basic.target - Basic System. Sep 5 00:38:37.099380 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 5 00:38:37.385225 systemd-fsck[804]: ROOT: clean, 14/553520 files, 52654/553472 blocks Sep 5 00:38:37.409680 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 5 00:38:37.447502 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 5 00:38:37.936889 systemd-networkd[784]: eth0: Gained IPv6LL Sep 5 00:38:38.192341 kernel: EXT4-fs (vda9): mounted filesystem 8c18da49-3eb0-4eeb-934e-6ac91f8051aa r/w with ordered data mode. Quota mode: none. Sep 5 00:38:38.197553 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 5 00:38:38.205530 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 5 00:38:38.229410 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 5 00:38:38.241126 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 5 00:38:38.246228 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Sep 5 00:38:38.246560 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 5 00:38:38.257230 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/vda6 scanned by mount (813) Sep 5 00:38:38.246597 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 5 00:38:38.282064 kernel: BTRFS info (device vda6): first mount of filesystem 40ce7b63-c029-41bb-9ac2-b503fd73bd4d Sep 5 00:38:38.282746 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 5 00:38:38.282766 kernel: BTRFS info (device vda6): using free space tree Sep 5 00:38:38.287197 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 5 00:38:38.317128 kernel: BTRFS info (device vda6): auto enabling async discard Sep 5 00:38:38.315617 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 5 00:38:38.340976 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 5 00:38:38.514122 initrd-setup-root[837]: cut: /sysroot/etc/passwd: No such file or directory Sep 5 00:38:38.532850 initrd-setup-root[844]: cut: /sysroot/etc/group: No such file or directory Sep 5 00:38:38.558180 initrd-setup-root[851]: cut: /sysroot/etc/shadow: No such file or directory Sep 5 00:38:38.583847 initrd-setup-root[858]: cut: /sysroot/etc/gshadow: No such file or directory Sep 5 00:38:39.328632 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 5 00:38:39.381133 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 5 00:38:39.408407 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 5 00:38:39.432604 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 5 00:38:39.446808 kernel: BTRFS info (device vda6): last unmount of filesystem 40ce7b63-c029-41bb-9ac2-b503fd73bd4d Sep 5 00:38:39.565631 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 5 00:38:39.607836 ignition[927]: INFO : Ignition 2.19.0 Sep 5 00:38:39.607836 ignition[927]: INFO : Stage: mount Sep 5 00:38:39.616562 ignition[927]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 5 00:38:39.616562 ignition[927]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 5 00:38:39.616562 ignition[927]: INFO : mount: mount passed Sep 5 00:38:39.616562 ignition[927]: INFO : Ignition finished successfully Sep 5 00:38:39.624428 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 5 00:38:39.661775 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 5 00:38:39.701434 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 5 00:38:39.752938 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 scanned by mount (939) Sep 5 00:38:39.764716 kernel: BTRFS info (device vda6): first mount of filesystem 40ce7b63-c029-41bb-9ac2-b503fd73bd4d Sep 5 00:38:39.765454 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 5 00:38:39.770311 kernel: BTRFS info (device vda6): using free space tree Sep 5 00:38:39.833674 kernel: BTRFS info (device vda6): auto enabling async discard Sep 5 00:38:39.845946 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 5 00:38:39.978723 ignition[956]: INFO : Ignition 2.19.0 Sep 5 00:38:39.978723 ignition[956]: INFO : Stage: files Sep 5 00:38:39.993465 ignition[956]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 5 00:38:39.993465 ignition[956]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 5 00:38:39.993465 ignition[956]: DEBUG : files: compiled without relabeling support, skipping Sep 5 00:38:40.033322 ignition[956]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 5 00:38:40.033322 ignition[956]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 5 00:38:40.060741 ignition[956]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 5 00:38:40.060741 ignition[956]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 5 00:38:40.091625 ignition[956]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 5 00:38:40.061685 unknown[956]: wrote ssh authorized keys file for user: core Sep 5 00:38:40.106645 ignition[956]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 5 00:38:40.106645 ignition[956]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 5 00:38:40.106645 ignition[956]: INFO : files: op(4): [started] processing unit "etcd-member.service" Sep 5 00:38:40.106645 ignition[956]: 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 5 00:38:40.147509 ignition[956]: 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 5 00:38:40.147509 ignition[956]: INFO : files: op(4): [finished] processing unit "etcd-member.service" Sep 5 00:38:40.147509 ignition[956]: INFO : files: op(6): [started] processing unit "coreos-metadata.service" Sep 5 00:38:40.147509 ignition[956]: INFO : files: op(6): op(7): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 5 00:38:40.147509 ignition[956]: INFO : files: op(6): op(7): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 5 00:38:40.147509 ignition[956]: INFO : files: op(6): [finished] processing unit "coreos-metadata.service" Sep 5 00:38:40.147509 ignition[956]: INFO : files: op(8): [started] setting preset to disabled for "coreos-metadata.service" Sep 5 00:38:40.342796 ignition[956]: INFO : files: op(8): op(9): [started] removing enablement symlink(s) for "coreos-metadata.service" Sep 5 00:38:40.349703 ignition[956]: INFO : files: op(8): op(9): [finished] removing enablement symlink(s) for "coreos-metadata.service" Sep 5 00:38:40.354758 ignition[956]: INFO : files: op(8): [finished] setting preset to disabled for "coreos-metadata.service" Sep 5 00:38:40.354758 ignition[956]: INFO : files: op(a): [started] setting preset to enabled for "etcd-member.service" Sep 5 00:38:40.365093 ignition[956]: INFO : files: op(a): [finished] setting preset to enabled for "etcd-member.service" Sep 5 00:38:40.365093 ignition[956]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 5 00:38:40.365093 ignition[956]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 5 00:38:40.365093 ignition[956]: INFO : files: files passed Sep 5 00:38:40.365093 ignition[956]: INFO : Ignition finished successfully Sep 5 00:38:40.362892 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 5 00:38:40.400176 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 5 00:38:40.445831 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 5 00:38:40.469129 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 5 00:38:40.471701 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 5 00:38:40.501181 initrd-setup-root-after-ignition[983]: grep: /sysroot/oem/oem-release: No such file or directory Sep 5 00:38:40.512646 initrd-setup-root-after-ignition[986]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 5 00:38:40.512646 initrd-setup-root-after-ignition[986]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 5 00:38:40.526470 initrd-setup-root-after-ignition[990]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 5 00:38:40.532726 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 5 00:38:40.542903 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 5 00:38:40.568125 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 5 00:38:40.726698 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 5 00:38:40.729814 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 5 00:38:40.739220 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 5 00:38:40.748066 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 5 00:38:40.756774 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 5 00:38:40.777916 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 5 00:38:40.838284 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 5 00:38:40.863875 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 5 00:38:40.950103 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 5 00:38:40.950958 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 5 00:38:40.959535 systemd[1]: Stopped target timers.target - Timer Units. Sep 5 00:38:40.966085 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 5 00:38:40.966473 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 5 00:38:40.979372 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 5 00:38:40.981597 systemd[1]: Stopped target basic.target - Basic System. Sep 5 00:38:40.984785 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 5 00:38:40.989964 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 5 00:38:40.995798 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 5 00:38:41.004698 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 5 00:38:41.015230 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 5 00:38:41.023149 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 5 00:38:41.028742 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 5 00:38:41.032545 systemd[1]: Stopped target swap.target - Swaps. Sep 5 00:38:41.036207 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 5 00:38:41.036563 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 5 00:38:41.042098 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 5 00:38:41.049533 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 5 00:38:41.064588 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 5 00:38:41.066790 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 5 00:38:41.073101 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 5 00:38:41.073779 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 5 00:38:41.162199 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 5 00:38:41.162682 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 5 00:38:41.176631 systemd[1]: Stopped target paths.target - Path Units. Sep 5 00:38:41.183721 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 5 00:38:41.189501 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 5 00:38:41.208448 systemd[1]: Stopped target slices.target - Slice Units. Sep 5 00:38:41.215285 systemd[1]: Stopped target sockets.target - Socket Units. Sep 5 00:38:41.228959 systemd[1]: iscsid.socket: Deactivated successfully. Sep 5 00:38:41.229582 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 5 00:38:41.230478 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 5 00:38:41.230593 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 5 00:38:41.248014 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 5 00:38:41.260671 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 5 00:38:41.267865 systemd[1]: ignition-files.service: Deactivated successfully. Sep 5 00:38:41.268199 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 5 00:38:41.289050 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 5 00:38:41.296000 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 5 00:38:41.299628 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 5 00:38:41.300123 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 5 00:38:41.303545 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 5 00:38:41.303852 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 5 00:38:41.329967 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 5 00:38:41.330682 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 5 00:38:41.433181 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 5 00:38:41.437617 ignition[1010]: INFO : Ignition 2.19.0 Sep 5 00:38:41.437617 ignition[1010]: INFO : Stage: umount Sep 5 00:38:41.437617 ignition[1010]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 5 00:38:41.437617 ignition[1010]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 5 00:38:41.437617 ignition[1010]: INFO : umount: umount passed Sep 5 00:38:41.437617 ignition[1010]: INFO : Ignition finished successfully Sep 5 00:38:41.440573 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 5 00:38:41.440833 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 5 00:38:41.458935 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 5 00:38:41.461587 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 5 00:38:41.471233 systemd[1]: Stopped target network.target - Network. Sep 5 00:38:41.477284 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 5 00:38:41.477643 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 5 00:38:41.488521 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 5 00:38:41.488861 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 5 00:38:41.497088 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 5 00:38:41.497575 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 5 00:38:41.499951 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 5 00:38:41.500058 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 5 00:38:41.503765 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 5 00:38:41.503988 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 5 00:38:41.507561 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 5 00:38:41.512157 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 5 00:38:41.527683 systemd-networkd[784]: eth0: DHCPv6 lease lost Sep 5 00:38:41.538777 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 5 00:38:41.538942 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 5 00:38:41.550207 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 5 00:38:41.550337 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 5 00:38:41.621238 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 5 00:38:41.630726 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 5 00:38:41.649108 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 5 00:38:41.649358 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 5 00:38:41.743095 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 5 00:38:41.747137 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 5 00:38:41.747363 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 5 00:38:41.757852 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 5 00:38:41.759218 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 5 00:38:41.796716 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 5 00:38:41.800727 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 5 00:38:41.816909 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 5 00:38:41.856542 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 5 00:38:41.856908 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 5 00:38:41.975608 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 5 00:38:41.984981 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 5 00:38:42.013148 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 5 00:38:42.013582 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 5 00:38:42.022453 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 5 00:38:42.022657 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 5 00:38:42.027480 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 5 00:38:42.027580 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 5 00:38:42.039280 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 5 00:38:42.039735 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 5 00:38:42.057848 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 5 00:38:42.061918 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 5 00:38:42.097440 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 5 00:38:42.102877 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 5 00:38:42.105719 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 5 00:38:42.119571 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Sep 5 00:38:42.119657 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 5 00:38:42.124509 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 5 00:38:42.124605 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 5 00:38:42.132670 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 5 00:38:42.132852 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 5 00:38:42.148076 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 5 00:38:42.150715 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 5 00:38:42.167379 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 5 00:38:42.247216 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 5 00:38:42.284773 systemd[1]: Switching root. Sep 5 00:38:42.392286 systemd-journald[195]: Received SIGTERM from PID 1 (systemd). Sep 5 00:38:42.392808 systemd-journald[195]: Journal stopped Sep 5 00:38:49.624040 kernel: SELinux: policy capability network_peer_controls=1 Sep 5 00:38:49.625233 kernel: SELinux: policy capability open_perms=1 Sep 5 00:38:49.625313 kernel: SELinux: policy capability extended_socket_class=1 Sep 5 00:38:49.625336 kernel: SELinux: policy capability always_check_network=0 Sep 5 00:38:49.625375 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 5 00:38:49.625389 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 5 00:38:49.625401 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 5 00:38:49.625413 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 5 00:38:49.625428 kernel: audit: type=1403 audit(1788568722.745:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 5 00:38:49.625447 systemd[1]: Successfully loaded SELinux policy in 107.013ms. Sep 5 00:38:49.625478 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 123.875ms. Sep 5 00:38:49.625493 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Sep 5 00:38:49.625507 systemd[1]: Detected virtualization kvm. Sep 5 00:38:49.625533 systemd[1]: Detected architecture x86-64. Sep 5 00:38:49.625549 systemd[1]: Detected first boot. Sep 5 00:38:49.625561 systemd[1]: Initializing machine ID from VM UUID. Sep 5 00:38:49.625576 zram_generator::config[1056]: No configuration found. Sep 5 00:38:49.625590 systemd[1]: Populated /etc with preset unit settings. Sep 5 00:38:49.625603 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 5 00:38:49.625658 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 5 00:38:49.625683 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 5 00:38:49.625701 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 5 00:38:49.625719 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 5 00:38:49.625734 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 5 00:38:49.625748 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 5 00:38:49.625759 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 5 00:38:49.625786 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 5 00:38:49.625805 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 5 00:38:49.625819 systemd[1]: Created slice user.slice - User and Session Slice. Sep 5 00:38:49.625832 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 5 00:38:49.625848 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 5 00:38:49.625862 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 5 00:38:49.625876 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 5 00:38:49.625890 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 5 00:38:49.625904 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 5 00:38:49.625932 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Sep 5 00:38:49.625945 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 5 00:38:49.625961 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 5 00:38:49.625977 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 5 00:38:49.625990 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 5 00:38:49.626003 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 5 00:38:49.626016 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 5 00:38:49.626029 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 5 00:38:49.626052 systemd[1]: Reached target slices.target - Slice Units. Sep 5 00:38:49.626139 systemd[1]: Reached target swap.target - Swaps. Sep 5 00:38:49.626162 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 5 00:38:49.626175 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 5 00:38:49.626188 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 5 00:38:49.626202 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 5 00:38:49.626216 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 5 00:38:49.626229 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 5 00:38:49.626308 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 5 00:38:49.626342 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 5 00:38:49.626357 systemd[1]: Mounting media.mount - External Media Directory... Sep 5 00:38:49.626372 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 5 00:38:49.626385 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 5 00:38:49.626403 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 5 00:38:49.626417 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 5 00:38:49.626432 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 5 00:38:49.626446 systemd[1]: Reached target machines.target - Containers. Sep 5 00:38:49.626458 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 5 00:38:49.626484 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 5 00:38:49.626496 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 5 00:38:49.626509 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 5 00:38:49.626521 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 5 00:38:49.626534 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 5 00:38:49.626547 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 5 00:38:49.626560 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 5 00:38:49.626572 kernel: ACPI: bus type drm_connector registered Sep 5 00:38:49.626599 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 5 00:38:49.626614 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 5 00:38:49.626626 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 5 00:38:49.626638 kernel: fuse: init (API version 7.39) Sep 5 00:38:49.626650 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 5 00:38:49.626662 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 5 00:38:49.626674 systemd[1]: Stopped systemd-fsck-usr.service. Sep 5 00:38:49.626686 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 5 00:38:49.626701 kernel: loop: module loaded Sep 5 00:38:49.628205 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 5 00:38:49.630843 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 5 00:38:49.632885 systemd-journald[1142]: Collecting audit messages is disabled. Sep 5 00:38:49.636043 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 5 00:38:49.655231 systemd-journald[1142]: Journal started Sep 5 00:38:49.655425 systemd-journald[1142]: Runtime Journal (/run/log/journal/63172615560e4f86aceb290308085849) is 6.0M, max 48.2M, 42.2M free. Sep 5 00:38:49.734883 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 5 00:38:47.223379 systemd[1]: Queued start job for default target multi-user.target. Sep 5 00:38:49.743780 systemd[1]: verity-setup.service: Deactivated successfully. Sep 5 00:38:49.743956 systemd[1]: Stopped verity-setup.service. Sep 5 00:38:47.320599 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Sep 5 00:38:47.342348 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 5 00:38:47.354922 systemd[1]: systemd-journald.service: Consumed 1.669s CPU time. Sep 5 00:38:49.765352 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 5 00:38:49.783634 systemd[1]: Started systemd-journald.service - Journal Service. Sep 5 00:38:49.817773 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 5 00:38:49.826881 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 5 00:38:49.840908 systemd[1]: Mounted media.mount - External Media Directory. Sep 5 00:38:49.870818 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 5 00:38:49.945857 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 5 00:38:49.973696 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 5 00:38:49.989007 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 5 00:38:50.000433 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 5 00:38:50.021464 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 5 00:38:50.022036 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 5 00:38:50.036510 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 5 00:38:50.038122 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 5 00:38:50.055042 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 5 00:38:50.064163 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 5 00:38:50.139560 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 5 00:38:50.145778 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 5 00:38:50.160757 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 5 00:38:50.168866 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 5 00:38:50.208760 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 5 00:38:50.211207 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 5 00:38:50.259085 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 5 00:38:50.307764 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 5 00:38:50.340153 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 5 00:38:50.453101 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 5 00:38:50.554497 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 5 00:38:50.649602 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 5 00:38:50.662966 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 5 00:38:50.663150 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 5 00:38:50.718988 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Sep 5 00:38:50.800940 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 5 00:38:50.916769 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 5 00:38:50.925538 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 5 00:38:51.009362 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 5 00:38:51.066685 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 5 00:38:51.098897 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 5 00:38:51.162275 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 5 00:38:51.168875 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 5 00:38:51.212425 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 5 00:38:51.273747 systemd-journald[1142]: Time spent on flushing to /var/log/journal/63172615560e4f86aceb290308085849 is 198.374ms for 970 entries. Sep 5 00:38:51.273747 systemd-journald[1142]: System Journal (/var/log/journal/63172615560e4f86aceb290308085849) is 8.0M, max 195.6M, 187.6M free. Sep 5 00:38:51.603015 systemd-journald[1142]: Received client request to flush runtime journal. Sep 5 00:38:51.341578 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 5 00:38:51.439620 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 5 00:38:51.601111 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 5 00:38:51.629671 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 5 00:38:51.649904 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 5 00:38:51.740335 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 5 00:38:51.749837 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 5 00:38:51.773670 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 5 00:38:52.052580 kernel: loop0: detected capacity change from 0 to 140768 Sep 5 00:38:51.957004 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 5 00:38:52.113739 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Sep 5 00:38:52.172414 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 5 00:38:52.265049 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Sep 5 00:38:52.356408 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 5 00:38:52.437145 systemd-tmpfiles[1173]: ACLs are not supported, ignoring. Sep 5 00:38:52.437338 systemd-tmpfiles[1173]: ACLs are not supported, ignoring. Sep 5 00:38:52.585429 kernel: loop1: detected capacity change from 0 to 142488 Sep 5 00:38:52.570459 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 5 00:38:52.591642 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 5 00:38:52.616930 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Sep 5 00:38:52.669608 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 5 00:38:52.673729 udevadm[1187]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Sep 5 00:38:52.770024 kernel: loop2: detected capacity change from 0 to 140768 Sep 5 00:38:52.926683 kernel: loop3: detected capacity change from 0 to 142488 Sep 5 00:38:53.021104 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 5 00:38:53.059022 (sd-merge)[1195]: Using extensions 'containerd-flatcar', 'docker-flatcar'. Sep 5 00:38:53.059844 (sd-merge)[1195]: Merged extensions into '/usr'. Sep 5 00:38:53.169265 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 5 00:38:53.189462 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 5 00:38:53.239946 systemd[1]: Starting ensure-sysext.service... Sep 5 00:38:53.310875 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 5 00:38:53.409598 systemd[1]: Reloading requested from client PID 1200 ('systemctl') (unit ensure-sysext.service)... Sep 5 00:38:53.409993 systemd[1]: Reloading... Sep 5 00:38:53.419471 systemd-tmpfiles[1197]: ACLs are not supported, ignoring. Sep 5 00:38:53.419661 systemd-tmpfiles[1197]: ACLs are not supported, ignoring. Sep 5 00:38:53.756795 systemd-tmpfiles[1201]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 5 00:38:53.764132 systemd-tmpfiles[1201]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 5 00:38:53.778674 systemd-tmpfiles[1201]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 5 00:38:53.786761 systemd-tmpfiles[1201]: ACLs are not supported, ignoring. Sep 5 00:38:53.786961 systemd-tmpfiles[1201]: ACLs are not supported, ignoring. Sep 5 00:38:53.843125 systemd-tmpfiles[1201]: Detected autofs mount point /boot during canonicalization of boot. Sep 5 00:38:53.843159 systemd-tmpfiles[1201]: Skipping /boot Sep 5 00:38:54.003206 systemd-tmpfiles[1201]: Detected autofs mount point /boot during canonicalization of boot. Sep 5 00:38:54.003240 systemd-tmpfiles[1201]: Skipping /boot Sep 5 00:38:54.060463 zram_generator::config[1229]: No configuration found. Sep 5 00:38:56.233850 ldconfig[1167]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 5 00:38:57.071967 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 5 00:38:57.978173 systemd[1]: Reloading finished in 4555 ms. Sep 5 00:38:58.808986 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 5 00:38:58.831989 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 5 00:38:58.901579 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 5 00:38:58.971015 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 5 00:38:59.247768 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Sep 5 00:38:59.331609 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 5 00:38:59.463873 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 5 00:38:59.655964 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 5 00:38:59.739212 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 5 00:38:59.771340 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 5 00:38:59.851423 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 5 00:38:59.851718 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 5 00:38:59.940288 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 5 00:38:59.985108 systemd-udevd[1282]: Using default interface naming scheme 'v255'. Sep 5 00:39:00.011185 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 5 00:39:00.141985 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 5 00:39:00.167842 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 5 00:39:00.208653 augenrules[1295]: No rules Sep 5 00:39:00.240856 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 5 00:39:00.248180 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 5 00:39:00.272619 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Sep 5 00:39:00.324804 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 5 00:39:00.374857 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 5 00:39:00.394652 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 5 00:39:00.400880 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 5 00:39:00.420706 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 5 00:39:00.437162 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 5 00:39:00.443981 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 5 00:39:00.461857 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 5 00:39:00.464000 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 5 00:39:00.569777 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 5 00:39:00.604457 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 5 00:39:00.653215 systemd[1]: Finished ensure-sysext.service. Sep 5 00:39:00.733075 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 5 00:39:00.733474 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 5 00:39:00.772718 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 5 00:39:00.859235 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 5 00:39:00.945742 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 5 00:39:01.057300 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 5 00:39:01.098215 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 5 00:39:01.190025 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 5 00:39:01.254575 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Sep 5 00:39:01.372186 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 5 00:39:01.424444 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 31 scanned by (udev-worker) (1309) Sep 5 00:39:01.406287 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 5 00:39:01.406609 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 5 00:39:01.414826 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 5 00:39:01.415494 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 5 00:39:01.437664 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 5 00:39:01.438197 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 5 00:39:01.467400 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 5 00:39:01.468145 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 5 00:39:01.526712 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 5 00:39:01.529913 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 5 00:39:01.581185 systemd-resolved[1281]: Positive Trust Anchors: Sep 5 00:39:01.581204 systemd-resolved[1281]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 5 00:39:01.581307 systemd-resolved[1281]: 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 5 00:39:01.582092 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 5 00:39:01.600532 systemd-resolved[1281]: Defaulting to hostname 'linux'. Sep 5 00:39:01.604708 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Sep 5 00:39:01.619959 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 5 00:39:01.742594 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 5 00:39:01.748143 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 5 00:39:01.748680 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 5 00:39:02.060943 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Sep 5 00:39:02.100613 systemd[1]: Reached target time-set.target - System Time Set. Sep 5 00:39:02.277897 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 5 00:39:02.356110 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 5 00:39:02.358475 systemd-networkd[1333]: lo: Link UP Sep 5 00:39:02.358482 systemd-networkd[1333]: lo: Gained carrier Sep 5 00:39:02.371891 systemd-networkd[1333]: Enumeration completed Sep 5 00:39:02.416696 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 5 00:39:02.440981 systemd[1]: Reached target network.target - Network. Sep 5 00:39:02.449285 systemd-networkd[1333]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 5 00:39:02.449619 systemd-networkd[1333]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 5 00:39:02.463561 systemd-networkd[1333]: eth0: Link UP Sep 5 00:39:02.464062 systemd-networkd[1333]: eth0: Gained carrier Sep 5 00:39:02.464167 systemd-networkd[1333]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 5 00:39:02.496185 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 5 00:39:02.542518 systemd-networkd[1333]: eth0: DHCPv4 address 10.0.0.104/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 5 00:39:02.547454 systemd-timesyncd[1335]: Network configuration changed, trying to establish connection. Sep 5 00:39:03.866921 systemd-timesyncd[1335]: Contacted time server 10.0.0.1:123 (10.0.0.1). Sep 5 00:39:03.866993 systemd-timesyncd[1335]: Initial clock synchronization to Sat 2026-09-05 00:39:03.866349 UTC. Sep 5 00:39:03.867105 systemd-resolved[1281]: Clock change detected. Flushing caches. Sep 5 00:39:04.094101 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 Sep 5 00:39:04.096869 kernel: ACPI: button: Power Button [PWRF] Sep 5 00:39:04.088003 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 5 00:39:04.256802 kernel: i801_smbus 0000:00:1f.3: Enabling SMBus device Sep 5 00:39:04.275122 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Sep 5 00:39:04.280221 kernel: i2c i2c-0: 1/1 memory slots populated (from DMI) Sep 5 00:39:04.290580 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Sep 5 00:39:04.315037 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 Sep 5 00:39:05.046254 systemd-networkd[1333]: eth0: Gained IPv6LL Sep 5 00:39:05.146339 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 5 00:39:05.237091 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 5 00:39:05.396666 systemd[1]: Reached target network-online.target - Network is Online. Sep 5 00:39:05.457627 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 5 00:39:05.474854 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 5 00:39:05.623973 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 5 00:39:06.433123 kernel: mousedev: PS/2 mouse device common for all mice Sep 5 00:39:06.831606 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 5 00:39:07.589095 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Sep 5 00:39:07.637721 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Sep 5 00:39:07.785605 lvm[1373]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 5 00:39:08.037039 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Sep 5 00:39:08.069570 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 5 00:39:08.086786 systemd[1]: Reached target sysinit.target - System Initialization. Sep 5 00:39:08.139870 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 5 00:39:08.158076 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 5 00:39:08.175865 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 5 00:39:08.191026 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 5 00:39:08.222141 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 5 00:39:08.240953 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 5 00:39:08.242132 systemd[1]: Reached target paths.target - Path Units. Sep 5 00:39:08.250928 systemd[1]: Reached target timers.target - Timer Units. Sep 5 00:39:08.268155 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 5 00:39:08.354719 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 5 00:39:08.491725 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 5 00:39:08.635612 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Sep 5 00:39:08.655326 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 5 00:39:08.668087 systemd[1]: Reached target sockets.target - Socket Units. Sep 5 00:39:08.681104 systemd[1]: Reached target basic.target - Basic System. Sep 5 00:39:08.734254 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 5 00:39:08.739783 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 5 00:39:08.777870 systemd[1]: Starting containerd.service - containerd container runtime... Sep 5 00:39:08.898686 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 5 00:39:08.945398 lvm[1377]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 5 00:39:08.962210 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 5 00:39:09.036039 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 5 00:39:09.116938 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 5 00:39:09.127259 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 5 00:39:09.220902 jq[1381]: false Sep 5 00:39:09.237178 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 5 00:39:09.352664 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 5 00:39:09.407882 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 5 00:39:09.469382 dbus-daemon[1380]: [system] SELinux support is enabled Sep 5 00:39:09.530599 extend-filesystems[1382]: Found loop2 Sep 5 00:39:09.530599 extend-filesystems[1382]: Found loop3 Sep 5 00:39:09.530599 extend-filesystems[1382]: Found sr0 Sep 5 00:39:09.530599 extend-filesystems[1382]: Found vda Sep 5 00:39:09.530599 extend-filesystems[1382]: Found vda1 Sep 5 00:39:09.530599 extend-filesystems[1382]: Found vda2 Sep 5 00:39:09.530599 extend-filesystems[1382]: Found vda3 Sep 5 00:39:09.530599 extend-filesystems[1382]: Found usr Sep 5 00:39:09.530599 extend-filesystems[1382]: Found vda4 Sep 5 00:39:09.530599 extend-filesystems[1382]: Found vda6 Sep 5 00:39:09.530599 extend-filesystems[1382]: Found vda7 Sep 5 00:39:09.530599 extend-filesystems[1382]: Found vda9 Sep 5 00:39:09.530599 extend-filesystems[1382]: Checking size of /dev/vda9 Sep 5 00:39:09.530416 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 5 00:39:09.744926 extend-filesystems[1382]: Old size kept for /dev/vda9 Sep 5 00:39:09.569709 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 5 00:39:09.583931 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 5 00:39:09.589039 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 5 00:39:09.609415 systemd[1]: Starting update-engine.service - Update Engine... Sep 5 00:39:09.763051 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 5 00:39:09.770706 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 5 00:39:09.783631 update_engine[1398]: I20260905 00:39:09.781410 1398 main.cc:92] Flatcar Update Engine starting Sep 5 00:39:09.783530 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Sep 5 00:39:09.880622 jq[1405]: true Sep 5 00:39:09.873809 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 5 00:39:09.874120 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 5 00:39:09.875763 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 5 00:39:09.875969 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 5 00:39:09.932435 systemd[1]: motdgen.service: Deactivated successfully. Sep 5 00:39:09.933216 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 5 00:39:09.981218 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 5 00:39:09.982972 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 5 00:39:10.012423 update_engine[1398]: I20260905 00:39:10.007335 1398 update_check_scheduler.cc:74] Next update check in 3m25s Sep 5 00:39:10.087496 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 5 00:39:10.177090 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 31 scanned by (udev-worker) (1332) Sep 5 00:39:10.209697 jq[1409]: true Sep 5 00:39:10.380388 systemd-logind[1395]: Watching system buttons on /dev/input/event1 (Power Button) Sep 5 00:39:10.406216 systemd-logind[1395]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Sep 5 00:39:10.453101 systemd-logind[1395]: New seat seat0. Sep 5 00:39:10.572089 (ntainerd)[1411]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 5 00:39:10.589013 systemd[1]: Started systemd-logind.service - User Login Management. Sep 5 00:39:11.010220 dbus-daemon[1380]: [system] Successfully activated service 'org.freedesktop.systemd1' Sep 5 00:39:11.221777 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 5 00:39:11.226759 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 5 00:39:11.332441 sshd_keygen[1410]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 5 00:39:11.659975 bash[1447]: Updated "/home/core/.ssh/authorized_keys" Sep 5 00:39:11.673656 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 5 00:39:11.727716 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 5 00:39:11.916102 systemd[1]: Started update-engine.service - Update Engine. Sep 5 00:39:12.080592 systemd[1]: Starting etcd-member.service - etcd (System Application Container)... Sep 5 00:39:12.179168 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 5 00:39:12.186903 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 5 00:39:12.189326 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 5 00:39:12.189709 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 5 00:39:12.190309 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 5 00:39:12.204370 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 5 00:39:12.240677 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 5 00:39:12.328130 (-wrapper)[1456]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS Sep 5 00:39:12.416451 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 5 00:39:12.753947 systemd[1]: issuegen.service: Deactivated successfully. Sep 5 00:39:12.770262 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 5 00:39:12.933016 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 5 00:39:13.054416 containerd[1411]: time="2026-09-05T00:39:13.050515887Z" level=info msg="starting containerd" revision=174e0d1785eeda18dc2beba45e1d5a188771636b version=v1.7.21 Sep 5 00:39:13.072416 locksmithd[1458]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 5 00:39:13.216508 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 5 00:39:13.342955 containerd[1411]: time="2026-09-05T00:39:13.337697515Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Sep 5 00:39:13.358126 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 5 00:39:13.397144 containerd[1411]: time="2026-09-05T00:39:13.369847960Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.156-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Sep 5 00:39:13.397144 containerd[1411]: time="2026-09-05T00:39:13.370085077Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Sep 5 00:39:13.397144 containerd[1411]: time="2026-09-05T00:39:13.370131262Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Sep 5 00:39:13.397144 containerd[1411]: time="2026-09-05T00:39:13.375677505Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Sep 5 00:39:13.397144 containerd[1411]: time="2026-09-05T00:39:13.375826041Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Sep 5 00:39:13.397144 containerd[1411]: time="2026-09-05T00:39:13.376150040Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Sep 5 00:39:13.397144 containerd[1411]: time="2026-09-05T00:39:13.376173395Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Sep 5 00:39:13.397144 containerd[1411]: time="2026-09-05T00:39:13.376861483Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Sep 5 00:39:13.397144 containerd[1411]: time="2026-09-05T00:39:13.376889752Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Sep 5 00:39:13.397144 containerd[1411]: time="2026-09-05T00:39:13.376904957Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Sep 5 00:39:13.397144 containerd[1411]: time="2026-09-05T00:39:13.376915309Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Sep 5 00:39:13.397810 containerd[1411]: time="2026-09-05T00:39:13.377012940Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Sep 5 00:39:13.397810 containerd[1411]: time="2026-09-05T00:39:13.382892356Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Sep 5 00:39:13.397810 containerd[1411]: time="2026-09-05T00:39:13.383402335Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Sep 5 00:39:13.397810 containerd[1411]: time="2026-09-05T00:39:13.383429598Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Sep 5 00:39:13.397810 containerd[1411]: time="2026-09-05T00:39:13.383858612Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Sep 5 00:39:13.397810 containerd[1411]: time="2026-09-05T00:39:13.383984174Z" level=info msg="metadata content store policy set" policy=shared Sep 5 00:39:13.417792 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Sep 5 00:39:13.434763 containerd[1411]: time="2026-09-05T00:39:13.432015769Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Sep 5 00:39:13.434763 containerd[1411]: time="2026-09-05T00:39:13.433724390Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Sep 5 00:39:13.434763 containerd[1411]: time="2026-09-05T00:39:13.433905416Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Sep 5 00:39:13.434763 containerd[1411]: time="2026-09-05T00:39:13.433937121Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Sep 5 00:39:13.434763 containerd[1411]: time="2026-09-05T00:39:13.434241971Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Sep 5 00:39:13.434763 containerd[1411]: time="2026-09-05T00:39:13.435399437Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Sep 5 00:39:13.434763 containerd[1411]: time="2026-09-05T00:39:13.436955358Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Sep 5 00:39:13.442395 containerd[1411]: time="2026-09-05T00:39:13.438012405Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Sep 5 00:39:13.442395 containerd[1411]: time="2026-09-05T00:39:13.438038269Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Sep 5 00:39:13.442395 containerd[1411]: time="2026-09-05T00:39:13.438137923Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Sep 5 00:39:13.442395 containerd[1411]: time="2026-09-05T00:39:13.438714194Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Sep 5 00:39:13.442395 containerd[1411]: time="2026-09-05T00:39:13.439038331Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Sep 5 00:39:13.442395 containerd[1411]: time="2026-09-05T00:39:13.439065810Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Sep 5 00:39:13.442395 containerd[1411]: time="2026-09-05T00:39:13.439152275Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Sep 5 00:39:13.442395 containerd[1411]: time="2026-09-05T00:39:13.439178723Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Sep 5 00:39:13.442395 containerd[1411]: time="2026-09-05T00:39:13.439223119Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Sep 5 00:39:13.442395 containerd[1411]: time="2026-09-05T00:39:13.439849433Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Sep 5 00:39:13.442395 containerd[1411]: time="2026-09-05T00:39:13.441662762Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Sep 5 00:39:13.442395 containerd[1411]: time="2026-09-05T00:39:13.441954343Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Sep 5 00:39:13.442395 containerd[1411]: time="2026-09-05T00:39:13.442025724Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Sep 5 00:39:13.442395 containerd[1411]: time="2026-09-05T00:39:13.442046824Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Sep 5 00:39:13.442831 containerd[1411]: time="2026-09-05T00:39:13.442068494Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Sep 5 00:39:13.442831 containerd[1411]: time="2026-09-05T00:39:13.442090360Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Sep 5 00:39:13.442831 containerd[1411]: time="2026-09-05T00:39:13.442120459Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Sep 5 00:39:13.442831 containerd[1411]: time="2026-09-05T00:39:13.442140197Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Sep 5 00:39:13.442831 containerd[1411]: time="2026-09-05T00:39:13.442166076Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Sep 5 00:39:13.442831 containerd[1411]: time="2026-09-05T00:39:13.442404222Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Sep 5 00:39:13.442831 containerd[1411]: time="2026-09-05T00:39:13.442452801Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Sep 5 00:39:13.442831 containerd[1411]: time="2026-09-05T00:39:13.442514061Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Sep 5 00:39:13.442831 containerd[1411]: time="2026-09-05T00:39:13.442562831Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Sep 5 00:39:13.442831 containerd[1411]: time="2026-09-05T00:39:13.442585773Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Sep 5 00:39:13.442831 containerd[1411]: time="2026-09-05T00:39:13.442614280Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Sep 5 00:39:13.442831 containerd[1411]: time="2026-09-05T00:39:13.442700452Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Sep 5 00:39:13.442831 containerd[1411]: time="2026-09-05T00:39:13.442716312Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Sep 5 00:39:13.442831 containerd[1411]: time="2026-09-05T00:39:13.442735755Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Sep 5 00:39:13.443147 containerd[1411]: time="2026-09-05T00:39:13.442843851Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Sep 5 00:39:13.443147 containerd[1411]: time="2026-09-05T00:39:13.443024162Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Sep 5 00:39:13.443147 containerd[1411]: time="2026-09-05T00:39:13.443049388Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Sep 5 00:39:13.443147 containerd[1411]: time="2026-09-05T00:39:13.443117622Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Sep 5 00:39:13.443147 containerd[1411]: time="2026-09-05T00:39:13.443132050Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Sep 5 00:39:13.443147 containerd[1411]: time="2026-09-05T00:39:13.443148918Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Sep 5 00:39:13.443330 containerd[1411]: time="2026-09-05T00:39:13.443183678Z" level=info msg="NRI interface is disabled by configuration." Sep 5 00:39:13.443330 containerd[1411]: time="2026-09-05T00:39:13.443202594Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Sep 5 00:39:13.446098 containerd[1411]: time="2026-09-05T00:39:13.445397227Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Sep 5 00:39:13.446098 containerd[1411]: time="2026-09-05T00:39:13.445851952Z" level=info msg="Connect containerd service" Sep 5 00:39:13.446098 containerd[1411]: time="2026-09-05T00:39:13.446006919Z" level=info msg="using legacy CRI server" Sep 5 00:39:13.446098 containerd[1411]: time="2026-09-05T00:39:13.446083494Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 5 00:39:13.449154 containerd[1411]: time="2026-09-05T00:39:13.446355740Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Sep 5 00:39:13.448703 systemd[1]: Reached target getty.target - Login Prompts. Sep 5 00:39:13.465261 containerd[1411]: time="2026-09-05T00:39:13.464924510Z" 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 5 00:39:13.469865 containerd[1411]: time="2026-09-05T00:39:13.468730279Z" level=info msg="Start subscribing containerd event" Sep 5 00:39:13.469865 containerd[1411]: time="2026-09-05T00:39:13.469165554Z" level=info msg="Start recovering state" Sep 5 00:39:13.469865 containerd[1411]: time="2026-09-05T00:39:13.469635379Z" level=info msg="Start event monitor" Sep 5 00:39:13.472344 containerd[1411]: time="2026-09-05T00:39:13.472051197Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 5 00:39:13.474809 containerd[1411]: time="2026-09-05T00:39:13.472765049Z" level=info msg="Start snapshots syncer" Sep 5 00:39:13.475734 containerd[1411]: time="2026-09-05T00:39:13.473153869Z" level=info msg="Start cni network conf syncer for default" Sep 5 00:39:13.477916 containerd[1411]: time="2026-09-05T00:39:13.475718206Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 5 00:39:13.477916 containerd[1411]: time="2026-09-05T00:39:13.475708942Z" level=info msg="Start streaming server" Sep 5 00:39:13.477916 containerd[1411]: time="2026-09-05T00:39:13.476642061Z" level=info msg="containerd successfully booted in 0.436551s" Sep 5 00:39:13.481070 systemd[1]: Started containerd.service - containerd container runtime. Sep 5 00:39:13.579765 systemd[1]: Starting docker.service - Docker Application Container Engine... Sep 5 00:39:13.717925 (dockerd)[1484]: 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 5 00:39:14.732441 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 5 00:39:14.809110 systemd[1]: Started sshd@0-10.0.0.104:22-10.0.0.1:39400.service - OpenSSH per-connection server daemon (10.0.0.1:39400). Sep 5 00:39:15.683803 sshd[1486]: Accepted publickey for core from 10.0.0.1 port 39400 ssh2: RSA SHA256:vBP/QiE5BHLKInLouGxnlgPQzzw5kcI7zSBTWQ7Q9QU Sep 5 00:39:15.696547 sshd[1486]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 5 00:39:15.890245 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 5 00:39:15.923634 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 5 00:39:16.027860 systemd-logind[1395]: New session 1 of user core. Sep 5 00:39:16.078378 dockerd[1484]: time="2026-09-05T00:39:16.076086502Z" level=info msg="Starting up" Sep 5 00:39:16.120110 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 5 00:39:16.226388 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 5 00:39:16.395188 (systemd)[1502]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 5 00:39:16.651709 dockerd[1484]: time="2026-09-05T00:39:16.649983880Z" level=info msg="Loading containers: start." Sep 5 00:39:17.035793 systemd[1502]: Queued start job for default target default.target. Sep 5 00:39:17.055951 systemd[1502]: Created slice app.slice - User Application Slice. Sep 5 00:39:17.056174 systemd[1502]: Reached target paths.target - Paths. Sep 5 00:39:17.056197 systemd[1502]: Reached target timers.target - Timers. Sep 5 00:39:17.075044 systemd[1502]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 5 00:39:17.150223 systemd[1502]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 5 00:39:17.152635 systemd[1502]: Reached target sockets.target - Sockets. Sep 5 00:39:17.152677 systemd[1502]: Reached target basic.target - Basic System. Sep 5 00:39:17.152843 systemd[1502]: Reached target default.target - Main User Target. Sep 5 00:39:17.152957 systemd[1502]: Startup finished in 725ms. Sep 5 00:39:17.153268 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 5 00:39:17.216815 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 5 00:39:17.414078 kernel: Initializing XFRM netlink socket Sep 5 00:39:17.426299 systemd[1]: Started sshd@1-10.0.0.104:22-10.0.0.1:39410.service - OpenSSH per-connection server daemon (10.0.0.1:39410). Sep 5 00:39:17.743438 sshd[1570]: Accepted publickey for core from 10.0.0.1 port 39410 ssh2: RSA SHA256:vBP/QiE5BHLKInLouGxnlgPQzzw5kcI7zSBTWQ7Q9QU Sep 5 00:39:17.756101 sshd[1570]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 5 00:39:17.800210 systemd-logind[1395]: New session 2 of user core. Sep 5 00:39:17.829453 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 5 00:39:18.083995 sshd[1570]: pam_unix(sshd:session): session closed for user core Sep 5 00:39:18.134935 systemd[1]: sshd@1-10.0.0.104:22-10.0.0.1:39410.service: Deactivated successfully. Sep 5 00:39:18.185194 systemd[1]: session-2.scope: Deactivated successfully. Sep 5 00:39:18.237933 systemd-logind[1395]: Session 2 logged out. Waiting for processes to exit. Sep 5 00:39:18.270146 systemd[1]: Started sshd@2-10.0.0.104:22-10.0.0.1:39412.service - OpenSSH per-connection server daemon (10.0.0.1:39412). Sep 5 00:39:18.302599 systemd-logind[1395]: Removed session 2. Sep 5 00:39:18.309084 systemd-networkd[1333]: docker0: Link UP Sep 5 00:39:18.424633 dockerd[1484]: time="2026-09-05T00:39:18.421000915Z" level=info msg="Loading containers: done." Sep 5 00:39:18.427572 sshd[1606]: Accepted publickey for core from 10.0.0.1 port 39412 ssh2: RSA SHA256:vBP/QiE5BHLKInLouGxnlgPQzzw5kcI7zSBTWQ7Q9QU Sep 5 00:39:18.432080 sshd[1606]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 5 00:39:18.543307 systemd-logind[1395]: New session 3 of user core. Sep 5 00:39:18.567376 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 5 00:39:18.575222 dockerd[1484]: time="2026-09-05T00:39:18.575027295Z" 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 5 00:39:18.575565 dockerd[1484]: time="2026-09-05T00:39:18.575507367Z" level=info msg="Docker daemon" commit=061aa95809be396a6b5542618d8a34b02a21ff77 containerd-snapshotter=false storage-driver=overlay2 version=26.1.0 Sep 5 00:39:18.575770 dockerd[1484]: time="2026-09-05T00:39:18.575700292Z" level=info msg="Daemon has completed initialization" Sep 5 00:39:18.767663 sshd[1606]: pam_unix(sshd:session): session closed for user core Sep 5 00:39:18.819910 systemd[1]: sshd@2-10.0.0.104:22-10.0.0.1:39412.service: Deactivated successfully. Sep 5 00:39:18.843366 systemd[1]: session-3.scope: Deactivated successfully. Sep 5 00:39:18.854399 systemd-logind[1395]: Session 3 logged out. Waiting for processes to exit. Sep 5 00:39:18.871538 systemd-logind[1395]: Removed session 3. Sep 5 00:39:18.882637 dockerd[1484]: time="2026-09-05T00:39:18.879540255Z" level=info msg="API listen on /run/docker.sock" Sep 5 00:39:18.882002 systemd[1]: Started docker.service - Docker Application Container Engine. Sep 5 00:39:18.895816 etcd-wrapper[1464]: Error response from daemon: No such container: etcd-member Sep 5 00:39:19.048399 etcd-wrapper[1650]: Error response from daemon: No such container: etcd-member Sep 5 00:39:19.208167 etcd-wrapper[1669]: Unable to find image 'quay.io/coreos/etcd:v3.5.24' locally Sep 5 00:39:21.188914 etcd-wrapper[1669]: v3.5.24: Pulling from coreos/etcd Sep 5 00:39:21.727359 etcd-wrapper[1669]: 804c8aba2cc6: Pulling fs layer Sep 5 00:39:21.727359 etcd-wrapper[1669]: 2ae710cd8bfe: Pulling fs layer Sep 5 00:39:21.728853 etcd-wrapper[1669]: d462aa345367: Pulling fs layer Sep 5 00:39:21.729738 etcd-wrapper[1669]: 0f8b424aa0b9: Pulling fs layer Sep 5 00:39:21.730664 etcd-wrapper[1669]: d557676654e5: Pulling fs layer Sep 5 00:39:21.731628 etcd-wrapper[1669]: c8022d07192e: Pulling fs layer Sep 5 00:39:21.732568 etcd-wrapper[1669]: d858cbc252ad: Pulling fs layer Sep 5 00:39:21.734762 etcd-wrapper[1669]: 1069fc2daed1: Pulling fs layer Sep 5 00:39:21.734762 etcd-wrapper[1669]: b40161cd83fc: Pulling fs layer Sep 5 00:39:21.734762 etcd-wrapper[1669]: 5318d93a3a65: Pulling fs layer Sep 5 00:39:21.736227 etcd-wrapper[1669]: 307c1adadb60: Pulling fs layer Sep 5 00:39:21.736227 etcd-wrapper[1669]: 49a553fa7ab9: Pulling fs layer Sep 5 00:39:21.736227 etcd-wrapper[1669]: 9cde5f0af3dd: Pulling fs layer Sep 5 00:39:21.736227 etcd-wrapper[1669]: b9581a18c15b: Pulling fs layer Sep 5 00:39:21.736227 etcd-wrapper[1669]: 95c11f3cd524: Pulling fs layer Sep 5 00:39:21.736227 etcd-wrapper[1669]: ed40c9b2e219: Pulling fs layer Sep 5 00:39:21.736227 etcd-wrapper[1669]: 0f8b424aa0b9: Waiting Sep 5 00:39:21.736227 etcd-wrapper[1669]: d557676654e5: Waiting Sep 5 00:39:21.736227 etcd-wrapper[1669]: c8022d07192e: Waiting Sep 5 00:39:21.736227 etcd-wrapper[1669]: d858cbc252ad: Waiting Sep 5 00:39:21.736227 etcd-wrapper[1669]: 1069fc2daed1: Waiting Sep 5 00:39:21.736227 etcd-wrapper[1669]: b40161cd83fc: Waiting Sep 5 00:39:21.736227 etcd-wrapper[1669]: 5318d93a3a65: Waiting Sep 5 00:39:21.736227 etcd-wrapper[1669]: 307c1adadb60: Waiting Sep 5 00:39:21.736227 etcd-wrapper[1669]: 49a553fa7ab9: Waiting Sep 5 00:39:21.736227 etcd-wrapper[1669]: 9cde5f0af3dd: Waiting Sep 5 00:39:21.736227 etcd-wrapper[1669]: b9581a18c15b: Waiting Sep 5 00:39:21.736227 etcd-wrapper[1669]: 95c11f3cd524: Waiting Sep 5 00:39:21.736227 etcd-wrapper[1669]: ed40c9b2e219: Waiting Sep 5 00:39:22.145884 etcd-wrapper[1669]: 804c8aba2cc6: Verifying Checksum Sep 5 00:39:22.145884 etcd-wrapper[1669]: 804c8aba2cc6: Download complete Sep 5 00:39:22.153997 etcd-wrapper[1669]: 2ae710cd8bfe: Verifying Checksum Sep 5 00:39:22.153997 etcd-wrapper[1669]: 2ae710cd8bfe: Download complete Sep 5 00:39:22.175324 etcd-wrapper[1669]: d462aa345367: Verifying Checksum Sep 5 00:39:22.176508 etcd-wrapper[1669]: d462aa345367: Download complete Sep 5 00:39:22.334321 etcd-wrapper[1669]: 804c8aba2cc6: Pull complete Sep 5 00:39:22.378037 systemd[1]: var-lib-docker-overlay2-3657d6e7ef06973b3481e0147effdbc3299236d8a328eccad1947a8bbcb1046b-merged.mount: Deactivated successfully. Sep 5 00:39:22.448608 etcd-wrapper[1669]: 2ae710cd8bfe: Pull complete Sep 5 00:39:22.540786 etcd-wrapper[1669]: d557676654e5: Verifying Checksum Sep 5 00:39:22.540786 etcd-wrapper[1669]: d557676654e5: Download complete Sep 5 00:39:22.543686 etcd-wrapper[1669]: 0f8b424aa0b9: Verifying Checksum Sep 5 00:39:22.543825 etcd-wrapper[1669]: 0f8b424aa0b9: Download complete Sep 5 00:39:22.571795 etcd-wrapper[1669]: c8022d07192e: Verifying Checksum Sep 5 00:39:22.572068 etcd-wrapper[1669]: c8022d07192e: Download complete Sep 5 00:39:22.894297 etcd-wrapper[1669]: b40161cd83fc: Verifying Checksum Sep 5 00:39:22.894297 etcd-wrapper[1669]: b40161cd83fc: Download complete Sep 5 00:39:22.894297 etcd-wrapper[1669]: d858cbc252ad: Verifying Checksum Sep 5 00:39:22.894297 etcd-wrapper[1669]: d858cbc252ad: Download complete Sep 5 00:39:22.900859 etcd-wrapper[1669]: 1069fc2daed1: Download complete Sep 5 00:39:23.225799 etcd-wrapper[1669]: 307c1adadb60: Verifying Checksum Sep 5 00:39:23.226537 etcd-wrapper[1669]: 307c1adadb60: Download complete Sep 5 00:39:23.226783 etcd-wrapper[1669]: 5318d93a3a65: Verifying Checksum Sep 5 00:39:23.226783 etcd-wrapper[1669]: 5318d93a3a65: Download complete Sep 5 00:39:23.386901 etcd-wrapper[1669]: 49a553fa7ab9: Verifying Checksum Sep 5 00:39:23.386901 etcd-wrapper[1669]: 49a553fa7ab9: Download complete Sep 5 00:39:23.772652 etcd-wrapper[1669]: 9cde5f0af3dd: Verifying Checksum Sep 5 00:39:23.772652 etcd-wrapper[1669]: 9cde5f0af3dd: Download complete Sep 5 00:39:23.780749 etcd-wrapper[1669]: 95c11f3cd524: Verifying Checksum Sep 5 00:39:23.780749 etcd-wrapper[1669]: 95c11f3cd524: Download complete Sep 5 00:39:23.800564 etcd-wrapper[1669]: b9581a18c15b: Download complete Sep 5 00:39:24.093181 etcd-wrapper[1669]: ed40c9b2e219: Verifying Checksum Sep 5 00:39:24.093181 etcd-wrapper[1669]: ed40c9b2e219: Download complete Sep 5 00:39:25.846095 systemd[1]: var-lib-docker-overlay2-04fb74726692d59a1ea76835f49966c24c551908fec38cdf6336ba115321335e-merged.mount: Deactivated successfully. Sep 5 00:39:26.104680 etcd-wrapper[1669]: d462aa345367: Pull complete Sep 5 00:39:26.189419 systemd[1]: var-lib-docker-overlay2-bc1c9a21623bfca0c1522f8582ae9ff059101668032739cb0a3484ea33cef8a8-merged.mount: Deactivated successfully. Sep 5 00:39:26.251734 etcd-wrapper[1669]: 0f8b424aa0b9: Pull complete Sep 5 00:39:26.360440 systemd[1]: var-lib-docker-overlay2-7b5efaafca6cb6e3dae3bc56ef854feebd2e91360f2149f7bc0f2f9a6ef8daf0-merged.mount: Deactivated successfully. Sep 5 00:39:26.397138 etcd-wrapper[1669]: d557676654e5: Pull complete Sep 5 00:39:26.524752 etcd-wrapper[1669]: c8022d07192e: Pull complete Sep 5 00:39:26.687902 etcd-wrapper[1669]: d858cbc252ad: Pull complete Sep 5 00:39:26.745693 etcd-wrapper[1669]: 1069fc2daed1: Pull complete Sep 5 00:39:26.849024 etcd-wrapper[1669]: b40161cd83fc: Pull complete Sep 5 00:39:26.908840 systemd[1]: var-lib-docker-overlay2-6c3b41e5ef1321a40f2ba3f7ac0ea201a1390963e73f97433e28e073513e7e00-merged.mount: Deactivated successfully. Sep 5 00:39:26.982400 etcd-wrapper[1669]: 5318d93a3a65: Pull complete Sep 5 00:39:27.099528 systemd[1]: var-lib-docker-overlay2-de21eca082d7bf9632511430f19f84d23cd84e988ad5817c73b9b4cd9312ac2f-merged.mount: Deactivated successfully. Sep 5 00:39:27.130581 etcd-wrapper[1669]: 307c1adadb60: Pull complete Sep 5 00:39:28.171771 systemd[1]: var-lib-docker-overlay2-05bba3f2db7aec6d4dc842367270446c599f863b8ce2cd8cadd266679acdca67-merged.mount: Deactivated successfully. Sep 5 00:39:28.257208 etcd-wrapper[1669]: 49a553fa7ab9: Pull complete Sep 5 00:39:28.825429 systemd[1]: Started sshd@3-10.0.0.104:22-10.0.0.1:33012.service - OpenSSH per-connection server daemon (10.0.0.1:33012). Sep 5 00:39:29.054802 systemd[1]: var-lib-docker-overlay2-c7ac55a02190847fb95cb8f3f5aee0fa6d045378b11c557dab328d9984931e48-merged.mount: Deactivated successfully. Sep 5 00:39:29.185819 etcd-wrapper[1669]: 9cde5f0af3dd: Pull complete Sep 5 00:39:29.244182 sshd[1747]: Accepted publickey for core from 10.0.0.1 port 33012 ssh2: RSA SHA256:vBP/QiE5BHLKInLouGxnlgPQzzw5kcI7zSBTWQ7Q9QU Sep 5 00:39:29.257686 sshd[1747]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 5 00:39:29.417297 systemd-logind[1395]: New session 4 of user core. Sep 5 00:39:29.438954 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 5 00:39:29.918296 sshd[1747]: pam_unix(sshd:session): session closed for user core Sep 5 00:39:30.024323 systemd[1]: Started sshd@4-10.0.0.104:22-10.0.0.1:33028.service - OpenSSH per-connection server daemon (10.0.0.1:33028). Sep 5 00:39:30.054997 systemd[1]: sshd@3-10.0.0.104:22-10.0.0.1:33012.service: Deactivated successfully. Sep 5 00:39:30.111988 systemd[1]: session-4.scope: Deactivated successfully. Sep 5 00:39:30.132339 systemd-logind[1395]: Session 4 logged out. Waiting for processes to exit. Sep 5 00:39:30.152135 systemd-logind[1395]: Removed session 4. Sep 5 00:39:30.414783 sshd[1757]: Accepted publickey for core from 10.0.0.1 port 33028 ssh2: RSA SHA256:vBP/QiE5BHLKInLouGxnlgPQzzw5kcI7zSBTWQ7Q9QU Sep 5 00:39:30.434918 sshd[1757]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 5 00:39:30.584329 systemd-logind[1395]: New session 5 of user core. Sep 5 00:39:30.636116 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 5 00:39:30.963444 systemd[1]: var-lib-docker-overlay2-ce21a0ead93e2103684ae4f9531df89ac6150eb7d17ba47360e603b535a48a5d-merged.mount: Deactivated successfully. Sep 5 00:39:31.162961 etcd-wrapper[1669]: b9581a18c15b: Pull complete Sep 5 00:39:31.495136 systemd[1]: var-lib-docker-overlay2-17405c06c8f429a76af462772381bdcab5f9942ba41a1955106fea7cd56dc95b-merged.mount: Deactivated successfully. Sep 5 00:39:31.570384 sshd[1757]: pam_unix(sshd:session): session closed for user core Sep 5 00:39:31.576556 etcd-wrapper[1669]: 95c11f3cd524: Pull complete Sep 5 00:39:31.700246 systemd[1]: sshd@4-10.0.0.104:22-10.0.0.1:33028.service: Deactivated successfully. Sep 5 00:39:31.834208 systemd[1]: session-5.scope: Deactivated successfully. Sep 5 00:39:31.863699 systemd[1]: var-lib-docker-overlay2-a648d4f7df7f88be8ac51ac45606e08e6e7f8f2db9f55d96d763f1badd0a7af4-merged.mount: Deactivated successfully. Sep 5 00:39:31.925903 systemd-logind[1395]: Session 5 logged out. Waiting for processes to exit. Sep 5 00:39:32.050224 systemd-logind[1395]: Removed session 5. Sep 5 00:39:32.056206 etcd-wrapper[1669]: ed40c9b2e219: Pull complete Sep 5 00:39:32.303288 etcd-wrapper[1669]: Digest: sha256:a193bdd3c1d1d11de961b6600231b2b3b4c4e092848dbb7d4c0e03b709ac7594 Sep 5 00:39:32.345008 etcd-wrapper[1669]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.24 Sep 5 00:39:32.688734 systemd[1]: var-lib-docker-overlay2-bb5d02ac32b217d7ac38eca335897d377a70b8f58f87083e34676ffaf1fe5763\x2dinit-merged.mount: Deactivated successfully. Sep 5 00:39:32.986332 systemd[1]: var-lib-docker-overlay2-bb5d02ac32b217d7ac38eca335897d377a70b8f58f87083e34676ffaf1fe5763-merged.mount: Deactivated successfully. Sep 5 00:39:35.095173 containerd[1411]: time="2026-09-05T00:39:35.093198967Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 5 00:39:35.095173 containerd[1411]: time="2026-09-05T00:39:35.094983015Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 5 00:39:35.157200 containerd[1411]: time="2026-09-05T00:39:35.095081630Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 5 00:39:35.157200 containerd[1411]: time="2026-09-05T00:39:35.097105082Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 5 00:39:35.684969 systemd[1]: run-docker-runtime\x2drunc-moby-fee0517c7f4943aeb60e5fa182f39b2fee6e297e87f3716155905a96e58821ae-runc.7m37CJ.mount: Deactivated successfully. Sep 5 00:39:35.836192 systemd[1]: Started docker-fee0517c7f4943aeb60e5fa182f39b2fee6e297e87f3716155905a96e58821ae.scope - libcontainer container fee0517c7f4943aeb60e5fa182f39b2fee6e297e87f3716155905a96e58821ae. Sep 5 00:39:36.649891 systemd-resolved[1281]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 5 00:39:37.750304 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:37.743774Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} Sep 5 00:39:37.750304 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:37.747840Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"63172615560e4f86aceb290308085849"} Sep 5 00:39:37.750304 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:39:37.747952Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} Sep 5 00:39:37.750304 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:39:37.747963Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.24"} Sep 5 00:39:37.750304 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:39:37.747969Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} Sep 5 00:39:37.750304 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:39:37.747974Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} Sep 5 00:39:37.804722 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:39:37.748203Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Sep 5 00:39:37.804722 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:37.749128Z","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.104:2379"]} Sep 5 00:39:37.804722 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:39:37.750148Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Sep 5 00:39:37.804722 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:37.750163Z","caller":"embed/etcd.go:140","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]} Sep 5 00:39:37.987028 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:37.983070Z","caller":"embed/etcd.go:148","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} Sep 5 00:39:38.014782 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.003035Z","caller":"embed/etcd.go:323","msg":"starting an etcd server","etcd-version":"3.5.24","git-sha":"e72f3c2","go-version":"go1.24.9","go-os":"linux","go-arch":"amd64","max-cpu-set":4,"max-cpu-available":4,"member-initialized":false,"name":"63172615560e4f86aceb290308085849","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.104:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"63172615560e4f86aceb290308085849=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 5 00:39:38.123400 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.113022Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"69.094466ms"} Sep 5 00:39:38.242758 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.234059Z","caller":"etcdserver/raft.go:507","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"} Sep 5 00:39:38.242758 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.237267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"} Sep 5 00:39:38.242758 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.238028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"} Sep 5 00:39:38.242758 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.238169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} Sep 5 00:39:38.242758 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.238534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"} Sep 5 00:39:38.242758 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.238642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Sep 5 00:39:38.378948 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:39:38.363271Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} Sep 5 00:39:38.408650 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.379138Z","caller":"mvcc/kvstore.go:425","msg":"kvstore restored","current-rev":1} Sep 5 00:39:38.439013 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.401806Z","caller":"etcdserver/server.go:628","msg":"restore consistentIndex","index":0} Sep 5 00:39:38.446658 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.411630Z","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 5 00:39:38.482645 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.475376Z","caller":"etcdserver/server.go:875","msg":"starting etcd server","local-member-id":"8e9e05c52164694d","local-server-version":"3.5.24","cluster-version":"to_be_decided"} Sep 5 00:39:38.482645 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.477883Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Sep 5 00:39:38.502917 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.485683Z","caller":"etcdserver/server.go:759","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 5 00:39:38.532932 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.496918Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} Sep 5 00:39:38.532932 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.498418Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} Sep 5 00:39:38.532932 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.499149Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} Sep 5 00:39:38.532932 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.527371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Sep 5 00:39:38.593067 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.531082Z","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"],"added-peer-is-learner":false} Sep 5 00:39:38.605068 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.595031Z","caller":"embed/etcd.go:292","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.104:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} Sep 5 00:39:38.605068 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.595117Z","caller":"embed/etcd.go:633","msg":"serving peer traffic","address":"127.0.0.1:2380"} Sep 5 00:39:38.605068 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.596959Z","caller":"embed/etcd.go:603","msg":"cmux::serve","address":"127.0.0.1:2380"} Sep 5 00:39:38.822699 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.751107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"} Sep 5 00:39:38.822699 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.754278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"} Sep 5 00:39:38.822699 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.755068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"} Sep 5 00:39:38.822699 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.755540Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"} Sep 5 00:39:38.822699 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.755609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"} Sep 5 00:39:38.822699 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.755625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"} Sep 5 00:39:38.822699 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.755636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"} Sep 5 00:39:38.822699 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.787943Z","caller":"etcdserver/server.go:2158","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:63172615560e4f86aceb290308085849 ClientURLs:[http://10.0.0.104:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"} Sep 5 00:39:38.839600 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.821948Z","caller":"etcdserver/server.go:2711","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} Sep 5 00:39:38.839600 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.827132Z","caller":"membership/cluster.go:587","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"} Sep 5 00:39:38.836865 systemd[1]: Started etcd-member.service - etcd (System Application Container). Sep 5 00:39:38.873086 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 5 00:39:38.883573 systemd[1]: Startup finished in 10.762s (kernel) + 34.213s (initrd) + 54.928s (userspace) = 1min 39.904s. Sep 5 00:39:38.945798 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.832005Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} Sep 5 00:39:38.945798 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.834320Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} Sep 5 00:39:38.945798 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.834564Z","caller":"etcdserver/server.go:2735","msg":"cluster version is updated","cluster-version":"3.5"} Sep 5 00:39:38.945798 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.835201Z","caller":"embed/serve.go:124","msg":"ready to serve client requests"} Sep 5 00:39:38.945798 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.835961Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Sep 5 00:39:38.945798 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.838281Z","caller":"embed/serve.go:210","msg":"serving client traffic insecurely; this is strongly discouraged!","traffic":"grpc+http","address":"[::]:2379"} Sep 5 00:39:38.945798 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:39:38.844221Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} Sep 5 00:39:42.192344 systemd[1]: Started sshd@5-10.0.0.104:22-10.0.0.1:43612.service - OpenSSH per-connection server daemon (10.0.0.1:43612). Sep 5 00:39:44.268095 sshd[1826]: Accepted publickey for core from 10.0.0.1 port 43612 ssh2: RSA SHA256:vBP/QiE5BHLKInLouGxnlgPQzzw5kcI7zSBTWQ7Q9QU Sep 5 00:39:44.400141 sshd[1826]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 5 00:39:44.841136 systemd-logind[1395]: New session 6 of user core. Sep 5 00:39:45.107348 systemd[1]: Started session-6.scope - Session 6 of User core. Sep 5 00:39:46.172742 sshd[1826]: pam_unix(sshd:session): session closed for user core Sep 5 00:39:46.381260 systemd[1]: sshd@5-10.0.0.104:22-10.0.0.1:43612.service: Deactivated successfully. Sep 5 00:39:46.507219 systemd[1]: session-6.scope: Deactivated successfully. Sep 5 00:39:46.563185 systemd-logind[1395]: Session 6 logged out. Waiting for processes to exit. Sep 5 00:39:46.760971 systemd[1]: Started sshd@6-10.0.0.104:22-10.0.0.1:43614.service - OpenSSH per-connection server daemon (10.0.0.1:43614). Sep 5 00:39:46.833067 systemd-logind[1395]: Removed session 6. Sep 5 00:39:47.915166 sshd[1833]: Accepted publickey for core from 10.0.0.1 port 43614 ssh2: RSA SHA256:vBP/QiE5BHLKInLouGxnlgPQzzw5kcI7zSBTWQ7Q9QU Sep 5 00:39:48.017410 sshd[1833]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 5 00:39:48.509377 systemd-logind[1395]: New session 7 of user core. Sep 5 00:39:48.634317 systemd[1]: Started session-7.scope - Session 7 of User core. Sep 5 00:39:49.251409 sshd[1833]: pam_unix(sshd:session): session closed for user core Sep 5 00:39:49.441296 systemd[1]: sshd@6-10.0.0.104:22-10.0.0.1:43614.service: Deactivated successfully. Sep 5 00:39:49.503058 systemd[1]: session-7.scope: Deactivated successfully. Sep 5 00:39:49.563408 systemd-logind[1395]: Session 7 logged out. Waiting for processes to exit. Sep 5 00:39:49.652920 systemd[1]: Started sshd@7-10.0.0.104:22-10.0.0.1:36524.service - OpenSSH per-connection server daemon (10.0.0.1:36524). Sep 5 00:39:49.734258 systemd-logind[1395]: Removed session 7. Sep 5 00:39:51.272574 sshd[1840]: Accepted publickey for core from 10.0.0.1 port 36524 ssh2: RSA SHA256:vBP/QiE5BHLKInLouGxnlgPQzzw5kcI7zSBTWQ7Q9QU Sep 5 00:39:51.396245 sshd[1840]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 5 00:39:51.873899 systemd-logind[1395]: New session 8 of user core. Sep 5 00:39:51.939559 systemd[1]: Started session-8.scope - Session 8 of User core. Sep 5 00:39:53.122251 sshd[1840]: pam_unix(sshd:session): session closed for user core Sep 5 00:39:53.436303 systemd[1]: sshd@7-10.0.0.104:22-10.0.0.1:36524.service: Deactivated successfully. Sep 5 00:39:53.520104 systemd[1]: session-8.scope: Deactivated successfully. Sep 5 00:39:53.625325 systemd-logind[1395]: Session 8 logged out. Waiting for processes to exit. Sep 5 00:39:53.810911 systemd[1]: Started sshd@8-10.0.0.104:22-10.0.0.1:36530.service - OpenSSH per-connection server daemon (10.0.0.1:36530). Sep 5 00:39:53.911975 systemd-logind[1395]: Removed session 8. Sep 5 00:39:54.808174 sshd[1847]: Accepted publickey for core from 10.0.0.1 port 36530 ssh2: RSA SHA256:vBP/QiE5BHLKInLouGxnlgPQzzw5kcI7zSBTWQ7Q9QU Sep 5 00:39:54.915398 sshd[1847]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 5 00:39:54.988349 update_engine[1398]: I20260905 00:39:54.986047 1398 update_attempter.cc:509] Updating boot flags... Sep 5 00:39:55.297202 systemd-logind[1395]: New session 9 of user core. Sep 5 00:39:55.421835 systemd[1]: Started session-9.scope - Session 9 of User core. Sep 5 00:39:55.798245 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 31 scanned by (udev-worker) (1856) Sep 5 00:39:56.171081 sudo[1863]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Sep 5 00:39:56.173812 sudo[1863]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 5 00:39:56.448602 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 31 scanned by (udev-worker) (1860) Sep 5 00:39:56.709073 sudo[1863]: pam_unix(sudo:session): session closed for user root Sep 5 00:39:56.794524 sshd[1847]: pam_unix(sshd:session): session closed for user core Sep 5 00:39:56.920303 systemd[1]: sshd@8-10.0.0.104:22-10.0.0.1:36530.service: Deactivated successfully. Sep 5 00:39:57.027860 systemd[1]: session-9.scope: Deactivated successfully. Sep 5 00:39:57.152810 systemd-logind[1395]: Session 9 logged out. Waiting for processes to exit. Sep 5 00:39:57.176143 systemd[1]: Started sshd@9-10.0.0.104:22-10.0.0.1:36544.service - OpenSSH per-connection server daemon (10.0.0.1:36544). Sep 5 00:39:57.397966 systemd-logind[1395]: Removed session 9. Sep 5 00:39:58.715822 sshd[1869]: Accepted publickey for core from 10.0.0.1 port 36544 ssh2: RSA SHA256:vBP/QiE5BHLKInLouGxnlgPQzzw5kcI7zSBTWQ7Q9QU Sep 5 00:39:58.780806 sshd[1869]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 5 00:39:59.123102 systemd-logind[1395]: New session 10 of user core. Sep 5 00:39:59.168903 systemd[1]: Started session-10.scope - Session 10 of User core. Sep 5 00:40:00.202319 sudo[1874]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Sep 5 00:40:00.225895 sudo[1874]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 5 00:40:00.444319 sudo[1874]: pam_unix(sudo:session): session closed for user root Sep 5 00:40:00.766336 sudo[1873]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/systemctl restart audit-rules Sep 5 00:40:00.774242 sudo[1873]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 5 00:40:01.842233 systemd[1]: Stopping audit-rules.service - Load Security Auditing Rules... Sep 5 00:40:02.076866 auditctl[1877]: No rules Sep 5 00:40:02.179247 systemd[1]: audit-rules.service: Deactivated successfully. Sep 5 00:40:02.192338 systemd[1]: Stopped audit-rules.service - Load Security Auditing Rules. Sep 5 00:40:02.476330 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Sep 5 00:40:03.662864 augenrules[1895]: No rules Sep 5 00:40:03.763247 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Sep 5 00:40:03.777951 sudo[1873]: pam_unix(sudo:session): session closed for user root Sep 5 00:40:03.806857 sshd[1869]: pam_unix(sshd:session): session closed for user core Sep 5 00:40:03.917269 systemd[1]: sshd@9-10.0.0.104:22-10.0.0.1:36544.service: Deactivated successfully. Sep 5 00:40:04.024234 systemd[1]: session-10.scope: Deactivated successfully. Sep 5 00:40:04.117071 systemd-logind[1395]: Session 10 logged out. Waiting for processes to exit. Sep 5 00:40:04.244648 systemd[1]: Started sshd@10-10.0.0.104:22-10.0.0.1:52668.service - OpenSSH per-connection server daemon (10.0.0.1:52668). Sep 5 00:40:04.287998 systemd-logind[1395]: Removed session 10. Sep 5 00:40:05.534352 sshd[1903]: Accepted publickey for core from 10.0.0.1 port 52668 ssh2: RSA SHA256:vBP/QiE5BHLKInLouGxnlgPQzzw5kcI7zSBTWQ7Q9QU Sep 5 00:40:05.641198 sshd[1903]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 5 00:40:05.932671 systemd-logind[1395]: New session 11 of user core. Sep 5 00:40:06.014034 systemd[1]: Started session-11.scope - Session 11 of User core. Sep 5 00:40:06.490156 sshd[1903]: pam_unix(sshd:session): session closed for user core Sep 5 00:40:06.652261 systemd[1]: sshd@10-10.0.0.104:22-10.0.0.1:52668.service: Deactivated successfully. Sep 5 00:40:06.739124 systemd[1]: session-11.scope: Deactivated successfully. Sep 5 00:40:06.836096 systemd-logind[1395]: Session 11 logged out. Waiting for processes to exit. Sep 5 00:40:06.936184 systemd[1]: Started sshd@11-10.0.0.104:22-10.0.0.1:52678.service - OpenSSH per-connection server daemon (10.0.0.1:52678). Sep 5 00:40:07.024343 systemd-logind[1395]: Removed session 11. Sep 5 00:40:08.311770 sshd[1910]: Accepted publickey for core from 10.0.0.1 port 52678 ssh2: RSA SHA256:vBP/QiE5BHLKInLouGxnlgPQzzw5kcI7zSBTWQ7Q9QU Sep 5 00:40:08.399065 sshd[1910]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 5 00:40:08.829990 systemd-logind[1395]: New session 12 of user core. Sep 5 00:40:08.980932 systemd[1]: Started session-12.scope - Session 12 of User core. Sep 5 00:40:10.537995 sshd[1910]: pam_unix(sshd:session): session closed for user core Sep 5 00:40:10.714309 systemd[1]: sshd@11-10.0.0.104:22-10.0.0.1:52678.service: Deactivated successfully. Sep 5 00:40:10.813926 systemd[1]: session-12.scope: Deactivated successfully. Sep 5 00:40:10.872659 systemd-logind[1395]: Session 12 logged out. Waiting for processes to exit. Sep 5 00:40:10.943206 systemd-logind[1395]: Removed session 12. Sep 5 00:42:35.150386 update_engine[1398]: I20260905 00:42:35.138265 1398 prefs.cc:52] certificate-report-to-send-update not present in /var/lib/update_engine/prefs Sep 5 00:42:35.150386 update_engine[1398]: I20260905 00:42:35.144847 1398 prefs.cc:52] certificate-report-to-send-download not present in /var/lib/update_engine/prefs Sep 5 00:42:35.218059 update_engine[1398]: I20260905 00:42:35.182793 1398 prefs.cc:52] aleph-version not present in /var/lib/update_engine/prefs Sep 5 00:42:35.226380 update_engine[1398]: I20260905 00:42:35.226019 1398 omaha_request_params.cc:62] Current group set to lts Sep 5 00:42:35.227662 update_engine[1398]: I20260905 00:42:35.227002 1398 update_attempter.cc:499] Already updated boot flags. Skipping. Sep 5 00:42:35.227662 update_engine[1398]: I20260905 00:42:35.227377 1398 update_attempter.cc:643] Scheduling an action processor start. Sep 5 00:42:35.235124 update_engine[1398]: I20260905 00:42:35.229619 1398 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Sep 5 00:42:35.235124 update_engine[1398]: I20260905 00:42:35.231236 1398 prefs.cc:52] previous-version not present in /var/lib/update_engine/prefs Sep 5 00:42:35.235124 update_engine[1398]: I20260905 00:42:35.233127 1398 omaha_request_action.cc:271] Posting an Omaha request to disabled Sep 5 00:42:35.235124 update_engine[1398]: I20260905 00:42:35.233275 1398 omaha_request_action.cc:272] Request: Sep 5 00:42:35.235124 update_engine[1398]: Sep 5 00:42:35.235124 update_engine[1398]: Sep 5 00:42:35.235124 update_engine[1398]: Sep 5 00:42:35.235124 update_engine[1398]: Sep 5 00:42:35.235124 update_engine[1398]: Sep 5 00:42:35.235124 update_engine[1398]: Sep 5 00:42:35.235124 update_engine[1398]: Sep 5 00:42:35.235124 update_engine[1398]: Sep 5 00:42:35.235124 update_engine[1398]: I20260905 00:42:35.233285 1398 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Sep 5 00:42:35.239262 locksmithd[1458]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_CHECKING_FOR_UPDATE" NewVersion=0.0.0 NewSize=0 Sep 5 00:42:35.270743 update_engine[1398]: I20260905 00:42:35.270115 1398 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Sep 5 00:42:35.273582 update_engine[1398]: I20260905 00:42:35.272145 1398 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Sep 5 00:42:35.317120 update_engine[1398]: E20260905 00:42:35.315201 1398 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Sep 5 00:42:35.324839 update_engine[1398]: I20260905 00:42:35.320993 1398 libcurl_http_fetcher.cc:283] No HTTP response, retry 1 Sep 5 00:42:46.008946 update_engine[1398]: I20260905 00:42:46.004690 1398 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Sep 5 00:42:46.038013 update_engine[1398]: I20260905 00:42:46.020938 1398 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Sep 5 00:42:46.041194 update_engine[1398]: I20260905 00:42:46.040380 1398 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Sep 5 00:42:46.108135 update_engine[1398]: E20260905 00:42:46.106917 1398 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Sep 5 00:42:46.111519 update_engine[1398]: I20260905 00:42:46.109206 1398 libcurl_http_fetcher.cc:283] No HTTP response, retry 2 Sep 5 00:42:56.008935 update_engine[1398]: I20260905 00:42:56.006718 1398 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Sep 5 00:42:56.028041 update_engine[1398]: I20260905 00:42:56.026273 1398 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Sep 5 00:42:56.059138 update_engine[1398]: I20260905 00:42:56.037055 1398 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Sep 5 00:42:56.107291 update_engine[1398]: E20260905 00:42:56.080576 1398 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Sep 5 00:42:56.107291 update_engine[1398]: I20260905 00:42:56.081018 1398 libcurl_http_fetcher.cc:283] No HTTP response, retry 3 Sep 5 00:43:05.994786 update_engine[1398]: I20260905 00:43:05.990343 1398 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Sep 5 00:43:06.013693 update_engine[1398]: I20260905 00:43:06.000922 1398 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Sep 5 00:43:06.013693 update_engine[1398]: I20260905 00:43:06.007350 1398 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Sep 5 00:43:06.127380 update_engine[1398]: E20260905 00:43:06.082635 1398 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Sep 5 00:43:06.134557 update_engine[1398]: I20260905 00:43:06.133162 1398 libcurl_http_fetcher.cc:297] Transfer resulted in an error (0), 0 bytes downloaded Sep 5 00:43:06.143940 update_engine[1398]: I20260905 00:43:06.139917 1398 omaha_request_action.cc:617] Omaha request response: Sep 5 00:43:06.146984 update_engine[1398]: E20260905 00:43:06.145183 1398 omaha_request_action.cc:636] Omaha request network transfer failed. Sep 5 00:43:06.146984 update_engine[1398]: I20260905 00:43:06.145496 1398 action_processor.cc:68] ActionProcessor::ActionComplete: OmahaRequestAction action failed. Aborting processing. Sep 5 00:43:06.146984 update_engine[1398]: I20260905 00:43:06.145514 1398 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Sep 5 00:43:06.146984 update_engine[1398]: I20260905 00:43:06.145520 1398 update_attempter.cc:306] Processing Done. Sep 5 00:43:06.146984 update_engine[1398]: E20260905 00:43:06.145545 1398 update_attempter.cc:619] Update failed. Sep 5 00:43:06.146984 update_engine[1398]: I20260905 00:43:06.145551 1398 utils.cc:600] Converting error code 2000 to kActionCodeOmahaErrorInHTTPResponse Sep 5 00:43:06.146984 update_engine[1398]: I20260905 00:43:06.145561 1398 payload_state.cc:97] Updating payload state for error code: 37 (kActionCodeOmahaErrorInHTTPResponse) Sep 5 00:43:06.146984 update_engine[1398]: I20260905 00:43:06.145567 1398 payload_state.cc:103] Ignoring failures until we get a valid Omaha response. Sep 5 00:43:06.146984 update_engine[1398]: I20260905 00:43:06.145825 1398 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Sep 5 00:43:06.146984 update_engine[1398]: I20260905 00:43:06.145917 1398 omaha_request_action.cc:271] Posting an Omaha request to disabled Sep 5 00:43:06.146984 update_engine[1398]: I20260905 00:43:06.145924 1398 omaha_request_action.cc:272] Request: Sep 5 00:43:06.146984 update_engine[1398]: Sep 5 00:43:06.146984 update_engine[1398]: Sep 5 00:43:06.146984 update_engine[1398]: Sep 5 00:43:06.146984 update_engine[1398]: Sep 5 00:43:06.146984 update_engine[1398]: Sep 5 00:43:06.146984 update_engine[1398]: Sep 5 00:43:06.146984 update_engine[1398]: I20260905 00:43:06.145936 1398 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Sep 5 00:43:06.150926 update_engine[1398]: I20260905 00:43:06.149153 1398 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Sep 5 00:43:06.150926 update_engine[1398]: I20260905 00:43:06.149681 1398 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Sep 5 00:43:06.150996 locksmithd[1458]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_REPORTING_ERROR_EVENT" NewVersion=0.0.0 NewSize=0 Sep 5 00:43:06.191868 update_engine[1398]: E20260905 00:43:06.191334 1398 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Sep 5 00:43:06.191868 update_engine[1398]: I20260905 00:43:06.191779 1398 libcurl_http_fetcher.cc:297] Transfer resulted in an error (0), 0 bytes downloaded Sep 5 00:43:06.191868 update_engine[1398]: I20260905 00:43:06.191795 1398 omaha_request_action.cc:617] Omaha request response: Sep 5 00:43:06.191868 update_engine[1398]: I20260905 00:43:06.191807 1398 action_processor.cc:65] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Sep 5 00:43:06.191868 update_engine[1398]: I20260905 00:43:06.191816 1398 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Sep 5 00:43:06.191868 update_engine[1398]: I20260905 00:43:06.191824 1398 update_attempter.cc:306] Processing Done. Sep 5 00:43:06.191868 update_engine[1398]: I20260905 00:43:06.191833 1398 update_attempter.cc:310] Error event sent. Sep 5 00:43:06.191868 update_engine[1398]: I20260905 00:43:06.191851 1398 update_check_scheduler.cc:74] Next update check in 48m8s Sep 5 00:43:06.195695 locksmithd[1458]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_IDLE" NewVersion=0.0.0 NewSize=0 Sep 5 00:43:20.610333 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:43:20.591705Z","caller":"traceutil/trace.go:171","msg":"trace[165974888] transaction","detail":"{read_only:false; response_revision:54; number_of_response:1; }","duration":"106.638961ms","start":"2026-09-05T00:43:20.485022Z","end":"2026-09-05T00:43:20.591661Z","steps":["trace[165974888] 'process raft request' (duration: 95.310503ms)"],"step_count":1} Sep 5 00:43:22.102337 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:43:22.085110Z","caller":"traceutil/trace.go:171","msg":"trace[1667005956] transaction","detail":"{read_only:false; response_revision:69; number_of_response:1; }","duration":"113.744121ms","start":"2026-09-05T00:43:21.971334Z","end":"2026-09-05T00:43:22.085078Z","steps":["trace[1667005956] 'process raft request' (duration: 13.771865ms)","trace[1667005956] 'compare' (duration: 92.868714ms)"],"step_count":2} Sep 5 00:43:23.556362 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:43:23.538057Z","caller":"traceutil/trace.go:171","msg":"trace[2070079532] transaction","detail":"{read_only:false; response_revision:88; number_of_response:1; }","duration":"114.948774ms","start":"2026-09-05T00:43:23.423090Z","end":"2026-09-05T00:43:23.538039Z","steps":["trace[2070079532] 'process raft request' (duration: 114.81342ms)"],"step_count":1} Sep 5 00:43:25.466168 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:43:25.460821Z","caller":"traceutil/trace.go:171","msg":"trace[1530631702] linearizableReadLoop","detail":"{readStateIndex:108; appliedIndex:107; }","duration":"105.385358ms","start":"2026-09-05T00:43:25.355411Z","end":"2026-09-05T00:43:25.460796Z","steps":["trace[1530631702] 'read index received' (duration: 14.618926ms)","trace[1530631702] 'applied index is now lower than readState.Index' (duration: 90.765719ms)"],"step_count":2} Sep 5 00:43:25.466168 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:43:25.461123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.989002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/localhost.18d245ff13987313\" limit:1 ","response":"range_response_count:1 size:650"} Sep 5 00:43:25.466168 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:43:25.461153Z","caller":"traceutil/trace.go:171","msg":"trace[1043274327] range","detail":"{range_begin:/registry/events/default/localhost.18d245ff13987313; range_end:; response_count:1; response_revision:103; }","duration":"107.088099ms","start":"2026-09-05T00:43:25.354057Z","end":"2026-09-05T00:43:25.461146Z","steps":["trace[1043274327] 'agreement among raft nodes before linearized reading' (duration: 106.941356ms)"],"step_count":1} Sep 5 00:43:25.466168 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:43:25.462501Z","caller":"traceutil/trace.go:171","msg":"trace[840260816] transaction","detail":"{read_only:false; response_revision:103; number_of_response:1; }","duration":"113.518342ms","start":"2026-09-05T00:43:25.348921Z","end":"2026-09-05T00:43:25.462439Z","steps":["trace[840260816] 'process raft request' (duration: 21.181588ms)","trace[840260816] 'compare' (duration: 90.596217ms)"],"step_count":2} Sep 5 00:43:34.255184 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:43:34.251841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.677762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:service-account-controller\" limit:1 ","response":"range_response_count:0 size:5"} Sep 5 00:43:34.324755 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:43:34.310122Z","caller":"traceutil/trace.go:171","msg":"trace[1374065101] range","detail":"{range_begin:/registry/clusterroles/system:controller:service-account-controller; range_end:; response_count:0; response_revision:189; }","duration":"685.90586ms","start":"2026-09-05T00:43:33.566133Z","end":"2026-09-05T00:43:34.252038Z","steps":["trace[1374065101] 'range keys from in-memory index tree' (duration: 683.832992ms)"],"step_count":1} Sep 5 00:43:34.324755 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:43:34.310449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T00:43:33.566105Z","time spent":"744.312862ms","remote":"10.0.0.109:36464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":29,"request content":"key:\"/registry/clusterroles/system:controller:service-account-controller\" limit:1 "} Sep 5 00:43:34.464514 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:43:34.455654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.920709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-scheduler-localhost.18d24603bbf66238\" limit:1 ","response":"range_response_count:1 size:779"} Sep 5 00:43:34.464514 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:43:34.455811Z","caller":"traceutil/trace.go:171","msg":"trace[1022989443] range","detail":"{range_begin:/registry/events/kube-system/kube-scheduler-localhost.18d24603bbf66238; range_end:; response_count:1; response_revision:189; }","duration":"386.073956ms","start":"2026-09-05T00:43:34.069708Z","end":"2026-09-05T00:43:34.455782Z","steps":["trace[1022989443] 'range keys from in-memory index tree' (duration: 385.739317ms)"],"step_count":1} Sep 5 00:43:34.464514 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:43:34.455847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T00:43:34.069690Z","time spent":"386.146606ms","remote":"10.0.0.109:35898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":803,"request content":"key:\"/registry/events/kube-system/kube-scheduler-localhost.18d24603bbf66238\" limit:1 "} Sep 5 00:43:58.979378 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:43:58.974154Z","caller":"traceutil/trace.go:171","msg":"trace[565737392] linearizableReadLoop","detail":"{readStateIndex:356; appliedIndex:355; }","duration":"100.536825ms","start":"2026-09-05T00:43:58.873584Z","end":"2026-09-05T00:43:58.974121Z","steps":["trace[565737392] 'read index received' (duration: 95.818056ms)","trace[565737392] 'applied index is now lower than readState.Index' (duration: 4.718362ms)"],"step_count":2} Sep 5 00:43:58.979378 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:43:58.974417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.824551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs\" limit:1 ","response":"range_response_count:0 size:5"} Sep 5 00:43:58.979378 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:43:58.974445Z","caller":"traceutil/trace.go:171","msg":"trace[1159376166] range","detail":"{range_begin:/registry/services/specs; range_end:; response_count:0; response_revision:350; }","duration":"100.865431ms","start":"2026-09-05T00:43:58.873571Z","end":"2026-09-05T00:43:58.974437Z","steps":["trace[1159376166] 'agreement among raft nodes before linearized reading' (duration: 100.785047ms)"],"step_count":1} Sep 5 00:43:58.979378 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:43:58.974534Z","caller":"traceutil/trace.go:171","msg":"trace[1501602282] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"102.410729ms","start":"2026-09-05T00:43:58.872117Z","end":"2026-09-05T00:43:58.974528Z","steps":["trace[1501602282] 'process raft request' (duration: 99.293101ms)"],"step_count":1} Sep 5 00:44:17.069293 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:44:17.059896Z","caller":"traceutil/trace.go:171","msg":"trace[156703248] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"101.996331ms","start":"2026-09-05T00:44:16.957858Z","end":"2026-09-05T00:44:17.059855Z","steps":["trace[156703248] 'process raft request' (duration: 19.188841ms)","trace[156703248] 'compare' (duration: 79.975027ms)"],"step_count":2} Sep 5 00:44:21.998690 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:44:21.990204Z","caller":"traceutil/trace.go:171","msg":"trace[1149646061] linearizableReadLoop","detail":"{readStateIndex:492; appliedIndex:491; }","duration":"110.798436ms","start":"2026-09-05T00:44:21.879380Z","end":"2026-09-05T00:44:21.990179Z","steps":["trace[1149646061] 'read index received' (duration: 100.942617ms)","trace[1149646061] 'applied index is now lower than readState.Index' (duration: 9.855287ms)"],"step_count":2} Sep 5 00:44:21.998690 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:44:21.994235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.832665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" limit:1 ","response":"range_response_count:1 size:472"} Sep 5 00:44:21.998690 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:44:21.994443Z","caller":"traceutil/trace.go:171","msg":"trace[1869066032] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:478; }","duration":"115.05278ms","start":"2026-09-05T00:44:21.879376Z","end":"2026-09-05T00:44:21.994429Z","steps":["trace[1869066032] 'agreement among raft nodes before linearized reading' (duration: 114.561641ms)"],"step_count":1} Sep 5 00:44:21.998690 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:44:21.995078Z","caller":"traceutil/trace.go:171","msg":"trace[618464193] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"116.615667ms","start":"2026-09-05T00:44:21.878447Z","end":"2026-09-05T00:44:21.995063Z","steps":["trace[618464193] 'process raft request' (duration: 101.958747ms)"],"step_count":1} Sep 5 00:44:22.041130 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:44:22.000740Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.154737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets\" limit:1 ","response":"range_response_count:0 size:5"} Sep 5 00:44:22.041130 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:44:22.000955Z","caller":"traceutil/trace.go:171","msg":"trace[732850204] range","detail":"{range_begin:/registry/daemonsets; range_end:; response_count:0; response_revision:478; }","duration":"121.394531ms","start":"2026-09-05T00:44:21.879546Z","end":"2026-09-05T00:44:22.000940Z","steps":["trace[732850204] 'agreement among raft nodes before linearized reading' (duration: 121.111481ms)"],"step_count":1} Sep 5 00:44:22.883797 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:44:22.881975Z","caller":"traceutil/trace.go:171","msg":"trace[828401045] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"116.119479ms","start":"2026-09-05T00:44:22.765827Z","end":"2026-09-05T00:44:22.881946Z","steps":["trace[828401045] 'process raft request' (duration: 98.321128ms)","trace[828401045] 'compare' (duration: 14.537754ms)"],"step_count":2} Sep 5 00:44:23.093638 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:44:23.082807Z","caller":"traceutil/trace.go:171","msg":"trace[1946931992] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"123.392273ms","start":"2026-09-05T00:44:22.959393Z","end":"2026-09-05T00:44:23.082785Z","steps":["trace[1946931992] 'process raft request' (duration: 82.413519ms)","trace[1946931992] 'compare' (duration: 40.632338ms)"],"step_count":2} Sep 5 00:44:23.093638 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:44:23.082994Z","caller":"traceutil/trace.go:171","msg":"trace[1559226389] linearizableReadLoop","detail":"{readStateIndex:503; appliedIndex:502; }","duration":"112.646459ms","start":"2026-09-05T00:44:22.970341Z","end":"2026-09-05T00:44:23.082987Z","steps":["trace[1559226389] 'read index received' (duration: 71.414855ms)","trace[1559226389] 'applied index is now lower than readState.Index' (duration: 41.228295ms)"],"step_count":2} Sep 5 00:44:23.093638 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:44:23.083107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.755508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-78cf5644cb-t9nsc\" limit:1 ","response":"range_response_count:1 size:3226"} Sep 5 00:44:23.093638 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:44:23.083126Z","caller":"traceutil/trace.go:171","msg":"trace[961706734] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-78cf5644cb-t9nsc; range_end:; response_count:1; response_revision:490; }","duration":"112.784364ms","start":"2026-09-05T00:44:22.970337Z","end":"2026-09-05T00:44:23.083121Z","steps":["trace[961706734] 'agreement among raft nodes before linearized reading' (duration: 112.711096ms)"],"step_count":1} Sep 5 00:44:23.102343 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:44:23.086938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.009972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" limit:1 ","response":"range_response_count:1 size:185"} Sep 5 00:44:23.102343 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:44:23.087150Z","caller":"traceutil/trace.go:171","msg":"trace[119336777] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:490; }","duration":"116.231985ms","start":"2026-09-05T00:44:22.970905Z","end":"2026-09-05T00:44:23.087137Z","steps":["trace[119336777] 'agreement among raft nodes before linearized reading' (duration: 115.962492ms)"],"step_count":1} Sep 5 00:44:23.102343 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:44:23.087228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.279861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Sep 5 00:44:23.102343 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:44:23.087241Z","caller":"traceutil/trace.go:171","msg":"trace[2054581939] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:490; }","duration":"106.293531ms","start":"2026-09-05T00:44:22.980943Z","end":"2026-09-05T00:44:23.087236Z","steps":["trace[2054581939] 'agreement among raft nodes before linearized reading' (duration: 106.270117ms)"],"step_count":1} Sep 5 00:44:31.808520 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:44:31.776992Z","caller":"traceutil/trace.go:171","msg":"trace[378779207] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"121.833646ms","start":"2026-09-05T00:44:31.655138Z","end":"2026-09-05T00:44:31.776972Z","steps":["trace[378779207] 'process raft request' (duration: 121.615267ms)"],"step_count":1} Sep 5 00:45:02.380814 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:45:02.363373Z","caller":"traceutil/trace.go:171","msg":"trace[1196949905] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"131.538409ms","start":"2026-09-05T00:45:02.231809Z","end":"2026-09-05T00:45:02.363348Z","steps":["trace[1196949905] 'process raft request' (duration: 131.486126ms)"],"step_count":1} Sep 5 00:45:02.380814 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:45:02.364758Z","caller":"traceutil/trace.go:171","msg":"trace[745990727] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"159.704298ms","start":"2026-09-05T00:45:02.205040Z","end":"2026-09-05T00:45:02.364744Z","steps":["trace[745990727] 'process raft request' (duration: 64.746217ms)","trace[745990727] 'compare' (duration: 90.560395ms)"],"step_count":2} Sep 5 00:45:02.380814 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:45:02.365342Z","caller":"traceutil/trace.go:171","msg":"trace[1126463481] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"158.124474ms","start":"2026-09-05T00:45:02.207208Z","end":"2026-09-05T00:45:02.365332Z","steps":["trace[1126463481] 'process raft request' (duration: 155.930683ms)"],"step_count":1} Sep 5 00:45:02.380814 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:45:02.365405Z","caller":"traceutil/trace.go:171","msg":"trace[1913295972] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:592; }","duration":"159.583803ms","start":"2026-09-05T00:45:02.205815Z","end":"2026-09-05T00:45:02.365399Z","steps":["trace[1913295972] 'read index received' (duration: 5.571191ms)","trace[1913295972] 'applied index is now lower than readState.Index' (duration: 154.011945ms)"],"step_count":2} Sep 5 00:45:02.402874 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:45:02.370278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.307274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Sep 5 00:45:02.402874 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:45:02.375227Z","caller":"traceutil/trace.go:171","msg":"trace[1651764980] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:572; }","duration":"170.244193ms","start":"2026-09-05T00:45:02.204947Z","end":"2026-09-05T00:45:02.375191Z","steps":["trace[1651764980] 'agreement among raft nodes before linearized reading' (duration: 165.265136ms)"],"step_count":1} Sep 5 00:45:02.402874 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:45:02.371767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.390713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" limit:1 ","response":"range_response_count:1 size:5577"} Sep 5 00:45:02.402874 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:45:02.375341Z","caller":"traceutil/trace.go:171","msg":"trace[2012818256] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:572; }","duration":"167.970756ms","start":"2026-09-05T00:45:02.207358Z","end":"2026-09-05T00:45:02.375329Z","steps":["trace[2012818256] 'agreement among raft nodes before linearized reading' (duration: 164.330958ms)"],"step_count":1} Sep 5 00:45:02.402874 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:45:02.371941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.141003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles\" limit:1 ","response":"range_response_count:0 size:5"} Sep 5 00:45:02.406698 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:45:02.375544Z","caller":"traceutil/trace.go:171","msg":"trace[542227315] range","detail":"{range_begin:/registry/clusterroles; range_end:; response_count:0; response_revision:572; }","duration":"143.744088ms","start":"2026-09-05T00:45:02.231792Z","end":"2026-09-05T00:45:02.375536Z","steps":["trace[542227315] 'agreement among raft nodes before linearized reading' (duration: 140.128572ms)"],"step_count":1} Sep 5 00:45:02.406698 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:45:02.371995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.614875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" limit:1 ","response":"range_response_count:1 size:20810"} Sep 5 00:45:02.406698 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:45:02.375651Z","caller":"traceutil/trace.go:171","msg":"trace[1166644291] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:572; }","duration":"168.271454ms","start":"2026-09-05T00:45:02.207374Z","end":"2026-09-05T00:45:02.375645Z","steps":["trace[1166644291] 'agreement among raft nodes before linearized reading' (duration: 164.583014ms)"],"step_count":1} Sep 5 00:45:03.963531 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:45:03.871820Z","caller":"traceutil/trace.go:171","msg":"trace[460872562] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"184.764843ms","start":"2026-09-05T00:45:03.686956Z","end":"2026-09-05T00:45:03.871721Z","steps":["trace[460872562] 'process raft request' (duration: 170.716082ms)","trace[460872562] 'marshal mvccpb.KeyValue' {req_type:put; key:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; req_size:176918; } (duration: 13.313172ms)"],"step_count":2} Sep 5 00:45:03.988343 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:45:03.965340Z","caller":"traceutil/trace.go:171","msg":"trace[1319951324] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"196.357039ms","start":"2026-09-05T00:45:03.768934Z","end":"2026-09-05T00:45:03.965291Z","steps":["trace[1319951324] 'process raft request' (duration: 174.52693ms)","trace[1319951324] 'marshal mvccpb.KeyValue' {req_type:put; key:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; req_size:177127; } (duration: 21.558718ms)"],"step_count":2} Sep 5 00:45:05.073496 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:45:05.057837Z","caller":"traceutil/trace.go:171","msg":"trace[45150674] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"104.65434ms","start":"2026-09-05T00:45:04.953154Z","end":"2026-09-05T00:45:05.057808Z","steps":["trace[45150674] 'process raft request' (duration: 85.117187ms)","trace[45150674] 'compare' (duration: 18.878457ms)"],"step_count":2} Sep 5 00:45:11.716425 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:45:11.641653Z","caller":"traceutil/trace.go:171","msg":"trace[1726172004] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:617; }","duration":"252.290413ms","start":"2026-09-05T00:45:11.389342Z","end":"2026-09-05T00:45:11.641632Z","steps":["trace[1726172004] 'read index received' (duration: 83.380753ms)","trace[1726172004] 'applied index is now lower than readState.Index' (duration: 168.909249ms)"],"step_count":2} Sep 5 00:45:11.777965 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:45:11.644913Z","caller":"traceutil/trace.go:171","msg":"trace[357097250] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"257.542471ms","start":"2026-09-05T00:45:11.387346Z","end":"2026-09-05T00:45:11.644888Z","steps":["trace[357097250] 'process raft request' (duration: 254.024251ms)"],"step_count":1} Sep 5 00:45:11.777965 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:45:11.645028Z","caller":"traceutil/trace.go:171","msg":"trace[1679453659] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"263.767726ms","start":"2026-09-05T00:45:11.381255Z","end":"2026-09-05T00:45:11.645023Z","steps":["trace[1679453659] 'process raft request' (duration: 256.627729ms)"],"step_count":1} Sep 5 00:45:11.777965 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:45:11.648623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.26674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Sep 5 00:45:11.777965 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:45:11.648674Z","caller":"traceutil/trace.go:171","msg":"trace[1278365163] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"259.31982ms","start":"2026-09-05T00:45:11.389337Z","end":"2026-09-05T00:45:11.648657Z","steps":["trace[1278365163] 'agreement among raft nodes before linearized reading' (duration: 259.247281ms)"],"step_count":1} Sep 5 00:45:11.777965 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:45:11.648744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.206714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" limit:1 ","response":"range_response_count:1 size:418"} Sep 5 00:45:11.778630 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:45:11.648779Z","caller":"traceutil/trace.go:171","msg":"trace[311613182] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:594; }","duration":"210.247665ms","start":"2026-09-05T00:45:11.438522Z","end":"2026-09-05T00:45:11.648770Z","steps":["trace[311613182] 'agreement among raft nodes before linearized reading' (duration: 210.174987ms)"],"step_count":1} Sep 5 00:45:11.778630 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:45:11.683131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.672937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" limit:1 ","response":"range_response_count:1 size:177919"} Sep 5 00:45:11.778630 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:45:11.702558Z","caller":"traceutil/trace.go:171","msg":"trace[1721021723] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:594; }","duration":"130.937185ms","start":"2026-09-05T00:45:11.552398Z","end":"2026-09-05T00:45:11.683335Z","steps":["trace[1721021723] 'agreement among raft nodes before linearized reading' (duration: 96.351417ms)","trace[1721021723] 'range keys from bolt db' (duration: 34.117682ms)"],"step_count":2} Sep 5 00:45:36.160694 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:45:36.072449Z","caller":"traceutil/trace.go:171","msg":"trace[1813363048] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"123.005788ms","start":"2026-09-05T00:45:35.949408Z","end":"2026-09-05T00:45:36.072426Z","steps":["trace[1813363048] 'process raft request' (duration: 122.861512ms)"],"step_count":1} Sep 5 00:45:36.281407 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:45:36.233673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.891029ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Sep 5 00:45:36.281407 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:45:36.234282Z","caller":"traceutil/trace.go:171","msg":"trace[1297852121] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"271.676678ms","start":"2026-09-05T00:45:35.962591Z","end":"2026-09-05T00:45:36.234268Z","steps":["trace[1297852121] 'process raft request' (duration: 126.71089ms)","trace[1297852121] 'compare' (duration: 100.932809ms)"],"step_count":2} Sep 5 00:45:40.009229 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:45:40.002163Z","caller":"traceutil/trace.go:171","msg":"trace[1470856182] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"118.981513ms","start":"2026-09-05T00:45:39.883161Z","end":"2026-09-05T00:45:40.002142Z","steps":["trace[1470856182] 'process raft request' (duration: 118.856169ms)"],"step_count":1} Sep 5 00:45:43.039402 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:45:43.035843Z","caller":"traceutil/trace.go:171","msg":"trace[603147217] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"110.486931ms","start":"2026-09-05T00:45:42.925329Z","end":"2026-09-05T00:45:43.035816Z","steps":["trace[603147217] 'process raft request' (duration: 58.820937ms)","trace[603147217] 'compare' (duration: 51.44322ms)"],"step_count":2} Sep 5 00:45:47.475848 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:45:47.455954Z","caller":"traceutil/trace.go:171","msg":"trace[635228499] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"135.187158ms","start":"2026-09-05T00:45:47.320730Z","end":"2026-09-05T00:45:47.455917Z","steps":["trace[635228499] 'process raft request' (duration: 106.711842ms)","trace[635228499] 'compare' (duration: 27.936569ms)"],"step_count":2} Sep 5 00:45:47.475848 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:45:47.457698Z","caller":"traceutil/trace.go:171","msg":"trace[1057006745] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:683; }","duration":"136.618025ms","start":"2026-09-05T00:45:47.321002Z","end":"2026-09-05T00:45:47.457620Z","steps":["trace[1057006745] 'read index received' (duration: 16.855366ms)","trace[1057006745] 'applied index is now lower than readState.Index' (duration: 119.760635ms)"],"step_count":2} Sep 5 00:45:47.475848 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:45:47.457771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.754417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets\" limit:1 ","response":"range_response_count:0 size:5"} Sep 5 00:45:47.475848 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:45:47.457790Z","caller":"traceutil/trace.go:171","msg":"trace[95736178] range","detail":"{range_begin:/registry/replicasets; range_end:; response_count:0; response_revision:652; }","duration":"136.78443ms","start":"2026-09-05T00:45:47.320999Z","end":"2026-09-05T00:45:47.457783Z","steps":["trace[95736178] 'agreement among raft nodes before linearized reading' (duration: 136.740588ms)"],"step_count":1} Sep 5 00:45:47.475848 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:45:47.457853Z","caller":"traceutil/trace.go:171","msg":"trace[783861077] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"136.779602ms","start":"2026-09-05T00:45:47.321064Z","end":"2026-09-05T00:45:47.457844Z","steps":["trace[783861077] 'process raft request' (duration: 134.451875ms)"],"step_count":1} Sep 5 00:45:58.040307 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:45:57.986097Z","caller":"traceutil/trace.go:171","msg":"trace[1783276615] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"113.340108ms","start":"2026-09-05T00:45:57.872737Z","end":"2026-09-05T00:45:57.986077Z","steps":["trace[1783276615] 'process raft request' (duration: 24.364176ms)","trace[1783276615] 'compare' (duration: 86.103538ms)"],"step_count":2} Sep 5 00:46:05.575036 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:46:05.570777Z","caller":"traceutil/trace.go:171","msg":"trace[1773779572] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"217.51469ms","start":"2026-09-05T00:46:05.353233Z","end":"2026-09-05T00:46:05.570748Z","steps":["trace[1773779572] 'process raft request' (duration: 163.279485ms)","trace[1773779572] 'compare' (duration: 53.874764ms)"],"step_count":2} Sep 5 00:46:30.383261 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:46:30.356891Z","caller":"traceutil/trace.go:171","msg":"trace[2070606493] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"106.576536ms","start":"2026-09-05T00:46:30.250283Z","end":"2026-09-05T00:46:30.356859Z","steps":["trace[2070606493] 'process raft request' (duration: 99.748546ms)"],"step_count":1} Sep 5 00:46:38.231608 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:46:38.181758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.507342ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} Sep 5 00:47:05.245326 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:47:05.189885Z","caller":"traceutil/trace.go:171","msg":"trace[819757974] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:837; }","duration":"103.225369ms","start":"2026-09-05T00:47:05.086619Z","end":"2026-09-05T00:47:05.189844Z","steps":["trace[819757974] 'read index received' (duration: 103.139169ms)","trace[819757974] 'applied index is now lower than readState.Index' (duration: 82.191µs)"],"step_count":2} Sep 5 00:47:05.245326 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:47:05.190146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.579456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets\" limit:1 ","response":"range_response_count:0 size:5"} Sep 5 00:47:05.245326 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:47:05.190168Z","caller":"traceutil/trace.go:171","msg":"trace[840603168] range","detail":"{range_begin:/registry/secrets; range_end:; response_count:0; response_revision:787; }","duration":"103.615355ms","start":"2026-09-05T00:47:05.086546Z","end":"2026-09-05T00:47:05.190161Z","steps":["trace[840603168] 'agreement among raft nodes before linearized reading' (duration: 103.554753ms)"],"step_count":1} Sep 5 00:47:05.309398 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:47:05.261379Z","caller":"traceutil/trace.go:171","msg":"trace[355302164] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"174.665016ms","start":"2026-09-05T00:47:05.086688Z","end":"2026-09-05T00:47:05.261353Z","steps":["trace[355302164] 'process raft request' (duration: 156.910456ms)","trace[355302164] 'compare' (duration: 17.616186ms)"],"step_count":2} Sep 5 00:47:29.185795 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:47:29.172618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.355475ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} Sep 5 00:47:29.185795 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:47:29.173196Z","caller":"traceutil/trace.go:171","msg":"trace[89358447] linearizableReadLoop","detail":"{readStateIndex:877; appliedIndex:876; }","duration":"464.860737ms","start":"2026-09-05T00:47:28.708296Z","end":"2026-09-05T00:47:29.173156Z","steps":["trace[89358447] 'read index received' (duration: 155.901562ms)","trace[89358447] 'applied index is now lower than readState.Index' (duration: 308.954023ms)"],"step_count":2} Sep 5 00:47:29.185795 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:47:29.173545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.206297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Sep 5 00:47:29.185795 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:47:29.173637Z","caller":"traceutil/trace.go:171","msg":"trace[247720520] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:821; }","duration":"480.320213ms","start":"2026-09-05T00:47:28.693241Z","end":"2026-09-05T00:47:29.173561Z","steps":["trace[247720520] 'agreement among raft nodes before linearized reading' (duration: 480.181378ms)"],"step_count":1} Sep 5 00:47:29.185795 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:47:29.173666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T00:47:28.693221Z","time spent":"480.436626ms","remote":"10.0.0.109:35778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} Sep 5 00:47:29.337984 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:47:29.173741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.945886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses\" limit:1 ","response":"range_response_count:0 size:5"} Sep 5 00:47:29.337984 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:47:29.173763Z","caller":"traceutil/trace.go:171","msg":"trace[1365892314] range","detail":"{range_begin:/registry/runtimeclasses; range_end:; response_count:0; response_revision:821; }","duration":"118.96746ms","start":"2026-09-05T00:47:29.054788Z","end":"2026-09-05T00:47:29.173756Z","steps":["trace[1365892314] 'agreement among raft nodes before linearized reading' (duration: 118.806846ms)"],"step_count":1} Sep 5 00:48:13.023161 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:48:12.967074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.66479ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Sep 5 00:48:13.023161 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:48:12.967851Z","caller":"traceutil/trace.go:171","msg":"trace[298036330] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:972; }","duration":"220.179179ms","start":"2026-09-05T00:48:12.747626Z","end":"2026-09-05T00:48:12.967805Z","steps":["trace[298036330] 'read index received' (duration: 94.615302ms)","trace[298036330] 'applied index is now lower than readState.Index' (duration: 125.562618ms)"],"step_count":2} Sep 5 00:48:13.023161 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:48:12.968196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.005266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Sep 5 00:48:13.023161 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:48:12.968225Z","caller":"traceutil/trace.go:171","msg":"trace[169036325] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:909; }","duration":"222.040017ms","start":"2026-09-05T00:48:12.746174Z","end":"2026-09-05T00:48:12.968214Z","steps":["trace[169036325] 'agreement among raft nodes before linearized reading' (duration: 221.877024ms)"],"step_count":1} Sep 5 00:48:13.023161 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:48:12.973285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.927964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods\" limit:1 ","response":"range_response_count:0 size:5"} Sep 5 00:48:13.070974 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:48:12.973398Z","caller":"traceutil/trace.go:171","msg":"trace[1483259194] range","detail":"{range_begin:/registry/pods; range_end:; response_count:0; response_revision:909; }","duration":"227.051018ms","start":"2026-09-05T00:48:12.746329Z","end":"2026-09-05T00:48:12.973380Z","steps":["trace[1483259194] 'agreement among raft nodes before linearized reading' (duration: 226.857994ms)"],"step_count":1} Sep 5 00:48:13.070974 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:48:12.983708Z","caller":"traceutil/trace.go:171","msg":"trace[586513713] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"237.532019ms","start":"2026-09-05T00:48:12.746040Z","end":"2026-09-05T00:48:12.983572Z","steps":["trace[586513713] 'process raft request' (duration: 115.750348ms)","trace[586513713] 'compare' (duration: 104.250669ms)"],"step_count":2} Sep 5 00:48:18.789890 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:48:18.787215Z","caller":"traceutil/trace.go:171","msg":"trace[1366323594] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"101.019703ms","start":"2026-09-05T00:48:18.686177Z","end":"2026-09-05T00:48:18.787196Z","steps":["trace[1366323594] 'process raft request' (duration: 100.707004ms)"],"step_count":1} Sep 5 00:48:19.477929 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:48:19.432994Z","caller":"traceutil/trace.go:171","msg":"trace[1696634456] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:1002; }","duration":"308.218752ms","start":"2026-09-05T00:48:19.124738Z","end":"2026-09-05T00:48:19.432956Z","steps":["trace[1696634456] 'read index received' (duration: 308.116806ms)","trace[1696634456] 'applied index is now lower than readState.Index' (duration: 97.605µs)"],"step_count":2} Sep 5 00:48:19.477929 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:48:19.437438Z","caller":"traceutil/trace.go:171","msg":"trace[1560535793] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"324.416363ms","start":"2026-09-05T00:48:19.112984Z","end":"2026-09-05T00:48:19.437400Z","steps":["trace[1560535793] 'process raft request' (duration: 318.147266ms)"],"step_count":1} Sep 5 00:48:19.477929 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:48:19.440412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T00:48:19.112952Z","time spent":"327.277484ms","remote":"10.0.0.109:35898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":855,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} Sep 5 00:48:19.477929 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:48:19.449540Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.788173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Sep 5 00:48:19.562671 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:48:19.450153Z","caller":"traceutil/trace.go:171","msg":"trace[2017908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:935; }","duration":"325.389399ms","start":"2026-09-05T00:48:19.124726Z","end":"2026-09-05T00:48:19.450116Z","steps":["trace[2017908] 'agreement among raft nodes before linearized reading' (duration: 311.630244ms)"],"step_count":1} Sep 5 00:48:19.562671 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:48:19.450312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T00:48:19.124689Z","time spent":"325.609983ms","remote":"10.0.0.109:35800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} Sep 5 00:48:19.562671 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:48:19.451443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.099292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-zgqhr\" limit:1 ","response":"range_response_count:1 size:3425"} Sep 5 00:48:19.562671 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:48:19.465010Z","caller":"traceutil/trace.go:171","msg":"trace[1061335377] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-zgqhr; range_end:; response_count:1; response_revision:936; }","duration":"321.631653ms","start":"2026-09-05T00:48:19.143334Z","end":"2026-09-05T00:48:19.464965Z","steps":["trace[1061335377] 'agreement among raft nodes before linearized reading' (duration: 307.924223ms)"],"step_count":1} Sep 5 00:48:19.562671 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:48:19.465383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T00:48:19.143305Z","time spent":"322.04822ms","remote":"10.0.0.109:36134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":3449,"request content":"key:\"/registry/pods/kube-system/kube-proxy-zgqhr\" limit:1 "} Sep 5 00:48:19.563118 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:48:19.456072Z","caller":"traceutil/trace.go:171","msg":"trace[667296989] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"291.765418ms","start":"2026-09-05T00:48:19.164197Z","end":"2026-09-05T00:48:19.455953Z","steps":["trace[667296989] 'process raft request' (duration: 275.657469ms)"],"step_count":1} Sep 5 00:48:19.563118 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:48:19.466022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T00:48:19.164097Z","time spent":"301.80846ms","remote":"10.0.0.109:36276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":465,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Sep 5 00:48:52.984650 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:48:52.964712Z","caller":"traceutil/trace.go:171","msg":"trace[516499377] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"144.01831ms","start":"2026-09-05T00:48:52.820673Z","end":"2026-09-05T00:48:52.964691Z","steps":["trace[516499377] 'process raft request' (duration: 110.898132ms)","trace[516499377] 'compare' (duration: 32.615182ms)"],"step_count":2} Sep 5 00:49:03.427203 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:49:03.415501Z","caller":"traceutil/trace.go:171","msg":"trace[491126189] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"141.418863ms","start":"2026-09-05T00:49:03.274029Z","end":"2026-09-05T00:49:03.415448Z","steps":["trace[491126189] 'process raft request' (duration: 139.314629ms)"],"step_count":1} Sep 5 00:49:03.427203 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:49:03.430340Z","caller":"traceutil/trace.go:171","msg":"trace[1682572246] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"140.428654ms","start":"2026-09-05T00:49:03.273230Z","end":"2026-09-05T00:49:03.413658Z","steps":["trace[1682572246] 'process raft request' (duration: 91.668186ms)","trace[1682572246] 'compare' (duration: 47.989966ms)"],"step_count":2} Sep 5 00:49:18.353487 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:49:18.341073Z","caller":"traceutil/trace.go:171","msg":"trace[699807937] linearizableReadLoop","detail":"{readStateIndex:1188; appliedIndex:1187; }","duration":"125.503782ms","start":"2026-09-05T00:49:18.215538Z","end":"2026-09-05T00:49:18.341042Z","steps":["trace[699807937] 'read index received' (duration: 37.57253ms)","trace[699807937] 'applied index is now lower than readState.Index' (duration: 87.930388ms)"],"step_count":2} Sep 5 00:49:18.353487 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:49:18.341399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.850202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs\" limit:1 ","response":"range_response_count:0 size:5"} Sep 5 00:49:18.353487 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:49:18.341434Z","caller":"traceutil/trace.go:171","msg":"trace[591907832] range","detail":"{range_begin:/registry/services/specs; range_end:; response_count:0; response_revision:1108; }","duration":"125.894566ms","start":"2026-09-05T00:49:18.215527Z","end":"2026-09-05T00:49:18.341421Z","steps":["trace[591907832] 'agreement among raft nodes before linearized reading' (duration: 125.745418ms)"],"step_count":1} Sep 5 00:49:19.596133 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:49:19.578209Z","caller":"traceutil/trace.go:171","msg":"trace[1824471156] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"113.052042ms","start":"2026-09-05T00:49:19.465141Z","end":"2026-09-05T00:49:19.578193Z","steps":["trace[1824471156] 'process raft request' (duration: 112.813362ms)"],"step_count":1} Sep 5 00:49:24.888656 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:49:24.869155Z","caller":"traceutil/trace.go:171","msg":"trace[780809955] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"129.045565ms","start":"2026-09-05T00:49:24.740086Z","end":"2026-09-05T00:49:24.869132Z","steps":["trace[780809955] 'process raft request' (duration: 122.000746ms)"],"step_count":1} Sep 5 00:49:45.232228 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:49:45.167647Z","caller":"traceutil/trace.go:171","msg":"trace[1126292569] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"245.761585ms","start":"2026-09-05T00:49:44.921859Z","end":"2026-09-05T00:49:45.167620Z","steps":["trace[1126292569] 'process raft request' (duration: 191.48683ms)","trace[1126292569] 'compare' (duration: 53.050602ms)"],"step_count":2} Sep 5 00:50:18.179338 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:50:18.175110Z","caller":"traceutil/trace.go:171","msg":"trace[1000999652] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"101.741515ms","start":"2026-09-05T00:50:18.073306Z","end":"2026-09-05T00:50:18.175047Z","steps":["trace[1000999652] 'process raft request' (duration: 87.559907ms)","trace[1000999652] 'store kv pair into bolt db' {req_type:put; key:/registry/leases/kube-system/kube-scheduler; req_size:462; } (duration: 11.482559ms)"],"step_count":2} Sep 5 00:50:49.169922 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:50:49.163273Z","caller":"traceutil/trace.go:171","msg":"trace[1811969514] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"113.423259ms","start":"2026-09-05T00:50:49.049827Z","end":"2026-09-05T00:50:49.163250Z","steps":["trace[1811969514] 'process raft request' (duration: 113.174304ms)"],"step_count":1} Sep 5 00:50:58.144331 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:50:58.142131Z","caller":"traceutil/trace.go:171","msg":"trace[2004816433] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"102.188802ms","start":"2026-09-05T00:50:58.039913Z","end":"2026-09-05T00:50:58.142101Z","steps":["trace[2004816433] 'process raft request' (duration: 101.206633ms)"],"step_count":1} Sep 5 00:51:18.599136 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:51:18.588994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.998529ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} Sep 5 00:51:43.379026 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:51:43.370167Z","caller":"traceutil/trace.go:171","msg":"trace[1424988836] transaction","detail":"{read_only:false; response_revision:1461; number_of_response:1; }","duration":"102.503415ms","start":"2026-09-05T00:51:43.267641Z","end":"2026-09-05T00:51:43.370144Z","steps":["trace[1424988836] 'process raft request' (duration: 76.169203ms)","trace[1424988836] 'compare' (duration: 25.713418ms)"],"step_count":2} Sep 5 00:52:08.665774 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:52:08.659636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.05333ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} Sep 5 00:52:50.496861 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:52:50.484214Z","caller":"traceutil/trace.go:171","msg":"trace[1222085791] transaction","detail":"{read_only:false; response_revision:1603; number_of_response:1; }","duration":"111.117635ms","start":"2026-09-05T00:52:50.373071Z","end":"2026-09-05T00:52:50.484188Z","steps":["trace[1222085791] 'process raft request' (duration: 110.862039ms)"],"step_count":1} Sep 5 00:52:55.268241 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:52:55.261420Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":862} Sep 5 00:52:55.361409 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:52:55.353793Z","caller":"mvcc/kvstore_compaction.go:72","msg":"finished scheduled compaction","compact-revision":862,"took":"90.539735ms","hash":983148291,"current-db-size-bytes":5128192,"current-db-size":"5.1 MB","current-db-size-in-use-bytes":5128192,"current-db-size-in-use":"5.1 MB"} Sep 5 00:52:55.361409 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:52:55.354272Z","caller":"mvcc/hash.go:151","msg":"storing new hash","hash":983148291,"revision":862,"compact-revision":-1} Sep 5 00:53:15.060426 systemd[1]: Starting systemd-tmpfiles-clean.service - Cleanup of Temporary Directories... Sep 5 00:53:15.352286 systemd-tmpfiles[1940]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 5 00:53:15.360213 systemd-tmpfiles[1940]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 5 00:53:15.370995 systemd-tmpfiles[1940]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 5 00:53:15.371304 systemd-tmpfiles[1940]: ACLs are not supported, ignoring. Sep 5 00:53:15.371369 systemd-tmpfiles[1940]: ACLs are not supported, ignoring. Sep 5 00:53:15.408841 systemd-tmpfiles[1940]: Detected autofs mount point /boot during canonicalization of boot. Sep 5 00:53:15.408876 systemd-tmpfiles[1940]: Skipping /boot Sep 5 00:53:15.575532 systemd[1]: systemd-tmpfiles-clean.service: Deactivated successfully. Sep 5 00:53:15.584159 systemd[1]: Finished systemd-tmpfiles-clean.service - Cleanup of Temporary Directories. Sep 5 00:54:58.641684 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:54:58.639058Z","caller":"traceutil/trace.go:171","msg":"trace[264625487] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1908; }","duration":"109.314678ms","start":"2026-09-05T00:54:58.529642Z","end":"2026-09-05T00:54:58.638957Z","steps":["trace[264625487] 'process raft request' (duration: 98.083231ms)"],"step_count":1} Sep 5 00:54:59.453699 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:54:59.444000Z","caller":"traceutil/trace.go:171","msg":"trace[1253279584] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"104.69098ms","start":"2026-09-05T00:54:59.338744Z","end":"2026-09-05T00:54:59.443435Z","steps":["trace[1253279584] 'process raft request' (duration: 40.115327ms)","trace[1253279584] 'compare' (duration: 64.26842ms)"],"step_count":2} Sep 5 00:55:16.078701 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:55:16.068017Z","caller":"traceutil/trace.go:171","msg":"trace[468066698] transaction","detail":"{read_only:false; response_revision:1971; number_of_response:1; }","duration":"109.819568ms","start":"2026-09-05T00:55:15.958159Z","end":"2026-09-05T00:55:16.067979Z","steps":["trace[468066698] 'process raft request' (duration: 108.783798ms)"],"step_count":1} Sep 5 00:55:45.937407 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:55:45.922772Z","caller":"traceutil/trace.go:171","msg":"trace[1070685857] linearizableReadLoop","detail":"{readStateIndex:2279; appliedIndex:2278; }","duration":"133.189164ms","start":"2026-09-05T00:55:45.789558Z","end":"2026-09-05T00:55:45.922747Z","steps":["trace[1070685857] 'read index received' (duration: 132.767862ms)","trace[1070685857] 'applied index is now lower than readState.Index' (duration: 420.812µs)"],"step_count":2} Sep 5 00:55:45.937407 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:55:45.922955Z","caller":"traceutil/trace.go:171","msg":"trace[2144054006] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"152.589723ms","start":"2026-09-05T00:55:45.770357Z","end":"2026-09-05T00:55:45.922947Z","steps":["trace[2144054006] 'process raft request' (duration: 152.041098ms)"],"step_count":1} Sep 5 00:55:45.937407 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:55:45.923406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.847965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8fswk\" limit:1 ","response":"range_response_count:1 size:17875"} Sep 5 00:55:45.937407 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:55:45.923442Z","caller":"traceutil/trace.go:171","msg":"trace[1211794976] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8fswk; range_end:; response_count:1; response_revision:2110; }","duration":"133.886838ms","start":"2026-09-05T00:55:45.789543Z","end":"2026-09-05T00:55:45.923430Z","steps":["trace[1211794976] 'agreement among raft nodes before linearized reading' (duration: 133.559235ms)"],"step_count":1} Sep 5 00:55:56.115741 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:55:56.089982Z","caller":"traceutil/trace.go:171","msg":"trace[448630017] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"121.543413ms","start":"2026-09-05T00:55:55.968297Z","end":"2026-09-05T00:55:56.089841Z","steps":["trace[448630017] 'process raft request' (duration: 121.30568ms)"],"step_count":1} Sep 5 00:56:03.102635 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:56:03.093579Z","caller":"traceutil/trace.go:171","msg":"trace[510751703] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"119.513241ms","start":"2026-09-05T00:56:02.974045Z","end":"2026-09-05T00:56:03.093558Z","steps":["trace[510751703] 'process raft request' (duration: 119.279833ms)"],"step_count":1} Sep 5 00:56:04.289943 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:56:04.282234Z","caller":"traceutil/trace.go:171","msg":"trace[542413501] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"115.29054ms","start":"2026-09-05T00:56:04.166915Z","end":"2026-09-05T00:56:04.282205Z","steps":["trace[542413501] 'process raft request' (duration: 115.015552ms)"],"step_count":1} Sep 5 00:56:04.302162 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:56:04.300102Z","caller":"traceutil/trace.go:171","msg":"trace[660684047] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"106.688894ms","start":"2026-09-05T00:56:04.193383Z","end":"2026-09-05T00:56:04.300071Z","steps":["trace[660684047] 'process raft request' (duration: 102.308779ms)"],"step_count":1} Sep 5 00:56:06.104647 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:56:06.076663Z","caller":"traceutil/trace.go:171","msg":"trace[302046307] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"124.867003ms","start":"2026-09-05T00:56:05.951700Z","end":"2026-09-05T00:56:06.076567Z","steps":["trace[302046307] 'process raft request' (duration: 30.068292ms)","trace[302046307] 'compare' (duration: 32.999532ms)","trace[302046307] 'store kv pair into bolt db' {req_type:put; key:/registry/events/kube-system/kube-proxy-4sdpj.18d2461644bcd894; req_size:782; } (duration: 33.858847ms)"],"step_count":3} Sep 5 00:56:06.104647 etcd-wrapper[1669]: {"level":"warn","ts":"2026-09-05T00:56:06.095591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.176524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:127"} Sep 5 00:56:06.135233 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:56:06.110183Z","caller":"traceutil/trace.go:171","msg":"trace[796141641] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:2175; }","duration":"112.465978ms","start":"2026-09-05T00:56:05.983391Z","end":"2026-09-05T00:56:06.095857Z","steps":["trace[796141641] 'agreement among raft nodes before linearized reading' (duration: 94.036162ms)","trace[796141641] 'range keys from in-memory index tree' (duration: 17.98437ms)"],"step_count":2} Sep 5 00:56:22.674567 etcd-wrapper[1669]: {"level":"info","ts":"2026-09-05T00:56:22.668675Z","caller":"traceutil/trace.go:171","msg":"trace[323122606] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"105.540537ms","start":"2026-09-05T00:56:22.562888Z","end":"2026-09-05T00:56:22.668429Z","steps":["trace[323122606] 'process raft request' (duration: 93.319018ms)","trace[323122606] 'compare' (duration: 10.783363ms)"],"step_count":2}