Mar 10 01:16:17.475951 kernel: Linux version 6.6.127-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 Mon Mar 9 22:55:40 -00 2026 Mar 10 01:16:17.475988 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=2de2345ba8612ade61882513e7d9ebf4aad52996b6d7f4c567d9970e886b17cc Mar 10 01:16:17.476005 kernel: BIOS-provided physical RAM map: Mar 10 01:16:17.476014 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Mar 10 01:16:17.476023 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Mar 10 01:16:17.476033 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Mar 10 01:16:17.476045 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000009cfdbfff] usable Mar 10 01:16:17.476055 kernel: BIOS-e820: [mem 0x000000009cfdc000-0x000000009cffffff] reserved Mar 10 01:16:17.476063 kernel: BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved Mar 10 01:16:17.476078 kernel: BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved Mar 10 01:16:17.476089 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Mar 10 01:16:17.476100 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Mar 10 01:16:17.476182 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Mar 10 01:16:17.476194 kernel: NX (Execute Disable) protection: active Mar 10 01:16:17.476205 kernel: APIC: Static calls initialized Mar 10 01:16:17.476220 kernel: SMBIOS 2.8 present. Mar 10 01:16:17.476230 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.16.2-debian-1.16.2-1 04/01/2014 Mar 10 01:16:17.476240 kernel: Hypervisor detected: KVM Mar 10 01:16:17.476249 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Mar 10 01:16:17.476259 kernel: kvm-clock: using sched offset of 20481501377 cycles Mar 10 01:16:17.476270 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Mar 10 01:16:17.476280 kernel: tsc: Detected 2445.424 MHz processor Mar 10 01:16:17.476291 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Mar 10 01:16:17.476579 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Mar 10 01:16:17.476598 kernel: last_pfn = 0x9cfdc max_arch_pfn = 0x400000000 Mar 10 01:16:17.476608 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Mar 10 01:16:17.476619 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Mar 10 01:16:17.476629 kernel: Using GB pages for direct mapping Mar 10 01:16:17.476640 kernel: ACPI: Early table checksum verification disabled Mar 10 01:16:17.476649 kernel: ACPI: RSDP 0x00000000000F59D0 000014 (v00 BOCHS ) Mar 10 01:16:17.476659 kernel: ACPI: RSDT 0x000000009CFE241A 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 10 01:16:17.476668 kernel: ACPI: FACP 0x000000009CFE21FA 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) Mar 10 01:16:17.476678 kernel: ACPI: DSDT 0x000000009CFE0040 0021BA (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 10 01:16:17.476692 kernel: ACPI: FACS 0x000000009CFE0000 000040 Mar 10 01:16:17.476701 kernel: ACPI: APIC 0x000000009CFE22EE 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 10 01:16:17.476711 kernel: ACPI: HPET 0x000000009CFE237E 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 10 01:16:17.476721 kernel: ACPI: MCFG 0x000000009CFE23B6 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 10 01:16:17.476731 kernel: ACPI: WAET 0x000000009CFE23F2 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 10 01:16:17.476741 kernel: ACPI: Reserving FACP table memory at [mem 0x9cfe21fa-0x9cfe22ed] Mar 10 01:16:17.476752 kernel: ACPI: Reserving DSDT table memory at [mem 0x9cfe0040-0x9cfe21f9] Mar 10 01:16:17.476769 kernel: ACPI: Reserving FACS table memory at [mem 0x9cfe0000-0x9cfe003f] Mar 10 01:16:17.476784 kernel: ACPI: Reserving APIC table memory at [mem 0x9cfe22ee-0x9cfe237d] Mar 10 01:16:17.476794 kernel: ACPI: Reserving HPET table memory at [mem 0x9cfe237e-0x9cfe23b5] Mar 10 01:16:17.476806 kernel: ACPI: Reserving MCFG table memory at [mem 0x9cfe23b6-0x9cfe23f1] Mar 10 01:16:17.476817 kernel: ACPI: Reserving WAET table memory at [mem 0x9cfe23f2-0x9cfe2419] Mar 10 01:16:17.476827 kernel: No NUMA configuration found Mar 10 01:16:17.476838 kernel: Faking a node at [mem 0x0000000000000000-0x000000009cfdbfff] Mar 10 01:16:17.476853 kernel: NODE_DATA(0) allocated [mem 0x9cfd6000-0x9cfdbfff] Mar 10 01:16:17.476864 kernel: Zone ranges: Mar 10 01:16:17.476875 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Mar 10 01:16:17.476886 kernel: DMA32 [mem 0x0000000001000000-0x000000009cfdbfff] Mar 10 01:16:17.476896 kernel: Normal empty Mar 10 01:16:17.476906 kernel: Movable zone start for each node Mar 10 01:16:17.476915 kernel: Early memory node ranges Mar 10 01:16:17.476925 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Mar 10 01:16:17.476936 kernel: node 0: [mem 0x0000000000100000-0x000000009cfdbfff] Mar 10 01:16:17.476946 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009cfdbfff] Mar 10 01:16:17.476962 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Mar 10 01:16:17.477044 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Mar 10 01:16:17.477057 kernel: On node 0, zone DMA32: 12324 pages in unavailable ranges Mar 10 01:16:17.477068 kernel: ACPI: PM-Timer IO Port: 0x608 Mar 10 01:16:17.477078 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Mar 10 01:16:17.477089 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Mar 10 01:16:17.477101 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Mar 10 01:16:17.477111 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Mar 10 01:16:17.477122 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Mar 10 01:16:17.477138 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Mar 10 01:16:17.477149 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Mar 10 01:16:17.477162 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Mar 10 01:16:17.477172 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Mar 10 01:16:17.477185 kernel: TSC deadline timer available Mar 10 01:16:17.477197 kernel: smpboot: Allowing 4 CPUs, 0 hotplug CPUs Mar 10 01:16:17.477207 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Mar 10 01:16:17.477219 kernel: kvm-guest: KVM setup pv remote TLB flush Mar 10 01:16:17.477232 kernel: kvm-guest: setup PV sched yield Mar 10 01:16:17.477249 kernel: [mem 0xc0000000-0xfed1bfff] available for PCI devices Mar 10 01:16:17.477262 kernel: Booting paravirtualized kernel on KVM Mar 10 01:16:17.477275 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Mar 10 01:16:17.477287 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 Mar 10 01:16:17.477692 kernel: percpu: Embedded 57 pages/cpu s196328 r8192 d28952 u524288 Mar 10 01:16:17.477708 kernel: pcpu-alloc: s196328 r8192 d28952 u524288 alloc=1*2097152 Mar 10 01:16:17.477719 kernel: pcpu-alloc: [0] 0 1 2 3 Mar 10 01:16:17.477728 kernel: kvm-guest: PV spinlocks enabled Mar 10 01:16:17.477739 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Mar 10 01:16:17.477756 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=2de2345ba8612ade61882513e7d9ebf4aad52996b6d7f4c567d9970e886b17cc Mar 10 01:16:17.477768 kernel: random: crng init done Mar 10 01:16:17.477780 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Mar 10 01:16:17.477790 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Mar 10 01:16:17.477800 kernel: Fallback order for Node 0: 0 Mar 10 01:16:17.477809 kernel: Built 1 zonelists, mobility grouping on. Total pages: 632732 Mar 10 01:16:17.477819 kernel: Policy zone: DMA32 Mar 10 01:16:17.477831 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Mar 10 01:16:17.477851 kernel: Memory: 2434604K/2571752K available (12288K kernel code, 2288K rwdata, 22752K rodata, 42896K init, 2300K bss, 136888K reserved, 0K cma-reserved) Mar 10 01:16:17.477864 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Mar 10 01:16:17.477873 kernel: ftrace: allocating 37996 entries in 149 pages Mar 10 01:16:17.477883 kernel: ftrace: allocated 149 pages with 4 groups Mar 10 01:16:17.477892 kernel: Dynamic Preempt: voluntary Mar 10 01:16:17.477904 kernel: rcu: Preemptible hierarchical RCU implementation. Mar 10 01:16:17.477917 kernel: rcu: RCU event tracing is enabled. Mar 10 01:16:17.477927 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Mar 10 01:16:17.477937 kernel: Trampoline variant of Tasks RCU enabled. Mar 10 01:16:17.477952 kernel: Rude variant of Tasks RCU enabled. Mar 10 01:16:17.477962 kernel: Tracing variant of Tasks RCU enabled. Mar 10 01:16:17.477972 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Mar 10 01:16:17.477982 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Mar 10 01:16:17.478061 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 Mar 10 01:16:17.478072 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Mar 10 01:16:17.478083 kernel: Console: colour VGA+ 80x25 Mar 10 01:16:17.478093 kernel: printk: console [ttyS0] enabled Mar 10 01:16:17.478103 kernel: ACPI: Core revision 20230628 Mar 10 01:16:17.478118 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Mar 10 01:16:17.478128 kernel: APIC: Switch to symmetric I/O mode setup Mar 10 01:16:17.478138 kernel: x2apic enabled Mar 10 01:16:17.478148 kernel: APIC: Switched APIC routing to: physical x2apic Mar 10 01:16:17.478158 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() Mar 10 01:16:17.478169 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() Mar 10 01:16:17.478178 kernel: kvm-guest: setup PV IPIs Mar 10 01:16:17.478189 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Mar 10 01:16:17.478214 kernel: tsc: Marking TSC unstable due to TSCs unsynchronized Mar 10 01:16:17.478225 kernel: Calibrating delay loop (skipped) preset value.. 4890.84 BogoMIPS (lpj=2445424) Mar 10 01:16:17.478235 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Mar 10 01:16:17.478246 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 Mar 10 01:16:17.478260 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 Mar 10 01:16:17.478271 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Mar 10 01:16:17.478281 kernel: Spectre V2 : Mitigation: Retpolines Mar 10 01:16:17.478293 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Mar 10 01:16:17.478582 kernel: Speculative Store Bypass: Vulnerable Mar 10 01:16:17.478600 kernel: Speculative Return Stack Overflow: IBPB-extending microcode not applied! Mar 10 01:16:17.478613 kernel: Speculative Return Stack Overflow: WARNING: See https://kernel.org/doc/html/latest/admin-guide/hw-vuln/srso.html for mitigation options. Mar 10 01:16:17.478624 kernel: active return thunk: srso_alias_return_thunk Mar 10 01:16:17.478635 kernel: Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode Mar 10 01:16:17.478646 kernel: Transient Scheduler Attacks: Forcing mitigation on in a VM Mar 10 01:16:17.478657 kernel: Transient Scheduler Attacks: Vulnerable: Clear CPU buffers attempted, no microcode Mar 10 01:16:17.478668 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Mar 10 01:16:17.478678 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Mar 10 01:16:17.478694 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Mar 10 01:16:17.478704 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Mar 10 01:16:17.478716 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format. Mar 10 01:16:17.478727 kernel: Freeing SMP alternatives memory: 32K Mar 10 01:16:17.478738 kernel: pid_max: default: 32768 minimum: 301 Mar 10 01:16:17.478751 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Mar 10 01:16:17.478763 kernel: landlock: Up and running. Mar 10 01:16:17.478773 kernel: SELinux: Initializing. Mar 10 01:16:17.478785 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Mar 10 01:16:17.478801 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Mar 10 01:16:17.478812 kernel: smpboot: CPU0: AMD EPYC 7763 64-Core Processor (family: 0x19, model: 0x1, stepping: 0x1) Mar 10 01:16:17.478822 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Mar 10 01:16:17.478833 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Mar 10 01:16:17.478845 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Mar 10 01:16:17.478856 kernel: Performance Events: PMU not available due to virtualization, using software events only. Mar 10 01:16:17.478867 kernel: signal: max sigframe size: 1776 Mar 10 01:16:17.478946 kernel: rcu: Hierarchical SRCU implementation. Mar 10 01:16:17.478962 kernel: rcu: Max phase no-delay instances is 400. Mar 10 01:16:17.478979 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Mar 10 01:16:17.478990 kernel: smp: Bringing up secondary CPUs ... Mar 10 01:16:17.479002 kernel: smpboot: x86: Booting SMP configuration: Mar 10 01:16:17.479013 kernel: .... node #0, CPUs: #1 #2 #3 Mar 10 01:16:17.479025 kernel: smp: Brought up 1 node, 4 CPUs Mar 10 01:16:17.479037 kernel: smpboot: Max logical packages: 1 Mar 10 01:16:17.479048 kernel: smpboot: Total of 4 processors activated (19563.39 BogoMIPS) Mar 10 01:16:17.479060 kernel: devtmpfs: initialized Mar 10 01:16:17.479071 kernel: x86/mm: Memory block size: 128MB Mar 10 01:16:17.479087 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Mar 10 01:16:17.479099 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Mar 10 01:16:17.479110 kernel: pinctrl core: initialized pinctrl subsystem Mar 10 01:16:17.479121 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Mar 10 01:16:17.479133 kernel: audit: initializing netlink subsys (disabled) Mar 10 01:16:17.479143 kernel: thermal_sys: Registered thermal governor 'step_wise' Mar 10 01:16:17.479154 kernel: thermal_sys: Registered thermal governor 'user_space' Mar 10 01:16:17.479165 kernel: audit: type=2000 audit(1773105363.823:1): state=initialized audit_enabled=0 res=1 Mar 10 01:16:17.479176 kernel: cpuidle: using governor menu Mar 10 01:16:17.479192 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Mar 10 01:16:17.479203 kernel: dca service started, version 1.12.1 Mar 10 01:16:17.479214 kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xb0000000-0xbfffffff] (base 0xb0000000) Mar 10 01:16:17.479225 kernel: PCI: MMCONFIG at [mem 0xb0000000-0xbfffffff] reserved as E820 entry Mar 10 01:16:17.479236 kernel: PCI: Using configuration type 1 for base access Mar 10 01:16:17.479247 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Mar 10 01:16:17.479258 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Mar 10 01:16:17.479269 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Mar 10 01:16:17.479280 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Mar 10 01:16:17.479573 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Mar 10 01:16:17.479589 kernel: ACPI: Added _OSI(Module Device) Mar 10 01:16:17.479601 kernel: ACPI: Added _OSI(Processor Device) Mar 10 01:16:17.479612 kernel: ACPI: Added _OSI(Processor Aggregator Device) Mar 10 01:16:17.479623 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Mar 10 01:16:17.479634 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Mar 10 01:16:17.479645 kernel: ACPI: Interpreter enabled Mar 10 01:16:17.479656 kernel: ACPI: PM: (supports S0 S3 S5) Mar 10 01:16:17.479668 kernel: ACPI: Using IOAPIC for interrupt routing Mar 10 01:16:17.479686 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Mar 10 01:16:17.479698 kernel: PCI: Using E820 reservations for host bridge windows Mar 10 01:16:17.479708 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F Mar 10 01:16:17.479719 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Mar 10 01:16:17.480120 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Mar 10 01:16:17.480728 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] Mar 10 01:16:17.480945 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] Mar 10 01:16:17.480969 kernel: PCI host bridge to bus 0000:00 Mar 10 01:16:17.481189 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Mar 10 01:16:17.481651 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Mar 10 01:16:17.481838 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Mar 10 01:16:17.482024 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xafffffff window] Mar 10 01:16:17.482195 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] Mar 10 01:16:17.484163 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] Mar 10 01:16:17.484600 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Mar 10 01:16:17.484892 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 Mar 10 01:16:17.485164 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 Mar 10 01:16:17.485362 kernel: pci 0000:00:01.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref] Mar 10 01:16:17.485700 kernel: pci 0000:00:01.0: reg 0x18: [mem 0xfebd0000-0xfebd0fff] Mar 10 01:16:17.486085 kernel: pci 0000:00:01.0: reg 0x30: [mem 0xfebc0000-0xfebcffff pref] Mar 10 01:16:17.489587 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Mar 10 01:16:17.489841 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 Mar 10 01:16:17.490039 kernel: pci 0000:00:02.0: reg 0x10: [io 0xc0c0-0xc0df] Mar 10 01:16:17.490229 kernel: pci 0000:00:02.0: reg 0x14: [mem 0xfebd1000-0xfebd1fff] Mar 10 01:16:17.490724 kernel: pci 0000:00:02.0: reg 0x20: [mem 0xfe000000-0xfe003fff 64bit pref] Mar 10 01:16:17.490950 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 Mar 10 01:16:17.491154 kernel: pci 0000:00:03.0: reg 0x10: [io 0xc000-0xc07f] Mar 10 01:16:17.491363 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xfebd2000-0xfebd2fff] Mar 10 01:16:17.491731 kernel: pci 0000:00:03.0: reg 0x20: [mem 0xfe004000-0xfe007fff 64bit pref] Mar 10 01:16:17.491945 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 Mar 10 01:16:17.492139 kernel: pci 0000:00:04.0: reg 0x10: [io 0xc0e0-0xc0ff] Mar 10 01:16:17.492326 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xfebd3000-0xfebd3fff] Mar 10 01:16:17.495720 kernel: pci 0000:00:04.0: reg 0x20: [mem 0xfe008000-0xfe00bfff 64bit pref] Mar 10 01:16:17.495948 kernel: pci 0000:00:04.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] Mar 10 01:16:17.496179 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 Mar 10 01:16:17.496399 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO Mar 10 01:16:17.496814 kernel: pci 0000:00:1f.0: quirk_ich7_lpc+0x0/0x180 took 19531 usecs Mar 10 01:16:17.497051 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 Mar 10 01:16:17.497267 kernel: pci 0000:00:1f.2: reg 0x20: [io 0xc100-0xc11f] Mar 10 01:16:17.497646 kernel: pci 0000:00:1f.2: reg 0x24: [mem 0xfebd4000-0xfebd4fff] Mar 10 01:16:17.497866 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 Mar 10 01:16:17.498159 kernel: pci 0000:00:1f.3: reg 0x20: [io 0x0700-0x073f] Mar 10 01:16:17.498181 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Mar 10 01:16:17.498193 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Mar 10 01:16:17.498203 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Mar 10 01:16:17.498212 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Mar 10 01:16:17.498225 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 Mar 10 01:16:17.498238 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 Mar 10 01:16:17.498250 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 Mar 10 01:16:17.498267 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 Mar 10 01:16:17.498278 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 Mar 10 01:16:17.498289 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 Mar 10 01:16:17.498302 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 Mar 10 01:16:17.498315 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 Mar 10 01:16:17.498326 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 Mar 10 01:16:17.498336 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 Mar 10 01:16:17.498346 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 Mar 10 01:16:17.498358 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 Mar 10 01:16:17.498377 kernel: iommu: Default domain type: Translated Mar 10 01:16:17.498388 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Mar 10 01:16:17.498397 kernel: PCI: Using ACPI for IRQ routing Mar 10 01:16:17.501177 kernel: PCI: pci_cache_line_size set to 64 bytes Mar 10 01:16:17.501197 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Mar 10 01:16:17.501208 kernel: e820: reserve RAM buffer [mem 0x9cfdc000-0x9fffffff] Mar 10 01:16:17.501661 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device Mar 10 01:16:17.501871 kernel: pci 0000:00:01.0: vgaarb: bridge control possible Mar 10 01:16:17.502074 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Mar 10 01:16:17.502098 kernel: vgaarb: loaded Mar 10 01:16:17.502110 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Mar 10 01:16:17.502122 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Mar 10 01:16:17.502134 kernel: clocksource: Switched to clocksource kvm-clock Mar 10 01:16:17.502146 kernel: VFS: Disk quotas dquot_6.6.0 Mar 10 01:16:17.502158 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Mar 10 01:16:17.502170 kernel: pnp: PnP ACPI init Mar 10 01:16:17.502612 kernel: system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved Mar 10 01:16:17.502639 kernel: pnp: PnP ACPI: found 6 devices Mar 10 01:16:17.502650 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Mar 10 01:16:17.502660 kernel: NET: Registered PF_INET protocol family Mar 10 01:16:17.502670 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Mar 10 01:16:17.502681 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Mar 10 01:16:17.502692 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Mar 10 01:16:17.502703 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Mar 10 01:16:17.502714 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Mar 10 01:16:17.502725 kernel: TCP: Hash tables configured (established 32768 bind 32768) Mar 10 01:16:17.502740 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Mar 10 01:16:17.502750 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Mar 10 01:16:17.502762 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Mar 10 01:16:17.502776 kernel: NET: Registered PF_XDP protocol family Mar 10 01:16:17.502972 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Mar 10 01:16:17.503145 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Mar 10 01:16:17.503325 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Mar 10 01:16:17.503858 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xafffffff window] Mar 10 01:16:17.504162 kernel: pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] Mar 10 01:16:17.504682 kernel: pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] Mar 10 01:16:17.504701 kernel: PCI: CLS 0 bytes, default 64 Mar 10 01:16:17.504714 kernel: Initialise system trusted keyrings Mar 10 01:16:17.504726 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Mar 10 01:16:17.504737 kernel: Key type asymmetric registered Mar 10 01:16:17.504750 kernel: Asymmetric key parser 'x509' registered Mar 10 01:16:17.504761 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Mar 10 01:16:17.504773 kernel: io scheduler mq-deadline registered Mar 10 01:16:17.504791 kernel: io scheduler kyber registered Mar 10 01:16:17.504803 kernel: io scheduler bfq registered Mar 10 01:16:17.504815 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Mar 10 01:16:17.504828 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 Mar 10 01:16:17.504840 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 Mar 10 01:16:17.504852 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 Mar 10 01:16:17.504864 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Mar 10 01:16:17.504876 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Mar 10 01:16:17.504887 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Mar 10 01:16:17.504904 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Mar 10 01:16:17.504915 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Mar 10 01:16:17.505198 kernel: rtc_cmos 00:04: RTC can wake from S4 Mar 10 01:16:17.505217 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Mar 10 01:16:17.505730 kernel: rtc_cmos 00:04: registered as rtc0 Mar 10 01:16:17.505923 kernel: rtc_cmos 00:04: setting system clock to 2026-03-10T01:16:14 UTC (1773105374) Mar 10 01:16:17.506100 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs Mar 10 01:16:17.506115 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled Mar 10 01:16:17.506133 kernel: NET: Registered PF_INET6 protocol family Mar 10 01:16:17.506143 kernel: Segment Routing with IPv6 Mar 10 01:16:17.506154 kernel: In-situ OAM (IOAM) with IPv6 Mar 10 01:16:17.506165 kernel: NET: Registered PF_PACKET protocol family Mar 10 01:16:17.506176 kernel: Key type dns_resolver registered Mar 10 01:16:17.506186 kernel: IPI shorthand broadcast: enabled Mar 10 01:16:17.506197 kernel: sched_clock: Marking stable (8009069911, 2271074528)->(12515640120, -2235495681) Mar 10 01:16:17.506207 kernel: registered taskstats version 1 Mar 10 01:16:17.506218 kernel: Loading compiled-in X.509 certificates Mar 10 01:16:17.506232 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.127-flatcar: 611e035accba842cc9fafb5ced2ca41a603067aa' Mar 10 01:16:17.506243 kernel: Key type .fscrypt registered Mar 10 01:16:17.506253 kernel: Key type fscrypt-provisioning registered Mar 10 01:16:17.506264 kernel: ima: No TPM chip found, activating TPM-bypass! Mar 10 01:16:17.506274 kernel: ima: Allocated hash algorithm: sha1 Mar 10 01:16:17.506285 kernel: ima: No architecture policies found Mar 10 01:16:17.506820 kernel: clk: Disabling unused clocks Mar 10 01:16:17.506838 kernel: Freeing unused kernel image (initmem) memory: 42896K Mar 10 01:16:17.506848 kernel: Write protecting the kernel read-only data: 36864k Mar 10 01:16:17.506864 kernel: Freeing unused kernel image (rodata/data gap) memory: 1824K Mar 10 01:16:17.506875 kernel: Run /init as init process Mar 10 01:16:17.506885 kernel: with arguments: Mar 10 01:16:17.506896 kernel: /init Mar 10 01:16:17.506906 kernel: with environment: Mar 10 01:16:17.506917 kernel: HOME=/ Mar 10 01:16:17.506927 kernel: TERM=linux Mar 10 01:16:17.506940 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Mar 10 01:16:17.506957 systemd[1]: Detected virtualization kvm. Mar 10 01:16:17.506968 systemd[1]: Detected architecture x86-64. Mar 10 01:16:17.506979 systemd[1]: Running in initrd. Mar 10 01:16:17.506990 systemd[1]: No hostname configured, using default hostname. Mar 10 01:16:17.507001 systemd[1]: Hostname set to . Mar 10 01:16:17.507013 systemd[1]: Initializing machine ID from VM UUID. Mar 10 01:16:17.507024 systemd[1]: Queued start job for default target initrd.target. Mar 10 01:16:17.507035 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 10 01:16:17.507050 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 10 01:16:17.507063 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Mar 10 01:16:17.507077 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Mar 10 01:16:17.507089 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Mar 10 01:16:17.507100 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Mar 10 01:16:17.507113 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Mar 10 01:16:17.507128 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Mar 10 01:16:17.507139 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 10 01:16:17.507150 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Mar 10 01:16:17.507162 systemd[1]: Reached target paths.target - Path Units. Mar 10 01:16:17.507173 systemd[1]: Reached target slices.target - Slice Units. Mar 10 01:16:17.507201 systemd[1]: Reached target swap.target - Swaps. Mar 10 01:16:17.507215 systemd[1]: Reached target timers.target - Timer Units. Mar 10 01:16:17.507231 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Mar 10 01:16:17.507242 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Mar 10 01:16:17.507254 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Mar 10 01:16:17.507265 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Mar 10 01:16:17.507277 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Mar 10 01:16:17.507288 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Mar 10 01:16:17.507566 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Mar 10 01:16:17.507582 systemd[1]: Reached target sockets.target - Socket Units. Mar 10 01:16:17.507605 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Mar 10 01:16:17.507620 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Mar 10 01:16:17.507634 systemd[1]: Finished network-cleanup.service - Network Cleanup. Mar 10 01:16:17.507648 systemd[1]: Starting systemd-fsck-usr.service... Mar 10 01:16:17.507660 systemd[1]: Starting systemd-journald.service - Journal Service... Mar 10 01:16:17.507671 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Mar 10 01:16:17.507683 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 10 01:16:17.507731 systemd-journald[195]: Collecting audit messages is disabled. Mar 10 01:16:17.507764 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Mar 10 01:16:17.507777 systemd-journald[195]: Journal started Mar 10 01:16:17.507803 systemd-journald[195]: Runtime Journal (/run/log/journal/2d3ff0f5d2fe4dea91e56c9c89cec8a2) is 6.0M, max 48.4M, 42.3M free. Mar 10 01:16:17.536743 systemd[1]: Started systemd-journald.service - Journal Service. Mar 10 01:16:17.575706 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Mar 10 01:16:17.597699 systemd[1]: Finished systemd-fsck-usr.service. Mar 10 01:16:17.682126 systemd-modules-load[196]: Inserted module 'overlay' Mar 10 01:16:18.632747 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Mar 10 01:16:18.632865 kernel: Bridge firewalling registered Mar 10 01:16:18.632883 kernel: hrtimer: interrupt took 12318733 ns Mar 10 01:16:17.683346 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Mar 10 01:16:17.897896 systemd-modules-load[196]: Inserted module 'br_netfilter' Mar 10 01:16:18.677854 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Mar 10 01:16:18.728781 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Mar 10 01:16:18.752818 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 10 01:16:18.855126 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 10 01:16:19.011355 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Mar 10 01:16:19.026972 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Mar 10 01:16:19.101299 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 10 01:16:19.117957 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 10 01:16:19.140065 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Mar 10 01:16:19.231203 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Mar 10 01:16:19.296131 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Mar 10 01:16:19.332949 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Mar 10 01:16:19.356233 dracut-cmdline[225]: dracut-dracut-053 Mar 10 01:16:19.356233 dracut-cmdline[225]: 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=2de2345ba8612ade61882513e7d9ebf4aad52996b6d7f4c567d9970e886b17cc Mar 10 01:16:19.459119 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 10 01:16:19.501159 systemd-resolved[233]: Positive Trust Anchors: Mar 10 01:16:19.501257 systemd-resolved[233]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 10 01:16:19.501298 systemd-resolved[233]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Mar 10 01:16:19.506856 systemd-resolved[233]: Defaulting to hostname 'linux'. Mar 10 01:16:19.607347 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Mar 10 01:16:19.636893 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Mar 10 01:16:19.724873 kernel: SCSI subsystem initialized Mar 10 01:16:19.754392 kernel: Loading iSCSI transport class v2.0-870. Mar 10 01:16:19.853891 kernel: iscsi: registered transport (tcp) Mar 10 01:16:19.950827 kernel: iscsi: registered transport (qla4xxx) Mar 10 01:16:19.951182 kernel: QLogic iSCSI HBA Driver Mar 10 01:16:20.145281 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Mar 10 01:16:20.219214 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Mar 10 01:16:20.322829 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Mar 10 01:16:20.322915 kernel: device-mapper: uevent: version 1.0.3 Mar 10 01:16:20.323685 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Mar 10 01:16:20.533031 kernel: raid6: avx2x4 gen() 14018 MB/s Mar 10 01:16:20.560353 kernel: raid6: avx2x2 gen() 19624 MB/s Mar 10 01:16:20.588918 kernel: raid6: avx2x1 gen() 7205 MB/s Mar 10 01:16:20.589013 kernel: raid6: using algorithm avx2x2 gen() 19624 MB/s Mar 10 01:16:20.618729 kernel: raid6: .... xor() 16672 MB/s, rmw enabled Mar 10 01:16:20.618821 kernel: raid6: using avx2x2 recovery algorithm Mar 10 01:16:20.677688 kernel: xor: automatically using best checksumming function avx Mar 10 01:16:21.786375 kernel: Btrfs loaded, zoned=no, fsverity=no Mar 10 01:16:21.836728 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Mar 10 01:16:21.885944 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 10 01:16:21.966165 systemd-udevd[417]: Using default interface naming scheme 'v255'. Mar 10 01:16:21.977290 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 10 01:16:22.121775 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Mar 10 01:16:22.244353 dracut-pre-trigger[425]: rd.md=0: removing MD RAID activation Mar 10 01:16:22.475008 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Mar 10 01:16:22.516078 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Mar 10 01:16:22.719102 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Mar 10 01:16:22.746786 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Mar 10 01:16:22.842040 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Mar 10 01:16:22.855385 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Mar 10 01:16:22.895078 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 10 01:16:22.931258 systemd[1]: Reached target remote-fs.target - Remote File Systems. Mar 10 01:16:22.983620 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Mar 10 01:16:23.088277 kernel: cryptd: max_cpu_qlen set to 1000 Mar 10 01:16:22.998112 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Mar 10 01:16:22.998290 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 10 01:16:23.002674 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 10 01:16:23.004222 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 10 01:16:23.004740 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 10 01:16:23.004861 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Mar 10 01:16:23.013006 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 10 01:16:23.184020 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Mar 10 01:16:23.330592 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues Mar 10 01:16:23.407936 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Mar 10 01:16:23.431669 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 10 01:16:23.667358 kernel: libata version 3.00 loaded. Mar 10 01:16:23.843197 kernel: AVX2 version of gcm_enc/dec engaged. Mar 10 01:16:23.847594 kernel: AES CTR mode by8 optimization enabled Mar 10 01:16:23.884019 kernel: ahci 0000:00:1f.2: version 3.0 Mar 10 01:16:23.887860 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 Mar 10 01:16:23.942679 kernel: ahci 0000:00:1f.2: AHCI 0001.0000 32 slots 6 ports 1.5 Gbps 0x3f impl SATA mode Mar 10 01:16:23.943034 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only Mar 10 01:16:23.946162 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Mar 10 01:16:25.051360 kernel: BTRFS: device label OEM devid 1 transid 9 /dev/vda6 scanned by (udev-worker) (465) Mar 10 01:16:25.057272 kernel: BTRFS: device fsid a7ce059b-f34b-4785-93b9-44632d452486 devid 1 transid 33 /dev/vda3 scanned by (udev-worker) (479) Mar 10 01:16:25.057325 kernel: scsi host0: ahci Mar 10 01:16:25.080048 kernel: scsi host1: ahci Mar 10 01:16:25.080395 kernel: scsi host2: ahci Mar 10 01:16:25.080821 kernel: scsi host3: ahci Mar 10 01:16:25.081267 kernel: scsi host4: ahci Mar 10 01:16:25.081666 kernel: scsi host5: ahci Mar 10 01:16:25.082662 kernel: ata1: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4100 irq 34 Mar 10 01:16:25.082681 kernel: ata2: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4180 irq 34 Mar 10 01:16:25.082696 kernel: ata3: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4200 irq 34 Mar 10 01:16:25.082713 kernel: ata4: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4280 irq 34 Mar 10 01:16:25.082729 kernel: ata5: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4300 irq 34 Mar 10 01:16:25.082743 kernel: ata6: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4380 irq 34 Mar 10 01:16:25.082767 kernel: ata6: SATA link down (SStatus 0 SControl 300) Mar 10 01:16:25.082783 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Mar 10 01:16:25.082797 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 Mar 10 01:16:25.082811 kernel: ata3.00: applying bridge limits Mar 10 01:16:25.082828 kernel: ata5: SATA link down (SStatus 0 SControl 300) Mar 10 01:16:25.082849 kernel: ata2: SATA link down (SStatus 0 SControl 300) Mar 10 01:16:25.082864 kernel: ata1: SATA link down (SStatus 0 SControl 300) Mar 10 01:16:25.082877 kernel: ata4: SATA link down (SStatus 0 SControl 300) Mar 10 01:16:25.082893 kernel: ata3.00: configured for UDMA/100 Mar 10 01:16:25.082907 kernel: scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 Mar 10 01:16:25.084340 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray Mar 10 01:16:25.085929 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Mar 10 01:16:25.085949 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 Mar 10 01:16:25.099365 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 10 01:16:25.141907 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Mar 10 01:16:25.197326 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Mar 10 01:16:25.229926 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Mar 10 01:16:25.319110 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Mar 10 01:16:25.383378 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Mar 10 01:16:25.450361 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 10 01:16:25.450398 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 10 01:16:25.401193 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 10 01:16:25.481620 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 10 01:16:25.484117 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 10 01:16:26.583154 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 10 01:16:26.599113 disk-uuid[570]: The operation has completed successfully. Mar 10 01:16:26.841183 systemd[1]: disk-uuid.service: Deactivated successfully. Mar 10 01:16:26.841723 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Mar 10 01:16:26.910020 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Mar 10 01:16:26.974650 sh[595]: Success Mar 10 01:16:27.112760 kernel: device-mapper: verity: sha256 using implementation "sha256-ni" Mar 10 01:16:27.402934 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Mar 10 01:16:27.422347 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Mar 10 01:16:27.448683 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Mar 10 01:16:27.543604 kernel: BTRFS info (device dm-0): first mount of filesystem a7ce059b-f34b-4785-93b9-44632d452486 Mar 10 01:16:27.543667 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Mar 10 01:16:27.584673 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Mar 10 01:16:27.584731 kernel: BTRFS info (device dm-0): disabling log replay at mount time Mar 10 01:16:27.592670 kernel: BTRFS info (device dm-0): using free space tree Mar 10 01:16:27.680652 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Mar 10 01:16:27.701120 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Mar 10 01:16:27.751952 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Mar 10 01:16:27.790009 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Mar 10 01:16:27.849152 kernel: BTRFS info (device vda6): first mount of filesystem 3e73d814-00c9-411d-8220-21b9b3666124 Mar 10 01:16:27.849215 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Mar 10 01:16:27.849614 kernel: BTRFS info (device vda6): using free space tree Mar 10 01:16:27.884808 kernel: BTRFS info (device vda6): auto enabling async discard Mar 10 01:16:27.925186 systemd[1]: mnt-oem.mount: Deactivated successfully. Mar 10 01:16:27.948921 kernel: BTRFS info (device vda6): last unmount of filesystem 3e73d814-00c9-411d-8220-21b9b3666124 Mar 10 01:16:27.966595 systemd[1]: Finished ignition-setup.service - Ignition (setup). Mar 10 01:16:28.006627 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Mar 10 01:16:29.423902 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Mar 10 01:16:29.465194 systemd[1]: Starting systemd-networkd.service - Network Configuration... Mar 10 01:16:29.498100 ignition[673]: Ignition 2.19.0 Mar 10 01:16:29.498296 ignition[673]: Stage: fetch-offline Mar 10 01:16:29.508801 ignition[673]: no configs at "/usr/lib/ignition/base.d" Mar 10 01:16:29.508832 ignition[673]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 10 01:16:29.525155 ignition[673]: parsed url from cmdline: "" Mar 10 01:16:29.525165 ignition[673]: no config URL provided Mar 10 01:16:29.525181 ignition[673]: reading system config file "/usr/lib/ignition/user.ign" Mar 10 01:16:29.542104 systemd-networkd[783]: lo: Link UP Mar 10 01:16:29.525200 ignition[673]: no config at "/usr/lib/ignition/user.ign" Mar 10 01:16:29.542113 systemd-networkd[783]: lo: Gained carrier Mar 10 01:16:29.525397 ignition[673]: op(1): [started] loading QEMU firmware config module Mar 10 01:16:29.547969 systemd-networkd[783]: Enumeration completed Mar 10 01:16:29.525404 ignition[673]: op(1): executing: "modprobe" "qemu_fw_cfg" Mar 10 01:16:29.549147 systemd[1]: Started systemd-networkd.service - Network Configuration. Mar 10 01:16:29.552009 systemd-networkd[783]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 10 01:16:29.552015 systemd-networkd[783]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 10 01:16:29.581722 systemd-networkd[783]: eth0: Link UP Mar 10 01:16:29.688858 ignition[673]: op(1): [finished] loading QEMU firmware config module Mar 10 01:16:29.581731 systemd-networkd[783]: eth0: Gained carrier Mar 10 01:16:29.690701 ignition[673]: parsing config with SHA512: 6bc30e7a3bc817a8dbf6cbbfa2ea1c152ec15ff30b08ba75eac932e104c244c2aa1847ae3f0640a6a6917d34acd18bb5959dc182d99b22f4418b10bd1dd9a5a7 Mar 10 01:16:29.581748 systemd-networkd[783]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 10 01:16:29.591875 systemd[1]: Reached target network.target - Network. Mar 10 01:16:29.741961 systemd-networkd[783]: eth0: DHCPv4 address 10.0.0.100/16, gateway 10.0.0.1 acquired from 10.0.0.1 Mar 10 01:16:29.813878 ignition[673]: fetch-offline: fetch-offline passed Mar 10 01:16:29.813246 unknown[673]: fetched base config from "system" Mar 10 01:16:29.814302 ignition[673]: Ignition finished successfully Mar 10 01:16:29.813259 unknown[673]: fetched user config from "qemu" Mar 10 01:16:29.821418 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Mar 10 01:16:29.856816 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Mar 10 01:16:29.892176 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Mar 10 01:16:30.100098 ignition[788]: Ignition 2.19.0 Mar 10 01:16:30.100183 ignition[788]: Stage: kargs Mar 10 01:16:30.100413 ignition[788]: no configs at "/usr/lib/ignition/base.d" Mar 10 01:16:30.103905 ignition[788]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 10 01:16:30.106245 ignition[788]: kargs: kargs passed Mar 10 01:16:30.106319 ignition[788]: Ignition finished successfully Mar 10 01:16:30.145701 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Mar 10 01:16:30.187021 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Mar 10 01:16:31.059092 ignition[796]: Ignition 2.19.0 Mar 10 01:16:31.059753 ignition[796]: Stage: disks Mar 10 01:16:31.096671 systemd[1]: Finished ignition-disks.service - Ignition (disks). Mar 10 01:16:31.061869 ignition[796]: no configs at "/usr/lib/ignition/base.d" Mar 10 01:16:31.061890 ignition[796]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 10 01:16:31.146782 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Mar 10 01:16:31.079121 ignition[796]: disks: disks passed Mar 10 01:16:31.240153 systemd-networkd[783]: eth0: Gained IPv6LL Mar 10 01:16:31.079318 ignition[796]: Ignition finished successfully Mar 10 01:16:31.248082 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Mar 10 01:16:31.353028 systemd[1]: Reached target local-fs.target - Local File Systems. Mar 10 01:16:31.385305 systemd[1]: Reached target sysinit.target - System Initialization. Mar 10 01:16:31.413328 systemd[1]: Reached target basic.target - Basic System. Mar 10 01:16:31.473395 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Mar 10 01:16:31.632791 systemd-fsck[806]: ROOT: clean, 14/553520 files, 52654/553472 blocks Mar 10 01:16:31.693146 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Mar 10 01:16:31.737988 systemd[1]: Mounting sysroot.mount - /sysroot... Mar 10 01:16:32.692146 kernel: EXT4-fs (vda9): mounted filesystem 8ab7565f-94b4-4514-a19e-abd5bcc78da1 r/w with ordered data mode. Quota mode: none. Mar 10 01:16:32.693962 systemd[1]: Mounted sysroot.mount - /sysroot. Mar 10 01:16:32.708829 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Mar 10 01:16:32.762863 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Mar 10 01:16:32.777985 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Mar 10 01:16:32.846201 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/vda6 scanned by mount (815) Mar 10 01:16:32.846269 kernel: BTRFS info (device vda6): first mount of filesystem 3e73d814-00c9-411d-8220-21b9b3666124 Mar 10 01:16:32.846290 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Mar 10 01:16:32.846327 kernel: BTRFS info (device vda6): using free space tree Mar 10 01:16:32.795416 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Mar 10 01:16:32.887357 kernel: BTRFS info (device vda6): auto enabling async discard Mar 10 01:16:32.795716 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Mar 10 01:16:32.795756 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Mar 10 01:16:32.859768 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Mar 10 01:16:32.922002 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Mar 10 01:16:32.969064 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Mar 10 01:16:33.212069 initrd-setup-root[839]: cut: /sysroot/etc/passwd: No such file or directory Mar 10 01:16:33.278843 initrd-setup-root[846]: cut: /sysroot/etc/group: No such file or directory Mar 10 01:16:33.321159 initrd-setup-root[853]: cut: /sysroot/etc/shadow: No such file or directory Mar 10 01:16:33.372837 initrd-setup-root[860]: cut: /sysroot/etc/gshadow: No such file or directory Mar 10 01:16:34.895869 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Mar 10 01:16:34.987944 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Mar 10 01:16:35.029922 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Mar 10 01:16:35.166684 systemd[1]: sysroot-oem.mount: Deactivated successfully. Mar 10 01:16:35.206842 kernel: BTRFS info (device vda6): last unmount of filesystem 3e73d814-00c9-411d-8220-21b9b3666124 Mar 10 01:16:35.589249 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Mar 10 01:16:36.788314 ignition[927]: INFO : Ignition 2.19.0 Mar 10 01:16:36.788314 ignition[927]: INFO : Stage: mount Mar 10 01:16:36.822996 ignition[927]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 10 01:16:36.822996 ignition[927]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 10 01:16:36.882923 ignition[927]: INFO : mount: mount passed Mar 10 01:16:36.882923 ignition[927]: INFO : Ignition finished successfully Mar 10 01:16:36.913275 systemd[1]: Finished ignition-mount.service - Ignition (mount). Mar 10 01:16:36.990849 systemd[1]: Starting ignition-files.service - Ignition (files)... Mar 10 01:16:37.131049 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Mar 10 01:16:37.343219 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 scanned by mount (941) Mar 10 01:16:37.382069 kernel: BTRFS info (device vda6): first mount of filesystem 3e73d814-00c9-411d-8220-21b9b3666124 Mar 10 01:16:37.382360 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Mar 10 01:16:37.422925 kernel: BTRFS info (device vda6): using free space tree Mar 10 01:16:37.609790 kernel: BTRFS info (device vda6): auto enabling async discard Mar 10 01:16:37.633113 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Mar 10 01:16:38.022163 ignition[957]: INFO : Ignition 2.19.0 Mar 10 01:16:38.022163 ignition[957]: INFO : Stage: files Mar 10 01:16:38.022163 ignition[957]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 10 01:16:38.022163 ignition[957]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 10 01:16:38.090919 ignition[957]: DEBUG : files: compiled without relabeling support, skipping Mar 10 01:16:38.114045 ignition[957]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Mar 10 01:16:38.114045 ignition[957]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Mar 10 01:16:38.210017 ignition[957]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Mar 10 01:16:38.229235 ignition[957]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Mar 10 01:16:38.229235 ignition[957]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Mar 10 01:16:38.225846 unknown[957]: wrote ssh authorized keys file for user: core Mar 10 01:16:38.304194 ignition[957]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Mar 10 01:16:38.304194 ignition[957]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Mar 10 01:16:38.304194 ignition[957]: INFO : files: op(4): [started] processing unit "coreos-metadata.service" Mar 10 01:16:38.304194 ignition[957]: INFO : files: op(4): op(5): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Mar 10 01:16:38.304194 ignition[957]: INFO : files: op(4): op(5): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Mar 10 01:16:38.304194 ignition[957]: INFO : files: op(4): [finished] processing unit "coreos-metadata.service" Mar 10 01:16:38.304194 ignition[957]: INFO : files: op(6): [started] processing unit "etcd-member.service" Mar 10 01:16:38.304194 ignition[957]: INFO : files: op(6): op(7): [started] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Mar 10 01:16:38.304194 ignition[957]: INFO : files: op(6): op(7): [finished] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Mar 10 01:16:38.304194 ignition[957]: INFO : files: op(6): [finished] processing unit "etcd-member.service" Mar 10 01:16:38.304194 ignition[957]: INFO : files: op(8): [started] setting preset to disabled for "coreos-metadata.service" Mar 10 01:16:38.679737 ignition[957]: INFO : files: op(8): op(9): [started] removing enablement symlink(s) for "coreos-metadata.service" Mar 10 01:16:38.704983 ignition[957]: INFO : files: op(8): op(9): [finished] removing enablement symlink(s) for "coreos-metadata.service" Mar 10 01:16:38.728323 ignition[957]: INFO : files: op(8): [finished] setting preset to disabled for "coreos-metadata.service" Mar 10 01:16:38.728323 ignition[957]: INFO : files: op(a): [started] setting preset to enabled for "etcd-member.service" Mar 10 01:16:38.728323 ignition[957]: INFO : files: op(a): [finished] setting preset to enabled for "etcd-member.service" Mar 10 01:16:38.728323 ignition[957]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Mar 10 01:16:38.728323 ignition[957]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Mar 10 01:16:38.728323 ignition[957]: INFO : files: files passed Mar 10 01:16:38.728323 ignition[957]: INFO : Ignition finished successfully Mar 10 01:16:38.857420 systemd[1]: Finished ignition-files.service - Ignition (files). Mar 10 01:16:38.938261 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Mar 10 01:16:38.951821 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Mar 10 01:16:38.997716 systemd[1]: ignition-quench.service: Deactivated successfully. Mar 10 01:16:39.021334 initrd-setup-root-after-ignition[985]: grep: /sysroot/oem/oem-release: No such file or directory Mar 10 01:16:38.998067 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Mar 10 01:16:39.069308 initrd-setup-root-after-ignition[987]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Mar 10 01:16:39.069308 initrd-setup-root-after-ignition[987]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Mar 10 01:16:39.032931 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Mar 10 01:16:39.133381 initrd-setup-root-after-ignition[992]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Mar 10 01:16:39.070317 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Mar 10 01:16:39.135884 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Mar 10 01:16:39.226026 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Mar 10 01:16:39.238993 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Mar 10 01:16:39.280985 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Mar 10 01:16:39.292929 systemd[1]: Reached target initrd.target - Initrd Default Target. Mar 10 01:16:39.322856 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Mar 10 01:16:39.346415 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Mar 10 01:16:39.420061 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Mar 10 01:16:39.477117 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Mar 10 01:16:39.604250 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Mar 10 01:16:39.622313 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 10 01:16:39.641305 systemd[1]: Stopped target timers.target - Timer Units. Mar 10 01:16:39.665324 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Mar 10 01:16:39.665761 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Mar 10 01:16:39.673365 systemd[1]: Stopped target initrd.target - Initrd Default Target. Mar 10 01:16:39.675657 systemd[1]: Stopped target basic.target - Basic System. Mar 10 01:16:39.689938 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Mar 10 01:16:39.690111 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Mar 10 01:16:40.203295 ignition[1012]: INFO : Ignition 2.19.0 Mar 10 01:16:40.203295 ignition[1012]: INFO : Stage: umount Mar 10 01:16:40.203295 ignition[1012]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 10 01:16:40.203295 ignition[1012]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 10 01:16:40.203295 ignition[1012]: INFO : umount: umount passed Mar 10 01:16:40.203295 ignition[1012]: INFO : Ignition finished successfully Mar 10 01:16:39.690252 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Mar 10 01:16:39.690394 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Mar 10 01:16:39.690747 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Mar 10 01:16:39.690903 systemd[1]: Stopped target sysinit.target - System Initialization. Mar 10 01:16:39.691047 systemd[1]: Stopped target local-fs.target - Local File Systems. Mar 10 01:16:39.691366 systemd[1]: Stopped target swap.target - Swaps. Mar 10 01:16:39.706313 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Mar 10 01:16:39.706823 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Mar 10 01:16:39.717746 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Mar 10 01:16:39.729859 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 10 01:16:39.729985 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Mar 10 01:16:39.732903 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 10 01:16:39.733042 systemd[1]: dracut-initqueue.service: Deactivated successfully. Mar 10 01:16:39.733219 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Mar 10 01:16:39.733724 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Mar 10 01:16:39.733889 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Mar 10 01:16:39.734232 systemd[1]: Stopped target paths.target - Path Units. Mar 10 01:16:39.734324 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Mar 10 01:16:39.742041 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 10 01:16:39.745714 systemd[1]: Stopped target slices.target - Slice Units. Mar 10 01:16:39.748998 systemd[1]: Stopped target sockets.target - Socket Units. Mar 10 01:16:39.771332 systemd[1]: iscsid.socket: Deactivated successfully. Mar 10 01:16:39.775977 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Mar 10 01:16:39.776676 systemd[1]: iscsiuio.socket: Deactivated successfully. Mar 10 01:16:39.776815 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Mar 10 01:16:39.781014 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Mar 10 01:16:39.781189 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Mar 10 01:16:39.787076 systemd[1]: ignition-files.service: Deactivated successfully. Mar 10 01:16:39.787217 systemd[1]: Stopped ignition-files.service - Ignition (files). Mar 10 01:16:40.046361 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Mar 10 01:16:40.078669 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Mar 10 01:16:40.079013 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Mar 10 01:16:40.208623 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Mar 10 01:16:40.220394 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Mar 10 01:16:40.220830 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Mar 10 01:16:40.240336 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Mar 10 01:16:40.240807 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Mar 10 01:16:40.341173 systemd[1]: ignition-mount.service: Deactivated successfully. Mar 10 01:16:40.342264 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Mar 10 01:16:40.379857 systemd[1]: Stopped target network.target - Network. Mar 10 01:16:40.423131 systemd[1]: ignition-disks.service: Deactivated successfully. Mar 10 01:16:40.423248 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Mar 10 01:16:40.438069 systemd[1]: ignition-kargs.service: Deactivated successfully. Mar 10 01:16:40.438162 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Mar 10 01:16:40.465336 systemd[1]: ignition-setup.service: Deactivated successfully. Mar 10 01:16:40.465632 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Mar 10 01:16:40.533341 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Mar 10 01:16:40.535005 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Mar 10 01:16:40.623919 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Mar 10 01:16:40.646050 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Mar 10 01:16:40.688196 systemd[1]: systemd-resolved.service: Deactivated successfully. Mar 10 01:16:40.688594 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Mar 10 01:16:40.697768 systemd-networkd[783]: eth0: DHCPv6 lease lost Mar 10 01:16:40.730908 systemd[1]: systemd-networkd.service: Deactivated successfully. Mar 10 01:16:40.731685 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Mar 10 01:16:41.630338 systemd-journald[195]: Received SIGTERM from PID 1 (systemd). Mar 10 01:16:40.746693 systemd[1]: initrd-cleanup.service: Deactivated successfully. Mar 10 01:16:40.746936 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Mar 10 01:16:40.803233 systemd[1]: systemd-networkd.socket: Deactivated successfully. Mar 10 01:16:40.803335 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Mar 10 01:16:40.910223 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Mar 10 01:16:40.945004 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Mar 10 01:16:40.945134 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Mar 10 01:16:40.968858 systemd[1]: systemd-sysctl.service: Deactivated successfully. Mar 10 01:16:40.968972 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Mar 10 01:16:40.983974 systemd[1]: systemd-modules-load.service: Deactivated successfully. Mar 10 01:16:40.984082 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Mar 10 01:16:40.984193 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Mar 10 01:16:40.984263 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 10 01:16:40.984882 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 10 01:16:41.003332 systemd[1]: sysroot-boot.mount: Deactivated successfully. Mar 10 01:16:41.057986 systemd[1]: network-cleanup.service: Deactivated successfully. Mar 10 01:16:41.058342 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Mar 10 01:16:41.080805 systemd[1]: systemd-udevd.service: Deactivated successfully. Mar 10 01:16:41.081160 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 10 01:16:41.088834 systemd[1]: sysroot-boot.service: Deactivated successfully. Mar 10 01:16:41.089078 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Mar 10 01:16:41.148077 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Mar 10 01:16:41.148199 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Mar 10 01:16:41.166975 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Mar 10 01:16:41.167061 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Mar 10 01:16:41.167154 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Mar 10 01:16:41.167239 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Mar 10 01:16:41.241097 systemd[1]: dracut-cmdline.service: Deactivated successfully. Mar 10 01:16:41.241205 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Mar 10 01:16:41.293813 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Mar 10 01:16:41.293934 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 10 01:16:41.332904 systemd[1]: initrd-setup-root.service: Deactivated successfully. Mar 10 01:16:41.333026 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Mar 10 01:16:41.396869 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Mar 10 01:16:41.433948 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Mar 10 01:16:41.434119 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 10 01:16:41.434261 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 10 01:16:41.434337 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 10 01:16:41.435309 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Mar 10 01:16:41.435848 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Mar 10 01:16:41.445931 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Mar 10 01:16:41.468997 systemd[1]: Starting initrd-switch-root.service - Switch Root... Mar 10 01:16:41.514219 systemd[1]: Switching root. Mar 10 01:16:41.685837 systemd-journald[195]: Journal stopped Mar 10 01:16:48.525945 kernel: SELinux: policy capability network_peer_controls=1 Mar 10 01:16:48.526169 kernel: SELinux: policy capability open_perms=1 Mar 10 01:16:48.526196 kernel: SELinux: policy capability extended_socket_class=1 Mar 10 01:16:48.526216 kernel: SELinux: policy capability always_check_network=0 Mar 10 01:16:48.526231 kernel: SELinux: policy capability cgroup_seclabel=1 Mar 10 01:16:48.526246 kernel: SELinux: policy capability nnp_nosuid_transition=1 Mar 10 01:16:48.526261 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Mar 10 01:16:48.526286 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Mar 10 01:16:48.526305 kernel: audit: type=1403 audit(1773105401.943:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Mar 10 01:16:48.534685 systemd[1]: Successfully loaded SELinux policy in 233.938ms. Mar 10 01:16:48.534742 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 74.515ms. Mar 10 01:16:48.534761 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Mar 10 01:16:48.534779 systemd[1]: Detected virtualization kvm. Mar 10 01:16:48.534885 systemd[1]: Detected architecture x86-64. Mar 10 01:16:48.534909 systemd[1]: Detected first boot. Mar 10 01:16:48.534926 systemd[1]: Initializing machine ID from VM UUID. Mar 10 01:16:48.534943 zram_generator::config[1056]: No configuration found. Mar 10 01:16:48.534962 systemd[1]: Populated /etc with preset unit settings. Mar 10 01:16:48.534982 systemd[1]: initrd-switch-root.service: Deactivated successfully. Mar 10 01:16:48.534998 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Mar 10 01:16:48.535014 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Mar 10 01:16:48.535031 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Mar 10 01:16:48.535063 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Mar 10 01:16:48.535080 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Mar 10 01:16:48.535097 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Mar 10 01:16:48.535127 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Mar 10 01:16:48.535144 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Mar 10 01:16:48.535161 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Mar 10 01:16:48.535184 systemd[1]: Created slice user.slice - User and Session Slice. Mar 10 01:16:48.535207 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 10 01:16:48.535224 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 10 01:16:48.535239 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Mar 10 01:16:48.535257 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Mar 10 01:16:48.535279 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Mar 10 01:16:48.535295 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Mar 10 01:16:48.535311 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Mar 10 01:16:48.535333 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 10 01:16:48.535353 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Mar 10 01:16:48.535370 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Mar 10 01:16:48.535385 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Mar 10 01:16:48.535593 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Mar 10 01:16:48.535616 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 10 01:16:48.539132 systemd[1]: Reached target remote-fs.target - Remote File Systems. Mar 10 01:16:48.539160 systemd[1]: Reached target slices.target - Slice Units. Mar 10 01:16:48.539187 systemd[1]: Reached target swap.target - Swaps. Mar 10 01:16:48.539208 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Mar 10 01:16:48.539227 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Mar 10 01:16:48.539247 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Mar 10 01:16:48.539393 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Mar 10 01:16:48.539708 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Mar 10 01:16:48.539729 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Mar 10 01:16:48.539747 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Mar 10 01:16:48.539766 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Mar 10 01:16:48.539793 systemd[1]: Mounting media.mount - External Media Directory... Mar 10 01:16:48.539813 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 10 01:16:48.539832 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Mar 10 01:16:48.539852 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Mar 10 01:16:48.539868 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Mar 10 01:16:48.539885 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Mar 10 01:16:48.539905 systemd[1]: Reached target machines.target - Containers. Mar 10 01:16:48.539922 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Mar 10 01:16:48.539941 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 10 01:16:48.539965 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Mar 10 01:16:48.539986 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Mar 10 01:16:48.540004 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 10 01:16:48.540023 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Mar 10 01:16:48.540041 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 10 01:16:48.540060 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Mar 10 01:16:48.540077 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 10 01:16:48.540220 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Mar 10 01:16:48.540248 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Mar 10 01:16:48.540268 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Mar 10 01:16:48.540287 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Mar 10 01:16:48.540305 systemd[1]: Stopped systemd-fsck-usr.service. Mar 10 01:16:48.540324 kernel: fuse: init (API version 7.39) Mar 10 01:16:48.540342 systemd[1]: Starting systemd-journald.service - Journal Service... Mar 10 01:16:48.540361 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Mar 10 01:16:48.540380 kernel: ACPI: bus type drm_connector registered Mar 10 01:16:48.540692 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Mar 10 01:16:48.540728 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Mar 10 01:16:48.540748 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Mar 10 01:16:48.540767 systemd[1]: verity-setup.service: Deactivated successfully. Mar 10 01:16:48.540786 systemd[1]: Stopped verity-setup.service. Mar 10 01:16:48.540805 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 10 01:16:48.540870 systemd-journald[1141]: Collecting audit messages is disabled. Mar 10 01:16:48.540914 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Mar 10 01:16:48.540933 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Mar 10 01:16:48.540954 systemd[1]: Mounted media.mount - External Media Directory. Mar 10 01:16:48.540971 kernel: loop: module loaded Mar 10 01:16:48.540992 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Mar 10 01:16:48.541136 systemd-journald[1141]: Journal started Mar 10 01:16:48.541169 systemd-journald[1141]: Runtime Journal (/run/log/journal/2d3ff0f5d2fe4dea91e56c9c89cec8a2) is 6.0M, max 48.4M, 42.3M free. Mar 10 01:16:45.271914 systemd[1]: Queued start job for default target multi-user.target. Mar 10 01:16:45.393263 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Mar 10 01:16:45.395736 systemd[1]: systemd-journald.service: Deactivated successfully. Mar 10 01:16:45.402292 systemd[1]: systemd-journald.service: Consumed 3.498s CPU time. Mar 10 01:16:48.582987 systemd[1]: Started systemd-journald.service - Journal Service. Mar 10 01:16:48.599197 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Mar 10 01:16:48.614113 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Mar 10 01:16:48.633191 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Mar 10 01:16:48.651846 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Mar 10 01:16:48.687614 systemd[1]: modprobe@configfs.service: Deactivated successfully. Mar 10 01:16:48.688262 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Mar 10 01:16:48.705757 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 10 01:16:48.706098 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 10 01:16:48.722883 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 10 01:16:48.723322 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Mar 10 01:16:48.741871 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 10 01:16:48.742294 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 10 01:16:48.771351 systemd[1]: modprobe@fuse.service: Deactivated successfully. Mar 10 01:16:48.772179 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Mar 10 01:16:48.793209 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 10 01:16:48.794278 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 10 01:16:48.818965 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Mar 10 01:16:48.841380 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Mar 10 01:16:48.879858 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Mar 10 01:16:48.909371 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Mar 10 01:16:49.042325 systemd[1]: Reached target network-pre.target - Preparation for Network. Mar 10 01:16:49.092957 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Mar 10 01:16:49.159877 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Mar 10 01:16:49.184682 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Mar 10 01:16:49.184903 systemd[1]: Reached target local-fs.target - Local File Systems. Mar 10 01:16:49.206819 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Mar 10 01:16:49.237005 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Mar 10 01:16:49.284731 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Mar 10 01:16:49.306845 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 10 01:16:49.318905 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Mar 10 01:16:49.388820 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Mar 10 01:16:49.406028 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 10 01:16:49.411117 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Mar 10 01:16:49.427628 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Mar 10 01:16:49.437289 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Mar 10 01:16:49.505875 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Mar 10 01:16:49.538749 systemd-journald[1141]: Time spent on flushing to /var/log/journal/2d3ff0f5d2fe4dea91e56c9c89cec8a2 is 518.058ms for 918 entries. Mar 10 01:16:49.538749 systemd-journald[1141]: System Journal (/var/log/journal/2d3ff0f5d2fe4dea91e56c9c89cec8a2) is 8.0M, max 195.6M, 187.6M free. Mar 10 01:16:51.225165 systemd-journald[1141]: Received client request to flush runtime journal. Mar 10 01:16:51.225626 kernel: loop0: detected capacity change from 0 to 142488 Mar 10 01:16:49.589049 systemd[1]: Starting systemd-sysusers.service - Create System Users... Mar 10 01:16:49.639122 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Mar 10 01:16:50.090106 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Mar 10 01:16:50.109633 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Mar 10 01:16:50.131269 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Mar 10 01:16:50.172272 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Mar 10 01:16:50.278361 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Mar 10 01:16:50.329088 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Mar 10 01:16:51.226195 udevadm[1175]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Mar 10 01:16:51.236777 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Mar 10 01:16:51.297066 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Mar 10 01:16:51.357972 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Mar 10 01:16:51.389622 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Mar 10 01:16:51.869247 systemd[1]: Finished systemd-sysusers.service - Create System Users. Mar 10 01:16:51.884034 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Mar 10 01:16:51.905770 kernel: loop1: detected capacity change from 0 to 140768 Mar 10 01:16:51.928692 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Mar 10 01:16:52.278366 kernel: loop2: detected capacity change from 0 to 142488 Mar 10 01:16:52.336335 systemd-tmpfiles[1191]: ACLs are not supported, ignoring. Mar 10 01:16:52.336365 systemd-tmpfiles[1191]: ACLs are not supported, ignoring. Mar 10 01:16:52.412340 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 10 01:16:52.649322 kernel: loop3: detected capacity change from 0 to 140768 Mar 10 01:16:53.042025 (sd-merge)[1194]: Using extensions 'containerd-flatcar', 'docker-flatcar'. Mar 10 01:16:53.051851 (sd-merge)[1194]: Merged extensions into '/usr'. Mar 10 01:16:53.529180 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Mar 10 01:16:53.870280 systemd[1]: Starting ensure-sysext.service... Mar 10 01:16:53.999772 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Mar 10 01:16:54.401212 systemd[1]: Reloading requested from client PID 1199 ('systemctl') (unit ensure-sysext.service)... Mar 10 01:16:54.401360 systemd[1]: Reloading... Mar 10 01:16:55.180646 systemd-tmpfiles[1200]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Mar 10 01:16:55.181612 systemd-tmpfiles[1200]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Mar 10 01:16:55.196188 systemd-tmpfiles[1200]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Mar 10 01:16:55.201591 systemd-tmpfiles[1200]: ACLs are not supported, ignoring. Mar 10 01:16:55.202273 systemd-tmpfiles[1200]: ACLs are not supported, ignoring. Mar 10 01:16:55.227673 zram_generator::config[1227]: No configuration found. Mar 10 01:16:55.240133 systemd-tmpfiles[1200]: Detected autofs mount point /boot during canonicalization of boot. Mar 10 01:16:55.240150 systemd-tmpfiles[1200]: Skipping /boot Mar 10 01:16:56.210647 systemd-tmpfiles[1200]: Detected autofs mount point /boot during canonicalization of boot. Mar 10 01:16:56.210674 systemd-tmpfiles[1200]: Skipping /boot Mar 10 01:16:56.921632 ldconfig[1167]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Mar 10 01:16:57.333369 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Mar 10 01:16:57.454984 systemd[1]: Reloading finished in 3052 ms. Mar 10 01:16:57.525794 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Mar 10 01:16:57.547663 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 10 01:16:58.376213 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Mar 10 01:16:58.419978 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Mar 10 01:16:58.489119 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Mar 10 01:16:58.526852 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Mar 10 01:16:58.549830 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Mar 10 01:16:58.613187 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Mar 10 01:16:58.639893 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Mar 10 01:16:58.683682 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Mar 10 01:16:58.714911 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 10 01:16:58.715881 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 10 01:16:58.739038 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 10 01:16:58.772724 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 10 01:16:58.804943 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 10 01:16:58.808807 augenrules[1292]: No rules Mar 10 01:16:58.823184 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 10 01:16:58.823397 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 10 01:16:58.826861 systemd[1]: Started systemd-userdbd.service - User Database Manager. Mar 10 01:16:58.840409 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Mar 10 01:16:58.859395 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 10 01:16:58.860291 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 10 01:16:58.887130 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Mar 10 01:16:58.903712 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 10 01:16:58.904058 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 10 01:16:58.922656 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Mar 10 01:16:58.938989 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 10 01:16:58.939217 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 10 01:16:58.984030 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 10 01:16:58.984320 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 10 01:16:58.996281 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 10 01:16:59.012609 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 10 01:16:59.035230 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 10 01:16:59.048016 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 10 01:16:59.052241 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 10 01:16:59.081263 systemd[1]: Starting systemd-update-done.service - Update is Completed... Mar 10 01:16:59.093314 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Mar 10 01:16:59.093822 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 10 01:16:59.097718 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 10 01:16:59.098136 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 10 01:16:59.113032 systemd-udevd[1312]: Using default interface naming scheme 'v255'. Mar 10 01:16:59.115780 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 10 01:16:59.116755 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 10 01:16:59.129684 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 10 01:16:59.129947 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 10 01:16:59.142894 systemd[1]: Finished systemd-update-done.service - Update is Completed. Mar 10 01:16:59.155045 systemd-resolved[1276]: Positive Trust Anchors: Mar 10 01:16:59.155144 systemd-resolved[1276]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 10 01:16:59.155190 systemd-resolved[1276]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Mar 10 01:16:59.169737 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 10 01:16:59.170021 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 10 01:16:59.173863 systemd-resolved[1276]: Defaulting to hostname 'linux'. Mar 10 01:16:59.190282 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 10 01:16:59.209224 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Mar 10 01:16:59.223766 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 10 01:16:59.248404 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 10 01:16:59.274991 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 10 01:16:59.275307 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Mar 10 01:16:59.276300 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 10 01:16:59.280623 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Mar 10 01:16:59.301372 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 10 01:16:59.322300 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 10 01:16:59.324114 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 10 01:16:59.349057 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 10 01:16:59.349598 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Mar 10 01:16:59.373624 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 10 01:16:59.374008 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 10 01:16:59.394249 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 10 01:16:59.394892 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 10 01:16:59.416822 systemd[1]: Finished ensure-sysext.service. Mar 10 01:16:59.477253 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Mar 10 01:16:59.489176 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Mar 10 01:16:59.527905 systemd[1]: Starting systemd-networkd.service - Network Configuration... Mar 10 01:16:59.551616 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 Mar 10 01:16:59.561318 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 10 01:16:59.561631 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Mar 10 01:16:59.576931 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Mar 10 01:16:59.602692 kernel: ACPI: button: Power Button [PWRF] Mar 10 01:16:59.620034 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 33 scanned by (udev-worker) (1328) Mar 10 01:16:59.675199 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Mar 10 01:16:59.677083 kernel: i2c i2c-0: 1/1 memory slots populated (from DMI) Mar 10 01:16:59.697896 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Mar 10 01:16:59.716788 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Mar 10 01:16:59.747950 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Mar 10 01:16:59.799780 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input4 Mar 10 01:16:59.823725 kernel: mousedev: PS/2 mouse device common for all mice Mar 10 01:16:59.840323 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 10 01:16:59.873115 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Mar 10 01:16:59.963243 systemd[1]: Reached target time-set.target - System Time Set. Mar 10 01:17:00.129365 systemd-networkd[1350]: lo: Link UP Mar 10 01:17:00.129409 systemd-networkd[1350]: lo: Gained carrier Mar 10 01:17:00.134975 systemd-networkd[1350]: Enumeration completed Mar 10 01:17:00.148060 systemd[1]: Started systemd-networkd.service - Network Configuration. Mar 10 01:17:00.160989 systemd[1]: Reached target network.target - Network. Mar 10 01:17:00.605993 systemd-networkd[1350]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 10 01:17:00.606156 systemd-networkd[1350]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 10 01:17:00.741419 systemd-networkd[1350]: eth0: Link UP Mar 10 01:17:00.741628 systemd-networkd[1350]: eth0: Gained carrier Mar 10 01:17:00.741666 systemd-networkd[1350]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 10 01:17:05.328935 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Mar 10 01:17:05.454782 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Mar 10 01:17:05.820673 systemd-networkd[1350]: eth0: DHCPv4 address 10.0.0.100/16, gateway 10.0.0.1 acquired from 10.0.0.1 Mar 10 01:17:06.399278 systemd-timesyncd[1353]: Network configuration changed, trying to establish connection. Mar 10 01:17:07.419249 systemd-timesyncd[1353]: Contacted time server 10.0.0.1:123 (10.0.0.1). Mar 10 01:17:07.426853 systemd-timesyncd[1353]: Initial clock synchronization to Tue 2026-03-10 01:17:07.407131 UTC. Mar 10 01:17:07.427283 systemd-resolved[1276]: Clock change detected. Flushing caches. Mar 10 01:17:07.554977 systemd-networkd[1350]: eth0: Gained IPv6LL Mar 10 01:17:07.794847 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Mar 10 01:17:07.887743 systemd[1]: Reached target network-online.target - Network is Online. Mar 10 01:17:09.871197 kernel: kvm_amd: TSC scaling supported Mar 10 01:17:09.875765 kernel: kvm_amd: Nested Virtualization enabled Mar 10 01:17:09.875810 kernel: kvm_amd: Nested Paging enabled Mar 10 01:17:09.875927 kernel: kvm_amd: Virtual VMLOAD VMSAVE supported Mar 10 01:17:09.876732 kernel: kvm_amd: PMU virtualization is disabled Mar 10 01:17:10.535188 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 10 01:17:11.187049 kernel: EDAC MC: Ver: 3.0.0 Mar 10 01:17:11.367073 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Mar 10 01:17:11.429300 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Mar 10 01:17:11.551345 lvm[1374]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 10 01:17:11.736152 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Mar 10 01:17:11.761072 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Mar 10 01:17:11.783216 systemd[1]: Reached target sysinit.target - System Initialization. Mar 10 01:17:11.800136 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Mar 10 01:17:11.818259 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Mar 10 01:17:11.837199 systemd[1]: Started logrotate.timer - Daily rotation of log files. Mar 10 01:17:11.863007 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Mar 10 01:17:11.883381 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Mar 10 01:17:11.903028 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Mar 10 01:17:11.903774 systemd[1]: Reached target paths.target - Path Units. Mar 10 01:17:11.918273 systemd[1]: Reached target timers.target - Timer Units. Mar 10 01:17:11.939747 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Mar 10 01:17:11.959954 systemd[1]: Starting docker.socket - Docker Socket for the API... Mar 10 01:17:12.011228 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Mar 10 01:17:12.058099 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Mar 10 01:17:12.082164 systemd[1]: Listening on docker.socket - Docker Socket for the API. Mar 10 01:17:12.099178 systemd[1]: Reached target sockets.target - Socket Units. Mar 10 01:17:12.100104 lvm[1378]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 10 01:17:12.115350 systemd[1]: Reached target basic.target - Basic System. Mar 10 01:17:12.144936 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Mar 10 01:17:12.145075 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Mar 10 01:17:12.171322 systemd[1]: Starting containerd.service - containerd container runtime... Mar 10 01:17:12.194033 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Mar 10 01:17:12.220800 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Mar 10 01:17:12.253204 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Mar 10 01:17:12.275230 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Mar 10 01:17:12.293233 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Mar 10 01:17:12.300139 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Mar 10 01:17:12.316023 jq[1382]: false Mar 10 01:17:12.324924 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Mar 10 01:17:12.376104 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Mar 10 01:17:12.415257 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Mar 10 01:17:12.432159 extend-filesystems[1383]: Found loop2 Mar 10 01:17:12.432159 extend-filesystems[1383]: Found loop3 Mar 10 01:17:12.432159 extend-filesystems[1383]: Found sr0 Mar 10 01:17:12.432159 extend-filesystems[1383]: Found vda Mar 10 01:17:12.493258 extend-filesystems[1383]: Found vda1 Mar 10 01:17:12.493258 extend-filesystems[1383]: Found vda2 Mar 10 01:17:12.493258 extend-filesystems[1383]: Found vda3 Mar 10 01:17:12.493258 extend-filesystems[1383]: Found usr Mar 10 01:17:12.493258 extend-filesystems[1383]: Found vda4 Mar 10 01:17:12.493258 extend-filesystems[1383]: Found vda6 Mar 10 01:17:12.493258 extend-filesystems[1383]: Found vda7 Mar 10 01:17:12.493258 extend-filesystems[1383]: Found vda9 Mar 10 01:17:12.493258 extend-filesystems[1383]: Checking size of /dev/vda9 Mar 10 01:17:12.613290 dbus-daemon[1381]: [system] SELinux support is enabled Mar 10 01:17:12.791880 extend-filesystems[1383]: Old size kept for /dev/vda9 Mar 10 01:17:12.518990 systemd[1]: Starting systemd-logind.service - User Login Management... Mar 10 01:17:12.580995 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Mar 10 01:17:12.584076 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Mar 10 01:17:12.898178 jq[1408]: true Mar 10 01:17:12.591308 systemd[1]: Starting update-engine.service - Update Engine... Mar 10 01:17:12.635770 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Mar 10 01:17:12.670905 systemd[1]: Started dbus.service - D-Bus System Message Bus. Mar 10 01:17:12.690160 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Mar 10 01:17:12.797922 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Mar 10 01:17:12.799223 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Mar 10 01:17:12.801167 systemd[1]: extend-filesystems.service: Deactivated successfully. Mar 10 01:17:12.808788 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Mar 10 01:17:12.866947 systemd[1]: motdgen.service: Deactivated successfully. Mar 10 01:17:12.867930 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Mar 10 01:17:12.885933 systemd-logind[1397]: Watching system buttons on /dev/input/event1 (Power Button) Mar 10 01:17:12.885968 systemd-logind[1397]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Mar 10 01:17:12.887276 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Mar 10 01:17:12.903378 systemd-logind[1397]: New seat seat0. Mar 10 01:17:12.929375 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Mar 10 01:17:12.934034 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Mar 10 01:17:12.982275 systemd[1]: Started systemd-logind.service - User Login Management. Mar 10 01:17:13.017284 update_engine[1406]: I20260310 01:17:13.000118 1406 main.cc:92] Flatcar Update Engine starting Mar 10 01:17:13.037148 update_engine[1406]: I20260310 01:17:13.036921 1406 update_check_scheduler.cc:74] Next update check in 3m12s Mar 10 01:17:13.063795 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Mar 10 01:17:13.063849 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Mar 10 01:17:13.066292 dbus-daemon[1381]: [system] Successfully activated service 'org.freedesktop.systemd1' Mar 10 01:17:13.076878 jq[1411]: true Mar 10 01:17:13.079187 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Mar 10 01:17:13.079224 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Mar 10 01:17:13.101612 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 33 scanned by (udev-worker) (1332) Mar 10 01:17:13.123302 (ntainerd)[1412]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Mar 10 01:17:13.127187 systemd[1]: Started update-engine.service - Update Engine. Mar 10 01:17:13.138848 systemd[1]: coreos-metadata.service: Deactivated successfully. Mar 10 01:17:13.141877 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Mar 10 01:17:13.187609 systemd[1]: Starting etcd-member.service - etcd (System Application Container)... Mar 10 01:17:13.196677 (-wrapper)[1429]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS Mar 10 01:17:13.201900 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Mar 10 01:17:13.205237 systemd[1]: Started locksmithd.service - Cluster reboot manager. Mar 10 01:17:13.447218 sshd_keygen[1407]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Mar 10 01:17:13.789747 locksmithd[1430]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Mar 10 01:17:13.835672 bash[1452]: Updated "/home/core/.ssh/authorized_keys" Mar 10 01:17:13.864944 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Mar 10 01:17:13.887034 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Mar 10 01:17:13.913769 systemd[1]: Starting issuegen.service - Generate /run/issue... Mar 10 01:17:13.924057 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Mar 10 01:17:14.006039 systemd[1]: issuegen.service: Deactivated successfully. Mar 10 01:17:14.006373 systemd[1]: Finished issuegen.service - Generate /run/issue. Mar 10 01:17:14.044090 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Mar 10 01:17:15.093170 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Mar 10 01:17:15.679792 systemd[1]: Started getty@tty1.service - Getty on tty1. Mar 10 01:17:15.712018 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Mar 10 01:17:15.725847 systemd[1]: Reached target getty.target - Login Prompts. Mar 10 01:17:17.886135 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Mar 10 01:17:17.918186 systemd[1]: Started sshd@0-10.0.0.100:22-10.0.0.1:47840.service - OpenSSH per-connection server daemon (10.0.0.1:47840). Mar 10 01:17:18.291793 containerd[1412]: time="2026-03-10T01:17:18.288710773Z" level=info msg="starting containerd" revision=174e0d1785eeda18dc2beba45e1d5a188771636b version=v1.7.21 Mar 10 01:17:18.423949 containerd[1412]: time="2026-03-10T01:17:18.423735551Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Mar 10 01:17:18.462138 containerd[1412]: time="2026-03-10T01:17:18.461875184Z" 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.127-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Mar 10 01:17:18.462138 containerd[1412]: time="2026-03-10T01:17:18.462011218Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Mar 10 01:17:18.462138 containerd[1412]: time="2026-03-10T01:17:18.462139898Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Mar 10 01:17:18.462981 containerd[1412]: time="2026-03-10T01:17:18.462845205Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Mar 10 01:17:18.463147 containerd[1412]: time="2026-03-10T01:17:18.463059502Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Mar 10 01:17:18.463738 containerd[1412]: time="2026-03-10T01:17:18.463641800Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Mar 10 01:17:18.463790 containerd[1412]: time="2026-03-10T01:17:18.463737088Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Mar 10 01:17:18.464114 containerd[1412]: time="2026-03-10T01:17:18.464051515Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Mar 10 01:17:18.464114 containerd[1412]: time="2026-03-10T01:17:18.464077654Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Mar 10 01:17:18.464114 containerd[1412]: time="2026-03-10T01:17:18.464097200Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Mar 10 01:17:18.464114 containerd[1412]: time="2026-03-10T01:17:18.464113591Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Mar 10 01:17:18.465260 containerd[1412]: time="2026-03-10T01:17:18.464694836Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Mar 10 01:17:18.465984 containerd[1412]: time="2026-03-10T01:17:18.465785043Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Mar 10 01:17:18.467120 containerd[1412]: time="2026-03-10T01:17:18.466122733Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Mar 10 01:17:18.467120 containerd[1412]: time="2026-03-10T01:17:18.467098736Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Mar 10 01:17:18.467917 containerd[1412]: time="2026-03-10T01:17:18.467770791Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Mar 10 01:17:18.467969 containerd[1412]: time="2026-03-10T01:17:18.467935227Z" level=info msg="metadata content store policy set" policy=shared Mar 10 01:17:18.484885 sshd[1483]: Accepted publickey for core from 10.0.0.1 port 47840 ssh2: RSA SHA256:ApINsR2hE/n5EcIFq8gQqQxccKFX8oefpoXcucPhBPc Mar 10 01:17:18.501175 sshd[1483]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 10 01:17:18.505673 containerd[1412]: time="2026-03-10T01:17:18.504566126Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Mar 10 01:17:18.505673 containerd[1412]: time="2026-03-10T01:17:18.504980339Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Mar 10 01:17:18.505673 containerd[1412]: time="2026-03-10T01:17:18.505006779Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Mar 10 01:17:18.505673 containerd[1412]: time="2026-03-10T01:17:18.505023580Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Mar 10 01:17:18.505673 containerd[1412]: time="2026-03-10T01:17:18.505040021Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Mar 10 01:17:18.505673 containerd[1412]: time="2026-03-10T01:17:18.505223253Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Mar 10 01:17:18.507026 containerd[1412]: time="2026-03-10T01:17:18.506290946Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Mar 10 01:17:18.507026 containerd[1412]: time="2026-03-10T01:17:18.506735767Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Mar 10 01:17:18.511848 containerd[1412]: time="2026-03-10T01:17:18.506754813Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Mar 10 01:17:18.511848 containerd[1412]: time="2026-03-10T01:17:18.507946364Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Mar 10 01:17:18.511848 containerd[1412]: time="2026-03-10T01:17:18.507981039Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Mar 10 01:17:18.511848 containerd[1412]: time="2026-03-10T01:17:18.508005455Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Mar 10 01:17:18.511848 containerd[1412]: time="2026-03-10T01:17:18.508023749Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Mar 10 01:17:18.511848 containerd[1412]: time="2026-03-10T01:17:18.508044277Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Mar 10 01:17:18.511848 containerd[1412]: time="2026-03-10T01:17:18.508077810Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Mar 10 01:17:18.533164 containerd[1412]: time="2026-03-10T01:17:18.508111112Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Mar 10 01:17:18.610119 containerd[1412]: time="2026-03-10T01:17:18.593986006Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Mar 10 01:17:18.610368 containerd[1412]: time="2026-03-10T01:17:18.610114601Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Mar 10 01:17:18.621167 containerd[1412]: time="2026-03-10T01:17:18.617207122Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Mar 10 01:17:18.621167 containerd[1412]: time="2026-03-10T01:17:18.624371260Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Mar 10 01:17:18.628281 containerd[1412]: time="2026-03-10T01:17:18.627887826Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Mar 10 01:17:18.628281 containerd[1412]: time="2026-03-10T01:17:18.628085626Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Mar 10 01:17:18.628281 containerd[1412]: time="2026-03-10T01:17:18.628108298Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Mar 10 01:17:18.628281 containerd[1412]: time="2026-03-10T01:17:18.628128556Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Mar 10 01:17:18.628281 containerd[1412]: time="2026-03-10T01:17:18.628249642Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Mar 10 01:17:18.628678 containerd[1412]: time="2026-03-10T01:17:18.628316256Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Mar 10 01:17:18.628678 containerd[1412]: time="2026-03-10T01:17:18.628336855Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Mar 10 01:17:18.628678 containerd[1412]: time="2026-03-10T01:17:18.628642305Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Mar 10 01:17:18.628754 containerd[1412]: time="2026-03-10T01:17:18.628736231Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Mar 10 01:17:18.628874 containerd[1412]: time="2026-03-10T01:17:18.628773781Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Mar 10 01:17:18.628874 containerd[1412]: time="2026-03-10T01:17:18.628860673Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Mar 10 01:17:18.629052 containerd[1412]: time="2026-03-10T01:17:18.628935443Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Mar 10 01:17:18.629219 containerd[1412]: time="2026-03-10T01:17:18.629142470Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Mar 10 01:17:18.629219 containerd[1412]: time="2026-03-10T01:17:18.629215736Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Mar 10 01:17:18.629279 containerd[1412]: time="2026-03-10T01:17:18.629233790Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Mar 10 01:17:18.629737 containerd[1412]: time="2026-03-10T01:17:18.629595876Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Mar 10 01:17:18.629903 containerd[1412]: time="2026-03-10T01:17:18.629796150Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Mar 10 01:17:18.629903 containerd[1412]: time="2026-03-10T01:17:18.629876460Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Mar 10 01:17:18.629903 containerd[1412]: time="2026-03-10T01:17:18.629897690Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Mar 10 01:17:18.629992 containerd[1412]: time="2026-03-10T01:17:18.629911846Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Mar 10 01:17:18.629992 containerd[1412]: time="2026-03-10T01:17:18.629984822Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Mar 10 01:17:18.630174 containerd[1412]: time="2026-03-10T01:17:18.630050415Z" level=info msg="NRI interface is disabled by configuration." Mar 10 01:17:18.630174 containerd[1412]: time="2026-03-10T01:17:18.630123612Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Mar 10 01:17:18.632278 containerd[1412]: time="2026-03-10T01:17:18.631922331Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Mar 10 01:17:18.632278 containerd[1412]: time="2026-03-10T01:17:18.632095935Z" level=info msg="Connect containerd service" Mar 10 01:17:18.632278 containerd[1412]: time="2026-03-10T01:17:18.632294466Z" level=info msg="using legacy CRI server" Mar 10 01:17:18.632278 containerd[1412]: time="2026-03-10T01:17:18.632309804Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Mar 10 01:17:18.662256 containerd[1412]: time="2026-03-10T01:17:18.639384124Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Mar 10 01:17:18.667040 containerd[1412]: time="2026-03-10T01:17:18.666123566Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Mar 10 01:17:18.669371 containerd[1412]: time="2026-03-10T01:17:18.667641588Z" level=info msg="Start subscribing containerd event" Mar 10 01:17:18.669371 containerd[1412]: time="2026-03-10T01:17:18.668036286Z" level=info msg="Start recovering state" Mar 10 01:17:18.669371 containerd[1412]: time="2026-03-10T01:17:18.667877000Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Mar 10 01:17:18.669371 containerd[1412]: time="2026-03-10T01:17:18.668373528Z" level=info msg=serving... address=/run/containerd/containerd.sock Mar 10 01:17:18.670689 containerd[1412]: time="2026-03-10T01:17:18.670002199Z" level=info msg="Start event monitor" Mar 10 01:17:18.672673 containerd[1412]: time="2026-03-10T01:17:18.671711481Z" level=info msg="Start snapshots syncer" Mar 10 01:17:18.672673 containerd[1412]: time="2026-03-10T01:17:18.671750674Z" level=info msg="Start cni network conf syncer for default" Mar 10 01:17:18.672673 containerd[1412]: time="2026-03-10T01:17:18.671768698Z" level=info msg="Start streaming server" Mar 10 01:17:18.672673 containerd[1412]: time="2026-03-10T01:17:18.672056625Z" level=info msg="containerd successfully booted in 0.393710s" Mar 10 01:17:18.673240 systemd[1]: Started containerd.service - containerd container runtime. Mar 10 01:17:18.701814 systemd-logind[1397]: New session 1 of user core. Mar 10 01:17:18.710148 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Mar 10 01:17:18.752323 systemd[1]: Starting docker.service - Docker Application Container Engine... Mar 10 01:17:18.769997 (dockerd)[1489]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU Mar 10 01:17:18.783224 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Mar 10 01:17:19.068373 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Mar 10 01:17:19.166358 systemd[1]: Starting user@500.service - User Manager for UID 500... Mar 10 01:17:19.199304 (systemd)[1492]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Mar 10 01:17:20.296318 systemd[1492]: Queued start job for default target default.target. Mar 10 01:17:20.311237 systemd[1492]: Created slice app.slice - User Application Slice. Mar 10 01:17:20.311356 systemd[1492]: Reached target paths.target - Paths. Mar 10 01:17:20.311380 systemd[1492]: Reached target timers.target - Timers. Mar 10 01:17:20.316177 systemd[1492]: Starting dbus.socket - D-Bus User Message Bus Socket... Mar 10 01:17:21.391642 systemd[1492]: Listening on dbus.socket - D-Bus User Message Bus Socket. Mar 10 01:17:21.392054 systemd[1492]: Reached target sockets.target - Sockets. Mar 10 01:17:21.392080 systemd[1492]: Reached target basic.target - Basic System. Mar 10 01:17:21.392278 systemd[1]: Started user@500.service - User Manager for UID 500. Mar 10 01:17:21.397722 systemd[1492]: Reached target default.target - Main User Target. Mar 10 01:17:21.399738 systemd[1492]: Startup finished in 2.166s. Mar 10 01:17:21.403093 systemd[1]: Started session-1.scope - Session 1 of User core. Mar 10 01:17:21.688954 systemd[1]: Started sshd@1-10.0.0.100:22-10.0.0.1:47852.service - OpenSSH per-connection server daemon (10.0.0.1:47852). Mar 10 01:17:22.306060 sshd[1503]: Accepted publickey for core from 10.0.0.1 port 47852 ssh2: RSA SHA256:ApINsR2hE/n5EcIFq8gQqQxccKFX8oefpoXcucPhBPc Mar 10 01:17:22.329967 sshd[1503]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 10 01:17:22.581811 systemd-logind[1397]: New session 2 of user core. Mar 10 01:17:22.601724 systemd[1]: Started session-2.scope - Session 2 of User core. Mar 10 01:17:22.720713 sshd[1503]: pam_unix(sshd:session): session closed for user core Mar 10 01:17:22.901013 systemd[1]: sshd@1-10.0.0.100:22-10.0.0.1:47852.service: Deactivated successfully. Mar 10 01:17:22.932345 systemd[1]: session-2.scope: Deactivated successfully. Mar 10 01:17:22.970073 systemd-logind[1397]: Session 2 logged out. Waiting for processes to exit. Mar 10 01:17:23.038135 systemd[1]: Started sshd@2-10.0.0.100:22-10.0.0.1:54382.service - OpenSSH per-connection server daemon (10.0.0.1:54382). Mar 10 01:17:23.075353 systemd-logind[1397]: Removed session 2. Mar 10 01:17:23.922054 sshd[1519]: Accepted publickey for core from 10.0.0.1 port 54382 ssh2: RSA SHA256:ApINsR2hE/n5EcIFq8gQqQxccKFX8oefpoXcucPhBPc Mar 10 01:17:23.943964 sshd[1519]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 10 01:17:23.990981 systemd-logind[1397]: New session 3 of user core. Mar 10 01:17:24.007145 systemd[1]: Started session-3.scope - Session 3 of User core. Mar 10 01:17:24.901902 sshd[1519]: pam_unix(sshd:session): session closed for user core Mar 10 01:17:24.934640 systemd[1]: sshd@2-10.0.0.100:22-10.0.0.1:54382.service: Deactivated successfully. Mar 10 01:17:24.973325 systemd[1]: session-3.scope: Deactivated successfully. Mar 10 01:17:24.979837 systemd-logind[1397]: Session 3 logged out. Waiting for processes to exit. Mar 10 01:17:24.987254 systemd-logind[1397]: Removed session 3. Mar 10 01:17:28.997954 dockerd[1489]: time="2026-03-10T01:17:28.978121262Z" level=info msg="Starting up" Mar 10 01:17:32.479155 dockerd[1489]: time="2026-03-10T01:17:32.478113098Z" level=info msg="Loading containers: start." Mar 10 01:17:34.300289 kernel: Initializing XFRM netlink socket Mar 10 01:17:34.928871 systemd[1]: Started sshd@3-10.0.0.100:22-10.0.0.1:55894.service - OpenSSH per-connection server daemon (10.0.0.1:55894). Mar 10 01:17:34.993876 systemd-networkd[1350]: docker0: Link UP Mar 10 01:17:35.101982 dockerd[1489]: time="2026-03-10T01:17:35.101819593Z" level=info msg="Loading containers: done." Mar 10 01:17:35.119935 sshd[1619]: Accepted publickey for core from 10.0.0.1 port 55894 ssh2: RSA SHA256:ApINsR2hE/n5EcIFq8gQqQxccKFX8oefpoXcucPhBPc Mar 10 01:17:35.125832 sshd[1619]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 10 01:17:35.151929 systemd-logind[1397]: New session 4 of user core. Mar 10 01:17:35.187349 systemd[1]: Started session-4.scope - Session 4 of User core. Mar 10 01:17:35.379198 sshd[1619]: pam_unix(sshd:session): session closed for user core Mar 10 01:17:35.470757 systemd[1]: sshd@3-10.0.0.100:22-10.0.0.1:55894.service: Deactivated successfully. Mar 10 01:17:35.473774 dockerd[1489]: time="2026-03-10T01:17:35.473337588Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 Mar 10 01:17:35.482713 dockerd[1489]: time="2026-03-10T01:17:35.480138761Z" level=info msg="Docker daemon" commit=061aa95809be396a6b5542618d8a34b02a21ff77 containerd-snapshotter=false storage-driver=overlay2 version=26.1.0 Mar 10 01:17:35.482713 dockerd[1489]: time="2026-03-10T01:17:35.480816216Z" level=info msg="Daemon has completed initialization" Mar 10 01:17:35.482863 systemd[1]: session-4.scope: Deactivated successfully. Mar 10 01:17:35.504842 systemd-logind[1397]: Session 4 logged out. Waiting for processes to exit. Mar 10 01:17:35.586908 systemd[1]: Started sshd@4-10.0.0.100:22-10.0.0.1:55910.service - OpenSSH per-connection server daemon (10.0.0.1:55910). Mar 10 01:17:35.590830 systemd-logind[1397]: Removed session 4. Mar 10 01:17:36.028790 sshd[1641]: Accepted publickey for core from 10.0.0.1 port 55910 ssh2: RSA SHA256:ApINsR2hE/n5EcIFq8gQqQxccKFX8oefpoXcucPhBPc Mar 10 01:17:36.038153 sshd[1641]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 10 01:17:36.192191 systemd-logind[1397]: New session 5 of user core. Mar 10 01:17:36.200332 systemd[1]: Started session-5.scope - Session 5 of User core. Mar 10 01:17:36.329943 dockerd[1489]: time="2026-03-10T01:17:36.329306672Z" level=info msg="API listen on /run/docker.sock" Mar 10 01:17:36.331735 systemd[1]: Started docker.service - Docker Application Container Engine. Mar 10 01:17:36.372777 etcd-wrapper[1436]: Error response from daemon: No such container: etcd-member Mar 10 01:17:36.380795 sshd[1641]: pam_unix(sshd:session): session closed for user core Mar 10 01:17:36.396656 systemd[1]: sshd@4-10.0.0.100:22-10.0.0.1:55910.service: Deactivated successfully. Mar 10 01:17:36.398718 systemd-logind[1397]: Session 5 logged out. Waiting for processes to exit. Mar 10 01:17:36.401790 systemd[1]: session-5.scope: Deactivated successfully. Mar 10 01:17:36.409040 systemd-logind[1397]: Removed session 5. Mar 10 01:17:36.969030 etcd-wrapper[1664]: Error response from daemon: No such container: etcd-member Mar 10 01:17:37.331883 etcd-wrapper[1684]: Unable to find image 'quay.io/coreos/etcd:v3.5.24' locally Mar 10 01:17:38.583681 etcd-wrapper[1684]: v3.5.24: Pulling from coreos/etcd Mar 10 01:17:38.777381 etcd-wrapper[1684]: 804c8aba2cc6: Pulling fs layer Mar 10 01:17:38.777381 etcd-wrapper[1684]: 2ae710cd8bfe: Pulling fs layer Mar 10 01:17:38.777381 etcd-wrapper[1684]: d462aa345367: Pulling fs layer Mar 10 01:17:38.777381 etcd-wrapper[1684]: 0f8b424aa0b9: Pulling fs layer Mar 10 01:17:38.777381 etcd-wrapper[1684]: d557676654e5: Pulling fs layer Mar 10 01:17:38.777381 etcd-wrapper[1684]: c8022d07192e: Pulling fs layer Mar 10 01:17:38.777381 etcd-wrapper[1684]: d858cbc252ad: Pulling fs layer Mar 10 01:17:38.777381 etcd-wrapper[1684]: 0f8b424aa0b9: Waiting Mar 10 01:17:38.777381 etcd-wrapper[1684]: d557676654e5: Waiting Mar 10 01:17:38.777381 etcd-wrapper[1684]: c8022d07192e: Waiting Mar 10 01:17:38.777381 etcd-wrapper[1684]: 1069fc2daed1: Pulling fs layer Mar 10 01:17:38.777381 etcd-wrapper[1684]: b40161cd83fc: Pulling fs layer Mar 10 01:17:38.777381 etcd-wrapper[1684]: d858cbc252ad: Waiting Mar 10 01:17:38.778161 etcd-wrapper[1684]: 5318d93a3a65: Pulling fs layer Mar 10 01:17:38.778161 etcd-wrapper[1684]: 1069fc2daed1: Waiting Mar 10 01:17:38.778161 etcd-wrapper[1684]: b40161cd83fc: Waiting Mar 10 01:17:38.778161 etcd-wrapper[1684]: 307c1adadb60: Pulling fs layer Mar 10 01:17:38.778161 etcd-wrapper[1684]: 49a553fa7ab9: Pulling fs layer Mar 10 01:17:38.778161 etcd-wrapper[1684]: 5318d93a3a65: Waiting Mar 10 01:17:38.778161 etcd-wrapper[1684]: 9cde5f0af3dd: Pulling fs layer Mar 10 01:17:38.778161 etcd-wrapper[1684]: b9581a18c15b: Pulling fs layer Mar 10 01:17:38.778161 etcd-wrapper[1684]: 95c11f3cd524: Pulling fs layer Mar 10 01:17:38.778681 etcd-wrapper[1684]: b9581a18c15b: Waiting Mar 10 01:17:38.783083 etcd-wrapper[1684]: 9cde5f0af3dd: Waiting Mar 10 01:17:38.783083 etcd-wrapper[1684]: 49a553fa7ab9: Waiting Mar 10 01:17:38.783083 etcd-wrapper[1684]: ed40c9b2e219: Pulling fs layer Mar 10 01:17:38.783083 etcd-wrapper[1684]: 95c11f3cd524: Waiting Mar 10 01:17:38.783083 etcd-wrapper[1684]: ed40c9b2e219: Waiting Mar 10 01:17:38.783083 etcd-wrapper[1684]: 307c1adadb60: Waiting Mar 10 01:17:38.946164 etcd-wrapper[1684]: 2ae710cd8bfe: Verifying Checksum Mar 10 01:17:38.946164 etcd-wrapper[1684]: 2ae710cd8bfe: Download complete Mar 10 01:17:38.959985 etcd-wrapper[1684]: 804c8aba2cc6: Verifying Checksum Mar 10 01:17:38.960098 etcd-wrapper[1684]: 804c8aba2cc6: Download complete Mar 10 01:17:39.031718 etcd-wrapper[1684]: d462aa345367: Verifying Checksum Mar 10 01:17:39.031718 etcd-wrapper[1684]: d462aa345367: Download complete Mar 10 01:17:39.076381 etcd-wrapper[1684]: 0f8b424aa0b9: Download complete Mar 10 01:17:39.077782 etcd-wrapper[1684]: d557676654e5: Verifying Checksum Mar 10 01:17:39.077782 etcd-wrapper[1684]: d557676654e5: Download complete Mar 10 01:17:39.157367 etcd-wrapper[1684]: c8022d07192e: Verifying Checksum Mar 10 01:17:39.157367 etcd-wrapper[1684]: c8022d07192e: Download complete Mar 10 01:17:39.181069 etcd-wrapper[1684]: 1069fc2daed1: Verifying Checksum Mar 10 01:17:39.181069 etcd-wrapper[1684]: 1069fc2daed1: Download complete Mar 10 01:17:39.202230 etcd-wrapper[1684]: 804c8aba2cc6: Pull complete Mar 10 01:17:39.209948 etcd-wrapper[1684]: d858cbc252ad: Download complete Mar 10 01:17:39.292966 etcd-wrapper[1684]: b40161cd83fc: Verifying Checksum Mar 10 01:17:39.293085 etcd-wrapper[1684]: b40161cd83fc: Download complete Mar 10 01:17:39.319293 systemd[1]: var-lib-docker-overlay2-34254191b570a9fc7d13ce50d8acaa875633a86d3870bff347225077bdbfbd4a-merged.mount: Deactivated successfully. Mar 10 01:17:39.342389 etcd-wrapper[1684]: 5318d93a3a65: Verifying Checksum Mar 10 01:17:39.342389 etcd-wrapper[1684]: 5318d93a3a65: Download complete Mar 10 01:17:39.393271 etcd-wrapper[1684]: 2ae710cd8bfe: Pull complete Mar 10 01:17:39.523283 etcd-wrapper[1684]: 307c1adadb60: Verifying Checksum Mar 10 01:17:39.523283 etcd-wrapper[1684]: 307c1adadb60: Download complete Mar 10 01:17:40.865100 etcd-wrapper[1684]: 9cde5f0af3dd: Verifying Checksum Mar 10 01:17:40.865100 etcd-wrapper[1684]: 9cde5f0af3dd: Download complete Mar 10 01:17:40.933297 etcd-wrapper[1684]: 49a553fa7ab9: Verifying Checksum Mar 10 01:17:40.933941 etcd-wrapper[1684]: 49a553fa7ab9: Download complete Mar 10 01:17:41.350597 etcd-wrapper[1684]: 95c11f3cd524: Verifying Checksum Mar 10 01:17:41.350597 etcd-wrapper[1684]: 95c11f3cd524: Download complete Mar 10 01:17:41.392797 etcd-wrapper[1684]: ed40c9b2e219: Verifying Checksum Mar 10 01:17:41.392797 etcd-wrapper[1684]: ed40c9b2e219: Download complete Mar 10 01:17:41.810596 etcd-wrapper[1684]: b9581a18c15b: Verifying Checksum Mar 10 01:17:41.810596 etcd-wrapper[1684]: b9581a18c15b: Download complete Mar 10 01:17:46.429322 systemd[1]: Started sshd@5-10.0.0.100:22-10.0.0.1:59880.service - OpenSSH per-connection server daemon (10.0.0.1:59880). Mar 10 01:17:46.553917 sshd[1714]: Accepted publickey for core from 10.0.0.1 port 59880 ssh2: RSA SHA256:ApINsR2hE/n5EcIFq8gQqQxccKFX8oefpoXcucPhBPc Mar 10 01:17:46.561796 sshd[1714]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 10 01:17:46.566003 systemd[1]: var-lib-docker-overlay2-e0760ae4aeb9dff5fdec48743d3fd37a98131689e9469e1589c16d9b3af3d005-merged.mount: Deactivated successfully. Mar 10 01:17:46.584555 systemd-logind[1397]: New session 6 of user core. Mar 10 01:17:46.602585 systemd[1]: Started session-6.scope - Session 6 of User core. Mar 10 01:17:46.788844 sshd[1714]: pam_unix(sshd:session): session closed for user core Mar 10 01:17:46.817118 systemd[1]: sshd@5-10.0.0.100:22-10.0.0.1:59880.service: Deactivated successfully. Mar 10 01:17:46.826773 systemd[1]: session-6.scope: Deactivated successfully. Mar 10 01:17:46.828963 systemd-logind[1397]: Session 6 logged out. Waiting for processes to exit. Mar 10 01:17:46.851697 etcd-wrapper[1684]: d462aa345367: Pull complete Mar 10 01:17:46.853629 systemd[1]: Started sshd@6-10.0.0.100:22-10.0.0.1:59882.service - OpenSSH per-connection server daemon (10.0.0.1:59882). Mar 10 01:17:46.857914 systemd-logind[1397]: Removed session 6. Mar 10 01:17:46.930987 systemd[1]: var-lib-docker-overlay2-e7f2a0a37cef5fddf8cb057b9b406a2a9d426cd4c589f3463f253df07c09c7e7-merged.mount: Deactivated successfully. Mar 10 01:17:46.990654 sshd[1721]: Accepted publickey for core from 10.0.0.1 port 59882 ssh2: RSA SHA256:ApINsR2hE/n5EcIFq8gQqQxccKFX8oefpoXcucPhBPc Mar 10 01:17:46.993702 sshd[1721]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 10 01:17:47.034623 systemd-logind[1397]: New session 7 of user core. Mar 10 01:17:47.051768 etcd-wrapper[1684]: 0f8b424aa0b9: Pull complete Mar 10 01:17:47.059099 systemd[1]: Started session-7.scope - Session 7 of User core. Mar 10 01:17:47.128043 systemd[1]: var-lib-docker-overlay2-9ee5c0f995ecd92ba570fbaaa5b7ca6c206ed7796b94f79f07994c3ac3e824c3-merged.mount: Deactivated successfully. Mar 10 01:17:47.173554 sshd[1721]: pam_unix(sshd:session): session closed for user core Mar 10 01:17:47.179780 etcd-wrapper[1684]: d557676654e5: Pull complete Mar 10 01:17:47.179866 systemd[1]: sshd@6-10.0.0.100:22-10.0.0.1:59882.service: Deactivated successfully. Mar 10 01:17:47.183740 systemd[1]: session-7.scope: Deactivated successfully. Mar 10 01:17:47.186046 systemd-logind[1397]: Session 7 logged out. Waiting for processes to exit. Mar 10 01:17:47.189904 systemd-logind[1397]: Removed session 7. Mar 10 01:17:47.263601 etcd-wrapper[1684]: c8022d07192e: Pull complete Mar 10 01:17:47.337697 etcd-wrapper[1684]: d858cbc252ad: Pull complete Mar 10 01:17:47.431737 etcd-wrapper[1684]: 1069fc2daed1: Pull complete Mar 10 01:17:47.526175 etcd-wrapper[1684]: b40161cd83fc: Pull complete Mar 10 01:17:47.575864 systemd[1]: var-lib-docker-overlay2-9334fe4e5d8a4a5233f93d72e0f3dc0ef1fcb5a77750c76ccdeed03b892ed9e3-merged.mount: Deactivated successfully. Mar 10 01:17:47.613317 etcd-wrapper[1684]: 5318d93a3a65: Pull complete Mar 10 01:17:47.668586 systemd[1]: var-lib-docker-overlay2-90b24a5baa7045baab1be6f85092a0e758911bb410d77308a71de3a00eb00b0b-merged.mount: Deactivated successfully. Mar 10 01:17:47.731835 etcd-wrapper[1684]: 307c1adadb60: Pull complete Mar 10 01:17:49.082693 systemd[1]: var-lib-docker-overlay2-3c817ee2d8e2de2d50322f621661e325fff97ce6a653d417b7b6a4e8da936812-merged.mount: Deactivated successfully. Mar 10 01:17:49.292253 etcd-wrapper[1684]: 49a553fa7ab9: Pull complete Mar 10 01:17:50.221722 systemd[1]: var-lib-docker-overlay2-06cab782b8e9e1c6408da92d84fe925c68b039d8af836ed64348a89fef19d38c-merged.mount: Deactivated successfully. Mar 10 01:17:50.282082 etcd-wrapper[1684]: 9cde5f0af3dd: Pull complete Mar 10 01:17:50.932933 systemd[1]: var-lib-docker-overlay2-1663e5a1f34d0b3a3ffbc9edea765bbc1c1bfba3b4e04e3329ad4a30548fe22d-merged.mount: Deactivated successfully. Mar 10 01:17:51.071942 etcd-wrapper[1684]: b9581a18c15b: Pull complete Mar 10 01:17:51.512886 systemd[1]: var-lib-docker-overlay2-d651b93bec42aca6066e8972b65462a7b03cfee1e5ea91cba4fd5882dcc1d4d3-merged.mount: Deactivated successfully. Mar 10 01:17:51.725035 etcd-wrapper[1684]: 95c11f3cd524: Pull complete Mar 10 01:17:52.313013 systemd[1]: var-lib-docker-overlay2-1d7c2b372684ed4ee422757f3712e96d80b2aaed2ce37f95f0818ad20028039e-merged.mount: Deactivated successfully. Mar 10 01:17:52.583917 etcd-wrapper[1684]: ed40c9b2e219: Pull complete Mar 10 01:17:52.786860 etcd-wrapper[1684]: Digest: sha256:a193bdd3c1d1d11de961b6600231b2b3b4c4e092848dbb7d4c0e03b709ac7594 Mar 10 01:17:52.829916 etcd-wrapper[1684]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.24 Mar 10 01:17:52.972826 systemd[1]: var-lib-docker-overlay2-0c93b32aec8326f367ca7342e62c51329d8e9bed30170501151177e39d75c155\x2dinit-merged.mount: Deactivated successfully. Mar 10 01:17:53.027852 systemd[1]: var-lib-docker-overlay2-0c93b32aec8326f367ca7342e62c51329d8e9bed30170501151177e39d75c155-merged.mount: Deactivated successfully. Mar 10 01:17:55.294774 containerd[1412]: time="2026-03-10T01:17:55.264813920Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Mar 10 01:17:55.294774 containerd[1412]: time="2026-03-10T01:17:55.291722066Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Mar 10 01:17:55.294774 containerd[1412]: time="2026-03-10T01:17:55.291839262Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Mar 10 01:17:55.294774 containerd[1412]: time="2026-03-10T01:17:55.292686172Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Mar 10 01:17:55.559781 systemd[1]: run-docker-runtime\x2drunc-moby-dbd0595ace5f0bb8b62f61073893993370271943b3e33a855d8c545c4d233a40-runc.nblVDs.mount: Deactivated successfully. Mar 10 01:17:55.575930 systemd[1]: Started docker-dbd0595ace5f0bb8b62f61073893993370271943b3e33a855d8c545c4d233a40.scope - libcontainer container dbd0595ace5f0bb8b62f61073893993370271943b3e33a855d8c545c4d233a40. Mar 10 01:17:55.691882 systemd-resolved[1276]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Mar 10 01:17:56.125027 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:56.118840Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} Mar 10 01:17:56.125027 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:56.123208Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"2d3ff0f5d2fe4dea91e56c9c89cec8a2"} Mar 10 01:17:56.125027 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:17:56.123254Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} Mar 10 01:17:56.125027 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:17:56.123266Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.24"} Mar 10 01:17:56.125027 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:17:56.123273Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} Mar 10 01:17:56.125027 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:17:56.123281Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} Mar 10 01:17:56.125027 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:17:56.124245Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Mar 10 01:17:56.127526 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:56.124658Z","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.100:2379"]} Mar 10 01:17:56.132218 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:17:56.130381Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Mar 10 01:17:56.132218 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:56.130609Z","caller":"embed/etcd.go:140","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]} Mar 10 01:17:56.167023 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:56.164286Z","caller":"embed/etcd.go:148","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} Mar 10 01:17:56.170187 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:56.168200Z","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":"2d3ff0f5d2fe4dea91e56c9c89cec8a2","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.100:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"2d3ff0f5d2fe4dea91e56c9c89cec8a2=http://localhost:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":false,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} Mar 10 01:17:56.186734 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:56.184367Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"12.661265ms"} Mar 10 01:17:56.203553 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:56.201891Z","caller":"etcdserver/raft.go:507","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"} Mar 10 01:17:56.205322 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:56.203214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"} Mar 10 01:17:56.207743 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:56.204632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"} Mar 10 01:17:56.207743 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:56.204895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} Mar 10 01:17:56.207743 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:56.205037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"} Mar 10 01:17:56.209563 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:56.207351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Mar 10 01:17:56.242915 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:17:56.235887Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} Mar 10 01:17:56.253925 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:56.252270Z","caller":"mvcc/kvstore.go:425","msg":"kvstore restored","current-rev":1} Mar 10 01:17:56.253925 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:56.252738Z","caller":"etcdserver/server.go:628","msg":"restore consistentIndex","index":0} Mar 10 01:17:56.263200 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:56.261887Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} Mar 10 01:17:56.268289 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:56.266866Z","caller":"etcdserver/server.go:875","msg":"starting etcd server","local-member-id":"8e9e05c52164694d","local-server-version":"3.5.24","cluster-version":"to_be_decided"} Mar 10 01:17:56.272739 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:56.269942Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Mar 10 01:17:56.272739 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:56.270357Z","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"} Mar 10 01:17:56.274585 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:56.270605Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} Mar 10 01:17:56.274585 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:56.272090Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} Mar 10 01:17:56.274585 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:56.272187Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} Mar 10 01:17:56.280018 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:56.275766Z","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.100:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} Mar 10 01:17:56.280018 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:56.276295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Mar 10 01:17:56.280018 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:56.276757Z","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} Mar 10 01:17:56.280018 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:56.276919Z","caller":"embed/etcd.go:633","msg":"serving peer traffic","address":"127.0.0.1:2380"} Mar 10 01:17:56.280018 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:56.276939Z","caller":"embed/etcd.go:603","msg":"cmux::serve","address":"127.0.0.1:2380"} Mar 10 01:17:57.018381 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:57.008901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"} Mar 10 01:17:57.018381 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:57.009329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"} Mar 10 01:17:57.018381 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:57.010041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"} Mar 10 01:17:57.018381 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:57.010070Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"} Mar 10 01:17:57.018381 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:57.010081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"} Mar 10 01:17:57.018381 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:57.010220Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"} Mar 10 01:17:57.018381 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:57.010288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"} Mar 10 01:17:57.025364 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:57.018731Z","caller":"etcdserver/server.go:2158","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:2d3ff0f5d2fe4dea91e56c9c89cec8a2 ClientURLs:[http://10.0.0.100:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"} Mar 10 01:17:57.025364 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:57.019255Z","caller":"etcdserver/server.go:2711","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} Mar 10 01:17:57.025364 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:57.019242Z","caller":"embed/serve.go:124","msg":"ready to serve client requests"} Mar 10 01:17:57.025364 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:57.022936Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} Mar 10 01:17:57.027718 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:57.025247Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Mar 10 01:17:57.027718 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:57.026320Z","caller":"embed/serve.go:210","msg":"serving client traffic insecurely; this is strongly discouraged!","traffic":"grpc+http","address":"[::]:2379"} Mar 10 01:17:57.028867 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:57.028511Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} Mar 10 01:17:57.032320 systemd[1]: Started etcd-member.service - etcd (System Application Container). Mar 10 01:17:57.036620 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:57.028789Z","caller":"membership/cluster.go:587","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"} Mar 10 01:17:57.036620 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:57.029248Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} Mar 10 01:17:57.036620 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:17:57.029663Z","caller":"etcdserver/server.go:2735","msg":"cluster version is updated","cluster-version":"3.5"} Mar 10 01:17:57.036977 systemd[1]: Reached target multi-user.target - Multi-User System. Mar 10 01:17:57.037642 systemd[1]: Startup finished in 8.609s (kernel) + 26.247s (initrd) + 1min 14.310s (userspace) = 1min 49.167s. Mar 10 01:17:57.220764 systemd[1]: Started sshd@7-10.0.0.100:22-10.0.0.1:58584.service - OpenSSH per-connection server daemon (10.0.0.1:58584). Mar 10 01:17:57.303077 sshd[1842]: Accepted publickey for core from 10.0.0.1 port 58584 ssh2: RSA SHA256:ApINsR2hE/n5EcIFq8gQqQxccKFX8oefpoXcucPhBPc Mar 10 01:17:57.315646 sshd[1842]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 10 01:17:57.330756 systemd-logind[1397]: New session 8 of user core. Mar 10 01:17:57.342015 systemd[1]: Started session-8.scope - Session 8 of User core. Mar 10 01:17:57.472813 sshd[1842]: pam_unix(sshd:session): session closed for user core Mar 10 01:17:57.507340 systemd[1]: sshd@7-10.0.0.100:22-10.0.0.1:58584.service: Deactivated successfully. Mar 10 01:17:57.514274 systemd[1]: session-8.scope: Deactivated successfully. Mar 10 01:17:57.523250 systemd-logind[1397]: Session 8 logged out. Waiting for processes to exit. Mar 10 01:17:57.560779 systemd[1]: Started sshd@8-10.0.0.100:22-10.0.0.1:58588.service - OpenSSH per-connection server daemon (10.0.0.1:58588). Mar 10 01:17:57.566898 systemd-logind[1397]: Removed session 8. Mar 10 01:17:57.673284 sshd[1849]: Accepted publickey for core from 10.0.0.1 port 58588 ssh2: RSA SHA256:ApINsR2hE/n5EcIFq8gQqQxccKFX8oefpoXcucPhBPc Mar 10 01:17:57.679617 sshd[1849]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 10 01:17:57.709755 systemd-logind[1397]: New session 9 of user core. Mar 10 01:17:57.723913 systemd[1]: Started session-9.scope - Session 9 of User core. Mar 10 01:17:57.850312 sshd[1849]: pam_unix(sshd:session): session closed for user core Mar 10 01:17:57.877300 update_engine[1406]: I20260310 01:17:57.876010 1406 update_attempter.cc:509] Updating boot flags... Mar 10 01:17:57.888317 systemd[1]: Started sshd@9-10.0.0.100:22-10.0.0.1:58602.service - OpenSSH per-connection server daemon (10.0.0.1:58602). Mar 10 01:17:57.890011 systemd[1]: sshd@8-10.0.0.100:22-10.0.0.1:58588.service: Deactivated successfully. Mar 10 01:17:57.892890 systemd[1]: session-9.scope: Deactivated successfully. Mar 10 01:17:57.909328 systemd-logind[1397]: Session 9 logged out. Waiting for processes to exit. Mar 10 01:17:57.927771 systemd-logind[1397]: Removed session 9. Mar 10 01:17:57.991350 sshd[1854]: Accepted publickey for core from 10.0.0.1 port 58602 ssh2: RSA SHA256:ApINsR2hE/n5EcIFq8gQqQxccKFX8oefpoXcucPhBPc Mar 10 01:17:57.994241 sshd[1854]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 10 01:17:57.998600 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 33 scanned by (udev-worker) (1865) Mar 10 01:17:58.024377 systemd-logind[1397]: New session 10 of user core. Mar 10 01:17:58.043684 systemd[1]: Started session-10.scope - Session 10 of User core. Mar 10 01:17:58.151320 sshd[1854]: pam_unix(sshd:session): session closed for user core Mar 10 01:17:58.198993 systemd[1]: sshd@9-10.0.0.100:22-10.0.0.1:58602.service: Deactivated successfully. Mar 10 01:17:58.203280 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 33 scanned by (udev-worker) (1869) Mar 10 01:17:58.203283 systemd[1]: session-10.scope: Deactivated successfully. Mar 10 01:17:58.212022 systemd-logind[1397]: Session 10 logged out. Waiting for processes to exit. Mar 10 01:17:58.245779 systemd-logind[1397]: Removed session 10. Mar 10 01:17:58.290922 systemd[1]: Started sshd@10-10.0.0.100:22-10.0.0.1:58618.service - OpenSSH per-connection server daemon (10.0.0.1:58618). Mar 10 01:17:58.380988 sshd[1877]: Accepted publickey for core from 10.0.0.1 port 58618 ssh2: RSA SHA256:ApINsR2hE/n5EcIFq8gQqQxccKFX8oefpoXcucPhBPc Mar 10 01:17:58.385165 sshd[1877]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 10 01:17:58.446515 systemd-logind[1397]: New session 11 of user core. Mar 10 01:17:58.463177 systemd[1]: Started session-11.scope - Session 11 of User core. Mar 10 01:17:58.617620 sudo[1880]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Mar 10 01:17:58.618962 sudo[1880]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Mar 10 01:17:58.662982 sudo[1880]: pam_unix(sudo:session): session closed for user root Mar 10 01:17:58.671173 sshd[1877]: pam_unix(sshd:session): session closed for user core Mar 10 01:17:58.689247 systemd[1]: sshd@10-10.0.0.100:22-10.0.0.1:58618.service: Deactivated successfully. Mar 10 01:17:58.693258 systemd[1]: session-11.scope: Deactivated successfully. Mar 10 01:17:58.698332 systemd-logind[1397]: Session 11 logged out. Waiting for processes to exit. Mar 10 01:17:58.714232 systemd[1]: Started sshd@11-10.0.0.100:22-10.0.0.1:58628.service - OpenSSH per-connection server daemon (10.0.0.1:58628). Mar 10 01:17:58.720627 systemd-logind[1397]: Removed session 11. Mar 10 01:17:58.892220 sshd[1885]: Accepted publickey for core from 10.0.0.1 port 58628 ssh2: RSA SHA256:ApINsR2hE/n5EcIFq8gQqQxccKFX8oefpoXcucPhBPc Mar 10 01:17:58.896153 sshd[1885]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 10 01:17:58.922911 systemd-logind[1397]: New session 12 of user core. Mar 10 01:17:58.946597 systemd[1]: Started session-12.scope - Session 12 of User core. Mar 10 01:17:59.062982 sudo[1889]: 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 Mar 10 01:17:59.064645 sudo[1889]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Mar 10 01:17:59.121157 sudo[1889]: pam_unix(sudo:session): session closed for user root Mar 10 01:17:59.179155 sudo[1888]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/systemctl restart audit-rules Mar 10 01:17:59.179946 sudo[1888]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Mar 10 01:17:59.234210 systemd[1]: Stopping audit-rules.service - Load Security Auditing Rules... Mar 10 01:17:59.257673 auditctl[1892]: No rules Mar 10 01:17:59.261892 systemd[1]: audit-rules.service: Deactivated successfully. Mar 10 01:17:59.262804 systemd[1]: Stopped audit-rules.service - Load Security Auditing Rules. Mar 10 01:17:59.284273 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Mar 10 01:17:59.435849 augenrules[1910]: No rules Mar 10 01:17:59.450698 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Mar 10 01:17:59.457358 sudo[1888]: pam_unix(sudo:session): session closed for user root Mar 10 01:17:59.463593 sshd[1885]: pam_unix(sshd:session): session closed for user core Mar 10 01:17:59.491909 systemd[1]: sshd@11-10.0.0.100:22-10.0.0.1:58628.service: Deactivated successfully. Mar 10 01:17:59.496848 systemd[1]: session-12.scope: Deactivated successfully. Mar 10 01:17:59.501003 systemd-logind[1397]: Session 12 logged out. Waiting for processes to exit. Mar 10 01:17:59.519934 systemd[1]: Started sshd@12-10.0.0.100:22-10.0.0.1:58640.service - OpenSSH per-connection server daemon (10.0.0.1:58640). Mar 10 01:17:59.523978 systemd-logind[1397]: Removed session 12. Mar 10 01:17:59.580522 sshd[1918]: Accepted publickey for core from 10.0.0.1 port 58640 ssh2: RSA SHA256:ApINsR2hE/n5EcIFq8gQqQxccKFX8oefpoXcucPhBPc Mar 10 01:17:59.584571 sshd[1918]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 10 01:17:59.602668 systemd-logind[1397]: New session 13 of user core. Mar 10 01:17:59.612180 systemd[1]: Started session-13.scope - Session 13 of User core. Mar 10 01:17:59.727898 sshd[1918]: pam_unix(sshd:session): session closed for user core Mar 10 01:17:59.747917 systemd[1]: sshd@12-10.0.0.100:22-10.0.0.1:58640.service: Deactivated successfully. Mar 10 01:17:59.753637 systemd[1]: session-13.scope: Deactivated successfully. Mar 10 01:17:59.758383 systemd-logind[1397]: Session 13 logged out. Waiting for processes to exit. Mar 10 01:17:59.767225 systemd[1]: Started sshd@13-10.0.0.100:22-10.0.0.1:58646.service - OpenSSH per-connection server daemon (10.0.0.1:58646). Mar 10 01:17:59.771800 systemd-logind[1397]: Removed session 13. Mar 10 01:17:59.876263 sshd[1925]: Accepted publickey for core from 10.0.0.1 port 58646 ssh2: RSA SHA256:ApINsR2hE/n5EcIFq8gQqQxccKFX8oefpoXcucPhBPc Mar 10 01:17:59.880815 sshd[1925]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 10 01:17:59.902835 systemd-logind[1397]: New session 14 of user core. Mar 10 01:17:59.920867 systemd[1]: Started session-14.scope - Session 14 of User core. Mar 10 01:18:00.610263 sshd[1925]: pam_unix(sshd:session): session closed for user core Mar 10 01:18:00.747607 systemd[1]: sshd@13-10.0.0.100:22-10.0.0.1:58646.service: Deactivated successfully. Mar 10 01:18:00.752248 systemd[1]: session-14.scope: Deactivated successfully. Mar 10 01:18:00.754520 systemd-logind[1397]: Session 14 logged out. Waiting for processes to exit. Mar 10 01:18:00.760186 systemd-logind[1397]: Removed session 14. Mar 10 01:20:24.995174 update_engine[1406]: I20260310 01:20:24.984928 1406 prefs.cc:52] certificate-report-to-send-update not present in /var/lib/update_engine/prefs Mar 10 01:20:25.035242 update_engine[1406]: I20260310 01:20:24.996776 1406 prefs.cc:52] certificate-report-to-send-download not present in /var/lib/update_engine/prefs Mar 10 01:20:25.035242 update_engine[1406]: I20260310 01:20:25.013293 1406 prefs.cc:52] aleph-version not present in /var/lib/update_engine/prefs Mar 10 01:20:25.035242 update_engine[1406]: I20260310 01:20:25.028721 1406 omaha_request_params.cc:62] Current group set to lts Mar 10 01:20:25.044271 update_engine[1406]: I20260310 01:20:25.044093 1406 update_attempter.cc:499] Already updated boot flags. Skipping. Mar 10 01:20:25.044271 update_engine[1406]: I20260310 01:20:25.044231 1406 update_attempter.cc:643] Scheduling an action processor start. Mar 10 01:20:25.045312 update_engine[1406]: I20260310 01:20:25.045001 1406 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Mar 10 01:20:25.045312 update_engine[1406]: I20260310 01:20:25.045158 1406 prefs.cc:52] previous-version not present in /var/lib/update_engine/prefs Mar 10 01:20:25.045972 update_engine[1406]: I20260310 01:20:25.045672 1406 omaha_request_action.cc:271] Posting an Omaha request to disabled Mar 10 01:20:25.045972 update_engine[1406]: I20260310 01:20:25.045692 1406 omaha_request_action.cc:272] Request: Mar 10 01:20:25.045972 update_engine[1406]: Mar 10 01:20:25.045972 update_engine[1406]: Mar 10 01:20:25.045972 update_engine[1406]: Mar 10 01:20:25.045972 update_engine[1406]: Mar 10 01:20:25.045972 update_engine[1406]: Mar 10 01:20:25.045972 update_engine[1406]: Mar 10 01:20:25.045972 update_engine[1406]: Mar 10 01:20:25.045972 update_engine[1406]: Mar 10 01:20:25.045972 update_engine[1406]: I20260310 01:20:25.045704 1406 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Mar 10 01:20:25.110610 update_engine[1406]: I20260310 01:20:25.110091 1406 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Mar 10 01:20:25.119235 locksmithd[1430]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_CHECKING_FOR_UPDATE" NewVersion=0.0.0 NewSize=0 Mar 10 01:20:25.130962 update_engine[1406]: I20260310 01:20:25.128283 1406 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Mar 10 01:20:25.161771 update_engine[1406]: E20260310 01:20:25.157826 1406 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Mar 10 01:20:25.176534 update_engine[1406]: I20260310 01:20:25.170375 1406 libcurl_http_fetcher.cc:283] No HTTP response, retry 1 Mar 10 01:20:35.885051 update_engine[1406]: I20260310 01:20:35.884127 1406 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Mar 10 01:20:35.892134 update_engine[1406]: I20260310 01:20:35.885681 1406 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Mar 10 01:20:35.892134 update_engine[1406]: I20260310 01:20:35.889714 1406 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Mar 10 01:20:35.904348 update_engine[1406]: E20260310 01:20:35.903734 1406 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Mar 10 01:20:35.904348 update_engine[1406]: I20260310 01:20:35.903913 1406 libcurl_http_fetcher.cc:283] No HTTP response, retry 2 Mar 10 01:20:45.886837 update_engine[1406]: I20260310 01:20:45.883975 1406 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Mar 10 01:20:45.886837 update_engine[1406]: I20260310 01:20:45.885095 1406 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Mar 10 01:20:45.886837 update_engine[1406]: I20260310 01:20:45.885691 1406 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Mar 10 01:20:45.911242 update_engine[1406]: E20260310 01:20:45.910703 1406 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Mar 10 01:20:45.911242 update_engine[1406]: I20260310 01:20:45.910827 1406 libcurl_http_fetcher.cc:283] No HTTP response, retry 3 Mar 10 01:20:55.885683 update_engine[1406]: I20260310 01:20:55.881677 1406 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Mar 10 01:20:55.897663 update_engine[1406]: I20260310 01:20:55.895015 1406 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Mar 10 01:20:55.901118 update_engine[1406]: I20260310 01:20:55.898993 1406 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Mar 10 01:20:55.923342 update_engine[1406]: E20260310 01:20:55.922950 1406 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Mar 10 01:20:55.923342 update_engine[1406]: I20260310 01:20:55.923168 1406 libcurl_http_fetcher.cc:297] Transfer resulted in an error (0), 0 bytes downloaded Mar 10 01:20:55.923342 update_engine[1406]: I20260310 01:20:55.923187 1406 omaha_request_action.cc:617] Omaha request response: Mar 10 01:20:55.923917 update_engine[1406]: E20260310 01:20:55.923753 1406 omaha_request_action.cc:636] Omaha request network transfer failed. Mar 10 01:20:55.923917 update_engine[1406]: I20260310 01:20:55.923787 1406 action_processor.cc:68] ActionProcessor::ActionComplete: OmahaRequestAction action failed. Aborting processing. Mar 10 01:20:55.923917 update_engine[1406]: I20260310 01:20:55.923799 1406 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Mar 10 01:20:55.923917 update_engine[1406]: I20260310 01:20:55.923810 1406 update_attempter.cc:306] Processing Done. Mar 10 01:20:55.923917 update_engine[1406]: E20260310 01:20:55.923831 1406 update_attempter.cc:619] Update failed. Mar 10 01:20:55.923917 update_engine[1406]: I20260310 01:20:55.923843 1406 utils.cc:600] Converting error code 2000 to kActionCodeOmahaErrorInHTTPResponse Mar 10 01:20:55.923917 update_engine[1406]: I20260310 01:20:55.923854 1406 payload_state.cc:97] Updating payload state for error code: 37 (kActionCodeOmahaErrorInHTTPResponse) Mar 10 01:20:55.923917 update_engine[1406]: I20260310 01:20:55.923864 1406 payload_state.cc:103] Ignoring failures until we get a valid Omaha response. Mar 10 01:20:55.926976 update_engine[1406]: I20260310 01:20:55.923951 1406 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Mar 10 01:20:55.926976 update_engine[1406]: I20260310 01:20:55.923983 1406 omaha_request_action.cc:271] Posting an Omaha request to disabled Mar 10 01:20:55.926976 update_engine[1406]: I20260310 01:20:55.923993 1406 omaha_request_action.cc:272] Request: Mar 10 01:20:55.926976 update_engine[1406]: Mar 10 01:20:55.926976 update_engine[1406]: Mar 10 01:20:55.926976 update_engine[1406]: Mar 10 01:20:55.926976 update_engine[1406]: Mar 10 01:20:55.926976 update_engine[1406]: Mar 10 01:20:55.926976 update_engine[1406]: Mar 10 01:20:55.926976 update_engine[1406]: I20260310 01:20:55.924005 1406 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Mar 10 01:20:55.926976 update_engine[1406]: I20260310 01:20:55.926836 1406 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Mar 10 01:20:55.927805 locksmithd[1430]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_REPORTING_ERROR_EVENT" NewVersion=0.0.0 NewSize=0 Mar 10 01:20:55.931983 update_engine[1406]: I20260310 01:20:55.930864 1406 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Mar 10 01:20:55.950691 update_engine[1406]: E20260310 01:20:55.950174 1406 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Mar 10 01:20:55.974728 update_engine[1406]: I20260310 01:20:55.974169 1406 libcurl_http_fetcher.cc:297] Transfer resulted in an error (0), 0 bytes downloaded Mar 10 01:20:55.978980 update_engine[1406]: I20260310 01:20:55.974833 1406 omaha_request_action.cc:617] Omaha request response: Mar 10 01:20:55.978980 update_engine[1406]: I20260310 01:20:55.975025 1406 action_processor.cc:65] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Mar 10 01:20:55.978980 update_engine[1406]: I20260310 01:20:55.975050 1406 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Mar 10 01:20:55.978980 update_engine[1406]: I20260310 01:20:55.975061 1406 update_attempter.cc:306] Processing Done. Mar 10 01:20:55.978980 update_engine[1406]: I20260310 01:20:55.975075 1406 update_attempter.cc:310] Error event sent. Mar 10 01:20:55.978980 update_engine[1406]: I20260310 01:20:55.975342 1406 update_check_scheduler.cc:74] Next update check in 41m48s Mar 10 01:20:55.986022 locksmithd[1430]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_IDLE" NewVersion=0.0.0 NewSize=0 Mar 10 01:22:10.776692 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:10.764077Z","caller":"traceutil/trace.go:171","msg":"trace[2101080148] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"107.101904ms","start":"2026-03-10T01:22:10.656964Z","end":"2026-03-10T01:22:10.764066Z","steps":["trace[2101080148] 'process raft request' (duration: 105.114056ms)"],"step_count":1} Mar 10 01:22:10.776692 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:10.762917Z","caller":"traceutil/trace.go:171","msg":"trace[467833199] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"107.182202ms","start":"2026-03-10T01:22:10.655094Z","end":"2026-03-10T01:22:10.762276Z","steps":["trace[467833199] 'process raft request' (duration: 15.667612ms)","trace[467833199] 'compare' (duration: 91.166895ms)"],"step_count":2} Mar 10 01:22:10.776692 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:10.763067Z","caller":"traceutil/trace.go:171","msg":"trace[1653668363] transaction","detail":"{read_only:false; response_revision:38; number_of_response:1; }","duration":"104.981057ms","start":"2026-03-10T01:22:10.658074Z","end":"2026-03-10T01:22:10.763055Z","steps":["trace[1653668363] 'process raft request' (duration: 104.024628ms)"],"step_count":1} Mar 10 01:22:10.776692 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:10.762912Z","caller":"traceutil/trace.go:171","msg":"trace[21166209] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"105.787018ms","start":"2026-03-10T01:22:10.656915Z","end":"2026-03-10T01:22:10.762702Z","steps":["trace[21166209] 'process raft request' (duration: 105.131096ms)"],"step_count":1} Mar 10 01:22:12.207118 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:12.197787Z","caller":"traceutil/trace.go:171","msg":"trace[1201216823] linearizableReadLoop","detail":"{readStateIndex:119; appliedIndex:118; }","duration":"149.401644ms","start":"2026-03-10T01:22:12.047690Z","end":"2026-03-10T01:22:12.197092Z","steps":["trace[1201216823] 'read index received' (duration: 108.050375ms)","trace[1201216823] 'applied index is now lower than readState.Index' (duration: 41.350647ms)"],"step_count":2} Mar 10 01:22:12.222824 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:12.206766Z","caller":"traceutil/trace.go:171","msg":"trace[1732554994] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"176.017434ms","start":"2026-03-10T01:22:12.030723Z","end":"2026-03-10T01:22:12.206741Z","steps":["trace[1732554994] 'process raft request' (duration: 125.031973ms)","trace[1732554994] 'compare' (duration: 41.249462ms)"],"step_count":2} Mar 10 01:22:12.222824 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:22:12.199113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.295723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-controller-manager\" limit:1 ","response":"range_response_count:0 size:4"} Mar 10 01:22:12.222824 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:12.212642Z","caller":"traceutil/trace.go:171","msg":"trace[1087982046] range","detail":"{range_begin:/registry/clusterroles/system:kube-controller-manager; range_end:; response_count:0; response_revision:114; }","duration":"164.941247ms","start":"2026-03-10T01:22:12.047684Z","end":"2026-03-10T01:22:12.212625Z","steps":["trace[1087982046] 'agreement among raft nodes before linearized reading' (duration: 150.26667ms)"],"step_count":1} Mar 10 01:22:12.412661 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:12.397718Z","caller":"traceutil/trace.go:171","msg":"trace[869537243] transaction","detail":"{read_only:false; response_revision:116; number_of_response:1; }","duration":"100.024614ms","start":"2026-03-10T01:22:12.297354Z","end":"2026-03-10T01:22:12.397378Z","steps":["trace[869537243] 'process raft request' (duration: 99.85273ms)"],"step_count":1} Mar 10 01:22:12.412661 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:12.400071Z","caller":"traceutil/trace.go:171","msg":"trace[2030605891] transaction","detail":"{read_only:false; response_revision:115; number_of_response:1; }","duration":"151.103611ms","start":"2026-03-10T01:22:12.248956Z","end":"2026-03-10T01:22:12.400059Z","steps":["trace[2030605891] 'process raft request' (duration: 148.105494ms)"],"step_count":1} Mar 10 01:22:12.425772 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:12.401688Z","caller":"traceutil/trace.go:171","msg":"trace[1773877794] linearizableReadLoop","detail":"{readStateIndex:120; appliedIndex:119; }","duration":"145.785539ms","start":"2026-03-10T01:22:12.255890Z","end":"2026-03-10T01:22:12.401676Z","steps":["trace[1773877794] 'read index received' (duration: 32.355452ms)","trace[1773877794] 'applied index is now lower than readState.Index' (duration: 113.429147ms)"],"step_count":2} Mar 10 01:22:12.425772 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:22:12.414580Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.661978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/localhost\" limit:1 ","response":"range_response_count:0 size:4"} Mar 10 01:22:12.425772 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:12.416855Z","caller":"traceutil/trace.go:171","msg":"trace[2019471286] range","detail":"{range_begin:/registry/minions/localhost; range_end:; response_count:0; response_revision:116; }","duration":"160.947399ms","start":"2026-03-10T01:22:12.255885Z","end":"2026-03-10T01:22:12.416832Z","steps":["trace[2019471286] 'agreement among raft nodes before linearized reading' (duration: 145.823931ms)"],"step_count":1} Mar 10 01:22:12.781036 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:22:12.769177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.638902ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} Mar 10 01:22:12.781036 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:12.769716Z","caller":"traceutil/trace.go:171","msg":"trace[1809438787] linearizableReadLoop","detail":"{readStateIndex:122; appliedIndex:121; }","duration":"331.888059ms","start":"2026-03-10T01:22:12.437814Z","end":"2026-03-10T01:22:12.769702Z","steps":["trace[1809438787] 'read index received' (duration: 35.044834ms)","trace[1809438787] 'applied index is now lower than readState.Index' (duration: 296.841872ms)"],"step_count":2} Mar 10 01:22:12.781036 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:12.769784Z","caller":"traceutil/trace.go:171","msg":"trace[264289424] transaction","detail":"{read_only:false; response_revision:117; number_of_response:1; }","duration":"336.126961ms","start":"2026-03-10T01:22:12.433651Z","end":"2026-03-10T01:22:12.769778Z","steps":["trace[264289424] 'process raft request' (duration: 39.462774ms)","trace[264289424] 'compare' (duration: 250.919442ms)"],"step_count":2} Mar 10 01:22:12.823617 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:22:12.769836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T01:22:12.433628Z","time spent":"336.176251ms","remote":"10.0.0.108:39310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":781,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} Mar 10 01:22:12.823617 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:22:12.770103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.284266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-dns\" limit:1 ","response":"range_response_count:0 size:4"} Mar 10 01:22:12.823617 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:12.770122Z","caller":"traceutil/trace.go:171","msg":"trace[1513999973] range","detail":"{range_begin:/registry/clusterroles/system:kube-dns; range_end:; response_count:0; response_revision:117; }","duration":"332.324368ms","start":"2026-03-10T01:22:12.437791Z","end":"2026-03-10T01:22:12.770116Z","steps":["trace[1513999973] 'agreement among raft nodes before linearized reading' (duration: 332.262745ms)"],"step_count":1} Mar 10 01:22:12.823617 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:22:12.770140Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T01:22:12.437777Z","time spent":"332.357931ms","remote":"10.0.0.108:39620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/system:kube-dns\" limit:1 "} Mar 10 01:22:12.823934 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:22:12.770179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.181244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} Mar 10 01:22:12.823934 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:12.770214Z","caller":"traceutil/trace.go:171","msg":"trace[2022634993] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:117; }","duration":"309.20552ms","start":"2026-03-10T01:22:12.460995Z","end":"2026-03-10T01:22:12.770200Z","steps":["trace[2022634993] 'agreement among raft nodes before linearized reading' (duration: 309.175945ms)"],"step_count":1} Mar 10 01:22:12.823934 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:22:12.770229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T01:22:12.460976Z","time spent":"309.248929ms","remote":"10.0.0.108:56070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} Mar 10 01:22:13.016553 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:13.010148Z","caller":"traceutil/trace.go:171","msg":"trace[1045505790] linearizableReadLoop","detail":"{readStateIndex:123; appliedIndex:122; }","duration":"159.209619ms","start":"2026-03-10T01:22:12.850920Z","end":"2026-03-10T01:22:13.010129Z","steps":["trace[1045505790] 'read index received' (duration: 158.199718ms)","trace[1045505790] 'applied index is now lower than readState.Index' (duration: 1.009361ms)"],"step_count":2} Mar 10 01:22:13.016553 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:22:13.010724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.785646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-localhost.189b563e470459b5\" limit:1 ","response":"range_response_count:1 size:814"} Mar 10 01:22:13.016553 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:13.010760Z","caller":"traceutil/trace.go:171","msg":"trace[1870671837] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-localhost.189b563e470459b5; range_end:; response_count:1; response_revision:118; }","duration":"159.835438ms","start":"2026-03-10T01:22:12.850914Z","end":"2026-03-10T01:22:13.010749Z","steps":["trace[1870671837] 'agreement among raft nodes before linearized reading' (duration: 159.750962ms)"],"step_count":1} Mar 10 01:22:13.016553 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:13.010862Z","caller":"traceutil/trace.go:171","msg":"trace[2104024117] transaction","detail":"{read_only:false; response_revision:118; number_of_response:1; }","duration":"186.524091ms","start":"2026-03-10T01:22:12.823746Z","end":"2026-03-10T01:22:13.010856Z","steps":["trace[2104024117] 'process raft request' (duration: 184.829553ms)"],"step_count":1} Mar 10 01:22:13.275959 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:13.268916Z","caller":"traceutil/trace.go:171","msg":"trace[649066004] transaction","detail":"{read_only:false; response_revision:120; number_of_response:1; }","duration":"219.89747ms","start":"2026-03-10T01:22:13.048996Z","end":"2026-03-10T01:22:13.268893Z","steps":["trace[649066004] 'process raft request' (duration: 154.971342ms)","trace[649066004] 'compare' (duration: 64.817507ms)"],"step_count":2} Mar 10 01:22:13.326368 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:13.312851Z","caller":"traceutil/trace.go:171","msg":"trace[2061153667] transaction","detail":"{read_only:false; response_revision:121; number_of_response:1; }","duration":"222.123791ms","start":"2026-03-10T01:22:13.090709Z","end":"2026-03-10T01:22:13.312833Z","steps":["trace[2061153667] 'process raft request' (duration: 221.930767ms)"],"step_count":1} Mar 10 01:22:13.326368 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:13.313759Z","caller":"traceutil/trace.go:171","msg":"trace[1320577051] transaction","detail":"{read_only:false; response_revision:122; number_of_response:1; }","duration":"132.977735ms","start":"2026-03-10T01:22:13.180766Z","end":"2026-03-10T01:22:13.313743Z","steps":["trace[1320577051] 'process raft request' (duration: 131.956222ms)"],"step_count":1} Mar 10 01:22:13.504065 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:13.498859Z","caller":"traceutil/trace.go:171","msg":"trace[287768747] transaction","detail":"{read_only:false; response_revision:123; number_of_response:1; }","duration":"141.074448ms","start":"2026-03-10T01:22:13.357757Z","end":"2026-03-10T01:22:13.498831Z","steps":["trace[287768747] 'process raft request' (duration: 43.298999ms)","trace[287768747] 'compare' (duration: 97.696836ms)"],"step_count":2} Mar 10 01:22:13.557743 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:13.552356Z","caller":"traceutil/trace.go:171","msg":"trace[416448276] linearizableReadLoop","detail":"{readStateIndex:129; appliedIndex:127; }","duration":"168.181995ms","start":"2026-03-10T01:22:13.384152Z","end":"2026-03-10T01:22:13.552334Z","steps":["trace[416448276] 'read index received' (duration: 16.852571ms)","trace[416448276] 'applied index is now lower than readState.Index' (duration: 151.328503ms)"],"step_count":2} Mar 10 01:22:13.557743 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:13.552696Z","caller":"traceutil/trace.go:171","msg":"trace[1716931905] transaction","detail":"{read_only:false; response_revision:124; number_of_response:1; }","duration":"184.673411ms","start":"2026-03-10T01:22:13.368011Z","end":"2026-03-10T01:22:13.552684Z","steps":["trace[1716931905] 'process raft request' (duration: 183.890005ms)"],"step_count":1} Mar 10 01:22:13.557743 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:22:13.552821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.655933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/localhost\" limit:1 ","response":"range_response_count:1 size:3139"} Mar 10 01:22:13.557743 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:13.552851Z","caller":"traceutil/trace.go:171","msg":"trace[424758358] range","detail":"{range_begin:/registry/minions/localhost; range_end:; response_count:1; response_revision:124; }","duration":"168.69704ms","start":"2026-03-10T01:22:13.384146Z","end":"2026-03-10T01:22:13.552843Z","steps":["trace[424758358] 'agreement among raft nodes before linearized reading' (duration: 168.609258ms)"],"step_count":1} Mar 10 01:22:13.646030 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:22:13.601158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.166725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges\" limit:1 ","response":"range_response_count:0 size:4"} Mar 10 01:22:13.646030 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:13.616077Z","caller":"traceutil/trace.go:171","msg":"trace[979006117] range","detail":"{range_begin:/registry/limitranges; range_end:; response_count:0; response_revision:125; }","duration":"140.076239ms","start":"2026-03-10T01:22:13.475970Z","end":"2026-03-10T01:22:13.616047Z","steps":["trace[979006117] 'agreement among raft nodes before linearized reading' (duration: 125.047466ms)"],"step_count":1} Mar 10 01:22:13.828342 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:22:13.810663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.643155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient\" limit:1 ","response":"range_response_count:0 size:4"} Mar 10 01:22:13.828342 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:13.810742Z","caller":"traceutil/trace.go:171","msg":"trace[1521936670] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient; range_end:; response_count:0; response_revision:125; }","duration":"239.731326ms","start":"2026-03-10T01:22:13.570992Z","end":"2026-03-10T01:22:13.810723Z","steps":["trace[1521936670] 'agreement among raft nodes before linearized reading' (duration: 40.126484ms)","trace[1521936670] 'range keys from in-memory index tree' (duration: 199.502535ms)"],"step_count":2} Mar 10 01:22:13.850120 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:22:13.817809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.853994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/localhost\" limit:1 ","response":"range_response_count:1 size:3139"} Mar 10 01:22:13.850120 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:13.817863Z","caller":"traceutil/trace.go:171","msg":"trace[1796120593] range","detail":"{range_begin:/registry/minions/localhost; range_end:; response_count:1; response_revision:125; }","duration":"129.917841ms","start":"2026-03-10T01:22:13.687934Z","end":"2026-03-10T01:22:13.817852Z","steps":["trace[1796120593] 'range keys from in-memory index tree' (duration: 129.776602ms)"],"step_count":1} Mar 10 01:22:13.850120 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:13.818080Z","caller":"traceutil/trace.go:171","msg":"trace[1468865752] transaction","detail":"{read_only:false; response_revision:126; number_of_response:1; }","duration":"126.937101ms","start":"2026-03-10T01:22:13.691135Z","end":"2026-03-10T01:22:13.818072Z","steps":["trace[1468865752] 'process raft request' (duration: 117.886045ms)"],"step_count":1} Mar 10 01:22:13.850120 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:22:13.819108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.076207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} Mar 10 01:22:13.850120 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:13.819141Z","caller":"traceutil/trace.go:171","msg":"trace[575117856] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:125; }","duration":"350.110079ms","start":"2026-03-10T01:22:13.469022Z","end":"2026-03-10T01:22:13.819132Z","steps":["trace[575117856] 'agreement among raft nodes before linearized reading' (duration: 142.108508ms)","trace[575117856] 'range keys from in-memory index tree' (duration: 199.702772ms)"],"step_count":2} Mar 10 01:22:13.867629 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:22:13.819271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T01:22:13.469003Z","time spent":"350.153529ms","remote":"10.0.0.108:56070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} Mar 10 01:22:13.983754 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:13.975790Z","caller":"traceutil/trace.go:171","msg":"trace[2067764262] transaction","detail":"{read_only:false; response_revision:128; number_of_response:1; }","duration":"115.277463ms","start":"2026-03-10T01:22:13.853854Z","end":"2026-03-10T01:22:13.969132Z","steps":["trace[2067764262] 'process raft request' (duration: 76.981948ms)","trace[2067764262] 'compare' (duration: 38.206121ms)"],"step_count":2} Mar 10 01:22:14.015678 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:13.999319Z","caller":"traceutil/trace.go:171","msg":"trace[1218502685] transaction","detail":"{read_only:false; response_revision:129; number_of_response:1; }","duration":"107.514956ms","start":"2026-03-10T01:22:13.891785Z","end":"2026-03-10T01:22:13.999300Z","steps":["trace[1218502685] 'process raft request' (duration: 107.067755ms)"],"step_count":1} Mar 10 01:22:16.399819 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:22:16.356927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.559516ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Mar 10 01:22:16.399819 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:16.361277Z","caller":"traceutil/trace.go:171","msg":"trace[167231152] linearizableReadLoop","detail":"{readStateIndex:180; appliedIndex:179; }","duration":"210.485401ms","start":"2026-03-10T01:22:16.150771Z","end":"2026-03-10T01:22:16.361256Z","steps":["trace[167231152] 'read index received' (duration: 35.145µs)","trace[167231152] 'applied index is now lower than readState.Index' (duration: 210.448312ms)"],"step_count":2} Mar 10 01:22:16.399819 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:22:16.361575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.60533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-scheduler-localhost.189b563e5bd9f593\" limit:1 ","response":"range_response_count:1 size:816"} Mar 10 01:22:16.399819 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:16.361606Z","caller":"traceutil/trace.go:171","msg":"trace[1269039612] range","detail":"{range_begin:/registry/events/kube-system/kube-scheduler-localhost.189b563e5bd9f593; range_end:; response_count:1; response_revision:175; }","duration":"210.829312ms","start":"2026-03-10T01:22:16.150766Z","end":"2026-03-10T01:22:16.361595Z","steps":["trace[1269039612] 'agreement among raft nodes before linearized reading' (duration: 210.5447ms)"],"step_count":1} Mar 10 01:22:16.419816 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:16.361694Z","caller":"traceutil/trace.go:171","msg":"trace[627187041] transaction","detail":"{read_only:false; response_revision:175; number_of_response:1; }","duration":"276.806821ms","start":"2026-03-10T01:22:16.084879Z","end":"2026-03-10T01:22:16.361686Z","steps":["trace[627187041] 'process raft request' (duration: 61.433415ms)","trace[627187041] 'compare' (duration: 206.443382ms)"],"step_count":2} Mar 10 01:22:17.095726 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:17.089739Z","caller":"traceutil/trace.go:171","msg":"trace[1641154729] transaction","detail":"{read_only:false; response_revision:188; number_of_response:1; }","duration":"115.965565ms","start":"2026-03-10T01:22:16.973755Z","end":"2026-03-10T01:22:17.089721Z","steps":["trace[1641154729] 'process raft request' (duration: 115.552426ms)"],"step_count":1} Mar 10 01:22:18.047554 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:18.024552Z","caller":"traceutil/trace.go:171","msg":"trace[1184793391] transaction","detail":"{read_only:false; response_revision:198; number_of_response:1; }","duration":"138.404187ms","start":"2026-03-10T01:22:17.886111Z","end":"2026-03-10T01:22:18.024515Z","steps":["trace[1184793391] 'process raft request' (duration: 75.201268ms)","trace[1184793391] 'compare' (duration: 58.398704ms)"],"step_count":2} Mar 10 01:22:18.280341 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:18.275375Z","caller":"traceutil/trace.go:171","msg":"trace[848511685] transaction","detail":"{read_only:false; response_revision:200; number_of_response:1; }","duration":"120.990635ms","start":"2026-03-10T01:22:18.154364Z","end":"2026-03-10T01:22:18.275355Z","steps":["trace[848511685] 'process raft request' (duration: 120.952216ms)"],"step_count":1} Mar 10 01:22:18.280341 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:18.275775Z","caller":"traceutil/trace.go:171","msg":"trace[433242214] transaction","detail":"{read_only:false; response_revision:199; number_of_response:1; }","duration":"143.389196ms","start":"2026-03-10T01:22:18.132376Z","end":"2026-03-10T01:22:18.275766Z","steps":["trace[433242214] 'process raft request' (duration: 66.815794ms)","trace[433242214] 'compare' (duration: 76.02814ms)"],"step_count":2} Mar 10 01:22:18.998661 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:22:18.979106Z","caller":"traceutil/trace.go:171","msg":"trace[890495549] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"193.994565ms","start":"2026-03-10T01:22:18.785084Z","end":"2026-03-10T01:22:18.979078Z","steps":["trace[890495549] 'process raft request' (duration: 140.075138ms)","trace[890495549] 'compare' (duration: 53.789007ms)"],"step_count":2} Mar 10 01:24:18.473316 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:24:18.440344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.709062ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Mar 10 01:24:18.473316 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:24:18.444056Z","caller":"traceutil/trace.go:171","msg":"trace[1263681251] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"502.20194ms","start":"2026-03-10T01:24:17.941755Z","end":"2026-03-10T01:24:18.443957Z","steps":["trace[1263681251] 'process raft request' (duration: 41.59749ms)","trace[1263681251] 'compare' (duration: 455.41344ms)"],"step_count":2} Mar 10 01:24:18.473316 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:24:18.451341Z","caller":"traceutil/trace.go:171","msg":"trace[1896138325] linearizableReadLoop","detail":"{readStateIndex:907; appliedIndex:906; }","duration":"499.584556ms","start":"2026-03-10T01:24:17.941760Z","end":"2026-03-10T01:24:18.441345Z","steps":["trace[1896138325] 'read index received' (duration: 205.103µs)","trace[1896138325] 'applied index is now lower than readState.Index' (duration: 499.377559ms)"],"step_count":2} Mar 10 01:24:18.473316 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:24:18.444370Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T01:24:17.941737Z","time spent":"502.588792ms","remote":"10.0.0.108:39336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4575,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} Mar 10 01:24:18.493141 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:24:18.469718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.9424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Mar 10 01:24:18.493141 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:24:18.470109Z","caller":"traceutil/trace.go:171","msg":"trace[1910391444] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:845; }","duration":"528.336194ms","start":"2026-03-10T01:24:17.941754Z","end":"2026-03-10T01:24:18.470090Z","steps":["trace[1910391444] 'agreement among raft nodes before linearized reading' (duration: 508.438007ms)","trace[1910391444] 'range keys from in-memory index tree' (duration: 19.49285ms)"],"step_count":2} Mar 10 01:24:18.493141 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:24:18.470225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.223071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/operator.tigera.io/installations/default\" limit:1 ","response":"range_response_count:1 size:3723"} Mar 10 01:24:18.493141 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:24:18.472867Z","caller":"traceutil/trace.go:171","msg":"trace[691132164] range","detail":"{range_begin:/registry/operator.tigera.io/installations/default; range_end:; response_count:1; response_revision:848; }","duration":"504.866768ms","start":"2026-03-10T01:24:17.967984Z","end":"2026-03-10T01:24:18.472851Z","steps":["trace[691132164] 'agreement among raft nodes before linearized reading' (duration: 502.193817ms)"],"step_count":1} Mar 10 01:24:18.493141 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:24:18.472986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T01:24:17.967186Z","time spent":"505.782438ms","remote":"10.0.0.108:51678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":3747,"request content":"key:\"/registry/operator.tigera.io/installations/default\" limit:1 "} Mar 10 01:24:18.496154 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:24:18.472144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T01:24:17.941641Z","time spent":"530.483537ms","remote":"10.0.0.108:56068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} Mar 10 01:24:20.105658 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:24:20.054673Z","caller":"traceutil/trace.go:171","msg":"trace[992558807] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:988; }","duration":"119.443045ms","start":"2026-03-10T01:24:19.934830Z","end":"2026-03-10T01:24:20.054273Z","steps":["trace[992558807] 'read index received' (duration: 100.497647ms)","trace[992558807] 'applied index is now lower than readState.Index' (duration: 18.944065ms)"],"step_count":2} Mar 10 01:24:20.105658 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:24:20.056129Z","caller":"traceutil/trace.go:171","msg":"trace[1910707894] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"134.818655ms","start":"2026-03-10T01:24:19.921292Z","end":"2026-03-10T01:24:20.056111Z","steps":["trace[1910707894] 'process raft request' (duration: 114.824598ms)","trace[1910707894] 'compare' (duration: 16.511083ms)"],"step_count":2} Mar 10 01:24:20.105658 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:24:20.056374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.527718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Mar 10 01:24:20.105658 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:24:20.056637Z","caller":"traceutil/trace.go:171","msg":"trace[880763155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:925; }","duration":"121.800306ms","start":"2026-03-10T01:24:19.934825Z","end":"2026-03-10T01:24:20.056625Z","steps":["trace[880763155] 'agreement among raft nodes before linearized reading' (duration: 121.510565ms)"],"step_count":1} Mar 10 01:24:20.105658 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:24:20.075619Z","caller":"traceutil/trace.go:171","msg":"trace[1223084941] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"120.747525ms","start":"2026-03-10T01:24:19.954739Z","end":"2026-03-10T01:24:20.075486Z","steps":["trace[1223084941] 'process raft request' (duration: 118.584192ms)"],"step_count":1} Mar 10 01:24:20.111326 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:24:20.091166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.953706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/calico-system/csi-node-driver\" limit:1 ","response":"range_response_count:1 size:447"} Mar 10 01:24:20.111326 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:24:20.091280Z","caller":"traceutil/trace.go:171","msg":"trace[1139327589] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"136.375429ms","start":"2026-03-10T01:24:19.954852Z","end":"2026-03-10T01:24:20.091227Z","steps":["trace[1139327589] 'process raft request' (duration: 119.356832ms)"],"step_count":1} Mar 10 01:24:20.111326 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:24:20.091338Z","caller":"traceutil/trace.go:171","msg":"trace[1611556981] range","detail":"{range_begin:/registry/serviceaccounts/calico-system/csi-node-driver; range_end:; response_count:1; response_revision:929; }","duration":"101.135286ms","start":"2026-03-10T01:24:19.990188Z","end":"2026-03-10T01:24:20.091323Z","steps":["trace[1611556981] 'agreement among raft nodes before linearized reading' (duration: 100.922268ms)"],"step_count":1} Mar 10 01:25:15.188229 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:25:15.166171Z","caller":"traceutil/trace.go:171","msg":"trace[2086436424] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"127.931694ms","start":"2026-03-10T01:25:15.037976Z","end":"2026-03-10T01:25:15.165908Z","steps":["trace[2086436424] 'process raft request' (duration: 80.37883ms)","trace[2086436424] 'compare' (duration: 34.985167ms)"],"step_count":2} Mar 10 01:25:15.879810 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:25:15.871845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.114602ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Mar 10 01:25:15.889875 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:25:15.880259Z","caller":"traceutil/trace.go:171","msg":"trace[1010318500] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"372.806273ms","start":"2026-03-10T01:25:15.505305Z","end":"2026-03-10T01:25:15.878112Z","steps":["trace[1010318500] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/events/kube-system/coredns-674b8bbfcf-zjpxs.189b566b08282df0; req_size:807; } (duration: 330.37039ms)"],"step_count":1} Mar 10 01:25:15.892594 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:25:15.883383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T01:25:15.505287Z","time spent":"375.021948ms","remote":"10.0.0.108:39310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":810,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Mar 10 01:25:16.725948 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:25:16.708283Z","caller":"traceutil/trace.go:171","msg":"trace[552054198] linearizableReadLoop","detail":"{readStateIndex:1367; appliedIndex:1367; }","duration":"339.957364ms","start":"2026-03-10T01:25:16.368125Z","end":"2026-03-10T01:25:16.708082Z","steps":["trace[552054198] 'read index received' (duration: 339.807765ms)","trace[552054198] 'applied index is now lower than readState.Index' (duration: 148.557µs)"],"step_count":2} Mar 10 01:25:16.879856 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:25:16.870367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.30873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/calico-system\" limit:1 ","response":"range_response_count:1 size:764"} Mar 10 01:25:16.879856 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:25:16.870680Z","caller":"traceutil/trace.go:171","msg":"trace[1595689940] range","detail":"{range_begin:/registry/namespaces/calico-system; range_end:; response_count:1; response_revision:1289; }","duration":"502.629942ms","start":"2026-03-10T01:25:16.368030Z","end":"2026-03-10T01:25:16.870660Z","steps":["trace[1595689940] 'agreement among raft nodes before linearized reading' (duration: 341.350219ms)","trace[1595689940] 'range keys from in-memory index tree' (duration: 160.672376ms)"],"step_count":2} Mar 10 01:25:16.879856 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:25:16.870807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T01:25:16.367962Z","time spent":"502.829556ms","remote":"10.0.0.108:39356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":788,"request content":"key:\"/registry/namespaces/calico-system\" limit:1 "} Mar 10 01:25:17.064124 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:25:17.029955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.690954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/calico-system/calico-apiserver-5bcbb475fd-w75z4\" limit:1 ","response":"range_response_count:1 size:5800"} Mar 10 01:25:17.083205 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:25:17.066330Z","caller":"traceutil/trace.go:171","msg":"trace[1942812998] range","detail":"{range_begin:/registry/pods/calico-system/calico-apiserver-5bcbb475fd-w75z4; range_end:; response_count:1; response_revision:1290; }","duration":"165.094901ms","start":"2026-03-10T01:25:16.887198Z","end":"2026-03-10T01:25:17.052292Z","steps":["trace[1942812998] 'range keys from in-memory index tree' (duration: 138.248464ms)"],"step_count":1} Mar 10 01:25:28.938713 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:25:28.925236Z","caller":"traceutil/trace.go:171","msg":"trace[931583409] linearizableReadLoop","detail":"{readStateIndex:1479; appliedIndex:1478; }","duration":"173.274845ms","start":"2026-03-10T01:25:28.751871Z","end":"2026-03-10T01:25:28.925145Z","steps":["trace[931583409] 'read index received' (duration: 165.687334ms)","trace[931583409] 'applied index is now lower than readState.Index' (duration: 7.586759ms)"],"step_count":2} Mar 10 01:25:28.938713 etcd-wrapper[1684]: {"level":"warn","ts":"2026-03-10T01:25:28.925610Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.730576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/crd.projectcalico.org/clusterinformations/default\" limit:1 ","response":"range_response_count:1 size:886"} Mar 10 01:25:28.938713 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:25:28.925639Z","caller":"traceutil/trace.go:171","msg":"trace[1038507030] range","detail":"{range_begin:/registry/crd.projectcalico.org/clusterinformations/default; range_end:; response_count:1; response_revision:1354; }","duration":"173.985965ms","start":"2026-03-10T01:25:28.751645Z","end":"2026-03-10T01:25:28.925631Z","steps":["trace[1038507030] 'agreement among raft nodes before linearized reading' (duration: 173.700711ms)"],"step_count":1} Mar 10 01:25:28.938713 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:25:28.925708Z","caller":"traceutil/trace.go:171","msg":"trace[1619347645] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"256.03029ms","start":"2026-03-10T01:25:28.669666Z","end":"2026-03-10T01:25:28.925696Z","steps":["trace[1619347645] 'process raft request' (duration: 248.267845ms)"],"step_count":1} Mar 10 01:25:58.470228 etcd-wrapper[1684]: {"level":"info","ts":"2026-03-10T01:25:58.425215Z","caller":"traceutil/trace.go:171","msg":"trace[1065267179] transaction","detail":"{read_only:false; response_revision:1508; number_of_response:1; }","duration":"106.777393ms","start":"2026-03-10T01:25:58.317871Z","end":"2026-03-10T01:25:58.424648Z","steps":["trace[1065267179] 'process raft request' (duration: 93.260295ms)","trace[1065267179] 'compare' (duration: 12.126366ms)"],"step_count":2}