Apr 13 22:19:46.080468 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 Apr 13 18:40:27 -00 2026 Apr 13 22:19:46.080517 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=c1ba97db2f6278922cfc5bd0ca74b4bb573fca2c3aed19c121a34271e693e156 Apr 13 22:19:46.080540 kernel: BIOS-provided physical RAM map: Apr 13 22:19:46.080549 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Apr 13 22:19:46.080557 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Apr 13 22:19:46.080565 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Apr 13 22:19:46.080575 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000009cfdbfff] usable Apr 13 22:19:46.080584 kernel: BIOS-e820: [mem 0x000000009cfdc000-0x000000009cffffff] reserved Apr 13 22:19:46.080592 kernel: BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved Apr 13 22:19:46.080604 kernel: BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved Apr 13 22:19:46.080612 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Apr 13 22:19:46.080620 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Apr 13 22:19:46.080629 kernel: NX (Execute Disable) protection: active Apr 13 22:19:46.080638 kernel: APIC: Static calls initialized Apr 13 22:19:46.080649 kernel: SMBIOS 2.8 present. Apr 13 22:19:46.080660 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.16.2-debian-1.16.2-1 04/01/2014 Apr 13 22:19:46.080669 kernel: Hypervisor detected: KVM Apr 13 22:19:46.080678 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Apr 13 22:19:46.080687 kernel: kvm-clock: using sched offset of 6168872506 cycles Apr 13 22:19:46.080698 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Apr 13 22:19:46.080707 kernel: tsc: Detected 2793.438 MHz processor Apr 13 22:19:46.080717 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Apr 13 22:19:46.080726 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Apr 13 22:19:46.080734 kernel: last_pfn = 0x9cfdc max_arch_pfn = 0x10000000000 Apr 13 22:19:46.080744 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Apr 13 22:19:46.080753 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Apr 13 22:19:46.080761 kernel: Using GB pages for direct mapping Apr 13 22:19:46.080769 kernel: ACPI: Early table checksum verification disabled Apr 13 22:19:46.080776 kernel: ACPI: RSDP 0x00000000000F59D0 000014 (v00 BOCHS ) Apr 13 22:19:46.080784 kernel: ACPI: RSDT 0x000000009CFE241A 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Apr 13 22:19:46.080792 kernel: ACPI: FACP 0x000000009CFE21FA 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) Apr 13 22:19:46.080800 kernel: ACPI: DSDT 0x000000009CFE0040 0021BA (v01 BOCHS BXPC 00000001 BXPC 00000001) Apr 13 22:19:46.080808 kernel: ACPI: FACS 0x000000009CFE0000 000040 Apr 13 22:19:46.080819 kernel: ACPI: APIC 0x000000009CFE22EE 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) Apr 13 22:19:46.080827 kernel: ACPI: HPET 0x000000009CFE237E 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Apr 13 22:19:46.080836 kernel: ACPI: MCFG 0x000000009CFE23B6 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Apr 13 22:19:46.080846 kernel: ACPI: WAET 0x000000009CFE23F2 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Apr 13 22:19:46.080854 kernel: ACPI: Reserving FACP table memory at [mem 0x9cfe21fa-0x9cfe22ed] Apr 13 22:19:46.080864 kernel: ACPI: Reserving DSDT table memory at [mem 0x9cfe0040-0x9cfe21f9] Apr 13 22:19:46.080874 kernel: ACPI: Reserving FACS table memory at [mem 0x9cfe0000-0x9cfe003f] Apr 13 22:19:46.080887 kernel: ACPI: Reserving APIC table memory at [mem 0x9cfe22ee-0x9cfe237d] Apr 13 22:19:46.080898 kernel: ACPI: Reserving HPET table memory at [mem 0x9cfe237e-0x9cfe23b5] Apr 13 22:19:46.080907 kernel: ACPI: Reserving MCFG table memory at [mem 0x9cfe23b6-0x9cfe23f1] Apr 13 22:19:46.080915 kernel: ACPI: Reserving WAET table memory at [mem 0x9cfe23f2-0x9cfe2419] Apr 13 22:19:46.080924 kernel: No NUMA configuration found Apr 13 22:19:46.080933 kernel: Faking a node at [mem 0x0000000000000000-0x000000009cfdbfff] Apr 13 22:19:46.080942 kernel: NODE_DATA(0) allocated [mem 0x9cfd6000-0x9cfdbfff] Apr 13 22:19:46.080954 kernel: Zone ranges: Apr 13 22:19:46.080963 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Apr 13 22:19:46.080972 kernel: DMA32 [mem 0x0000000001000000-0x000000009cfdbfff] Apr 13 22:19:46.080981 kernel: Normal empty Apr 13 22:19:46.080990 kernel: Movable zone start for each node Apr 13 22:19:46.081000 kernel: Early memory node ranges Apr 13 22:19:46.081008 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Apr 13 22:19:46.081018 kernel: node 0: [mem 0x0000000000100000-0x000000009cfdbfff] Apr 13 22:19:46.081026 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009cfdbfff] Apr 13 22:19:46.081035 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Apr 13 22:19:46.081047 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Apr 13 22:19:46.081056 kernel: On node 0, zone DMA32: 12324 pages in unavailable ranges Apr 13 22:19:46.081065 kernel: ACPI: PM-Timer IO Port: 0x608 Apr 13 22:19:46.081075 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Apr 13 22:19:46.081085 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Apr 13 22:19:46.081093 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Apr 13 22:19:46.081102 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Apr 13 22:19:46.081110 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Apr 13 22:19:46.081147 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Apr 13 22:19:46.081159 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Apr 13 22:19:46.081168 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Apr 13 22:19:46.081176 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Apr 13 22:19:46.081185 kernel: TSC deadline timer available Apr 13 22:19:46.081193 kernel: smpboot: Allowing 4 CPUs, 0 hotplug CPUs Apr 13 22:19:46.081202 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Apr 13 22:19:46.081211 kernel: kvm-guest: KVM setup pv remote TLB flush Apr 13 22:19:46.081221 kernel: kvm-guest: setup PV sched yield Apr 13 22:19:46.081231 kernel: [mem 0xc0000000-0xfed1bfff] available for PCI devices Apr 13 22:19:46.081243 kernel: Booting paravirtualized kernel on KVM Apr 13 22:19:46.081252 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Apr 13 22:19:46.081262 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 Apr 13 22:19:46.081273 kernel: percpu: Embedded 57 pages/cpu s196328 r8192 d28952 u524288 Apr 13 22:19:46.081284 kernel: pcpu-alloc: s196328 r8192 d28952 u524288 alloc=1*2097152 Apr 13 22:19:46.081293 kernel: pcpu-alloc: [0] 0 1 2 3 Apr 13 22:19:46.081302 kernel: kvm-guest: PV spinlocks enabled Apr 13 22:19:46.081312 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Apr 13 22:19:46.081324 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=c1ba97db2f6278922cfc5bd0ca74b4bb573fca2c3aed19c121a34271e693e156 Apr 13 22:19:46.081336 kernel: random: crng init done Apr 13 22:19:46.081346 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Apr 13 22:19:46.081356 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Apr 13 22:19:46.081366 kernel: Fallback order for Node 0: 0 Apr 13 22:19:46.081376 kernel: Built 1 zonelists, mobility grouping on. Total pages: 632732 Apr 13 22:19:46.081385 kernel: Policy zone: DMA32 Apr 13 22:19:46.081395 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Apr 13 22:19:46.081405 kernel: Memory: 2433656K/2571752K available (12288K kernel code, 2288K rwdata, 22752K rodata, 42896K init, 2300K bss, 137892K reserved, 0K cma-reserved) Apr 13 22:19:46.081418 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Apr 13 22:19:46.081464 kernel: ftrace: allocating 37996 entries in 149 pages Apr 13 22:19:46.081475 kernel: ftrace: allocated 149 pages with 4 groups Apr 13 22:19:46.081484 kernel: Dynamic Preempt: voluntary Apr 13 22:19:46.081494 kernel: rcu: Preemptible hierarchical RCU implementation. Apr 13 22:19:46.081505 kernel: rcu: RCU event tracing is enabled. Apr 13 22:19:46.081514 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Apr 13 22:19:46.081523 kernel: Trampoline variant of Tasks RCU enabled. Apr 13 22:19:46.081531 kernel: Rude variant of Tasks RCU enabled. Apr 13 22:19:46.081544 kernel: Tracing variant of Tasks RCU enabled. Apr 13 22:19:46.081554 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Apr 13 22:19:46.081562 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Apr 13 22:19:46.081572 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 Apr 13 22:19:46.081580 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Apr 13 22:19:46.081589 kernel: Console: colour VGA+ 80x25 Apr 13 22:19:46.081598 kernel: printk: console [ttyS0] enabled Apr 13 22:19:46.081607 kernel: ACPI: Core revision 20230628 Apr 13 22:19:46.081617 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Apr 13 22:19:46.081629 kernel: APIC: Switch to symmetric I/O mode setup Apr 13 22:19:46.081638 kernel: x2apic enabled Apr 13 22:19:46.081647 kernel: APIC: Switched APIC routing to: physical x2apic Apr 13 22:19:46.081656 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() Apr 13 22:19:46.081666 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() Apr 13 22:19:46.081675 kernel: kvm-guest: setup PV IPIs Apr 13 22:19:46.081685 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Apr 13 22:19:46.081695 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x284409db922, max_idle_ns: 440795228871 ns Apr 13 22:19:46.081715 kernel: Calibrating delay loop (skipped) preset value.. 5586.87 BogoMIPS (lpj=2793438) Apr 13 22:19:46.081724 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Apr 13 22:19:46.081734 kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 Apr 13 22:19:46.081743 kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 Apr 13 22:19:46.081755 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Apr 13 22:19:46.081764 kernel: Spectre V2 : Mitigation: Retpolines Apr 13 22:19:46.081773 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Apr 13 22:19:46.081781 kernel: RETBleed: WARNING: Spectre v2 mitigation leaves CPU vulnerable to RETBleed attacks, data leaks possible! Apr 13 22:19:46.081792 kernel: RETBleed: Vulnerable Apr 13 22:19:46.081800 kernel: Speculative Store Bypass: Vulnerable Apr 13 22:19:46.081809 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Apr 13 22:19:46.081817 kernel: GDS: Unknown: Dependent on hypervisor status Apr 13 22:19:46.081826 kernel: active return thunk: its_return_thunk Apr 13 22:19:46.081834 kernel: ITS: Mitigation: Aligned branch/return thunks Apr 13 22:19:46.081843 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Apr 13 22:19:46.081852 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Apr 13 22:19:46.081862 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Apr 13 22:19:46.081873 kernel: x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' Apr 13 22:19:46.081882 kernel: x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' Apr 13 22:19:46.081892 kernel: x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' Apr 13 22:19:46.081901 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Apr 13 22:19:46.081910 kernel: x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64 Apr 13 22:19:46.081919 kernel: x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512 Apr 13 22:19:46.081928 kernel: x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024 Apr 13 22:19:46.081938 kernel: x86/fpu: Enabled xstate features 0xe7, context size is 2432 bytes, using 'compacted' format. Apr 13 22:19:46.081947 kernel: Freeing SMP alternatives memory: 32K Apr 13 22:19:46.081958 kernel: pid_max: default: 32768 minimum: 301 Apr 13 22:19:46.081967 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Apr 13 22:19:46.081977 kernel: landlock: Up and running. Apr 13 22:19:46.081986 kernel: SELinux: Initializing. Apr 13 22:19:46.081995 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Apr 13 22:19:46.082005 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Apr 13 22:19:46.082015 kernel: smpboot: CPU0: Intel(R) Xeon(R) Platinum 8370C CPU @ 2.80GHz (family: 0x6, model: 0x6a, stepping: 0x6) Apr 13 22:19:46.082025 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Apr 13 22:19:46.082034 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Apr 13 22:19:46.082047 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Apr 13 22:19:46.082056 kernel: Performance Events: unsupported p6 CPU model 106 no PMU driver, software events only. Apr 13 22:19:46.082065 kernel: signal: max sigframe size: 3632 Apr 13 22:19:46.082073 kernel: rcu: Hierarchical SRCU implementation. Apr 13 22:19:46.082083 kernel: rcu: Max phase no-delay instances is 400. Apr 13 22:19:46.082092 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Apr 13 22:19:46.082102 kernel: smp: Bringing up secondary CPUs ... Apr 13 22:19:46.082111 kernel: smpboot: x86: Booting SMP configuration: Apr 13 22:19:46.082914 kernel: .... node #0, CPUs: #1 #2 #3 Apr 13 22:19:46.082948 kernel: smp: Brought up 1 node, 4 CPUs Apr 13 22:19:46.082958 kernel: smpboot: Max logical packages: 1 Apr 13 22:19:46.082969 kernel: smpboot: Total of 4 processors activated (22347.50 BogoMIPS) Apr 13 22:19:46.082980 kernel: devtmpfs: initialized Apr 13 22:19:46.082991 kernel: x86/mm: Memory block size: 128MB Apr 13 22:19:46.083001 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Apr 13 22:19:46.083012 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Apr 13 22:19:46.083022 kernel: pinctrl core: initialized pinctrl subsystem Apr 13 22:19:46.083033 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Apr 13 22:19:46.083050 kernel: audit: initializing netlink subsys (disabled) Apr 13 22:19:46.083060 kernel: thermal_sys: Registered thermal governor 'step_wise' Apr 13 22:19:46.083071 kernel: thermal_sys: Registered thermal governor 'user_space' Apr 13 22:19:46.083081 kernel: audit: type=2000 audit(1776118781.799:1): state=initialized audit_enabled=0 res=1 Apr 13 22:19:46.083091 kernel: cpuidle: using governor menu Apr 13 22:19:46.083101 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Apr 13 22:19:46.083112 kernel: dca service started, version 1.12.1 Apr 13 22:19:46.162144 kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xb0000000-0xbfffffff] (base 0xb0000000) Apr 13 22:19:46.162178 kernel: PCI: MMCONFIG at [mem 0xb0000000-0xbfffffff] reserved as E820 entry Apr 13 22:19:46.162201 kernel: PCI: Using configuration type 1 for base access Apr 13 22:19:46.162210 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Apr 13 22:19:46.162219 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Apr 13 22:19:46.162228 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Apr 13 22:19:46.162237 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Apr 13 22:19:46.162246 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Apr 13 22:19:46.162254 kernel: ACPI: Added _OSI(Module Device) Apr 13 22:19:46.162262 kernel: ACPI: Added _OSI(Processor Device) Apr 13 22:19:46.162271 kernel: ACPI: Added _OSI(Processor Aggregator Device) Apr 13 22:19:46.162281 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Apr 13 22:19:46.162289 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Apr 13 22:19:46.162297 kernel: ACPI: Interpreter enabled Apr 13 22:19:46.162305 kernel: ACPI: PM: (supports S0 S3 S5) Apr 13 22:19:46.162313 kernel: ACPI: Using IOAPIC for interrupt routing Apr 13 22:19:46.162321 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Apr 13 22:19:46.162330 kernel: PCI: Using E820 reservations for host bridge windows Apr 13 22:19:46.162337 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F Apr 13 22:19:46.162345 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Apr 13 22:19:46.162666 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Apr 13 22:19:46.162776 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] Apr 13 22:19:46.162864 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] Apr 13 22:19:46.162878 kernel: PCI host bridge to bus 0000:00 Apr 13 22:19:46.162966 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Apr 13 22:19:46.163037 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Apr 13 22:19:46.163111 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Apr 13 22:19:46.163206 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xafffffff window] Apr 13 22:19:46.163276 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] Apr 13 22:19:46.163344 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] Apr 13 22:19:46.163412 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Apr 13 22:19:46.164190 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 Apr 13 22:19:46.164309 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 Apr 13 22:19:46.164403 kernel: pci 0000:00:01.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref] Apr 13 22:19:46.164519 kernel: pci 0000:00:01.0: reg 0x18: [mem 0xfebd0000-0xfebd0fff] Apr 13 22:19:46.164602 kernel: pci 0000:00:01.0: reg 0x30: [mem 0xfebc0000-0xfebcffff pref] Apr 13 22:19:46.164686 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Apr 13 22:19:46.164773 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 Apr 13 22:19:46.164859 kernel: pci 0000:00:02.0: reg 0x10: [io 0xc0c0-0xc0df] Apr 13 22:19:46.164937 kernel: pci 0000:00:02.0: reg 0x14: [mem 0xfebd1000-0xfebd1fff] Apr 13 22:19:46.165019 kernel: pci 0000:00:02.0: reg 0x20: [mem 0xfe000000-0xfe003fff 64bit pref] Apr 13 22:19:46.165108 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 Apr 13 22:19:46.173314 kernel: pci 0000:00:03.0: reg 0x10: [io 0xc000-0xc07f] Apr 13 22:19:46.174552 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xfebd2000-0xfebd2fff] Apr 13 22:19:46.174722 kernel: pci 0000:00:03.0: reg 0x20: [mem 0xfe004000-0xfe007fff 64bit pref] Apr 13 22:19:46.174812 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 Apr 13 22:19:46.174897 kernel: pci 0000:00:04.0: reg 0x10: [io 0xc0e0-0xc0ff] Apr 13 22:19:46.174971 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xfebd3000-0xfebd3fff] Apr 13 22:19:46.176004 kernel: pci 0000:00:04.0: reg 0x20: [mem 0xfe008000-0xfe00bfff 64bit pref] Apr 13 22:19:46.176147 kernel: pci 0000:00:04.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] Apr 13 22:19:46.176254 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 Apr 13 22:19:46.176331 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO Apr 13 22:19:46.176416 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 Apr 13 22:19:46.177840 kernel: pci 0000:00:1f.2: reg 0x20: [io 0xc100-0xc11f] Apr 13 22:19:46.177921 kernel: pci 0000:00:1f.2: reg 0x24: [mem 0xfebd4000-0xfebd4fff] Apr 13 22:19:46.178006 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 Apr 13 22:19:46.178088 kernel: pci 0000:00:1f.3: reg 0x20: [io 0x0700-0x073f] Apr 13 22:19:46.178101 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Apr 13 22:19:46.178111 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Apr 13 22:19:46.178141 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Apr 13 22:19:46.178151 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Apr 13 22:19:46.178166 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 Apr 13 22:19:46.178176 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 Apr 13 22:19:46.178185 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 Apr 13 22:19:46.178195 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 Apr 13 22:19:46.178204 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 Apr 13 22:19:46.178214 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 Apr 13 22:19:46.178223 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 Apr 13 22:19:46.178233 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 Apr 13 22:19:46.178243 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 Apr 13 22:19:46.178259 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 Apr 13 22:19:46.178268 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 Apr 13 22:19:46.178277 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 Apr 13 22:19:46.178285 kernel: iommu: Default domain type: Translated Apr 13 22:19:46.178294 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Apr 13 22:19:46.178302 kernel: PCI: Using ACPI for IRQ routing Apr 13 22:19:46.178312 kernel: PCI: pci_cache_line_size set to 64 bytes Apr 13 22:19:46.178322 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Apr 13 22:19:46.178333 kernel: e820: reserve RAM buffer [mem 0x9cfdc000-0x9fffffff] Apr 13 22:19:46.178739 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device Apr 13 22:19:46.178828 kernel: pci 0000:00:01.0: vgaarb: bridge control possible Apr 13 22:19:46.178902 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Apr 13 22:19:46.178913 kernel: vgaarb: loaded Apr 13 22:19:46.178921 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Apr 13 22:19:46.178930 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Apr 13 22:19:46.178939 kernel: clocksource: Switched to clocksource kvm-clock Apr 13 22:19:46.178948 kernel: VFS: Disk quotas dquot_6.6.0 Apr 13 22:19:46.178965 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Apr 13 22:19:46.178974 kernel: pnp: PnP ACPI init Apr 13 22:19:46.179059 kernel: system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved Apr 13 22:19:46.179072 kernel: pnp: PnP ACPI: found 6 devices Apr 13 22:19:46.179080 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Apr 13 22:19:46.179089 kernel: NET: Registered PF_INET protocol family Apr 13 22:19:46.179097 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Apr 13 22:19:46.179106 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Apr 13 22:19:46.179138 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Apr 13 22:19:46.179149 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Apr 13 22:19:46.179158 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Apr 13 22:19:46.179168 kernel: TCP: Hash tables configured (established 32768 bind 32768) Apr 13 22:19:46.179177 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Apr 13 22:19:46.179186 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Apr 13 22:19:46.179195 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Apr 13 22:19:46.179204 kernel: NET: Registered PF_XDP protocol family Apr 13 22:19:46.179286 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Apr 13 22:19:46.179359 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Apr 13 22:19:46.180002 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Apr 13 22:19:46.180089 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xafffffff window] Apr 13 22:19:46.180203 kernel: pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] Apr 13 22:19:46.180281 kernel: pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] Apr 13 22:19:46.180293 kernel: PCI: CLS 0 bytes, default 64 Apr 13 22:19:46.180302 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Apr 13 22:19:46.180311 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x284409db922, max_idle_ns: 440795228871 ns Apr 13 22:19:46.180325 kernel: Initialise system trusted keyrings Apr 13 22:19:46.180334 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Apr 13 22:19:46.180344 kernel: Key type asymmetric registered Apr 13 22:19:46.180353 kernel: Asymmetric key parser 'x509' registered Apr 13 22:19:46.180362 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Apr 13 22:19:46.180371 kernel: io scheduler mq-deadline registered Apr 13 22:19:46.180380 kernel: io scheduler kyber registered Apr 13 22:19:46.180393 kernel: io scheduler bfq registered Apr 13 22:19:46.180402 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Apr 13 22:19:46.180416 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 Apr 13 22:19:46.180739 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 Apr 13 22:19:46.180755 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 Apr 13 22:19:46.180765 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Apr 13 22:19:46.180774 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Apr 13 22:19:46.180784 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Apr 13 22:19:46.180794 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Apr 13 22:19:46.180804 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Apr 13 22:19:46.180912 kernel: rtc_cmos 00:04: RTC can wake from S4 Apr 13 22:19:46.180930 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Apr 13 22:19:46.181003 kernel: rtc_cmos 00:04: registered as rtc0 Apr 13 22:19:46.181073 kernel: rtc_cmos 00:04: setting system clock to 2026-04-13T22:19:44 UTC (1776118784) Apr 13 22:19:46.181170 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs Apr 13 22:19:46.181183 kernel: intel_pstate: CPU model not supported Apr 13 22:19:46.181193 kernel: NET: Registered PF_INET6 protocol family Apr 13 22:19:46.181202 kernel: Segment Routing with IPv6 Apr 13 22:19:46.181211 kernel: In-situ OAM (IOAM) with IPv6 Apr 13 22:19:46.181224 kernel: NET: Registered PF_PACKET protocol family Apr 13 22:19:46.181233 kernel: Key type dns_resolver registered Apr 13 22:19:46.181255 kernel: IPI shorthand broadcast: enabled Apr 13 22:19:46.181264 kernel: sched_clock: Marking stable (2304156165, 537171907)->(3315825824, -474497752) Apr 13 22:19:46.181272 kernel: registered taskstats version 1 Apr 13 22:19:46.181281 kernel: Loading compiled-in X.509 certificates Apr 13 22:19:46.181290 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.127-flatcar: 51221ce98a81ccf90ef3d16403b42695603c5d00' Apr 13 22:19:46.181299 kernel: Key type .fscrypt registered Apr 13 22:19:46.181307 kernel: Key type fscrypt-provisioning registered Apr 13 22:19:46.181318 kernel: ima: No TPM chip found, activating TPM-bypass! Apr 13 22:19:46.181327 kernel: ima: Allocated hash algorithm: sha1 Apr 13 22:19:46.181335 kernel: ima: No architecture policies found Apr 13 22:19:46.181343 kernel: clk: Disabling unused clocks Apr 13 22:19:46.181352 kernel: Freeing unused kernel image (initmem) memory: 42896K Apr 13 22:19:46.181361 kernel: Write protecting the kernel read-only data: 36864k Apr 13 22:19:46.181370 kernel: Freeing unused kernel image (rodata/data gap) memory: 1824K Apr 13 22:19:46.181379 kernel: Run /init as init process Apr 13 22:19:46.181387 kernel: with arguments: Apr 13 22:19:46.181396 kernel: /init Apr 13 22:19:46.181407 kernel: with environment: Apr 13 22:19:46.181416 kernel: HOME=/ Apr 13 22:19:46.181449 kernel: TERM=linux Apr 13 22:19:46.181462 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) Apr 13 22:19:46.181474 systemd[1]: Detected virtualization kvm. Apr 13 22:19:46.181484 systemd[1]: Detected architecture x86-64. Apr 13 22:19:46.181493 systemd[1]: Running in initrd. Apr 13 22:19:46.181505 systemd[1]: No hostname configured, using default hostname. Apr 13 22:19:46.181514 systemd[1]: Hostname set to . Apr 13 22:19:46.181523 systemd[1]: Initializing machine ID from VM UUID. Apr 13 22:19:46.181532 systemd[1]: Queued start job for default target initrd.target. Apr 13 22:19:46.181541 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 13 22:19:46.181550 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 13 22:19:46.181560 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Apr 13 22:19:46.181569 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Apr 13 22:19:46.181581 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Apr 13 22:19:46.181590 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Apr 13 22:19:46.181612 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Apr 13 22:19:46.181622 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Apr 13 22:19:46.181631 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 13 22:19:46.181642 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Apr 13 22:19:46.181652 systemd[1]: Reached target paths.target - Path Units. Apr 13 22:19:46.181661 systemd[1]: Reached target slices.target - Slice Units. Apr 13 22:19:46.181670 systemd[1]: Reached target swap.target - Swaps. Apr 13 22:19:46.181679 systemd[1]: Reached target timers.target - Timer Units. Apr 13 22:19:46.181689 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Apr 13 22:19:46.181698 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Apr 13 22:19:46.181708 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Apr 13 22:19:46.181720 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Apr 13 22:19:46.181730 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Apr 13 22:19:46.181740 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Apr 13 22:19:46.181750 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Apr 13 22:19:46.181760 systemd[1]: Reached target sockets.target - Socket Units. Apr 13 22:19:46.181769 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Apr 13 22:19:46.181779 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Apr 13 22:19:46.181790 systemd[1]: Finished network-cleanup.service - Network Cleanup. Apr 13 22:19:46.181801 systemd[1]: Starting systemd-fsck-usr.service... Apr 13 22:19:46.181814 systemd[1]: Starting systemd-journald.service - Journal Service... Apr 13 22:19:46.181824 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Apr 13 22:19:46.181859 systemd-journald[194]: Collecting audit messages is disabled. Apr 13 22:19:46.186399 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 13 22:19:46.186499 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Apr 13 22:19:46.186512 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Apr 13 22:19:46.186525 systemd[1]: Finished systemd-fsck-usr.service. Apr 13 22:19:46.186615 systemd-journald[194]: Journal started Apr 13 22:19:46.186645 systemd-journald[194]: Runtime Journal (/run/log/journal/2224627589184d62be2825a039c455f6) is 6.0M, max 48.4M, 42.3M free. Apr 13 22:19:46.209815 systemd-modules-load[195]: Inserted module 'overlay' Apr 13 22:19:46.587837 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Apr 13 22:19:46.587901 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Apr 13 22:19:46.587919 kernel: Bridge firewalling registered Apr 13 22:19:46.587933 systemd[1]: Started systemd-journald.service - Journal Service. Apr 13 22:19:46.368863 systemd-modules-load[195]: Inserted module 'br_netfilter' Apr 13 22:19:46.644556 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Apr 13 22:19:46.654144 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 13 22:19:46.664055 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Apr 13 22:19:46.764349 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 13 22:19:46.780407 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Apr 13 22:19:46.868733 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Apr 13 22:19:46.891372 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Apr 13 22:19:46.935619 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 13 22:19:46.942522 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Apr 13 22:19:46.961088 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 13 22:19:46.983069 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 13 22:19:47.027930 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Apr 13 22:19:47.066220 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Apr 13 22:19:47.090723 dracut-cmdline[230]: dracut-dracut-053 Apr 13 22:19:47.090723 dracut-cmdline[230]: 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=c1ba97db2f6278922cfc5bd0ca74b4bb573fca2c3aed19c121a34271e693e156 Apr 13 22:19:47.274542 systemd-resolved[232]: Positive Trust Anchors: Apr 13 22:19:47.274569 systemd-resolved[232]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Apr 13 22:19:47.274611 systemd-resolved[232]: 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 Apr 13 22:19:47.281271 systemd-resolved[232]: Defaulting to hostname 'linux'. Apr 13 22:19:47.362415 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Apr 13 22:19:47.375490 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Apr 13 22:19:47.681199 kernel: SCSI subsystem initialized Apr 13 22:19:47.720729 kernel: Loading iSCSI transport class v2.0-870. Apr 13 22:19:47.803541 kernel: iscsi: registered transport (tcp) Apr 13 22:19:47.902338 kernel: iscsi: registered transport (qla4xxx) Apr 13 22:19:47.902603 kernel: QLogic iSCSI HBA Driver Apr 13 22:19:48.210947 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Apr 13 22:19:48.239212 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Apr 13 22:19:48.383628 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Apr 13 22:19:48.383795 kernel: device-mapper: uevent: version 1.0.3 Apr 13 22:19:48.385351 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Apr 13 22:19:48.530654 kernel: raid6: avx512x4 gen() 29986 MB/s Apr 13 22:19:48.550407 kernel: raid6: avx512x2 gen() 25196 MB/s Apr 13 22:19:48.569262 kernel: raid6: avx512x1 gen() 19798 MB/s Apr 13 22:19:48.591371 kernel: raid6: avx2x4 gen() 17729 MB/s Apr 13 22:19:48.635321 kernel: raid6: avx2x2 gen() 7111 MB/s Apr 13 22:19:48.655367 kernel: raid6: avx2x1 gen() 12452 MB/s Apr 13 22:19:48.659665 kernel: raid6: using algorithm avx512x4 gen() 29986 MB/s Apr 13 22:19:48.679728 kernel: raid6: .... xor() 7484 MB/s, rmw enabled Apr 13 22:19:48.679984 kernel: raid6: using avx512x2 recovery algorithm Apr 13 22:19:48.732374 kernel: xor: automatically using best checksumming function avx Apr 13 22:19:49.161086 kernel: Btrfs loaded, zoned=no, fsverity=no Apr 13 22:19:49.250988 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Apr 13 22:19:49.279173 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 13 22:19:49.324782 systemd-udevd[415]: Using default interface naming scheme 'v255'. Apr 13 22:19:49.339107 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 13 22:19:49.440976 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Apr 13 22:19:49.522208 dracut-pre-trigger[421]: rd.md=0: removing MD RAID activation Apr 13 22:19:49.615731 kernel: hrtimer: interrupt took 8148726 ns Apr 13 22:19:49.719352 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Apr 13 22:19:49.744062 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Apr 13 22:19:49.827985 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Apr 13 22:19:49.866695 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Apr 13 22:19:49.960265 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Apr 13 22:19:49.986619 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Apr 13 22:19:50.002790 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 13 22:19:50.035465 systemd[1]: Reached target remote-fs.target - Remote File Systems. Apr 13 22:19:50.121023 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Apr 13 22:19:50.174027 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Apr 13 22:19:50.175015 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 13 22:19:50.245059 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 13 22:19:50.263115 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Apr 13 22:19:50.263382 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Apr 13 22:19:50.287744 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Apr 13 22:19:50.314744 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 13 22:19:50.320216 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Apr 13 22:19:50.356594 kernel: cryptd: max_cpu_qlen set to 1000 Apr 13 22:19:50.569462 kernel: libata version 3.00 loaded. Apr 13 22:19:50.695113 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues Apr 13 22:19:50.750026 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Apr 13 22:19:50.770239 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Apr 13 22:19:50.821635 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 13 22:19:50.890180 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 13 22:19:50.972775 kernel: BTRFS: device fsid de1edd48-4571-4695-92f0-7af6e33c4e3d devid 1 transid 31 /dev/vda3 scanned by (udev-worker) (478) Apr 13 22:19:50.991753 kernel: BTRFS: device label OEM devid 1 transid 9 /dev/vda6 scanned by (udev-worker) (467) Apr 13 22:19:50.998685 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Apr 13 22:19:51.131981 kernel: ahci 0000:00:1f.2: version 3.0 Apr 13 22:19:51.132238 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 Apr 13 22:19:51.132258 kernel: AVX2 version of gcm_enc/dec engaged. Apr 13 22:19:51.132271 kernel: ahci 0000:00:1f.2: AHCI 0001.0000 32 slots 6 ports 1.5 Gbps 0x3f impl SATA mode Apr 13 22:19:51.132408 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only Apr 13 22:19:51.132580 kernel: scsi host0: ahci Apr 13 22:19:51.132712 kernel: scsi host1: ahci Apr 13 22:19:51.132913 kernel: scsi host2: ahci Apr 13 22:19:51.133025 kernel: scsi host3: ahci Apr 13 22:19:51.133134 kernel: scsi host4: ahci Apr 13 22:19:51.055503 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 13 22:19:51.118047 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Apr 13 22:19:51.148992 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Apr 13 22:19:51.206372 kernel: AES CTR mode by8 optimization enabled Apr 13 22:19:51.206414 kernel: scsi host5: ahci Apr 13 22:19:51.206675 kernel: ata1: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4100 irq 34 Apr 13 22:19:51.206694 kernel: ata2: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4180 irq 34 Apr 13 22:19:51.206707 kernel: ata3: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4200 irq 34 Apr 13 22:19:51.206719 kernel: ata4: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4280 irq 34 Apr 13 22:19:51.206731 kernel: ata5: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4300 irq 34 Apr 13 22:19:51.206743 kernel: ata6: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4380 irq 34 Apr 13 22:19:51.182943 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Apr 13 22:19:51.230628 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Apr 13 22:19:51.263353 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Apr 13 22:19:51.290484 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Apr 13 22:19:51.321403 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Apr 13 22:19:51.520157 kernel: ata1: SATA link down (SStatus 0 SControl 300) Apr 13 22:19:51.520395 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Apr 13 22:19:51.520412 kernel: ata2: SATA link down (SStatus 0 SControl 300) Apr 13 22:19:51.520423 kernel: ata6: SATA link down (SStatus 0 SControl 300) Apr 13 22:19:51.520459 kernel: ata5: SATA link down (SStatus 0 SControl 300) Apr 13 22:19:51.523385 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 Apr 13 22:19:51.549456 kernel: ata3.00: applying bridge limits Apr 13 22:19:51.549658 kernel: ata4: SATA link down (SStatus 0 SControl 300) Apr 13 22:19:51.560494 kernel: ata3.00: configured for UDMA/100 Apr 13 22:19:51.572207 kernel: scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 Apr 13 22:19:51.808602 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray Apr 13 22:19:51.809627 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Apr 13 22:19:51.837320 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 Apr 13 22:19:52.367713 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Apr 13 22:19:52.373056 disk-uuid[565]: The operation has completed successfully. Apr 13 22:19:52.498132 systemd[1]: disk-uuid.service: Deactivated successfully. Apr 13 22:19:52.500676 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Apr 13 22:19:52.588752 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Apr 13 22:19:52.644678 sh[596]: Success Apr 13 22:19:52.746725 kernel: device-mapper: verity: sha256 using implementation "sha256-ni" Apr 13 22:19:52.964840 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Apr 13 22:19:53.021280 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Apr 13 22:19:53.068655 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Apr 13 22:19:53.157333 kernel: BTRFS info (device dm-0): first mount of filesystem de1edd48-4571-4695-92f0-7af6e33c4e3d Apr 13 22:19:53.157773 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Apr 13 22:19:53.157807 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Apr 13 22:19:53.161845 kernel: BTRFS info (device dm-0): disabling log replay at mount time Apr 13 22:19:53.164896 kernel: BTRFS info (device dm-0): using free space tree Apr 13 22:19:53.200053 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Apr 13 22:19:53.208999 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Apr 13 22:19:53.236398 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Apr 13 22:19:53.250873 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Apr 13 22:19:53.288787 kernel: BTRFS info (device vda6): first mount of filesystem 7dd1319a-da93-42af-ac3b-f04d4587a8af Apr 13 22:19:53.289038 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Apr 13 22:19:53.292619 kernel: BTRFS info (device vda6): using free space tree Apr 13 22:19:53.322065 kernel: BTRFS info (device vda6): auto enabling async discard Apr 13 22:19:53.372518 systemd[1]: mnt-oem.mount: Deactivated successfully. Apr 13 22:19:53.383316 kernel: BTRFS info (device vda6): last unmount of filesystem 7dd1319a-da93-42af-ac3b-f04d4587a8af Apr 13 22:19:53.477645 systemd[1]: Finished ignition-setup.service - Ignition (setup). Apr 13 22:19:53.524930 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Apr 13 22:19:53.687619 ignition[686]: Ignition 2.19.0 Apr 13 22:19:53.688519 ignition[686]: Stage: fetch-offline Apr 13 22:19:53.688663 ignition[686]: no configs at "/usr/lib/ignition/base.d" Apr 13 22:19:53.688674 ignition[686]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Apr 13 22:19:53.689010 ignition[686]: parsed url from cmdline: "" Apr 13 22:19:53.689015 ignition[686]: no config URL provided Apr 13 22:19:53.689022 ignition[686]: reading system config file "/usr/lib/ignition/user.ign" Apr 13 22:19:53.689031 ignition[686]: no config at "/usr/lib/ignition/user.ign" Apr 13 22:19:53.689128 ignition[686]: op(1): [started] loading QEMU firmware config module Apr 13 22:19:53.689136 ignition[686]: op(1): executing: "modprobe" "qemu_fw_cfg" Apr 13 22:19:53.806779 ignition[686]: op(1): [finished] loading QEMU firmware config module Apr 13 22:19:53.807283 ignition[686]: parsing config with SHA512: 7a392c0d15c3aaed64bc0ec376911e22e7c509fb699ee0a3ab551d208b7b1df46acf78abd5a3146fc52e37f9ca554fc2d9de0dc0bbad3701fb6517b0da9f455e Apr 13 22:19:53.818423 unknown[686]: fetched base config from "system" Apr 13 22:19:53.818904 unknown[686]: fetched user config from "qemu" Apr 13 22:19:53.819121 ignition[686]: fetch-offline: fetch-offline passed Apr 13 22:19:53.823019 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Apr 13 22:19:53.819395 ignition[686]: Ignition finished successfully Apr 13 22:19:53.969271 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Apr 13 22:19:54.041279 systemd[1]: Starting systemd-networkd.service - Network Configuration... Apr 13 22:19:54.090962 systemd-networkd[786]: lo: Link UP Apr 13 22:19:54.091270 systemd-networkd[786]: lo: Gained carrier Apr 13 22:19:54.096531 systemd-networkd[786]: Enumeration completed Apr 13 22:19:54.097067 systemd-networkd[786]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 13 22:19:54.097070 systemd-networkd[786]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Apr 13 22:19:54.099799 systemd[1]: Started systemd-networkd.service - Network Configuration. Apr 13 22:19:54.103727 systemd[1]: Reached target network.target - Network. Apr 13 22:19:54.110866 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Apr 13 22:19:54.123730 systemd-networkd[786]: eth0: Link UP Apr 13 22:19:54.123736 systemd-networkd[786]: eth0: Gained carrier Apr 13 22:19:54.123751 systemd-networkd[786]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 13 22:19:54.142829 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Apr 13 22:19:54.265375 systemd-networkd[786]: eth0: DHCPv4 address 10.0.0.99/16, gateway 10.0.0.1 acquired from 10.0.0.1 Apr 13 22:19:54.335455 ignition[788]: Ignition 2.19.0 Apr 13 22:19:54.335465 ignition[788]: Stage: kargs Apr 13 22:19:54.335687 ignition[788]: no configs at "/usr/lib/ignition/base.d" Apr 13 22:19:54.350671 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Apr 13 22:19:54.335696 ignition[788]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Apr 13 22:19:54.336491 ignition[788]: kargs: kargs passed Apr 13 22:19:54.336544 ignition[788]: Ignition finished successfully Apr 13 22:19:54.408960 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Apr 13 22:19:54.480879 ignition[798]: Ignition 2.19.0 Apr 13 22:19:54.480896 ignition[798]: Stage: disks Apr 13 22:19:54.481115 ignition[798]: no configs at "/usr/lib/ignition/base.d" Apr 13 22:19:54.481125 ignition[798]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Apr 13 22:19:54.558292 systemd[1]: Finished ignition-disks.service - Ignition (disks). Apr 13 22:19:54.489217 ignition[798]: disks: disks passed Apr 13 22:19:54.565083 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Apr 13 22:19:54.491251 ignition[798]: Ignition finished successfully Apr 13 22:19:54.573293 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Apr 13 22:19:54.581044 systemd[1]: Reached target local-fs.target - Local File Systems. Apr 13 22:19:54.595110 systemd[1]: Reached target sysinit.target - System Initialization. Apr 13 22:19:54.610800 systemd[1]: Reached target basic.target - Basic System. Apr 13 22:19:54.648881 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Apr 13 22:19:54.675665 systemd-fsck[808]: ROOT: clean, 14/553520 files, 52654/553472 blocks Apr 13 22:19:54.699282 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Apr 13 22:19:54.772149 systemd[1]: Mounting sysroot.mount - /sysroot... Apr 13 22:19:55.230237 kernel: EXT4-fs (vda9): mounted filesystem e02793bf-3e0d-4c7e-b11a-92c664da7ce3 r/w with ordered data mode. Quota mode: none. Apr 13 22:19:55.236148 systemd[1]: Mounted sysroot.mount - /sysroot. Apr 13 22:19:55.247016 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Apr 13 22:19:55.270800 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Apr 13 22:19:55.300039 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Apr 13 22:19:55.317627 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Apr 13 22:19:55.317834 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Apr 13 22:19:55.317876 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Apr 13 22:19:55.320340 systemd-networkd[786]: eth0: Gained IPv6LL Apr 13 22:19:55.352737 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/vda6 scanned by mount (816) Apr 13 22:19:55.352991 kernel: BTRFS info (device vda6): first mount of filesystem 7dd1319a-da93-42af-ac3b-f04d4587a8af Apr 13 22:19:55.364227 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Apr 13 22:19:55.372138 kernel: BTRFS info (device vda6): using free space tree Apr 13 22:19:55.373817 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Apr 13 22:19:55.451405 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Apr 13 22:19:55.474839 kernel: BTRFS info (device vda6): auto enabling async discard Apr 13 22:19:55.487843 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Apr 13 22:19:55.654877 initrd-setup-root[840]: cut: /sysroot/etc/passwd: No such file or directory Apr 13 22:19:55.687006 initrd-setup-root[847]: cut: /sysroot/etc/group: No such file or directory Apr 13 22:19:55.755257 initrd-setup-root[854]: cut: /sysroot/etc/shadow: No such file or directory Apr 13 22:19:55.768727 initrd-setup-root[861]: cut: /sysroot/etc/gshadow: No such file or directory Apr 13 22:19:56.212139 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Apr 13 22:19:56.259798 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Apr 13 22:19:56.281697 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Apr 13 22:19:56.345694 systemd[1]: sysroot-oem.mount: Deactivated successfully. Apr 13 22:19:56.361555 kernel: BTRFS info (device vda6): last unmount of filesystem 7dd1319a-da93-42af-ac3b-f04d4587a8af Apr 13 22:19:56.496563 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Apr 13 22:19:56.514188 ignition[930]: INFO : Ignition 2.19.0 Apr 13 22:19:56.514188 ignition[930]: INFO : Stage: mount Apr 13 22:19:56.514188 ignition[930]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 13 22:19:56.514188 ignition[930]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Apr 13 22:19:56.528867 ignition[930]: INFO : mount: mount passed Apr 13 22:19:56.528867 ignition[930]: INFO : Ignition finished successfully Apr 13 22:19:56.529456 systemd[1]: Finished ignition-mount.service - Ignition (mount). Apr 13 22:19:56.574653 systemd[1]: Starting ignition-files.service - Ignition (files)... Apr 13 22:19:56.681996 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Apr 13 22:19:56.709718 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 scanned by mount (943) Apr 13 22:19:56.723809 kernel: BTRFS info (device vda6): first mount of filesystem 7dd1319a-da93-42af-ac3b-f04d4587a8af Apr 13 22:19:56.724061 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Apr 13 22:19:56.724078 kernel: BTRFS info (device vda6): using free space tree Apr 13 22:19:56.785234 kernel: BTRFS info (device vda6): auto enabling async discard Apr 13 22:19:56.792712 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Apr 13 22:19:56.945746 ignition[960]: INFO : Ignition 2.19.0 Apr 13 22:19:56.945746 ignition[960]: INFO : Stage: files Apr 13 22:19:56.945746 ignition[960]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 13 22:19:56.945746 ignition[960]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Apr 13 22:19:56.974842 ignition[960]: DEBUG : files: compiled without relabeling support, skipping Apr 13 22:19:56.974842 ignition[960]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Apr 13 22:19:56.974842 ignition[960]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Apr 13 22:19:57.002312 ignition[960]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Apr 13 22:19:57.002312 ignition[960]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Apr 13 22:19:57.002312 ignition[960]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Apr 13 22:19:56.994817 unknown[960]: wrote ssh authorized keys file for user: core Apr 13 22:19:57.035820 ignition[960]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Apr 13 22:19:57.035820 ignition[960]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Apr 13 22:19:57.035820 ignition[960]: INFO : files: op(4): [started] processing unit "etcd-member.service" Apr 13 22:19:57.035820 ignition[960]: INFO : files: op(4): op(5): [started] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Apr 13 22:19:57.035820 ignition[960]: INFO : files: op(4): op(5): [finished] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Apr 13 22:19:57.035820 ignition[960]: INFO : files: op(4): [finished] processing unit "etcd-member.service" Apr 13 22:19:57.035820 ignition[960]: INFO : files: op(6): [started] processing unit "coreos-metadata.service" Apr 13 22:19:57.035820 ignition[960]: INFO : files: op(6): op(7): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Apr 13 22:19:57.035820 ignition[960]: INFO : files: op(6): op(7): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Apr 13 22:19:57.035820 ignition[960]: INFO : files: op(6): [finished] processing unit "coreos-metadata.service" Apr 13 22:19:57.035820 ignition[960]: INFO : files: op(8): [started] setting preset to disabled for "coreos-metadata.service" Apr 13 22:19:57.307029 ignition[960]: INFO : files: op(8): op(9): [started] removing enablement symlink(s) for "coreos-metadata.service" Apr 13 22:19:57.336206 ignition[960]: INFO : files: op(8): op(9): [finished] removing enablement symlink(s) for "coreos-metadata.service" Apr 13 22:19:57.345762 ignition[960]: INFO : files: op(8): [finished] setting preset to disabled for "coreos-metadata.service" Apr 13 22:19:57.345762 ignition[960]: INFO : files: op(a): [started] setting preset to enabled for "etcd-member.service" Apr 13 22:19:57.345762 ignition[960]: INFO : files: op(a): [finished] setting preset to enabled for "etcd-member.service" Apr 13 22:19:57.345762 ignition[960]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Apr 13 22:19:57.345762 ignition[960]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Apr 13 22:19:57.345762 ignition[960]: INFO : files: files passed Apr 13 22:19:57.345762 ignition[960]: INFO : Ignition finished successfully Apr 13 22:19:57.366526 systemd[1]: Finished ignition-files.service - Ignition (files). Apr 13 22:19:57.471961 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Apr 13 22:19:57.496841 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Apr 13 22:19:57.527361 systemd[1]: ignition-quench.service: Deactivated successfully. Apr 13 22:19:57.527536 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Apr 13 22:19:57.573257 initrd-setup-root-after-ignition[989]: grep: /sysroot/oem/oem-release: No such file or directory Apr 13 22:19:57.589752 initrd-setup-root-after-ignition[991]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Apr 13 22:19:57.589752 initrd-setup-root-after-ignition[991]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Apr 13 22:19:57.604905 initrd-setup-root-after-ignition[995]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Apr 13 22:19:57.628155 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Apr 13 22:19:57.631248 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Apr 13 22:19:57.671023 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Apr 13 22:19:57.828718 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Apr 13 22:19:57.828911 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Apr 13 22:19:57.845940 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Apr 13 22:19:57.866492 systemd[1]: Reached target initrd.target - Initrd Default Target. Apr 13 22:19:57.873727 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Apr 13 22:19:57.918512 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Apr 13 22:19:58.004761 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Apr 13 22:19:58.078837 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Apr 13 22:19:58.125989 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Apr 13 22:19:58.129111 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 13 22:19:58.138587 systemd[1]: Stopped target timers.target - Timer Units. Apr 13 22:19:58.145295 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Apr 13 22:19:58.145905 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Apr 13 22:19:58.185480 systemd[1]: Stopped target initrd.target - Initrd Default Target. Apr 13 22:19:58.192155 systemd[1]: Stopped target basic.target - Basic System. Apr 13 22:19:58.214836 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Apr 13 22:19:58.249650 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Apr 13 22:19:58.277012 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Apr 13 22:19:58.317364 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Apr 13 22:19:58.359333 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Apr 13 22:19:58.364796 systemd[1]: Stopped target sysinit.target - System Initialization. Apr 13 22:19:58.386642 systemd[1]: Stopped target local-fs.target - Local File Systems. Apr 13 22:19:58.410951 systemd[1]: Stopped target swap.target - Swaps. Apr 13 22:19:58.447229 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Apr 13 22:19:58.447393 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Apr 13 22:19:58.472104 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Apr 13 22:19:58.482670 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 13 22:19:58.483164 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Apr 13 22:19:58.484337 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 13 22:19:58.500634 systemd[1]: dracut-initqueue.service: Deactivated successfully. Apr 13 22:19:58.500809 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Apr 13 22:19:58.550488 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Apr 13 22:19:58.550706 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Apr 13 22:19:58.567264 systemd[1]: Stopped target paths.target - Path Units. Apr 13 22:19:58.572196 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Apr 13 22:19:58.619067 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 13 22:19:58.672209 systemd[1]: Stopped target slices.target - Slice Units. Apr 13 22:19:58.680642 systemd[1]: Stopped target sockets.target - Socket Units. Apr 13 22:19:58.688268 systemd[1]: iscsid.socket: Deactivated successfully. Apr 13 22:19:58.688384 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Apr 13 22:19:58.698287 systemd[1]: iscsiuio.socket: Deactivated successfully. Apr 13 22:19:58.698400 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Apr 13 22:19:58.718554 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Apr 13 22:19:58.725036 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Apr 13 22:19:58.736869 systemd[1]: ignition-files.service: Deactivated successfully. Apr 13 22:19:58.741919 systemd[1]: Stopped ignition-files.service - Ignition (files). Apr 13 22:19:58.786026 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Apr 13 22:19:58.789249 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Apr 13 22:19:58.789482 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Apr 13 22:19:58.885044 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Apr 13 22:19:58.945102 ignition[1015]: INFO : Ignition 2.19.0 Apr 13 22:19:58.945102 ignition[1015]: INFO : Stage: umount Apr 13 22:19:58.945102 ignition[1015]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 13 22:19:58.945102 ignition[1015]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Apr 13 22:19:58.888677 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Apr 13 22:19:59.057293 ignition[1015]: INFO : umount: umount passed Apr 13 22:19:59.057293 ignition[1015]: INFO : Ignition finished successfully Apr 13 22:19:58.889070 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Apr 13 22:19:58.889491 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Apr 13 22:19:58.889790 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Apr 13 22:19:58.938081 systemd[1]: initrd-cleanup.service: Deactivated successfully. Apr 13 22:19:58.938223 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Apr 13 22:19:58.968418 systemd[1]: ignition-mount.service: Deactivated successfully. Apr 13 22:19:58.969123 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Apr 13 22:19:58.970048 systemd[1]: Stopped target network.target - Network. Apr 13 22:19:58.970106 systemd[1]: ignition-disks.service: Deactivated successfully. Apr 13 22:19:58.970169 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Apr 13 22:19:58.995042 systemd[1]: ignition-kargs.service: Deactivated successfully. Apr 13 22:19:58.995234 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Apr 13 22:19:58.995316 systemd[1]: ignition-setup.service: Deactivated successfully. Apr 13 22:19:58.995349 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Apr 13 22:19:58.995388 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Apr 13 22:19:58.995416 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Apr 13 22:19:58.995633 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Apr 13 22:19:58.995723 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Apr 13 22:19:59.011074 systemd[1]: sysroot-boot.mount: Deactivated successfully. Apr 13 22:19:59.038235 systemd[1]: systemd-resolved.service: Deactivated successfully. Apr 13 22:19:59.038929 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Apr 13 22:19:59.067690 systemd-networkd[786]: eth0: DHCPv6 lease lost Apr 13 22:19:59.233786 systemd[1]: systemd-networkd.service: Deactivated successfully. Apr 13 22:19:59.233912 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Apr 13 22:19:59.242285 systemd[1]: sysroot-boot.service: Deactivated successfully. Apr 13 22:19:59.242833 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Apr 13 22:19:59.258904 systemd[1]: systemd-networkd.socket: Deactivated successfully. Apr 13 22:19:59.258981 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Apr 13 22:19:59.311242 systemd[1]: initrd-setup-root.service: Deactivated successfully. Apr 13 22:19:59.320014 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Apr 13 22:19:59.344368 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Apr 13 22:19:59.350018 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Apr 13 22:19:59.350117 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Apr 13 22:19:59.357947 systemd[1]: systemd-sysctl.service: Deactivated successfully. Apr 13 22:19:59.358038 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Apr 13 22:19:59.365986 systemd[1]: systemd-modules-load.service: Deactivated successfully. Apr 13 22:19:59.366064 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Apr 13 22:19:59.379383 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Apr 13 22:19:59.379525 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 13 22:19:59.433950 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 13 22:19:59.490355 systemd[1]: systemd-udevd.service: Deactivated successfully. Apr 13 22:19:59.491125 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 13 22:19:59.545688 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Apr 13 22:19:59.545774 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Apr 13 22:19:59.552870 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Apr 13 22:19:59.553058 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Apr 13 22:19:59.553115 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Apr 13 22:19:59.553163 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Apr 13 22:19:59.553769 systemd[1]: dracut-cmdline.service: Deactivated successfully. Apr 13 22:19:59.553830 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Apr 13 22:19:59.553929 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Apr 13 22:19:59.553969 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 13 22:19:59.574214 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Apr 13 22:19:59.703089 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Apr 13 22:19:59.711094 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 13 22:19:59.757033 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Apr 13 22:19:59.757642 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Apr 13 22:19:59.771885 systemd[1]: network-cleanup.service: Deactivated successfully. Apr 13 22:19:59.772026 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Apr 13 22:19:59.781371 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Apr 13 22:19:59.781557 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Apr 13 22:19:59.848723 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Apr 13 22:19:59.931854 systemd[1]: Starting initrd-switch-root.service - Switch Root... Apr 13 22:19:59.974497 systemd[1]: Switching root. Apr 13 22:20:00.036146 systemd-journald[194]: Journal stopped Apr 13 22:20:02.839683 systemd-journald[194]: Received SIGTERM from PID 1 (systemd). Apr 13 22:20:02.839795 kernel: SELinux: policy capability network_peer_controls=1 Apr 13 22:20:02.839817 kernel: SELinux: policy capability open_perms=1 Apr 13 22:20:02.839836 kernel: SELinux: policy capability extended_socket_class=1 Apr 13 22:20:02.839850 kernel: SELinux: policy capability always_check_network=0 Apr 13 22:20:02.839864 kernel: SELinux: policy capability cgroup_seclabel=1 Apr 13 22:20:02.839877 kernel: SELinux: policy capability nnp_nosuid_transition=1 Apr 13 22:20:02.839891 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Apr 13 22:20:02.839905 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Apr 13 22:20:02.839918 kernel: audit: type=1403 audit(1776118800.270:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Apr 13 22:20:02.839938 systemd[1]: Successfully loaded SELinux policy in 73.461ms. Apr 13 22:20:02.839966 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 25.439ms. Apr 13 22:20:02.839982 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) Apr 13 22:20:02.840000 systemd[1]: Detected virtualization kvm. Apr 13 22:20:02.840014 systemd[1]: Detected architecture x86-64. Apr 13 22:20:02.840029 systemd[1]: Detected first boot. Apr 13 22:20:02.840049 systemd[1]: Initializing machine ID from VM UUID. Apr 13 22:20:02.840064 zram_generator::config[1064]: No configuration found. Apr 13 22:20:02.840080 systemd[1]: Populated /etc with preset unit settings. Apr 13 22:20:02.840095 systemd[1]: initrd-switch-root.service: Deactivated successfully. Apr 13 22:20:02.840111 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Apr 13 22:20:02.840125 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Apr 13 22:20:02.840144 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Apr 13 22:20:02.840159 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Apr 13 22:20:02.840174 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Apr 13 22:20:02.840190 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Apr 13 22:20:02.840225 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Apr 13 22:20:02.840237 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Apr 13 22:20:02.840250 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Apr 13 22:20:02.840262 systemd[1]: Created slice user.slice - User and Session Slice. Apr 13 22:20:02.840275 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 13 22:20:02.840287 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 13 22:20:02.840298 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Apr 13 22:20:02.840309 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Apr 13 22:20:02.840325 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Apr 13 22:20:02.840336 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Apr 13 22:20:02.840347 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Apr 13 22:20:02.840359 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 13 22:20:02.840371 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Apr 13 22:20:02.840382 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Apr 13 22:20:02.840393 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Apr 13 22:20:02.840406 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Apr 13 22:20:02.840417 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 13 22:20:02.840455 systemd[1]: Reached target remote-fs.target - Remote File Systems. Apr 13 22:20:02.840467 systemd[1]: Reached target slices.target - Slice Units. Apr 13 22:20:02.840479 systemd[1]: Reached target swap.target - Swaps. Apr 13 22:20:02.840491 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Apr 13 22:20:02.840502 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Apr 13 22:20:02.840513 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Apr 13 22:20:02.840525 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Apr 13 22:20:02.840536 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Apr 13 22:20:02.840550 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Apr 13 22:20:02.840561 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Apr 13 22:20:02.840575 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Apr 13 22:20:02.840587 systemd[1]: Mounting media.mount - External Media Directory... Apr 13 22:20:02.840599 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 13 22:20:02.840611 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Apr 13 22:20:02.840622 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Apr 13 22:20:02.840634 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Apr 13 22:20:02.840648 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). Apr 13 22:20:02.840661 systemd[1]: Reached target machines.target - Containers. Apr 13 22:20:02.840674 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Apr 13 22:20:02.840686 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 13 22:20:02.840697 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Apr 13 22:20:02.840708 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Apr 13 22:20:02.840720 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Apr 13 22:20:02.840731 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Apr 13 22:20:02.840743 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Apr 13 22:20:02.840756 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Apr 13 22:20:02.840768 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Apr 13 22:20:02.840779 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Apr 13 22:20:02.840790 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Apr 13 22:20:02.840801 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Apr 13 22:20:02.840812 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Apr 13 22:20:02.840824 systemd[1]: Stopped systemd-fsck-usr.service. Apr 13 22:20:02.840835 systemd[1]: Starting systemd-journald.service - Journal Service... Apr 13 22:20:02.840851 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Apr 13 22:20:02.840863 kernel: fuse: init (API version 7.39) Apr 13 22:20:02.840876 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Apr 13 22:20:02.840887 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Apr 13 22:20:02.840898 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Apr 13 22:20:02.840909 systemd[1]: verity-setup.service: Deactivated successfully. Apr 13 22:20:02.840920 systemd[1]: Stopped verity-setup.service. Apr 13 22:20:02.840932 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 13 22:20:02.840986 systemd-journald[1145]: Collecting audit messages is disabled. Apr 13 22:20:02.841014 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Apr 13 22:20:02.841028 systemd-journald[1145]: Journal started Apr 13 22:20:02.841053 systemd-journald[1145]: Runtime Journal (/run/log/journal/2224627589184d62be2825a039c455f6) is 6.0M, max 48.4M, 42.3M free. Apr 13 22:20:01.774401 systemd[1]: Queued start job for default target multi-user.target. Apr 13 22:20:01.815341 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Apr 13 22:20:01.817122 systemd[1]: systemd-journald.service: Deactivated successfully. Apr 13 22:20:01.818361 systemd[1]: systemd-journald.service: Consumed 1.420s CPU time. Apr 13 22:20:02.863613 systemd[1]: Started systemd-journald.service - Journal Service. Apr 13 22:20:02.877499 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Apr 13 22:20:02.884524 kernel: loop: module loaded Apr 13 22:20:02.884709 systemd[1]: Mounted media.mount - External Media Directory. Apr 13 22:20:02.887141 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Apr 13 22:20:02.889625 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Apr 13 22:20:02.894634 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Apr 13 22:20:02.898751 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Apr 13 22:20:02.901483 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Apr 13 22:20:02.903994 systemd[1]: modprobe@configfs.service: Deactivated successfully. Apr 13 22:20:02.904145 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Apr 13 22:20:02.909996 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 13 22:20:02.910147 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Apr 13 22:20:02.914548 kernel: ACPI: bus type drm_connector registered Apr 13 22:20:02.914127 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Apr 13 22:20:02.914708 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Apr 13 22:20:02.917358 systemd[1]: modprobe@fuse.service: Deactivated successfully. Apr 13 22:20:02.917535 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Apr 13 22:20:02.921241 systemd[1]: modprobe@drm.service: Deactivated successfully. Apr 13 22:20:02.922610 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Apr 13 22:20:02.924995 systemd[1]: modprobe@loop.service: Deactivated successfully. Apr 13 22:20:02.925116 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Apr 13 22:20:02.928946 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Apr 13 22:20:02.932780 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Apr 13 22:20:02.935864 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Apr 13 22:20:02.976414 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Apr 13 22:20:02.983611 systemd[1]: Reached target network-pre.target - Preparation for Network. Apr 13 22:20:03.003007 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Apr 13 22:20:03.013480 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Apr 13 22:20:03.018321 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Apr 13 22:20:03.018619 systemd[1]: Reached target local-fs.target - Local File Systems. Apr 13 22:20:03.024592 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Apr 13 22:20:03.056878 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Apr 13 22:20:03.072664 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Apr 13 22:20:03.075687 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 13 22:20:03.083259 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Apr 13 22:20:03.088872 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Apr 13 22:20:03.092858 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Apr 13 22:20:03.136906 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Apr 13 22:20:03.147666 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Apr 13 22:20:03.153560 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Apr 13 22:20:03.182774 systemd-journald[1145]: Time spent on flushing to /var/log/journal/2224627589184d62be2825a039c455f6 is 133.301ms for 925 entries. Apr 13 22:20:03.182774 systemd-journald[1145]: System Journal (/var/log/journal/2224627589184d62be2825a039c455f6) is 8.0M, max 195.6M, 187.6M free. Apr 13 22:20:03.414186 systemd-journald[1145]: Received client request to flush runtime journal. Apr 13 22:20:03.414455 kernel: loop0: detected capacity change from 0 to 142488 Apr 13 22:20:03.201646 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Apr 13 22:20:03.249914 systemd[1]: Starting systemd-sysusers.service - Create System Users... Apr 13 22:20:03.261313 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Apr 13 22:20:03.269750 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Apr 13 22:20:03.277013 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Apr 13 22:20:03.314617 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Apr 13 22:20:03.377060 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Apr 13 22:20:03.381158 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Apr 13 22:20:03.409966 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Apr 13 22:20:03.417960 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Apr 13 22:20:03.424133 udevadm[1181]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Apr 13 22:20:03.443573 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Apr 13 22:20:03.475030 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Apr 13 22:20:03.477060 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Apr 13 22:20:03.481422 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Apr 13 22:20:03.491170 systemd[1]: Finished systemd-sysusers.service - Create System Users. Apr 13 22:20:03.499642 kernel: loop1: detected capacity change from 0 to 140768 Apr 13 22:20:03.517415 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Apr 13 22:20:03.590030 kernel: loop2: detected capacity change from 0 to 142488 Apr 13 22:20:03.593614 systemd-tmpfiles[1197]: ACLs are not supported, ignoring. Apr 13 22:20:03.593641 systemd-tmpfiles[1197]: ACLs are not supported, ignoring. Apr 13 22:20:03.651943 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 13 22:20:03.695636 kernel: loop3: detected capacity change from 0 to 140768 Apr 13 22:20:03.767460 (sd-merge)[1200]: Using extensions 'containerd-flatcar', 'docker-flatcar'. Apr 13 22:20:03.769368 (sd-merge)[1200]: Merged extensions into '/usr'. Apr 13 22:20:03.779083 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Apr 13 22:20:03.803176 systemd[1]: Starting ensure-sysext.service... Apr 13 22:20:03.818305 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Apr 13 22:20:03.830734 systemd[1]: Reloading requested from client PID 1203 ('systemctl') (unit ensure-sysext.service)... Apr 13 22:20:03.830753 systemd[1]: Reloading... Apr 13 22:20:03.948892 systemd-tmpfiles[1204]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Apr 13 22:20:03.949255 systemd-tmpfiles[1204]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Apr 13 22:20:03.952149 systemd-tmpfiles[1204]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Apr 13 22:20:03.952887 systemd-tmpfiles[1204]: ACLs are not supported, ignoring. Apr 13 22:20:03.952947 systemd-tmpfiles[1204]: ACLs are not supported, ignoring. Apr 13 22:20:03.976188 systemd-tmpfiles[1204]: Detected autofs mount point /boot during canonicalization of boot. Apr 13 22:20:03.976537 systemd-tmpfiles[1204]: Skipping /boot Apr 13 22:20:04.017903 zram_generator::config[1232]: No configuration found. Apr 13 22:20:04.036794 systemd-tmpfiles[1204]: Detected autofs mount point /boot during canonicalization of boot. Apr 13 22:20:04.039670 systemd-tmpfiles[1204]: Skipping /boot Apr 13 22:20:04.568109 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. Apr 13 22:20:04.644471 systemd[1]: Reloading finished in 807 ms. Apr 13 22:20:04.699639 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 13 22:20:04.740791 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Apr 13 22:20:04.763020 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Apr 13 22:20:04.875539 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Apr 13 22:20:04.880807 ldconfig[1174]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Apr 13 22:20:04.927705 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Apr 13 22:20:04.977821 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Apr 13 22:20:04.992010 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Apr 13 22:20:05.112174 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Apr 13 22:20:05.122540 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 13 22:20:05.122736 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 13 22:20:05.135104 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Apr 13 22:20:05.165577 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Apr 13 22:20:05.183794 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Apr 13 22:20:05.191745 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 13 22:20:05.191939 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 13 22:20:05.194985 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Apr 13 22:20:05.231848 augenrules[1295]: No rules Apr 13 22:20:05.209200 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Apr 13 22:20:05.216875 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 13 22:20:05.217050 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Apr 13 22:20:05.232060 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Apr 13 22:20:05.236045 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Apr 13 22:20:05.253828 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Apr 13 22:20:05.263276 systemd[1]: modprobe@loop.service: Deactivated successfully. Apr 13 22:20:05.263519 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Apr 13 22:20:05.334626 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Apr 13 22:20:05.334904 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Apr 13 22:20:05.335043 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). Apr 13 22:20:05.342362 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 13 22:20:05.342625 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 13 22:20:05.350118 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Apr 13 22:20:05.369855 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Apr 13 22:20:05.394107 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Apr 13 22:20:05.405015 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 13 22:20:05.408399 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). Apr 13 22:20:05.408894 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 13 22:20:05.415745 systemd[1]: Started systemd-userdbd.service - User Database Manager. Apr 13 22:20:05.423183 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Apr 13 22:20:05.433255 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 13 22:20:05.433774 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Apr 13 22:20:05.449937 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Apr 13 22:20:05.450114 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Apr 13 22:20:05.456309 systemd[1]: modprobe@loop.service: Deactivated successfully. Apr 13 22:20:05.456514 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Apr 13 22:20:05.509937 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 13 22:20:05.510180 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 13 22:20:05.539972 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Apr 13 22:20:05.565016 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Apr 13 22:20:05.589343 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Apr 13 22:20:05.647175 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Apr 13 22:20:05.653021 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 13 22:20:05.658799 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). Apr 13 22:20:05.658987 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 13 22:20:05.661829 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 13 22:20:05.661984 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Apr 13 22:20:05.693878 systemd[1]: modprobe@drm.service: Deactivated successfully. Apr 13 22:20:05.694915 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Apr 13 22:20:05.717769 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Apr 13 22:20:05.717934 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Apr 13 22:20:05.723652 systemd[1]: modprobe@loop.service: Deactivated successfully. Apr 13 22:20:05.723823 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Apr 13 22:20:05.739150 systemd[1]: Finished ensure-sysext.service. Apr 13 22:20:05.773321 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Apr 13 22:20:05.773403 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Apr 13 22:20:05.817390 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Apr 13 22:20:05.890606 systemd-resolved[1283]: Positive Trust Anchors: Apr 13 22:20:05.890932 systemd-resolved[1283]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Apr 13 22:20:05.890967 systemd-resolved[1283]: 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 Apr 13 22:20:05.938746 systemd-resolved[1283]: Defaulting to hostname 'linux'. Apr 13 22:20:05.944006 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Apr 13 22:20:05.951073 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Apr 13 22:20:06.129613 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Apr 13 22:20:06.139857 systemd[1]: Reached target time-set.target - System Time Set. Apr 13 22:20:06.157638 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Apr 13 22:20:06.195937 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 13 22:20:06.214552 systemd[1]: Starting systemd-update-done.service - Update is Completed... Apr 13 22:20:06.267074 systemd-udevd[1330]: Using default interface naming scheme 'v255'. Apr 13 22:20:06.268500 systemd[1]: Finished systemd-update-done.service - Update is Completed. Apr 13 22:20:06.429348 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 13 22:20:06.467736 systemd[1]: Starting systemd-networkd.service - Network Configuration... Apr 13 22:20:06.595198 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Apr 13 22:20:06.672973 systemd-networkd[1336]: lo: Link UP Apr 13 22:20:06.672998 systemd-networkd[1336]: lo: Gained carrier Apr 13 22:20:06.676988 systemd-networkd[1336]: Enumeration completed Apr 13 22:20:06.677180 systemd[1]: Started systemd-networkd.service - Network Configuration. Apr 13 22:20:06.679643 systemd-networkd[1336]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 13 22:20:06.679665 systemd-networkd[1336]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Apr 13 22:20:06.684357 systemd[1]: Reached target network.target - Network. Apr 13 22:20:06.686274 systemd-networkd[1336]: eth0: Link UP Apr 13 22:20:06.686294 systemd-networkd[1336]: eth0: Gained carrier Apr 13 22:20:06.686317 systemd-networkd[1336]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 13 22:20:06.695744 systemd-networkd[1336]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 13 22:20:06.726339 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Apr 13 22:20:06.739712 systemd-networkd[1336]: eth0: DHCPv4 address 10.0.0.99/16, gateway 10.0.0.1 acquired from 10.0.0.1 Apr 13 22:20:06.740636 systemd-timesyncd[1327]: Network configuration changed, trying to establish connection. Apr 13 22:20:06.750675 systemd-timesyncd[1327]: Contacted time server 10.0.0.1:123 (10.0.0.1). Apr 13 22:20:06.750754 systemd-timesyncd[1327]: Initial clock synchronization to Mon 2026-04-13 22:20:07.055158 UTC. Apr 13 22:20:06.752548 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 31 scanned by (udev-worker) (1347) Apr 13 22:20:06.888104 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 Apr 13 22:20:07.018259 kernel: ACPI: button: Power Button [PWRF] Apr 13 22:20:07.041827 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input4 Apr 13 22:20:07.099273 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 13 22:20:07.134599 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Apr 13 22:20:07.148100 kernel: mousedev: PS/2 mouse device common for all mice Apr 13 22:20:07.219737 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Apr 13 22:20:07.250218 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Apr 13 22:20:07.258268 kernel: i2c i2c-0: 1/1 memory slots populated (from DMI) Apr 13 22:20:07.259436 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Apr 13 22:20:07.272018 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Apr 13 22:20:07.785178 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 13 22:20:07.863084 systemd-networkd[1336]: eth0: Gained IPv6LL Apr 13 22:20:07.904006 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Apr 13 22:20:07.913707 systemd[1]: Reached target network-online.target - Network is Online. Apr 13 22:20:09.038622 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Apr 13 22:20:09.086439 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Apr 13 22:20:09.228719 lvm[1376]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Apr 13 22:20:09.308228 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Apr 13 22:20:09.314654 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Apr 13 22:20:09.322918 systemd[1]: Reached target sysinit.target - System Initialization. Apr 13 22:20:09.335005 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Apr 13 22:20:09.341153 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Apr 13 22:20:09.346309 systemd[1]: Started logrotate.timer - Daily rotation of log files. Apr 13 22:20:09.361426 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Apr 13 22:20:09.381614 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Apr 13 22:20:09.389641 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Apr 13 22:20:09.389878 systemd[1]: Reached target paths.target - Path Units. Apr 13 22:20:09.416628 systemd[1]: Reached target timers.target - Timer Units. Apr 13 22:20:09.426005 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Apr 13 22:20:09.439994 systemd[1]: Starting docker.socket - Docker Socket for the API... Apr 13 22:20:09.462884 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Apr 13 22:20:09.559339 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Apr 13 22:20:09.574222 systemd[1]: Listening on docker.socket - Docker Socket for the API. Apr 13 22:20:09.590952 lvm[1380]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Apr 13 22:20:09.588935 systemd[1]: Reached target sockets.target - Socket Units. Apr 13 22:20:09.595600 systemd[1]: Reached target basic.target - Basic System. Apr 13 22:20:09.601072 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Apr 13 22:20:09.601113 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Apr 13 22:20:09.608101 systemd[1]: Starting containerd.service - containerd container runtime... Apr 13 22:20:09.621288 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Apr 13 22:20:09.645827 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Apr 13 22:20:09.682413 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Apr 13 22:20:09.720816 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Apr 13 22:20:09.721995 jq[1384]: false Apr 13 22:20:09.729559 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). Apr 13 22:20:09.746601 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Apr 13 22:20:09.771216 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Apr 13 22:20:09.798980 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Apr 13 22:20:09.861271 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Apr 13 22:20:09.884572 extend-filesystems[1385]: Found loop2 Apr 13 22:20:09.884572 extend-filesystems[1385]: Found loop3 Apr 13 22:20:09.884572 extend-filesystems[1385]: Found sr0 Apr 13 22:20:09.884572 extend-filesystems[1385]: Found vda Apr 13 22:20:09.884572 extend-filesystems[1385]: Found vda1 Apr 13 22:20:09.884572 extend-filesystems[1385]: Found vda2 Apr 13 22:20:09.884572 extend-filesystems[1385]: Found vda3 Apr 13 22:20:09.884572 extend-filesystems[1385]: Found usr Apr 13 22:20:09.884572 extend-filesystems[1385]: Found vda4 Apr 13 22:20:09.884572 extend-filesystems[1385]: Found vda6 Apr 13 22:20:09.884572 extend-filesystems[1385]: Found vda7 Apr 13 22:20:09.884572 extend-filesystems[1385]: Found vda9 Apr 13 22:20:09.884572 extend-filesystems[1385]: Checking size of /dev/vda9 Apr 13 22:20:09.885649 systemd[1]: Starting systemd-logind.service - User Login Management... Apr 13 22:20:09.929591 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Apr 13 22:20:09.930259 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Apr 13 22:20:09.968046 dbus-daemon[1383]: [system] SELinux support is enabled Apr 13 22:20:10.003645 extend-filesystems[1385]: Old size kept for /dev/vda9 Apr 13 22:20:09.995946 systemd[1]: Starting update-engine.service - Update Engine... Apr 13 22:20:10.033860 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Apr 13 22:20:10.041356 systemd[1]: Started dbus.service - D-Bus System Message Bus. Apr 13 22:20:10.045340 jq[1409]: true Apr 13 22:20:10.054323 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Apr 13 22:20:10.089937 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Apr 13 22:20:10.091080 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Apr 13 22:20:10.091969 systemd[1]: extend-filesystems.service: Deactivated successfully. Apr 13 22:20:10.092168 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Apr 13 22:20:10.152386 systemd[1]: motdgen.service: Deactivated successfully. Apr 13 22:20:10.153360 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Apr 13 22:20:10.160349 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Apr 13 22:20:10.171617 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 31 scanned by (udev-worker) (1338) Apr 13 22:20:10.172007 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Apr 13 22:20:10.172191 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Apr 13 22:20:10.198102 update_engine[1401]: I20260413 22:20:10.197647 1401 main.cc:92] Flatcar Update Engine starting Apr 13 22:20:10.222108 update_engine[1401]: I20260413 22:20:10.208133 1401 update_check_scheduler.cc:74] Next update check in 7m33s Apr 13 22:20:10.280120 jq[1415]: true Apr 13 22:20:10.283687 systemd-logind[1396]: Watching system buttons on /dev/input/event1 (Power Button) Apr 13 22:20:10.283705 systemd-logind[1396]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Apr 13 22:20:10.285375 (ntainerd)[1416]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Apr 13 22:20:10.291648 systemd-logind[1396]: New seat seat0. Apr 13 22:20:10.310295 systemd[1]: coreos-metadata.service: Deactivated successfully. Apr 13 22:20:10.310969 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Apr 13 22:20:10.338009 systemd[1]: Started systemd-logind.service - User Login Management. Apr 13 22:20:10.382626 dbus-daemon[1383]: [system] Successfully activated service 'org.freedesktop.systemd1' Apr 13 22:20:10.493149 systemd[1]: Started update-engine.service - Update Engine. Apr 13 22:20:10.543521 systemd[1]: Starting etcd-member.service - etcd (System Application Container)... Apr 13 22:20:10.549845 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Apr 13 22:20:10.550296 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). Apr 13 22:20:10.550545 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Apr 13 22:20:10.562388 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). Apr 13 22:20:10.562629 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Apr 13 22:20:10.600615 (-wrapper)[1446]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS Apr 13 22:20:10.601873 systemd[1]: Started locksmithd.service - Cluster reboot manager. Apr 13 22:20:10.679067 bash[1445]: Updated "/home/core/.ssh/authorized_keys" Apr 13 22:20:10.697295 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Apr 13 22:20:10.729547 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Apr 13 22:20:11.252795 sshd_keygen[1405]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Apr 13 22:20:11.293718 locksmithd[1447]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Apr 13 22:20:11.722204 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Apr 13 22:20:11.767965 systemd[1]: Starting issuegen.service - Generate /run/issue... Apr 13 22:20:11.865599 systemd[1]: issuegen.service: Deactivated successfully. Apr 13 22:20:11.865983 systemd[1]: Finished issuegen.service - Generate /run/issue. Apr 13 22:20:11.918662 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Apr 13 22:20:12.359317 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Apr 13 22:20:12.510642 systemd[1]: Started getty@tty1.service - Getty on tty1. Apr 13 22:20:12.581020 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Apr 13 22:20:12.589794 systemd[1]: Reached target getty.target - Login Prompts. Apr 13 22:20:14.015547 containerd[1416]: time="2026-04-13T22:20:14.010328061Z" level=info msg="starting containerd" revision=174e0d1785eeda18dc2beba45e1d5a188771636b version=v1.7.21 Apr 13 22:20:14.225386 containerd[1416]: time="2026-04-13T22:20:14.224309520Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Apr 13 22:20:14.249240 containerd[1416]: time="2026-04-13T22:20:14.238695525Z" 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 Apr 13 22:20:14.249240 containerd[1416]: time="2026-04-13T22:20:14.247832710Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Apr 13 22:20:14.258624 containerd[1416]: time="2026-04-13T22:20:14.257308884Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Apr 13 22:20:14.260583 containerd[1416]: time="2026-04-13T22:20:14.260391622Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Apr 13 22:20:14.261320 containerd[1416]: time="2026-04-13T22:20:14.260843348Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Apr 13 22:20:14.261320 containerd[1416]: time="2026-04-13T22:20:14.261154651Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Apr 13 22:20:14.262644 containerd[1416]: time="2026-04-13T22:20:14.262077112Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Apr 13 22:20:14.264503 containerd[1416]: time="2026-04-13T22:20:14.263865231Z" 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 Apr 13 22:20:14.265822 containerd[1416]: time="2026-04-13T22:20:14.265208526Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Apr 13 22:20:14.266613 containerd[1416]: time="2026-04-13T22:20:14.265953719Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Apr 13 22:20:14.270949 containerd[1416]: time="2026-04-13T22:20:14.266709426Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Apr 13 22:20:14.270949 containerd[1416]: time="2026-04-13T22:20:14.266952329Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Apr 13 22:20:14.270949 containerd[1416]: time="2026-04-13T22:20:14.267983485Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Apr 13 22:20:14.270949 containerd[1416]: time="2026-04-13T22:20:14.268315249Z" 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 Apr 13 22:20:14.270949 containerd[1416]: time="2026-04-13T22:20:14.268336387Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Apr 13 22:20:14.270949 containerd[1416]: time="2026-04-13T22:20:14.268412374Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Apr 13 22:20:14.278023 containerd[1416]: time="2026-04-13T22:20:14.275343142Z" level=info msg="metadata content store policy set" policy=shared Apr 13 22:20:14.358263 containerd[1416]: time="2026-04-13T22:20:14.357821265Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Apr 13 22:20:14.359505 containerd[1416]: time="2026-04-13T22:20:14.359394796Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Apr 13 22:20:14.369875 containerd[1416]: time="2026-04-13T22:20:14.361878680Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Apr 13 22:20:14.378668 containerd[1416]: time="2026-04-13T22:20:14.372150836Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Apr 13 22:20:14.438640 containerd[1416]: time="2026-04-13T22:20:14.437939300Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Apr 13 22:20:14.444998 containerd[1416]: time="2026-04-13T22:20:14.441412514Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Apr 13 22:20:14.497196 containerd[1416]: time="2026-04-13T22:20:14.494053194Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Apr 13 22:20:14.497196 containerd[1416]: time="2026-04-13T22:20:14.495055582Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Apr 13 22:20:14.497196 containerd[1416]: time="2026-04-13T22:20:14.495093243Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Apr 13 22:20:14.497196 containerd[1416]: time="2026-04-13T22:20:14.495115851Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Apr 13 22:20:14.497196 containerd[1416]: time="2026-04-13T22:20:14.495142999Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Apr 13 22:20:14.497196 containerd[1416]: time="2026-04-13T22:20:14.495215404Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Apr 13 22:20:14.497196 containerd[1416]: time="2026-04-13T22:20:14.495248188Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Apr 13 22:20:14.497196 containerd[1416]: time="2026-04-13T22:20:14.495319480Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Apr 13 22:20:14.499817 containerd[1416]: time="2026-04-13T22:20:14.495354949Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Apr 13 22:20:14.522941 containerd[1416]: time="2026-04-13T22:20:14.522345173Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Apr 13 22:20:14.523215 containerd[1416]: time="2026-04-13T22:20:14.523187899Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Apr 13 22:20:14.536864 containerd[1416]: time="2026-04-13T22:20:14.527419445Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Apr 13 22:20:14.557969 containerd[1416]: time="2026-04-13T22:20:14.557396879Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Apr 13 22:20:14.567854 containerd[1416]: time="2026-04-13T22:20:14.564313298Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Apr 13 22:20:14.571957 containerd[1416]: time="2026-04-13T22:20:14.569306400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Apr 13 22:20:14.579336 containerd[1416]: time="2026-04-13T22:20:14.578239283Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Apr 13 22:20:14.598687 containerd[1416]: time="2026-04-13T22:20:14.580150392Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Apr 13 22:20:14.598687 containerd[1416]: time="2026-04-13T22:20:14.580201823Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Apr 13 22:20:14.598687 containerd[1416]: time="2026-04-13T22:20:14.580217426Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Apr 13 22:20:14.598687 containerd[1416]: time="2026-04-13T22:20:14.580247975Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Apr 13 22:20:14.631016 containerd[1416]: time="2026-04-13T22:20:14.614806729Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Apr 13 22:20:14.636834 containerd[1416]: time="2026-04-13T22:20:14.635134226Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Apr 13 22:20:14.636834 containerd[1416]: time="2026-04-13T22:20:14.635419711Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Apr 13 22:20:14.641749 containerd[1416]: time="2026-04-13T22:20:14.639686032Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Apr 13 22:20:14.646900 containerd[1416]: time="2026-04-13T22:20:14.646044360Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Apr 13 22:20:14.650161 containerd[1416]: time="2026-04-13T22:20:14.648907048Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Apr 13 22:20:14.652529 containerd[1416]: time="2026-04-13T22:20:14.651962098Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Apr 13 22:20:14.653870 containerd[1416]: time="2026-04-13T22:20:14.652740046Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Apr 13 22:20:14.653870 containerd[1416]: time="2026-04-13T22:20:14.652965123Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Apr 13 22:20:14.657570 containerd[1416]: time="2026-04-13T22:20:14.654077852Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Apr 13 22:20:14.657570 containerd[1416]: time="2026-04-13T22:20:14.654292477Z" 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 Apr 13 22:20:14.663085 containerd[1416]: time="2026-04-13T22:20:14.660851911Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Apr 13 22:20:14.663085 containerd[1416]: time="2026-04-13T22:20:14.661393349Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Apr 13 22:20:14.663085 containerd[1416]: time="2026-04-13T22:20:14.661536337Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Apr 13 22:20:14.663085 containerd[1416]: time="2026-04-13T22:20:14.661751075Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Apr 13 22:20:14.663085 containerd[1416]: time="2026-04-13T22:20:14.661767675Z" level=info msg="NRI interface is disabled by configuration." Apr 13 22:20:14.663085 containerd[1416]: time="2026-04-13T22:20:14.661782067Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Apr 13 22:20:14.663708 containerd[1416]: time="2026-04-13T22:20:14.662654239Z" 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}" Apr 13 22:20:14.663708 containerd[1416]: time="2026-04-13T22:20:14.662817946Z" level=info msg="Connect containerd service" Apr 13 22:20:14.669269 containerd[1416]: time="2026-04-13T22:20:14.664101152Z" level=info msg="using legacy CRI server" Apr 13 22:20:14.669269 containerd[1416]: time="2026-04-13T22:20:14.666476902Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Apr 13 22:20:14.676887 containerd[1416]: time="2026-04-13T22:20:14.675242513Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Apr 13 22:20:14.732372 containerd[1416]: time="2026-04-13T22:20:14.729898092Z" 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" Apr 13 22:20:14.739708 containerd[1416]: time="2026-04-13T22:20:14.737307134Z" level=info msg="Start subscribing containerd event" Apr 13 22:20:14.739708 containerd[1416]: time="2026-04-13T22:20:14.738166948Z" level=info msg="Start recovering state" Apr 13 22:20:14.746174 containerd[1416]: time="2026-04-13T22:20:14.742236488Z" level=info msg="Start event monitor" Apr 13 22:20:14.746174 containerd[1416]: time="2026-04-13T22:20:14.742579823Z" level=info msg="Start snapshots syncer" Apr 13 22:20:14.746174 containerd[1416]: time="2026-04-13T22:20:14.742684215Z" level=info msg="Start cni network conf syncer for default" Apr 13 22:20:14.746174 containerd[1416]: time="2026-04-13T22:20:14.742738566Z" level=info msg="Start streaming server" Apr 13 22:20:14.765710 containerd[1416]: time="2026-04-13T22:20:14.765335991Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Apr 13 22:20:14.778040 containerd[1416]: time="2026-04-13T22:20:14.775737012Z" level=info msg=serving... address=/run/containerd/containerd.sock Apr 13 22:20:14.778040 containerd[1416]: time="2026-04-13T22:20:14.776391077Z" level=info msg="containerd successfully booted in 0.798808s" Apr 13 22:20:14.780141 systemd[1]: Started containerd.service - containerd container runtime. Apr 13 22:20:14.801338 systemd[1]: Starting docker.service - Docker Application Container Engine... Apr 13 22:20:14.834032 (dockerd)[1488]: 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 Apr 13 22:20:17.958871 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Apr 13 22:20:18.015154 systemd[1]: Started sshd@0-10.0.0.99:22-10.0.0.1:55400.service - OpenSSH per-connection server daemon (10.0.0.1:55400). Apr 13 22:20:18.882594 sshd[1496]: Accepted publickey for core from 10.0.0.1 port 55400 ssh2: RSA SHA256:W5N50Zpm460ysbmW58qL6krVZjeW4Y8kSGRetRBMpjQ Apr 13 22:20:18.898004 sshd[1496]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 13 22:20:19.553535 systemd-logind[1396]: New session 1 of user core. Apr 13 22:20:19.560186 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Apr 13 22:20:19.596282 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Apr 13 22:20:19.706412 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Apr 13 22:20:19.763106 systemd[1]: Starting user@500.service - User Manager for UID 500... Apr 13 22:20:19.789667 dockerd[1488]: time="2026-04-13T22:20:19.789522171Z" level=info msg="Starting up" Apr 13 22:20:19.806614 (systemd)[1507]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Apr 13 22:20:21.101778 systemd[1507]: Queued start job for default target default.target. Apr 13 22:20:21.143210 systemd[1507]: Created slice app.slice - User Application Slice. Apr 13 22:20:21.168277 systemd[1507]: Reached target paths.target - Paths. Apr 13 22:20:21.168882 systemd[1507]: Reached target timers.target - Timers. Apr 13 22:20:21.260578 systemd[1507]: Starting dbus.socket - D-Bus User Message Bus Socket... Apr 13 22:20:21.354154 systemd[1507]: Listening on dbus.socket - D-Bus User Message Bus Socket. Apr 13 22:20:21.354775 systemd[1507]: Reached target sockets.target - Sockets. Apr 13 22:20:21.354801 systemd[1507]: Reached target basic.target - Basic System. Apr 13 22:20:21.354858 systemd[1507]: Reached target default.target - Main User Target. Apr 13 22:20:21.354892 systemd[1507]: Startup finished in 1.456s. Apr 13 22:20:21.355371 systemd[1]: Started user@500.service - User Manager for UID 500. Apr 13 22:20:21.371585 systemd[1]: Started session-1.scope - Session 1 of User core. Apr 13 22:20:21.405116 dockerd[1488]: time="2026-04-13T22:20:21.404607124Z" level=info msg="Loading containers: start." Apr 13 22:20:21.652748 systemd[1]: Started sshd@1-10.0.0.99:22-10.0.0.1:55408.service - OpenSSH per-connection server daemon (10.0.0.1:55408). Apr 13 22:20:22.150621 sshd[1532]: Accepted publickey for core from 10.0.0.1 port 55408 ssh2: RSA SHA256:W5N50Zpm460ysbmW58qL6krVZjeW4Y8kSGRetRBMpjQ Apr 13 22:20:22.167388 sshd[1532]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 13 22:20:22.232916 systemd-logind[1396]: New session 2 of user core. Apr 13 22:20:22.279078 systemd[1]: Started session-2.scope - Session 2 of User core. Apr 13 22:20:22.447548 sshd[1532]: pam_unix(sshd:session): session closed for user core Apr 13 22:20:22.482948 systemd[1]: sshd@1-10.0.0.99:22-10.0.0.1:55408.service: Deactivated successfully. Apr 13 22:20:22.495248 systemd[1]: session-2.scope: Deactivated successfully. Apr 13 22:20:22.513898 systemd-logind[1396]: Session 2 logged out. Waiting for processes to exit. Apr 13 22:20:22.538569 systemd[1]: Started sshd@2-10.0.0.99:22-10.0.0.1:55422.service - OpenSSH per-connection server daemon (10.0.0.1:55422). Apr 13 22:20:22.556824 systemd-logind[1396]: Removed session 2. Apr 13 22:20:22.801075 sshd[1554]: Accepted publickey for core from 10.0.0.1 port 55422 ssh2: RSA SHA256:W5N50Zpm460ysbmW58qL6krVZjeW4Y8kSGRetRBMpjQ Apr 13 22:20:22.814636 sshd[1554]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 13 22:20:22.964088 systemd-logind[1396]: New session 3 of user core. Apr 13 22:20:22.977577 systemd[1]: Started session-3.scope - Session 3 of User core. Apr 13 22:20:23.286998 sshd[1554]: pam_unix(sshd:session): session closed for user core Apr 13 22:20:23.311281 systemd[1]: sshd@2-10.0.0.99:22-10.0.0.1:55422.service: Deactivated successfully. Apr 13 22:20:23.340112 systemd[1]: session-3.scope: Deactivated successfully. Apr 13 22:20:23.358285 systemd-logind[1396]: Session 3 logged out. Waiting for processes to exit. Apr 13 22:20:23.385087 systemd-logind[1396]: Removed session 3. Apr 13 22:20:24.062598 kernel: Initializing XFRM netlink socket Apr 13 22:20:25.454239 systemd-networkd[1336]: docker0: Link UP Apr 13 22:20:25.804413 dockerd[1488]: time="2026-04-13T22:20:25.801734106Z" level=info msg="Loading containers: done." Apr 13 22:20:26.528515 dockerd[1488]: time="2026-04-13T22:20:26.528263618Z" 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 Apr 13 22:20:26.535225 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck3047658951-merged.mount: Deactivated successfully. Apr 13 22:20:26.546070 dockerd[1488]: time="2026-04-13T22:20:26.544956857Z" level=info msg="Docker daemon" commit=061aa95809be396a6b5542618d8a34b02a21ff77 containerd-snapshotter=false storage-driver=overlay2 version=26.1.0 Apr 13 22:20:26.551108 dockerd[1488]: time="2026-04-13T22:20:26.550030253Z" level=info msg="Daemon has completed initialization" Apr 13 22:20:27.805094 dockerd[1488]: time="2026-04-13T22:20:27.799091869Z" level=info msg="API listen on /run/docker.sock" Apr 13 22:20:27.802653 systemd[1]: Started docker.service - Docker Application Container Engine. Apr 13 22:20:27.919485 etcd-wrapper[1452]: Error response from daemon: No such container: etcd-member Apr 13 22:20:28.358418 etcd-wrapper[1651]: Error response from daemon: No such container: etcd-member Apr 13 22:20:28.854365 etcd-wrapper[1669]: Unable to find image 'quay.io/coreos/etcd:v3.5.24' locally Apr 13 22:20:33.582308 systemd[1]: Started sshd@3-10.0.0.99:22-10.0.0.1:46046.service - OpenSSH per-connection server daemon (10.0.0.1:46046). Apr 13 22:20:33.827566 etcd-wrapper[1669]: v3.5.24: Pulling from coreos/etcd Apr 13 22:20:34.251584 sshd[1680]: Accepted publickey for core from 10.0.0.1 port 46046 ssh2: RSA SHA256:W5N50Zpm460ysbmW58qL6krVZjeW4Y8kSGRetRBMpjQ Apr 13 22:20:34.276376 sshd[1680]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 13 22:20:34.362179 systemd-logind[1396]: New session 4 of user core. Apr 13 22:20:34.384772 systemd[1]: Started session-4.scope - Session 4 of User core. Apr 13 22:20:34.602459 etcd-wrapper[1669]: 804c8aba2cc6: Pulling fs layer Apr 13 22:20:34.602459 etcd-wrapper[1669]: 2ae710cd8bfe: Pulling fs layer Apr 13 22:20:34.602459 etcd-wrapper[1669]: d462aa345367: Pulling fs layer Apr 13 22:20:34.602459 etcd-wrapper[1669]: 0f8b424aa0b9: Pulling fs layer Apr 13 22:20:34.602459 etcd-wrapper[1669]: d557676654e5: Pulling fs layer Apr 13 22:20:34.602459 etcd-wrapper[1669]: c8022d07192e: Pulling fs layer Apr 13 22:20:34.602459 etcd-wrapper[1669]: d858cbc252ad: Pulling fs layer Apr 13 22:20:34.602459 etcd-wrapper[1669]: 1069fc2daed1: Pulling fs layer Apr 13 22:20:34.602459 etcd-wrapper[1669]: b40161cd83fc: Pulling fs layer Apr 13 22:20:34.602459 etcd-wrapper[1669]: 5318d93a3a65: Pulling fs layer Apr 13 22:20:34.602459 etcd-wrapper[1669]: 307c1adadb60: Pulling fs layer Apr 13 22:20:34.602459 etcd-wrapper[1669]: 49a553fa7ab9: Pulling fs layer Apr 13 22:20:34.602459 etcd-wrapper[1669]: 9cde5f0af3dd: Pulling fs layer Apr 13 22:20:34.602459 etcd-wrapper[1669]: b9581a18c15b: Pulling fs layer Apr 13 22:20:34.602459 etcd-wrapper[1669]: 95c11f3cd524: Pulling fs layer Apr 13 22:20:34.602459 etcd-wrapper[1669]: ed40c9b2e219: Pulling fs layer Apr 13 22:20:34.602459 etcd-wrapper[1669]: 5318d93a3a65: Waiting Apr 13 22:20:34.602459 etcd-wrapper[1669]: 307c1adadb60: Waiting Apr 13 22:20:34.602459 etcd-wrapper[1669]: 49a553fa7ab9: Waiting Apr 13 22:20:34.602459 etcd-wrapper[1669]: 9cde5f0af3dd: Waiting Apr 13 22:20:34.602459 etcd-wrapper[1669]: b9581a18c15b: Waiting Apr 13 22:20:34.602459 etcd-wrapper[1669]: 95c11f3cd524: Waiting Apr 13 22:20:34.602459 etcd-wrapper[1669]: ed40c9b2e219: Waiting Apr 13 22:20:34.602459 etcd-wrapper[1669]: 0f8b424aa0b9: Waiting Apr 13 22:20:34.602459 etcd-wrapper[1669]: b40161cd83fc: Waiting Apr 13 22:20:34.602459 etcd-wrapper[1669]: 1069fc2daed1: Waiting Apr 13 22:20:34.602459 etcd-wrapper[1669]: c8022d07192e: Waiting Apr 13 22:20:34.634188 sshd[1680]: pam_unix(sshd:session): session closed for user core Apr 13 22:20:34.683898 systemd[1]: sshd@3-10.0.0.99:22-10.0.0.1:46046.service: Deactivated successfully. Apr 13 22:20:34.700680 systemd[1]: session-4.scope: Deactivated successfully. Apr 13 22:20:34.722220 systemd-logind[1396]: Session 4 logged out. Waiting for processes to exit. Apr 13 22:20:34.758966 systemd[1]: Started sshd@4-10.0.0.99:22-10.0.0.1:46058.service - OpenSSH per-connection server daemon (10.0.0.1:46058). Apr 13 22:20:34.862882 systemd-logind[1396]: Removed session 4. Apr 13 22:20:35.233066 etcd-wrapper[1669]: 2ae710cd8bfe: Verifying Checksum Apr 13 22:20:35.233066 etcd-wrapper[1669]: 2ae710cd8bfe: Download complete Apr 13 22:20:35.247812 etcd-wrapper[1669]: 804c8aba2cc6: Download complete Apr 13 22:20:35.325168 sshd[1687]: Accepted publickey for core from 10.0.0.1 port 46058 ssh2: RSA SHA256:W5N50Zpm460ysbmW58qL6krVZjeW4Y8kSGRetRBMpjQ Apr 13 22:20:35.338172 etcd-wrapper[1669]: d462aa345367: Verifying Checksum Apr 13 22:20:35.338172 etcd-wrapper[1669]: d462aa345367: Download complete Apr 13 22:20:35.332326 sshd[1687]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 13 22:20:35.482040 systemd-logind[1396]: New session 5 of user core. Apr 13 22:20:35.503160 systemd[1]: Started session-5.scope - Session 5 of User core. Apr 13 22:20:35.830636 sshd[1687]: pam_unix(sshd:session): session closed for user core Apr 13 22:20:35.844214 etcd-wrapper[1669]: d557676654e5: Verifying Checksum Apr 13 22:20:35.887556 etcd-wrapper[1669]: d557676654e5: Download complete Apr 13 22:20:36.001714 systemd[1]: sshd@4-10.0.0.99:22-10.0.0.1:46058.service: Deactivated successfully. Apr 13 22:20:36.025844 etcd-wrapper[1669]: 0f8b424aa0b9: Download complete Apr 13 22:20:36.022307 systemd[1]: session-5.scope: Deactivated successfully. Apr 13 22:20:36.036897 systemd-logind[1396]: Session 5 logged out. Waiting for processes to exit. Apr 13 22:20:36.067078 systemd-logind[1396]: Removed session 5. Apr 13 22:20:36.084100 etcd-wrapper[1669]: c8022d07192e: Verifying Checksum Apr 13 22:20:36.112051 etcd-wrapper[1669]: c8022d07192e: Download complete Apr 13 22:20:36.473999 etcd-wrapper[1669]: d858cbc252ad: Verifying Checksum Apr 13 22:20:36.473999 etcd-wrapper[1669]: d858cbc252ad: Download complete Apr 13 22:20:36.607007 etcd-wrapper[1669]: b40161cd83fc: Verifying Checksum Apr 13 22:20:36.607007 etcd-wrapper[1669]: b40161cd83fc: Download complete Apr 13 22:20:36.607007 etcd-wrapper[1669]: 1069fc2daed1: Verifying Checksum Apr 13 22:20:36.607007 etcd-wrapper[1669]: 1069fc2daed1: Download complete Apr 13 22:20:36.835099 etcd-wrapper[1669]: 804c8aba2cc6: Pull complete Apr 13 22:20:37.457951 etcd-wrapper[1669]: 5318d93a3a65: Verifying Checksum Apr 13 22:20:37.458896 etcd-wrapper[1669]: 5318d93a3a65: Download complete Apr 13 22:20:37.458896 etcd-wrapper[1669]: 307c1adadb60: Download complete Apr 13 22:20:37.657726 systemd[1]: var-lib-docker-overlay2-e9fad169037b18e5c3fcfcc9666b8322579ba5728a26ef37d818af140f8ef90d-merged.mount: Deactivated successfully. Apr 13 22:20:37.857823 etcd-wrapper[1669]: 2ae710cd8bfe: Pull complete Apr 13 22:20:38.621285 etcd-wrapper[1669]: 49a553fa7ab9: Verifying Checksum Apr 13 22:20:38.626299 etcd-wrapper[1669]: 49a553fa7ab9: Download complete Apr 13 22:20:38.735229 etcd-wrapper[1669]: 9cde5f0af3dd: Verifying Checksum Apr 13 22:20:38.735229 etcd-wrapper[1669]: 9cde5f0af3dd: Download complete Apr 13 22:20:38.801546 etcd-wrapper[1669]: b9581a18c15b: Verifying Checksum Apr 13 22:20:38.801546 etcd-wrapper[1669]: b9581a18c15b: Download complete Apr 13 22:20:39.174587 etcd-wrapper[1669]: 95c11f3cd524: Verifying Checksum Apr 13 22:20:39.174587 etcd-wrapper[1669]: 95c11f3cd524: Download complete Apr 13 22:20:39.178746 etcd-wrapper[1669]: ed40c9b2e219: Verifying Checksum Apr 13 22:20:39.178746 etcd-wrapper[1669]: ed40c9b2e219: Download complete Apr 13 22:20:45.941980 systemd[1]: Started sshd@5-10.0.0.99:22-10.0.0.1:33442.service - OpenSSH per-connection server daemon (10.0.0.1:33442). Apr 13 22:20:46.231186 sshd[1712]: Accepted publickey for core from 10.0.0.1 port 33442 ssh2: RSA SHA256:W5N50Zpm460ysbmW58qL6krVZjeW4Y8kSGRetRBMpjQ Apr 13 22:20:46.251745 sshd[1712]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 13 22:20:46.399480 systemd-logind[1396]: New session 6 of user core. Apr 13 22:20:46.417252 systemd[1]: Started session-6.scope - Session 6 of User core. Apr 13 22:20:46.729519 sshd[1712]: pam_unix(sshd:session): session closed for user core Apr 13 22:20:46.865379 systemd[1]: sshd@5-10.0.0.99:22-10.0.0.1:33442.service: Deactivated successfully. Apr 13 22:20:46.917975 systemd[1]: session-6.scope: Deactivated successfully. Apr 13 22:20:46.965814 systemd-logind[1396]: Session 6 logged out. Waiting for processes to exit. Apr 13 22:20:47.012200 systemd[1]: Started sshd@6-10.0.0.99:22-10.0.0.1:33444.service - OpenSSH per-connection server daemon (10.0.0.1:33444). Apr 13 22:20:47.064726 systemd-logind[1396]: Removed session 6. Apr 13 22:20:47.683209 sshd[1719]: Accepted publickey for core from 10.0.0.1 port 33444 ssh2: RSA SHA256:W5N50Zpm460ysbmW58qL6krVZjeW4Y8kSGRetRBMpjQ Apr 13 22:20:47.691639 sshd[1719]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 13 22:20:47.787172 systemd-logind[1396]: New session 7 of user core. Apr 13 22:20:47.817945 systemd[1]: Started session-7.scope - Session 7 of User core. Apr 13 22:20:48.257778 sshd[1719]: pam_unix(sshd:session): session closed for user core Apr 13 22:20:48.351336 systemd[1]: sshd@6-10.0.0.99:22-10.0.0.1:33444.service: Deactivated successfully. Apr 13 22:20:48.456942 systemd[1]: session-7.scope: Deactivated successfully. Apr 13 22:20:48.553784 systemd-logind[1396]: Session 7 logged out. Waiting for processes to exit. Apr 13 22:20:48.603352 systemd-logind[1396]: Removed session 7. Apr 13 22:20:55.300586 update_engine[1401]: I20260413 22:20:55.298694 1401 update_attempter.cc:509] Updating boot flags... Apr 13 22:20:55.467486 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 31 scanned by (udev-worker) (1732) Apr 13 22:20:58.362812 systemd[1]: Started sshd@7-10.0.0.99:22-10.0.0.1:50960.service - OpenSSH per-connection server daemon (10.0.0.1:50960). Apr 13 22:20:58.870366 sshd[1739]: Accepted publickey for core from 10.0.0.1 port 50960 ssh2: RSA SHA256:W5N50Zpm460ysbmW58qL6krVZjeW4Y8kSGRetRBMpjQ Apr 13 22:20:58.887529 sshd[1739]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 13 22:20:58.929836 systemd-logind[1396]: New session 8 of user core. Apr 13 22:20:58.939321 systemd[1]: Started session-8.scope - Session 8 of User core. Apr 13 22:20:59.372998 sshd[1739]: pam_unix(sshd:session): session closed for user core Apr 13 22:20:59.485240 systemd[1]: sshd@7-10.0.0.99:22-10.0.0.1:50960.service: Deactivated successfully. Apr 13 22:20:59.548666 systemd[1]: session-8.scope: Deactivated successfully. Apr 13 22:20:59.640975 systemd-logind[1396]: Session 8 logged out. Waiting for processes to exit. Apr 13 22:20:59.682256 systemd[1]: Started sshd@8-10.0.0.99:22-10.0.0.1:50974.service - OpenSSH per-connection server daemon (10.0.0.1:50974). Apr 13 22:20:59.699150 systemd-logind[1396]: Removed session 8. Apr 13 22:20:59.880082 sshd[1746]: Accepted publickey for core from 10.0.0.1 port 50974 ssh2: RSA SHA256:W5N50Zpm460ysbmW58qL6krVZjeW4Y8kSGRetRBMpjQ Apr 13 22:20:59.888167 sshd[1746]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 13 22:20:59.918709 systemd-logind[1396]: New session 9 of user core. Apr 13 22:20:59.933085 systemd[1]: Started session-9.scope - Session 9 of User core. Apr 13 22:21:00.170473 sshd[1746]: pam_unix(sshd:session): session closed for user core Apr 13 22:21:00.196722 systemd[1]: sshd@8-10.0.0.99:22-10.0.0.1:50974.service: Deactivated successfully. Apr 13 22:21:00.210752 systemd[1]: session-9.scope: Deactivated successfully. Apr 13 22:21:00.240030 systemd-logind[1396]: Session 9 logged out. Waiting for processes to exit. Apr 13 22:21:00.285209 systemd-logind[1396]: Removed session 9. Apr 13 22:21:10.376712 systemd[1]: Started sshd@9-10.0.0.99:22-10.0.0.1:60964.service - OpenSSH per-connection server daemon (10.0.0.1:60964). Apr 13 22:21:10.991649 sshd[1753]: Accepted publickey for core from 10.0.0.1 port 60964 ssh2: RSA SHA256:W5N50Zpm460ysbmW58qL6krVZjeW4Y8kSGRetRBMpjQ Apr 13 22:21:11.025370 sshd[1753]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 13 22:21:11.140649 systemd-logind[1396]: New session 10 of user core. Apr 13 22:21:11.166803 systemd[1]: Started session-10.scope - Session 10 of User core. Apr 13 22:21:11.386516 sshd[1753]: pam_unix(sshd:session): session closed for user core Apr 13 22:21:11.420604 systemd[1]: sshd@9-10.0.0.99:22-10.0.0.1:60964.service: Deactivated successfully. Apr 13 22:21:11.437069 systemd[1]: session-10.scope: Deactivated successfully. Apr 13 22:21:11.446835 systemd-logind[1396]: Session 10 logged out. Waiting for processes to exit. Apr 13 22:21:11.491155 systemd[1]: Started sshd@10-10.0.0.99:22-10.0.0.1:60966.service - OpenSSH per-connection server daemon (10.0.0.1:60966). Apr 13 22:21:11.551020 systemd-logind[1396]: Removed session 10. Apr 13 22:21:11.846314 sshd[1760]: Accepted publickey for core from 10.0.0.1 port 60966 ssh2: RSA SHA256:W5N50Zpm460ysbmW58qL6krVZjeW4Y8kSGRetRBMpjQ Apr 13 22:21:11.882521 sshd[1760]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 13 22:21:11.998413 systemd-logind[1396]: New session 11 of user core. Apr 13 22:21:12.063921 systemd[1]: Started session-11.scope - Session 11 of User core. Apr 13 22:21:12.480492 sshd[1760]: pam_unix(sshd:session): session closed for user core Apr 13 22:21:12.540244 systemd-logind[1396]: Session 11 logged out. Waiting for processes to exit. Apr 13 22:21:12.548024 systemd[1]: sshd@10-10.0.0.99:22-10.0.0.1:60966.service: Deactivated successfully. Apr 13 22:21:12.568995 systemd[1]: session-11.scope: Deactivated successfully. Apr 13 22:21:12.587769 systemd-logind[1396]: Removed session 11. Apr 13 22:21:20.178242 systemd[1]: var-lib-docker-overlay2-7573098012c260d6c6cf40e9854639e7222add5bbdb43a139bf338bcb8b80eed-merged.mount: Deactivated successfully. Apr 13 22:21:20.562705 etcd-wrapper[1669]: d462aa345367: Pull complete Apr 13 22:21:20.941307 systemd[1]: var-lib-docker-overlay2-0c3c7c96ad822dd28b26f7ae84a9d2e18e9d3b1f8b4918340c6193f6a691a9b3-merged.mount: Deactivated successfully. Apr 13 22:21:21.258620 etcd-wrapper[1669]: 0f8b424aa0b9: Pull complete Apr 13 22:21:21.859944 systemd[1]: var-lib-docker-overlay2-6a10cfdab7721081ed35463d4a4fdbe01003c891371a76b913decfd2d284e722-merged.mount: Deactivated successfully. Apr 13 22:21:22.170882 etcd-wrapper[1669]: d557676654e5: Pull complete Apr 13 22:21:22.824378 systemd[1]: Started sshd@11-10.0.0.99:22-10.0.0.1:60176.service - OpenSSH per-connection server daemon (10.0.0.1:60176). Apr 13 22:21:22.869856 systemd[1]: var-lib-docker-overlay2-4091da6d7a12ffaea24c3ca98329b7a9d2059e9dfff61a89f15b3c457c7a738a-merged.mount: Deactivated successfully. Apr 13 22:21:22.970746 etcd-wrapper[1669]: c8022d07192e: Pull complete Apr 13 22:21:23.111798 sshd[1779]: Accepted publickey for core from 10.0.0.1 port 60176 ssh2: RSA SHA256:W5N50Zpm460ysbmW58qL6krVZjeW4Y8kSGRetRBMpjQ Apr 13 22:21:23.118153 sshd[1779]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 13 22:21:23.250517 systemd-logind[1396]: New session 12 of user core. Apr 13 22:21:23.349153 systemd[1]: Started session-12.scope - Session 12 of User core. Apr 13 22:21:23.378030 systemd[1]: var-lib-docker-overlay2-1583e08c78ae9be1c0db48459761b644544793984227de3b97e35ffef01bde5d-merged.mount: Deactivated successfully. Apr 13 22:21:23.557549 etcd-wrapper[1669]: d858cbc252ad: Pull complete Apr 13 22:21:23.631241 sshd[1779]: pam_unix(sshd:session): session closed for user core Apr 13 22:21:23.664566 systemd[1]: sshd@11-10.0.0.99:22-10.0.0.1:60176.service: Deactivated successfully. Apr 13 22:21:23.679870 systemd[1]: session-12.scope: Deactivated successfully. Apr 13 22:21:23.683656 systemd-logind[1396]: Session 12 logged out. Waiting for processes to exit. Apr 13 22:21:23.711222 systemd[1]: var-lib-docker-overlay2-25b6a49a53bd256fa828a7bd392468c83bb4caec707711a07a8bf3676b072541-merged.mount: Deactivated successfully. Apr 13 22:21:23.730855 systemd[1]: Started sshd@12-10.0.0.99:22-10.0.0.1:60190.service - OpenSSH per-connection server daemon (10.0.0.1:60190). Apr 13 22:21:23.738242 systemd-logind[1396]: Removed session 12. Apr 13 22:21:23.776638 etcd-wrapper[1669]: 1069fc2daed1: Pull complete Apr 13 22:21:24.047112 systemd[1]: var-lib-docker-overlay2-2df8a9f0f0ff0c4d66069475c26099ea3f4c5253143510d0b0e4b3d9e4e95b65-merged.mount: Deactivated successfully. Apr 13 22:21:24.097165 sshd[1796]: Accepted publickey for core from 10.0.0.1 port 60190 ssh2: RSA SHA256:W5N50Zpm460ysbmW58qL6krVZjeW4Y8kSGRetRBMpjQ Apr 13 22:21:24.191145 sshd[1796]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 13 22:21:24.267733 etcd-wrapper[1669]: b40161cd83fc: Pull complete Apr 13 22:21:24.327170 systemd-logind[1396]: New session 13 of user core. Apr 13 22:21:24.382788 systemd[1]: Started session-13.scope - Session 13 of User core. Apr 13 22:21:24.658494 sshd[1796]: pam_unix(sshd:session): session closed for user core Apr 13 22:21:24.705797 systemd[1]: var-lib-docker-overlay2-02235ebcae0212bab9d0229a708ab4d9ab958dd51e5f8339e6f209be40ee334e-merged.mount: Deactivated successfully. Apr 13 22:21:24.731872 systemd[1]: sshd@12-10.0.0.99:22-10.0.0.1:60190.service: Deactivated successfully. Apr 13 22:21:24.759504 systemd[1]: session-13.scope: Deactivated successfully. Apr 13 22:21:24.794064 systemd-logind[1396]: Session 13 logged out. Waiting for processes to exit. Apr 13 22:21:24.850287 systemd-logind[1396]: Removed session 13. Apr 13 22:21:24.971167 etcd-wrapper[1669]: 5318d93a3a65: Pull complete Apr 13 22:21:25.721908 systemd[1]: var-lib-docker-overlay2-8a7b74034a0fdf8497fac36018bb7e907edbbc86111d6c9392a2883df8f5ccdf-merged.mount: Deactivated successfully. Apr 13 22:21:25.969703 etcd-wrapper[1669]: 307c1adadb60: Pull complete Apr 13 22:21:32.752553 systemd[1]: var-lib-docker-overlay2-d8a456945ae5a39b921a190b57e363bd0460c600716d21e3ade51c876f83aeaf-merged.mount: Deactivated successfully. Apr 13 22:21:33.065861 etcd-wrapper[1669]: 49a553fa7ab9: Pull complete Apr 13 22:21:34.740543 systemd[1]: Started sshd@13-10.0.0.99:22-10.0.0.1:40226.service - OpenSSH per-connection server daemon (10.0.0.1:40226). Apr 13 22:21:35.642298 sshd[1829]: Accepted publickey for core from 10.0.0.1 port 40226 ssh2: RSA SHA256:W5N50Zpm460ysbmW58qL6krVZjeW4Y8kSGRetRBMpjQ Apr 13 22:21:35.649692 sshd[1829]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 13 22:21:35.706674 systemd-logind[1396]: New session 14 of user core. Apr 13 22:21:35.721556 systemd[1]: Started session-14.scope - Session 14 of User core. Apr 13 22:21:36.012290 sshd[1829]: pam_unix(sshd:session): session closed for user core Apr 13 22:21:36.139321 systemd[1]: sshd@13-10.0.0.99:22-10.0.0.1:40226.service: Deactivated successfully. Apr 13 22:21:36.185474 systemd[1]: session-14.scope: Deactivated successfully. Apr 13 22:21:36.223693 systemd-logind[1396]: Session 14 logged out. Waiting for processes to exit. Apr 13 22:21:36.285737 systemd[1]: Started sshd@14-10.0.0.99:22-10.0.0.1:53636.service - OpenSSH per-connection server daemon (10.0.0.1:53636). Apr 13 22:21:36.295375 systemd-logind[1396]: Removed session 14. Apr 13 22:21:36.538355 sshd[1836]: Accepted publickey for core from 10.0.0.1 port 53636 ssh2: RSA SHA256:W5N50Zpm460ysbmW58qL6krVZjeW4Y8kSGRetRBMpjQ Apr 13 22:21:36.560902 sshd[1836]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 13 22:21:36.695134 systemd-logind[1396]: New session 15 of user core. Apr 13 22:21:36.723052 systemd[1]: Started session-15.scope - Session 15 of User core. Apr 13 22:21:37.027942 sshd[1836]: pam_unix(sshd:session): session closed for user core Apr 13 22:21:37.215124 systemd-logind[1396]: Session 15 logged out. Waiting for processes to exit. Apr 13 22:21:37.215467 systemd[1]: sshd@14-10.0.0.99:22-10.0.0.1:53636.service: Deactivated successfully. Apr 13 22:21:37.233835 systemd[1]: session-15.scope: Deactivated successfully. Apr 13 22:21:37.261678 systemd-logind[1396]: Removed session 15. Apr 13 22:21:37.460742 systemd[1]: var-lib-docker-overlay2-9aba14247be8c89eb622b5b66852d4b0fe335c93d271471146691740d5174a44-merged.mount: Deactivated successfully. Apr 13 22:21:37.768407 etcd-wrapper[1669]: 9cde5f0af3dd: Pull complete Apr 13 22:21:40.947831 systemd[1]: var-lib-docker-overlay2-a58eae2941609c699d3cf94551c0fe771c16efba05473fa334b8706f7489e63f-merged.mount: Deactivated successfully. Apr 13 22:21:41.336289 etcd-wrapper[1669]: b9581a18c15b: Pull complete Apr 13 22:21:41.644472 systemd[1]: var-lib-docker-overlay2-7370cac5d95acf5a9220a8f4e35033f2d1183e3165a47bf2a59948cc28530edd-merged.mount: Deactivated successfully. Apr 13 22:21:41.858806 etcd-wrapper[1669]: 95c11f3cd524: Pull complete Apr 13 22:21:42.254140 systemd[1]: var-lib-docker-overlay2-d228ed56c72000f95715f597f6df7b6812d7de0eb7c84e3413ebc55353d498ea-merged.mount: Deactivated successfully. Apr 13 22:21:42.428732 etcd-wrapper[1669]: ed40c9b2e219: Pull complete Apr 13 22:21:42.860873 etcd-wrapper[1669]: Digest: sha256:a193bdd3c1d1d11de961b6600231b2b3b4c4e092848dbb7d4c0e03b709ac7594 Apr 13 22:21:42.959335 etcd-wrapper[1669]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.24 Apr 13 22:21:43.794936 systemd[1]: var-lib-docker-overlay2-fd339cef152a1d6f4a0dc4b31fad4600df3a6851dac9d5f8b68c390458af0822\x2dinit-merged.mount: Deactivated successfully. Apr 13 22:21:44.122242 systemd[1]: var-lib-docker-overlay2-fd339cef152a1d6f4a0dc4b31fad4600df3a6851dac9d5f8b68c390458af0822-merged.mount: Deactivated successfully. Apr 13 22:21:45.824263 systemd[1]: var-lib-docker-tmp-moby\x2ddetect\x2drro3997258680.mount: Deactivated successfully. Apr 13 22:21:47.483260 systemd[1]: Started sshd@15-10.0.0.99:22-10.0.0.1:42004.service - OpenSSH per-connection server daemon (10.0.0.1:42004). Apr 13 22:21:47.657531 sshd[1863]: Accepted publickey for core from 10.0.0.1 port 42004 ssh2: RSA SHA256:W5N50Zpm460ysbmW58qL6krVZjeW4Y8kSGRetRBMpjQ Apr 13 22:21:47.690641 containerd[1416]: time="2026-04-13T22:21:47.689497993Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 13 22:21:47.706182 sshd[1863]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 13 22:21:47.756821 containerd[1416]: time="2026-04-13T22:21:47.744625026Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 13 22:21:47.756821 containerd[1416]: time="2026-04-13T22:21:47.744750451Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 13 22:21:47.756821 containerd[1416]: time="2026-04-13T22:21:47.745176034Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 13 22:21:47.818533 systemd-logind[1396]: New session 16 of user core. Apr 13 22:21:47.916978 systemd[1]: Started session-16.scope - Session 16 of User core. Apr 13 22:21:48.147660 systemd[1]: Started docker-c43107c5cbddd2057b4fb3ef819e5776ae5b9bb20cbd5227b418a0848b7fa93e.scope - libcontainer container c43107c5cbddd2057b4fb3ef819e5776ae5b9bb20cbd5227b418a0848b7fa93e. Apr 13 22:21:48.249899 sshd[1863]: pam_unix(sshd:session): session closed for user core Apr 13 22:21:48.399867 systemd[1]: sshd@15-10.0.0.99:22-10.0.0.1:42004.service: Deactivated successfully. Apr 13 22:21:48.476411 systemd[1]: session-16.scope: Deactivated successfully. Apr 13 22:21:48.543019 systemd-logind[1396]: Session 16 logged out. Waiting for processes to exit. Apr 13 22:21:48.574938 systemd[1]: Started sshd@16-10.0.0.99:22-10.0.0.1:42018.service - OpenSSH per-connection server daemon (10.0.0.1:42018). Apr 13 22:21:48.638353 systemd-logind[1396]: Removed session 16. Apr 13 22:21:48.756896 systemd-resolved[1283]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Apr 13 22:21:48.878377 sshd[1901]: Accepted publickey for core from 10.0.0.1 port 42018 ssh2: RSA SHA256:W5N50Zpm460ysbmW58qL6krVZjeW4Y8kSGRetRBMpjQ Apr 13 22:21:48.963856 sshd[1901]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 13 22:21:49.166838 systemd-logind[1396]: New session 17 of user core. Apr 13 22:21:49.188633 systemd[1]: Started session-17.scope - Session 17 of User core. Apr 13 22:21:49.409823 sshd[1901]: pam_unix(sshd:session): session closed for user core Apr 13 22:21:49.489072 systemd[1]: sshd@16-10.0.0.99:22-10.0.0.1:42018.service: Deactivated successfully. Apr 13 22:21:49.560077 systemd[1]: session-17.scope: Deactivated successfully. Apr 13 22:21:49.605251 systemd-logind[1396]: Session 17 logged out. Waiting for processes to exit. Apr 13 22:21:49.623984 systemd-logind[1396]: Removed session 17. Apr 13 22:21:50.767317 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:50.624049Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} Apr 13 22:21:50.767317 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:50.634280Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"2224627589184d62be2825a039c455f6"} Apr 13 22:21:50.790989 etcd-wrapper[1669]: {"level":"warn","ts":"2026-04-13T22:21:50.664851Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} Apr 13 22:21:50.818463 etcd-wrapper[1669]: {"level":"warn","ts":"2026-04-13T22:21:50.684013Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.24"} Apr 13 22:21:50.818463 etcd-wrapper[1669]: {"level":"warn","ts":"2026-04-13T22:21:50.684544Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} Apr 13 22:21:50.818463 etcd-wrapper[1669]: {"level":"warn","ts":"2026-04-13T22:21:50.684554Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} Apr 13 22:21:50.818463 etcd-wrapper[1669]: {"level":"warn","ts":"2026-04-13T22:21:50.689332Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Apr 13 22:21:50.818463 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:50.764813Z","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.99:2379"]} Apr 13 22:21:50.818463 etcd-wrapper[1669]: {"level":"warn","ts":"2026-04-13T22:21:50.785182Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Apr 13 22:21:50.824989 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:50.804280Z","caller":"embed/etcd.go:140","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]} Apr 13 22:21:50.970382 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:50.939352Z","caller":"embed/etcd.go:148","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} Apr 13 22:21:50.993082 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:50.973697Z","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":"2224627589184d62be2825a039c455f6","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.99:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"2224627589184d62be2825a039c455f6=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"} Apr 13 22:21:51.102422 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:51.064223Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"79.074398ms"} Apr 13 22:21:51.208412 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:51.189910Z","caller":"etcdserver/raft.go:507","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"} Apr 13 22:21:51.269253 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:51.196262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"} Apr 13 22:21:51.269253 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:51.219918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"} Apr 13 22:21:51.269253 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:51.231362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} Apr 13 22:21:51.269253 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:51.249503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"} Apr 13 22:21:51.269253 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:51.250076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Apr 13 22:21:51.295023 etcd-wrapper[1669]: {"level":"warn","ts":"2026-04-13T22:21:51.278766Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} Apr 13 22:21:51.359191 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:51.347045Z","caller":"mvcc/kvstore.go:425","msg":"kvstore restored","current-rev":1} Apr 13 22:21:51.359191 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:51.347526Z","caller":"etcdserver/server.go:628","msg":"restore consistentIndex","index":0} Apr 13 22:21:51.382398 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:51.370110Z","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"} Apr 13 22:21:51.407038 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:51.396768Z","caller":"etcdserver/server.go:875","msg":"starting etcd server","local-member-id":"8e9e05c52164694d","local-server-version":"3.5.24","cluster-version":"to_be_decided"} Apr 13 22:21:51.407038 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:51.397933Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Apr 13 22:21:51.407038 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:51.400170Z","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"} Apr 13 22:21:51.407038 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:51.400218Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} Apr 13 22:21:51.407038 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:51.400506Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} Apr 13 22:21:51.407038 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:51.400520Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} Apr 13 22:21:51.426571 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:51.419214Z","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.99:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} Apr 13 22:21:51.429317 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:51.419314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Apr 13 22:21:51.429317 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:51.419383Z","caller":"embed/etcd.go:633","msg":"serving peer traffic","address":"127.0.0.1:2380"} Apr 13 22:21:51.429317 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:51.428096Z","caller":"embed/etcd.go:603","msg":"cmux::serve","address":"127.0.0.1:2380"} Apr 13 22:21:51.429317 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:51.428405Z","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} Apr 13 22:21:51.917836 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:51.889220Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"} Apr 13 22:21:51.920565 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:51.919114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"} Apr 13 22:21:51.920565 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:51.919826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"} Apr 13 22:21:51.920565 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:51.919854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"} Apr 13 22:21:51.920565 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:51.919860Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"} Apr 13 22:21:51.920565 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:51.919870Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"} Apr 13 22:21:51.920565 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:51.919878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"} Apr 13 22:21:51.941733 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:51.930618Z","caller":"etcdserver/server.go:2711","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} Apr 13 22:21:51.959533 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:51.950458Z","caller":"embed/serve.go:124","msg":"ready to serve client requests"} Apr 13 22:21:51.981841 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:51.963166Z","caller":"etcdserver/server.go:2158","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:2224627589184d62be2825a039c455f6 ClientURLs:[http://10.0.0.99:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"} Apr 13 22:21:51.992289 systemd[1]: Started etcd-member.service - etcd (System Application Container). Apr 13 22:21:51.998034 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:51.965006Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Apr 13 22:21:51.998034 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:51.961193Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} Apr 13 22:21:51.998034 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:51.981200Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} Apr 13 22:21:51.993230 systemd[1]: Reached target multi-user.target - Multi-User System. Apr 13 22:21:52.011402 systemd[1]: Startup finished in 2.769s (kernel) + 15.148s (initrd) + 1min 51.811s (userspace) = 2min 9.729s. Apr 13 22:21:52.030005 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:52.020849Z","caller":"membership/cluster.go:587","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"} Apr 13 22:21:52.030005 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:51.965421Z","caller":"embed/serve.go:210","msg":"serving client traffic insecurely; this is strongly discouraged!","traffic":"grpc+http","address":"[::]:2379"} Apr 13 22:21:52.060900 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:52.043056Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} Apr 13 22:21:52.075944 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:21:52.065328Z","caller":"etcdserver/server.go:2735","msg":"cluster version is updated","cluster-version":"3.5"} Apr 13 22:21:59.628558 systemd[1]: Started sshd@17-10.0.0.99:22-10.0.0.1:59942.service - OpenSSH per-connection server daemon (10.0.0.1:59942). Apr 13 22:21:59.935030 sshd[1925]: Accepted publickey for core from 10.0.0.1 port 59942 ssh2: RSA SHA256:W5N50Zpm460ysbmW58qL6krVZjeW4Y8kSGRetRBMpjQ Apr 13 22:21:59.949676 sshd[1925]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 13 22:22:00.096013 systemd-logind[1396]: New session 18 of user core. Apr 13 22:22:00.183004 systemd[1]: Started session-18.scope - Session 18 of User core. Apr 13 22:22:00.575253 sshd[1925]: pam_unix(sshd:session): session closed for user core Apr 13 22:22:00.688410 systemd[1]: sshd@17-10.0.0.99:22-10.0.0.1:59942.service: Deactivated successfully. Apr 13 22:22:00.704049 systemd[1]: session-18.scope: Deactivated successfully. Apr 13 22:22:00.749598 systemd-logind[1396]: Session 18 logged out. Waiting for processes to exit. Apr 13 22:22:00.782513 systemd[1]: Started sshd@18-10.0.0.99:22-10.0.0.1:59944.service - OpenSSH per-connection server daemon (10.0.0.1:59944). Apr 13 22:22:00.804819 systemd-logind[1396]: Removed session 18. Apr 13 22:22:01.075508 sshd[1932]: Accepted publickey for core from 10.0.0.1 port 59944 ssh2: RSA SHA256:W5N50Zpm460ysbmW58qL6krVZjeW4Y8kSGRetRBMpjQ Apr 13 22:22:01.086053 sshd[1932]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 13 22:22:01.272146 systemd-logind[1396]: New session 19 of user core. Apr 13 22:22:01.306104 systemd[1]: Started session-19.scope - Session 19 of User core. Apr 13 22:22:01.499692 sshd[1932]: pam_unix(sshd:session): session closed for user core Apr 13 22:22:01.622825 systemd[1]: Started sshd@19-10.0.0.99:22-10.0.0.1:59952.service - OpenSSH per-connection server daemon (10.0.0.1:59952). Apr 13 22:22:01.644797 systemd[1]: sshd@18-10.0.0.99:22-10.0.0.1:59944.service: Deactivated successfully. Apr 13 22:22:01.671089 systemd[1]: session-19.scope: Deactivated successfully. Apr 13 22:22:01.714658 systemd-logind[1396]: Session 19 logged out. Waiting for processes to exit. Apr 13 22:22:01.759233 systemd-logind[1396]: Removed session 19. Apr 13 22:22:02.082810 sshd[1937]: Accepted publickey for core from 10.0.0.1 port 59952 ssh2: RSA SHA256:W5N50Zpm460ysbmW58qL6krVZjeW4Y8kSGRetRBMpjQ Apr 13 22:22:02.130275 sshd[1937]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 13 22:22:02.287191 systemd-logind[1396]: New session 20 of user core. Apr 13 22:22:02.325687 systemd[1]: Started session-20.scope - Session 20 of User core. Apr 13 22:22:02.585917 sshd[1937]: pam_unix(sshd:session): session closed for user core Apr 13 22:22:02.667189 systemd[1]: Started sshd@20-10.0.0.99:22-10.0.0.1:59962.service - OpenSSH per-connection server daemon (10.0.0.1:59962). Apr 13 22:22:02.671804 systemd[1]: sshd@19-10.0.0.99:22-10.0.0.1:59952.service: Deactivated successfully. Apr 13 22:22:02.687153 systemd[1]: session-20.scope: Deactivated successfully. Apr 13 22:22:02.710516 systemd-logind[1396]: Session 20 logged out. Waiting for processes to exit. Apr 13 22:22:02.721716 systemd-logind[1396]: Removed session 20. Apr 13 22:22:03.048090 sshd[1944]: Accepted publickey for core from 10.0.0.1 port 59962 ssh2: RSA SHA256:W5N50Zpm460ysbmW58qL6krVZjeW4Y8kSGRetRBMpjQ Apr 13 22:22:03.073956 sshd[1944]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 13 22:22:03.239811 systemd-logind[1396]: New session 21 of user core. Apr 13 22:22:03.266164 systemd[1]: Started session-21.scope - Session 21 of User core. Apr 13 22:22:03.508145 sshd[1944]: pam_unix(sshd:session): session closed for user core Apr 13 22:22:03.658585 systemd[1]: sshd@20-10.0.0.99:22-10.0.0.1:59962.service: Deactivated successfully. Apr 13 22:22:03.664750 systemd[1]: session-21.scope: Deactivated successfully. Apr 13 22:22:03.673367 systemd-logind[1396]: Session 21 logged out. Waiting for processes to exit. Apr 13 22:22:03.713937 systemd[1]: Started sshd@21-10.0.0.99:22-10.0.0.1:59964.service - OpenSSH per-connection server daemon (10.0.0.1:59964). Apr 13 22:22:03.729552 systemd-logind[1396]: Removed session 21. Apr 13 22:22:03.971825 sshd[1953]: Accepted publickey for core from 10.0.0.1 port 59964 ssh2: RSA SHA256:W5N50Zpm460ysbmW58qL6krVZjeW4Y8kSGRetRBMpjQ Apr 13 22:22:03.989034 sshd[1953]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 13 22:22:04.101915 systemd-logind[1396]: New session 22 of user core. Apr 13 22:22:04.132683 systemd[1]: Started session-22.scope - Session 22 of User core. Apr 13 22:22:05.133241 sshd[1953]: pam_unix(sshd:session): session closed for user core Apr 13 22:22:05.340701 systemd[1]: sshd@21-10.0.0.99:22-10.0.0.1:59964.service: Deactivated successfully. Apr 13 22:22:05.370549 systemd[1]: session-22.scope: Deactivated successfully. Apr 13 22:22:05.375074 systemd-logind[1396]: Session 22 logged out. Waiting for processes to exit. Apr 13 22:22:05.383667 systemd-logind[1396]: Removed session 22. Apr 13 22:27:43.322227 update_engine[1401]: I20260413 22:27:43.319317 1401 prefs.cc:52] certificate-report-to-send-update not present in /var/lib/update_engine/prefs Apr 13 22:27:43.322227 update_engine[1401]: I20260413 22:27:43.319406 1401 prefs.cc:52] certificate-report-to-send-download not present in /var/lib/update_engine/prefs Apr 13 22:27:43.342907 update_engine[1401]: I20260413 22:27:43.333911 1401 prefs.cc:52] aleph-version not present in /var/lib/update_engine/prefs Apr 13 22:27:43.342907 update_engine[1401]: I20260413 22:27:43.334646 1401 omaha_request_params.cc:62] Current group set to lts Apr 13 22:27:43.342907 update_engine[1401]: I20260413 22:27:43.341571 1401 update_attempter.cc:499] Already updated boot flags. Skipping. Apr 13 22:27:43.342907 update_engine[1401]: I20260413 22:27:43.341629 1401 update_attempter.cc:643] Scheduling an action processor start. Apr 13 22:27:43.342907 update_engine[1401]: I20260413 22:27:43.341715 1401 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Apr 13 22:27:43.342907 update_engine[1401]: I20260413 22:27:43.342898 1401 prefs.cc:52] previous-version not present in /var/lib/update_engine/prefs Apr 13 22:27:43.344660 update_engine[1401]: I20260413 22:27:43.343803 1401 omaha_request_action.cc:271] Posting an Omaha request to disabled Apr 13 22:27:43.344660 update_engine[1401]: I20260413 22:27:43.343883 1401 omaha_request_action.cc:272] Request: Apr 13 22:27:43.344660 update_engine[1401]: Apr 13 22:27:43.344660 update_engine[1401]: Apr 13 22:27:43.344660 update_engine[1401]: Apr 13 22:27:43.344660 update_engine[1401]: Apr 13 22:27:43.344660 update_engine[1401]: Apr 13 22:27:43.344660 update_engine[1401]: Apr 13 22:27:43.344660 update_engine[1401]: Apr 13 22:27:43.344660 update_engine[1401]: Apr 13 22:27:43.344660 update_engine[1401]: I20260413 22:27:43.343915 1401 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Apr 13 22:27:43.357135 locksmithd[1447]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_CHECKING_FOR_UPDATE" NewVersion=0.0.0 NewSize=0 Apr 13 22:27:43.358867 update_engine[1401]: I20260413 22:27:43.357594 1401 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Apr 13 22:27:43.358867 update_engine[1401]: I20260413 22:27:43.358196 1401 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Apr 13 22:27:43.371033 update_engine[1401]: E20260413 22:27:43.370852 1401 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Apr 13 22:27:43.371976 update_engine[1401]: I20260413 22:27:43.371095 1401 libcurl_http_fetcher.cc:283] No HTTP response, retry 1 Apr 13 22:27:53.298351 update_engine[1401]: I20260413 22:27:53.293165 1401 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Apr 13 22:27:53.304052 update_engine[1401]: I20260413 22:27:53.303544 1401 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Apr 13 22:27:53.304052 update_engine[1401]: I20260413 22:27:53.303875 1401 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Apr 13 22:27:53.331164 update_engine[1401]: E20260413 22:27:53.330410 1401 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Apr 13 22:27:53.331164 update_engine[1401]: I20260413 22:27:53.330673 1401 libcurl_http_fetcher.cc:283] No HTTP response, retry 2 Apr 13 22:28:03.298995 update_engine[1401]: I20260413 22:28:03.297871 1401 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Apr 13 22:28:03.309171 update_engine[1401]: I20260413 22:28:03.308327 1401 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Apr 13 22:28:03.309171 update_engine[1401]: I20260413 22:28:03.308665 1401 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Apr 13 22:28:03.331384 update_engine[1401]: E20260413 22:28:03.331089 1401 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Apr 13 22:28:03.331384 update_engine[1401]: I20260413 22:28:03.331382 1401 libcurl_http_fetcher.cc:283] No HTTP response, retry 3 Apr 13 22:28:13.297206 update_engine[1401]: I20260413 22:28:13.296597 1401 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Apr 13 22:28:13.298152 update_engine[1401]: I20260413 22:28:13.297916 1401 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Apr 13 22:28:13.309199 update_engine[1401]: I20260413 22:28:13.308341 1401 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Apr 13 22:28:13.332707 update_engine[1401]: E20260413 22:28:13.330955 1401 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Apr 13 22:28:13.343079 update_engine[1401]: I20260413 22:28:13.338197 1401 libcurl_http_fetcher.cc:297] Transfer resulted in an error (0), 0 bytes downloaded Apr 13 22:28:13.345693 update_engine[1401]: I20260413 22:28:13.342195 1401 omaha_request_action.cc:617] Omaha request response: Apr 13 22:28:13.352093 update_engine[1401]: E20260413 22:28:13.351081 1401 omaha_request_action.cc:636] Omaha request network transfer failed. Apr 13 22:28:13.354268 update_engine[1401]: I20260413 22:28:13.354143 1401 action_processor.cc:68] ActionProcessor::ActionComplete: OmahaRequestAction action failed. Aborting processing. Apr 13 22:28:13.354268 update_engine[1401]: I20260413 22:28:13.354216 1401 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Apr 13 22:28:13.354268 update_engine[1401]: I20260413 22:28:13.354225 1401 update_attempter.cc:306] Processing Done. Apr 13 22:28:13.355014 update_engine[1401]: E20260413 22:28:13.354275 1401 update_attempter.cc:619] Update failed. Apr 13 22:28:13.355014 update_engine[1401]: I20260413 22:28:13.354295 1401 utils.cc:600] Converting error code 2000 to kActionCodeOmahaErrorInHTTPResponse Apr 13 22:28:13.355014 update_engine[1401]: I20260413 22:28:13.354300 1401 payload_state.cc:97] Updating payload state for error code: 37 (kActionCodeOmahaErrorInHTTPResponse) Apr 13 22:28:13.355014 update_engine[1401]: I20260413 22:28:13.354306 1401 payload_state.cc:103] Ignoring failures until we get a valid Omaha response. Apr 13 22:28:13.367091 update_engine[1401]: I20260413 22:28:13.365032 1401 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Apr 13 22:28:13.367091 update_engine[1401]: I20260413 22:28:13.365279 1401 omaha_request_action.cc:271] Posting an Omaha request to disabled Apr 13 22:28:13.367091 update_engine[1401]: I20260413 22:28:13.365288 1401 omaha_request_action.cc:272] Request: Apr 13 22:28:13.367091 update_engine[1401]: Apr 13 22:28:13.367091 update_engine[1401]: Apr 13 22:28:13.367091 update_engine[1401]: Apr 13 22:28:13.367091 update_engine[1401]: Apr 13 22:28:13.367091 update_engine[1401]: Apr 13 22:28:13.367091 update_engine[1401]: Apr 13 22:28:13.367091 update_engine[1401]: I20260413 22:28:13.365298 1401 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Apr 13 22:28:13.368158 locksmithd[1447]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_REPORTING_ERROR_EVENT" NewVersion=0.0.0 NewSize=0 Apr 13 22:28:13.372188 update_engine[1401]: I20260413 22:28:13.368768 1401 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Apr 13 22:28:13.372188 update_engine[1401]: I20260413 22:28:13.371843 1401 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Apr 13 22:28:13.400287 update_engine[1401]: E20260413 22:28:13.396474 1401 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Apr 13 22:28:13.402256 update_engine[1401]: I20260413 22:28:13.401678 1401 libcurl_http_fetcher.cc:297] Transfer resulted in an error (0), 0 bytes downloaded Apr 13 22:28:13.403816 update_engine[1401]: I20260413 22:28:13.402544 1401 omaha_request_action.cc:617] Omaha request response: Apr 13 22:28:13.408872 update_engine[1401]: I20260413 22:28:13.408119 1401 action_processor.cc:65] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Apr 13 22:28:13.413737 update_engine[1401]: I20260413 22:28:13.412228 1401 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Apr 13 22:28:13.413737 update_engine[1401]: I20260413 22:28:13.412278 1401 update_attempter.cc:306] Processing Done. Apr 13 22:28:13.413737 update_engine[1401]: I20260413 22:28:13.412288 1401 update_attempter.cc:310] Error event sent. Apr 13 22:28:13.413737 update_engine[1401]: I20260413 22:28:13.412418 1401 update_check_scheduler.cc:74] Next update check in 44m16s Apr 13 22:28:13.417510 locksmithd[1447]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_IDLE" NewVersion=0.0.0 NewSize=0 Apr 13 22:34:53.568698 systemd[1]: Starting systemd-tmpfiles-clean.service - Cleanup of Temporary Directories... Apr 13 22:34:53.697153 systemd-tmpfiles[1975]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Apr 13 22:34:53.698258 systemd-tmpfiles[1975]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Apr 13 22:34:53.700618 systemd-tmpfiles[1975]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Apr 13 22:34:53.701028 systemd-tmpfiles[1975]: ACLs are not supported, ignoring. Apr 13 22:34:53.701105 systemd-tmpfiles[1975]: ACLs are not supported, ignoring. Apr 13 22:34:53.719347 systemd-tmpfiles[1975]: Detected autofs mount point /boot during canonicalization of boot. Apr 13 22:34:53.719856 systemd-tmpfiles[1975]: Skipping /boot Apr 13 22:34:53.753318 systemd[1]: systemd-tmpfiles-clean.service: Deactivated successfully. Apr 13 22:34:53.753636 systemd[1]: Finished systemd-tmpfiles-clean.service - Cleanup of Temporary Directories. Apr 13 22:49:02.541072 etcd-wrapper[1669]: {"level":"warn","ts":"2026-04-13T22:49:02.505559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.411594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourceclaimtemplates\" limit:1 ","response":"range_response_count:0 size:4"} Apr 13 22:49:02.541072 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:49:02.506231Z","caller":"traceutil/trace.go:171","msg":"trace[1344594708] range","detail":"{range_begin:/registry/resourceclaimtemplates; range_end:; response_count:0; response_revision:1; }","duration":"106.739831ms","start":"2026-04-13T22:49:02.399453Z","end":"2026-04-13T22:49:02.506193Z","steps":["trace[1344594708] 'agreement among raft nodes before linearized reading' (duration: 105.354622ms)"],"step_count":1} Apr 13 22:49:02.562556 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:49:02.518186Z","caller":"traceutil/trace.go:171","msg":"trace[1419419158] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:5; }","duration":"111.911478ms","start":"2026-04-13T22:49:02.405765Z","end":"2026-04-13T22:49:02.517677Z","steps":["trace[1419419158] 'read index received' (duration: 12.589189ms)","trace[1419419158] 'applied index is now lower than readState.Index' (duration: 55.282905ms)"],"step_count":2} Apr 13 22:51:13.195971 etcd-wrapper[1669]: 2026/04/13 22:51:13 WARNING: [core] [Server #5]grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" Apr 13 22:59:06.029767 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:59:06.012632Z","caller":"traceutil/trace.go:171","msg":"trace[1086899786] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:10; }","duration":"107.157068ms","start":"2026-04-13T22:59:05.905469Z","end":"2026-04-13T22:59:06.012626Z","steps":["trace[1086899786] 'read index received' (duration: 7.004648ms)","trace[1086899786] 'applied index is now lower than readState.Index' (duration: 100.151887ms)"],"step_count":2} Apr 13 22:59:06.029767 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:59:06.012720Z","caller":"traceutil/trace.go:171","msg":"trace[229112003] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"102.103537ms","start":"2026-04-13T22:59:05.910610Z","end":"2026-04-13T22:59:06.012714Z","steps":["trace[229112003] 'process raft request' (duration: 101.536151ms)"],"step_count":1} Apr 13 22:59:06.029767 etcd-wrapper[1669]: {"level":"warn","ts":"2026-04-13T22:59:06.012912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.415613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" limit:1 ","response":"range_response_count:1 size:117"} Apr 13 22:59:06.029767 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:59:06.012937Z","caller":"traceutil/trace.go:171","msg":"trace[1098365704] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"102.268374ms","start":"2026-04-13T22:59:05.910660Z","end":"2026-04-13T22:59:06.012929Z","steps":["trace[1098365704] 'process raft request' (duration: 101.605382ms)"],"step_count":1} Apr 13 22:59:06.029767 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:59:06.012610Z","caller":"traceutil/trace.go:171","msg":"trace[126278828] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"102.067507ms","start":"2026-04-13T22:59:05.910523Z","end":"2026-04-13T22:59:06.012590Z","steps":["trace[126278828] 'process raft request' (duration: 89.6508ms)","trace[126278828] 'compare' (duration: 11.615592ms)"],"step_count":2} Apr 13 22:59:06.038117 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:59:06.012942Z","caller":"traceutil/trace.go:171","msg":"trace[99635906] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:16; }","duration":"107.470531ms","start":"2026-04-13T22:59:05.905465Z","end":"2026-04-13T22:59:06.012936Z","steps":["trace[99635906] 'agreement among raft nodes before linearized reading' (duration: 107.383161ms)"],"step_count":1} Apr 13 22:59:06.038117 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:59:06.012577Z","caller":"traceutil/trace.go:171","msg":"trace[852520472] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"101.764097ms","start":"2026-04-13T22:59:05.910790Z","end":"2026-04-13T22:59:06.012554Z","steps":["trace[852520472] 'process raft request' (duration: 101.520052ms)"],"step_count":1} Apr 13 22:59:06.038117 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:59:06.013045Z","caller":"traceutil/trace.go:171","msg":"trace[828976086] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"102.320438ms","start":"2026-04-13T22:59:05.910717Z","end":"2026-04-13T22:59:06.013038Z","steps":["trace[828976086] 'process raft request' (duration: 101.571715ms)"],"step_count":1} Apr 13 22:59:14.612282 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:59:14.595792Z","caller":"traceutil/trace.go:171","msg":"trace[1996276041] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"120.503437ms","start":"2026-04-13T22:59:14.466792Z","end":"2026-04-13T22:59:14.587295Z","steps":["trace[1996276041] 'process raft request' (duration: 51.558003ms)","trace[1996276041] 'compare' (duration: 68.806716ms)"],"step_count":2} Apr 13 22:59:27.371814 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:59:27.341063Z","caller":"traceutil/trace.go:171","msg":"trace[247546440] linearizableReadLoop","detail":"{readStateIndex:102; appliedIndex:101; }","duration":"134.391177ms","start":"2026-04-13T22:59:27.206579Z","end":"2026-04-13T22:59:27.340970Z","steps":["trace[247546440] 'read index received' (duration: 11.07484ms)","trace[247546440] 'applied index is now lower than readState.Index' (duration: 123.315471ms)"],"step_count":2} Apr 13 22:59:27.371814 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:59:27.342389Z","caller":"traceutil/trace.go:171","msg":"trace[1452962892] transaction","detail":"{read_only:false; response_revision:95; number_of_response:1; }","duration":"138.450653ms","start":"2026-04-13T22:59:27.203841Z","end":"2026-04-13T22:59:27.342291Z","steps":["trace[1452962892] 'process raft request' (duration: 57.721044ms)","trace[1452962892] 'compare' (duration: 67.893653ms)","trace[1452962892] 'store kv pair into bolt db' {req_type:put; key:/registry/clusterroles/system:aggregate-to-admin; req_size:824; } (duration: 11.26069ms)"],"step_count":3} Apr 13 22:59:27.371814 etcd-wrapper[1669]: {"level":"warn","ts":"2026-04-13T22:59:27.355254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.284788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/localhost.18a60c9c5c0e2b8f\" limit:1 ","response":"range_response_count:1 size:661"} Apr 13 22:59:27.371814 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:59:27.355396Z","caller":"traceutil/trace.go:171","msg":"trace[1895473603] range","detail":"{range_begin:/registry/events/default/localhost.18a60c9c5c0e2b8f; range_end:; response_count:1; response_revision:95; }","duration":"107.476731ms","start":"2026-04-13T22:59:27.247906Z","end":"2026-04-13T22:59:27.355382Z","steps":["trace[1895473603] 'agreement among raft nodes before linearized reading' (duration: 107.221939ms)"],"step_count":1} Apr 13 22:59:27.394629 etcd-wrapper[1669]: {"level":"warn","ts":"2026-04-13T22:59:27.342637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.966829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} Apr 13 22:59:27.394629 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T22:59:27.355495Z","caller":"traceutil/trace.go:171","msg":"trace[960708512] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:95; }","duration":"151.838663ms","start":"2026-04-13T22:59:27.203652Z","end":"2026-04-13T22:59:27.355490Z","steps":["trace[960708512] 'agreement among raft nodes before linearized reading' (duration: 138.807299ms)"],"step_count":1} Apr 13 23:02:37.802162 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:02:37.791104Z","caller":"traceutil/trace.go:171","msg":"trace[2088383954] transaction","detail":"{read_only:false; response_revision:193; number_of_response:1; }","duration":"101.747975ms","start":"2026-04-13T23:02:37.689261Z","end":"2026-04-13T23:02:37.791009Z","steps":["trace[2088383954] 'process raft request' (duration: 49.243795ms)"],"step_count":1} Apr 13 23:02:37.834855 etcd-wrapper[1669]: {"level":"warn","ts":"2026-04-13T23:02:37.808582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.101542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints\" limit:1 ","response":"range_response_count:0 size:5"} Apr 13 23:02:37.834855 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:02:37.810714Z","caller":"traceutil/trace.go:171","msg":"trace[1849439088] range","detail":"{range_begin:/registry/services/endpoints; range_end:; response_count:0; response_revision:193; }","duration":"121.431662ms","start":"2026-04-13T23:02:37.689178Z","end":"2026-04-13T23:02:37.810610Z","steps":["trace[1849439088] 'agreement among raft nodes before linearized reading' (duration: 99.33509ms)","trace[1849439088] 'range keys from in-memory index tree' (duration: 18.704724ms)"],"step_count":2} Apr 13 23:02:37.850222 etcd-wrapper[1669]: {"level":"warn","ts":"2026-04-13T23:02:37.828645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.995369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints\" limit:1 ","response":"range_response_count:0 size:5"} Apr 13 23:02:37.850222 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:02:37.828845Z","caller":"traceutil/trace.go:171","msg":"trace[744986054] range","detail":"{range_begin:/registry/services/endpoints; range_end:; response_count:0; response_revision:193; }","duration":"139.255028ms","start":"2026-04-13T23:02:37.689533Z","end":"2026-04-13T23:02:37.828788Z","steps":["trace[744986054] 'agreement among raft nodes before linearized reading' (duration: 101.819571ms)","trace[744986054] 'range keys from in-memory index tree' (duration: 37.100673ms)"],"step_count":2} Apr 13 23:02:37.850222 etcd-wrapper[1669]: {"level":"warn","ts":"2026-04-13T23:02:37.829090Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.591266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods\" limit:1 ","response":"range_response_count:0 size:5"} Apr 13 23:02:37.850222 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:02:37.829140Z","caller":"traceutil/trace.go:171","msg":"trace[464436395] range","detail":"{range_begin:/registry/pods; range_end:; response_count:0; response_revision:193; }","duration":"139.603416ms","start":"2026-04-13T23:02:37.689493Z","end":"2026-04-13T23:02:37.829097Z","steps":["trace[464436395] 'agreement among raft nodes before linearized reading' (duration: 101.861264ms)","trace[464436395] 'range keys from in-memory index tree' (duration: 37.726723ms)"],"step_count":2} Apr 13 23:02:37.850222 etcd-wrapper[1669]: {"level":"warn","ts":"2026-04-13T23:02:37.829178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.709177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets\" limit:1 ","response":"range_response_count:0 size:5"} Apr 13 23:02:37.851454 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:02:37.829189Z","caller":"traceutil/trace.go:171","msg":"trace[1361370745] range","detail":"{range_begin:/registry/daemonsets; range_end:; response_count:0; response_revision:193; }","duration":"139.721754ms","start":"2026-04-13T23:02:37.689463Z","end":"2026-04-13T23:02:37.829184Z","steps":["trace[1361370745] 'agreement among raft nodes before linearized reading' (duration: 101.894307ms)","trace[1361370745] 'range keys from in-memory index tree' (duration: 37.807196ms)"],"step_count":2} Apr 13 23:02:37.851454 etcd-wrapper[1669]: {"level":"warn","ts":"2026-04-13T23:02:37.829220Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.923825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingadmissionpolicies\" limit:1 ","response":"range_response_count:0 size:5"} Apr 13 23:02:37.851454 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:02:37.829229Z","caller":"traceutil/trace.go:171","msg":"trace[203582297] range","detail":"{range_begin:/registry/validatingadmissionpolicies; range_end:; response_count:0; response_revision:193; }","duration":"139.932182ms","start":"2026-04-13T23:02:37.689293Z","end":"2026-04-13T23:02:37.829225Z","steps":["trace[203582297] 'agreement among raft nodes before linearized reading' (duration: 102.067556ms)","trace[203582297] 'range keys from in-memory index tree' (duration: 37.851705ms)"],"step_count":2} Apr 13 23:02:37.851454 etcd-wrapper[1669]: {"level":"warn","ts":"2026-04-13T23:02:37.829252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.917183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests\" limit:1 ","response":"range_response_count:0 size:5"} Apr 13 23:02:37.853177 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:02:37.829261Z","caller":"traceutil/trace.go:171","msg":"trace[1701242988] range","detail":"{range_begin:/registry/certificatesigningrequests; range_end:; response_count:0; response_revision:193; }","duration":"139.924858ms","start":"2026-04-13T23:02:37.689332Z","end":"2026-04-13T23:02:37.829257Z","steps":["trace[1701242988] 'agreement among raft nodes before linearized reading' (duration: 102.029701ms)","trace[1701242988] 'range keys from in-memory index tree' (duration: 37.885641ms)"],"step_count":2} Apr 13 23:02:37.853177 etcd-wrapper[1669]: {"level":"warn","ts":"2026-04-13T23:02:37.829357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.093597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourceclaims\" limit:1 ","response":"range_response_count:0 size:5"} Apr 13 23:02:37.853177 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:02:37.829367Z","caller":"traceutil/trace.go:171","msg":"trace[1288424038] range","detail":"{range_begin:/registry/resourceclaims; range_end:; response_count:0; response_revision:193; }","duration":"140.101926ms","start":"2026-04-13T23:02:37.689261Z","end":"2026-04-13T23:02:37.829362Z","steps":["trace[1288424038] 'agreement among raft nodes before linearized reading' (duration: 102.106445ms)","trace[1288424038] 'range keys from in-memory index tree' (duration: 37.984745ms)"],"step_count":2} Apr 13 23:02:37.853177 etcd-wrapper[1669]: {"level":"warn","ts":"2026-04-13T23:02:37.823460Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.142867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets\" limit:1 ","response":"range_response_count:0 size:5"} Apr 13 23:02:37.853465 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:02:37.829395Z","caller":"traceutil/trace.go:171","msg":"trace[1016652423] range","detail":"{range_begin:/registry/statefulsets; range_end:; response_count:0; response_revision:193; }","duration":"140.165316ms","start":"2026-04-13T23:02:37.689226Z","end":"2026-04-13T23:02:37.829391Z","steps":["trace[1016652423] 'agreement among raft nodes before linearized reading' (duration: 102.144175ms)","trace[1016652423] 'range keys from in-memory index tree' (duration: 31.935244ms)"],"step_count":2} Apr 13 23:02:37.853465 etcd-wrapper[1669]: {"level":"warn","ts":"2026-04-13T23:02:37.835168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.252067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes\" limit:1 ","response":"range_response_count:0 size:5"} Apr 13 23:02:37.853465 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:02:37.835781Z","caller":"traceutil/trace.go:171","msg":"trace[1671631909] range","detail":"{range_begin:/registry/csinodes; range_end:; response_count:0; response_revision:193; }","duration":"134.095848ms","start":"2026-04-13T23:02:37.701669Z","end":"2026-04-13T23:02:37.835765Z","steps":["trace[1671631909] 'agreement among raft nodes before linearized reading' (duration: 89.598292ms)","trace[1671631909] 'range keys from in-memory index tree' (duration: 43.638945ms)"],"step_count":2} Apr 13 23:02:37.853465 etcd-wrapper[1669]: {"level":"warn","ts":"2026-04-13T23:02:37.835947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.302047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipaddresses\" limit:1 ","response":"range_response_count:0 size:5"} Apr 13 23:02:37.853465 etcd-wrapper[1669]: {"level":"warn","ts":"2026-04-13T23:02:37.835883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.81893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses\" limit:1 ","response":"range_response_count:0 size:5"} Apr 13 23:02:37.853692 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:02:37.835969Z","caller":"traceutil/trace.go:171","msg":"trace[2108815765] range","detail":"{range_begin:/registry/storageclasses; range_end:; response_count:0; response_revision:193; }","duration":"133.904714ms","start":"2026-04-13T23:02:37.702059Z","end":"2026-04-13T23:02:37.835964Z","steps":["trace[2108815765] 'agreement among raft nodes before linearized reading' (duration: 89.203231ms)","trace[2108815765] 'range keys from in-memory index tree' (duration: 44.612572ms)"],"step_count":2} Apr 13 23:02:37.853692 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:02:37.835969Z","caller":"traceutil/trace.go:171","msg":"trace[1681087767] range","detail":"{range_begin:/registry/ipaddresses; range_end:; response_count:0; response_revision:193; }","duration":"134.734958ms","start":"2026-04-13T23:02:37.701224Z","end":"2026-04-13T23:02:37.835959Z","steps":["trace[1681087767] 'agreement among raft nodes before linearized reading' (duration: 90.045087ms)","trace[1681087767] 'range keys from in-memory index tree' (duration: 44.494814ms)"],"step_count":2} Apr 13 23:02:37.853692 etcd-wrapper[1669]: {"level":"warn","ts":"2026-04-13T23:02:37.835576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.73377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities\" limit:1 ","response":"range_response_count:0 size:5"} Apr 13 23:02:37.853692 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:02:37.836003Z","caller":"traceutil/trace.go:171","msg":"trace[1117140893] range","detail":"{range_begin:/registry/csistoragecapacities; range_end:; response_count:0; response_revision:193; }","duration":"130.186491ms","start":"2026-04-13T23:02:37.705811Z","end":"2026-04-13T23:02:37.835998Z","steps":["trace[1117140893] 'agreement among raft nodes before linearized reading' (duration: 85.44579ms)","trace[1117140893] 'range keys from in-memory index tree' (duration: 44.279002ms)"],"step_count":2} Apr 13 23:02:37.853910 etcd-wrapper[1669]: {"level":"warn","ts":"2026-04-13T23:02:37.841283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.552577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets\" limit:1 ","response":"range_response_count:0 size:5"} Apr 13 23:02:37.853910 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:02:37.841677Z","caller":"traceutil/trace.go:171","msg":"trace[992064448] range","detail":"{range_begin:/registry/poddisruptionbudgets; range_end:; response_count:0; response_revision:193; }","duration":"152.06322ms","start":"2026-04-13T23:02:37.689595Z","end":"2026-04-13T23:02:37.841659Z","steps":["trace[992064448] 'agreement among raft nodes before linearized reading' (duration: 101.676437ms)","trace[992064448] 'range keys from in-memory index tree' (duration: 49.862192ms)"],"step_count":2} Apr 13 23:02:37.853910 etcd-wrapper[1669]: {"level":"warn","ts":"2026-04-13T23:02:37.823388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.960282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions\" limit:1 ","response":"range_response_count:0 size:5"} Apr 13 23:02:37.853910 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:02:37.842093Z","caller":"traceutil/trace.go:171","msg":"trace[434699221] range","detail":"{range_begin:/registry/controllerrevisions; range_end:; response_count:0; response_revision:193; }","duration":"152.426227ms","start":"2026-04-13T23:02:37.689402Z","end":"2026-04-13T23:02:37.841828Z","steps":["trace[434699221] 'agreement among raft nodes before linearized reading' (duration: 101.946515ms)","trace[434699221] 'range keys from in-memory index tree' (duration: 31.994656ms)"],"step_count":2} Apr 13 23:02:38.393386 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:02:38.385489Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4} Apr 13 23:02:38.406051 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:02:38.400751Z","caller":"mvcc/kvstore_compaction.go:72","msg":"finished scheduled compaction","compact-revision":4,"took":"186.31µs","hash":1273253232,"current-db-size-bytes":360448,"current-db-size":"360 kB","current-db-size-in-use-bytes":352256,"current-db-size-in-use":"352 kB"} Apr 13 23:02:38.406051 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:02:38.401187Z","caller":"mvcc/hash.go:151","msg":"storing new hash","hash":1273253232,"revision":4,"compact-revision":-1} Apr 13 23:03:00.776710 etcd-wrapper[1669]: {"level":"warn","ts":"2026-04-13T23:03:00.714524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.455412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Apr 13 23:03:00.776710 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:03:00.735140Z","caller":"traceutil/trace.go:171","msg":"trace[267939025] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:203; }","duration":"177.201166ms","start":"2026-04-13T23:03:00.557825Z","end":"2026-04-13T23:03:00.735026Z","steps":["trace[267939025] 'agreement among raft nodes before linearized reading' (duration: 23.735351ms)","trace[267939025] 'range keys from in-memory index tree' (duration: 121.150284ms)"],"step_count":2} Apr 13 23:03:11.806414 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:03:11.793052Z","caller":"traceutil/trace.go:171","msg":"trace[527655041] transaction","detail":"{read_only:false; response_revision:222; number_of_response:1; }","duration":"103.957289ms","start":"2026-04-13T23:03:11.689048Z","end":"2026-04-13T23:03:11.793006Z","steps":["trace[527655041] 'process raft request' (duration: 61.44344ms)","trace[527655041] 'compare' (duration: 31.148044ms)"],"step_count":2} Apr 13 23:03:14.336298 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:03:14.322725Z","caller":"traceutil/trace.go:171","msg":"trace[1803957758] transaction","detail":"{read_only:false; response_revision:223; number_of_response:1; }","duration":"115.000848ms","start":"2026-04-13T23:03:14.203404Z","end":"2026-04-13T23:03:14.318405Z","steps":["trace[1803957758] 'process raft request' (duration: 108.64993ms)"],"step_count":1} Apr 13 23:04:36.018269 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:04:36.012157Z","caller":"traceutil/trace.go:171","msg":"trace[370401297] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"125.29225ms","start":"2026-04-13T23:04:35.886778Z","end":"2026-04-13T23:04:36.012070Z","steps":["trace[370401297] 'process raft request' (duration: 116.924252ms)"],"step_count":1} Apr 13 23:07:07.825706 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:07:07.775388Z","caller":"traceutil/trace.go:171","msg":"trace[2025769557] linearizableReadLoop","detail":"{readStateIndex:317; appliedIndex:316; }","duration":"109.442505ms","start":"2026-04-13T23:07:07.665842Z","end":"2026-04-13T23:07:07.775284Z","steps":["trace[2025769557] 'read index received' (duration: 27.218868ms)","trace[2025769557] 'applied index is now lower than readState.Index' (duration: 82.221375ms)"],"step_count":2} Apr 13 23:07:07.904616 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:07:07.782969Z","caller":"traceutil/trace.go:171","msg":"trace[373133716] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"115.063336ms","start":"2026-04-13T23:07:07.665959Z","end":"2026-04-13T23:07:07.781023Z","steps":["trace[373133716] 'process raft request' (duration: 35.818901ms)","trace[373133716] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/apiserver-l2yubf4cvcl5c7a3wrubrwqdve; req_size:671; } (duration: 48.917327ms)"],"step_count":2} Apr 13 23:07:07.924400 etcd-wrapper[1669]: {"level":"warn","ts":"2026-04-13T23:07:07.895269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.370726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Apr 13 23:07:07.924400 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:07:07.912372Z","caller":"traceutil/trace.go:171","msg":"trace[62929735] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:304; }","duration":"247.188407ms","start":"2026-04-13T23:07:07.664718Z","end":"2026-04-13T23:07:07.911906Z","steps":["trace[62929735] 'agreement among raft nodes before linearized reading' (duration: 117.619718ms)","trace[62929735] 'range keys from in-memory index tree' (duration: 108.688221ms)"],"step_count":2} Apr 13 23:07:32.134767 etcd-wrapper[1669]: {"level":"warn","ts":"2026-04-13T23:07:32.123028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.199284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Apr 13 23:07:32.134767 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:07:32.123600Z","caller":"traceutil/trace.go:171","msg":"trace[360048930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:312; }","duration":"130.850485ms","start":"2026-04-13T23:07:31.992673Z","end":"2026-04-13T23:07:32.123524Z","steps":["trace[360048930] 'agreement among raft nodes before linearized reading' (duration: 24.698677ms)","trace[360048930] 'range keys from in-memory index tree' (duration: 105.159921ms)"],"step_count":2} Apr 13 23:07:41.796101 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:07:41.781539Z","caller":"traceutil/trace.go:171","msg":"trace[1498185265] compact","detail":"{revision:193; response_revision:323; }","duration":"116.714814ms","start":"2026-04-13T23:07:41.628628Z","end":"2026-04-13T23:07:41.745343Z","steps":["trace[1498185265] 'process raft request' (duration: 52.103731ms)","trace[1498185265] 'check and update compact revision' (duration: 51.634553ms)"],"step_count":2} Apr 13 23:07:41.796101 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:07:41.745984Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":193} Apr 13 23:07:41.820478 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:07:41.805561Z","caller":"mvcc/kvstore_compaction.go:72","msg":"finished scheduled compaction","compact-revision":193,"took":"22.301261ms","hash":3216549430,"current-db-size-bytes":700416,"current-db-size":"700 kB","current-db-size-in-use-bytes":700416,"current-db-size-in-use":"700 kB"} Apr 13 23:07:41.820478 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:07:41.805976Z","caller":"mvcc/hash.go:151","msg":"storing new hash","hash":3216549430,"revision":193,"compact-revision":4} Apr 13 23:07:43.901364 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:07:43.874859Z","caller":"traceutil/trace.go:171","msg":"trace[1111990594] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"151.91261ms","start":"2026-04-13T23:07:43.720327Z","end":"2026-04-13T23:07:43.872239Z","steps":["trace[1111990594] 'process raft request' (duration: 40.897647ms)","trace[1111990594] 'store kv pair into bolt db' {req_type:put; key:/registry/clusterrolebindings/system:controller:job-controller; req_size:713; } (duration: 53.102656ms)"],"step_count":2} Apr 13 23:08:16.638184 etcd-wrapper[1669]: {"level":"warn","ts":"2026-04-13T23:08:16.616893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.357295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings\" limit:1 ","response":"range_response_count:0 size:5"} Apr 13 23:08:16.638184 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:08:16.617604Z","caller":"traceutil/trace.go:171","msg":"trace[1032099090] range","detail":"{range_begin:/registry/rolebindings; range_end:; response_count:0; response_revision:331; }","duration":"105.0476ms","start":"2026-04-13T23:08:16.512312Z","end":"2026-04-13T23:08:16.617360Z","steps":["trace[1032099090] 'agreement among raft nodes before linearized reading' (duration: 103.875699ms)"],"step_count":1} Apr 13 23:08:45.328852 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:08:45.306835Z","caller":"traceutil/trace.go:171","msg":"trace[110440211] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"105.683616ms","start":"2026-04-13T23:08:45.200985Z","end":"2026-04-13T23:08:45.306668Z","steps":["trace[110440211] 'process raft request' (duration: 46.131469ms)","trace[110440211] 'compare' (duration: 45.776698ms)","trace[110440211] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/events/kube-system/kube-controller-manager-localhost.18a60d1775bce1d5; req_size:851; } (duration: 12.224368ms)"],"step_count":3} Apr 13 23:08:53.563531 etcd-wrapper[1669]: {"level":"warn","ts":"2026-04-13T23:08:53.531735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.351526ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Apr 13 23:08:53.563531 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:08:53.532192Z","caller":"traceutil/trace.go:171","msg":"trace[378564087] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"175.717245ms","start":"2026-04-13T23:08:53.356455Z","end":"2026-04-13T23:08:53.532173Z","steps":["trace[378564087] 'process raft request' (duration: 54.250376ms)","trace[378564087] 'compare' (duration: 23.576946ms)","trace[378564087] 'check requests' (duration: 86.584952ms)"],"step_count":3} Apr 13 23:08:53.924164 etcd-wrapper[1669]: {"level":"warn","ts":"2026-04-13T23:08:53.874395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.890057ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Apr 13 23:08:53.924164 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:08:53.879407Z","caller":"traceutil/trace.go:171","msg":"trace[1823361823] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"520.790366ms","start":"2026-04-13T23:08:53.356456Z","end":"2026-04-13T23:08:53.877246Z","steps":["trace[1823361823] 'process raft request' (duration: 334.372145ms)","trace[1823361823] 'compare' (duration: 113.378654ms)"],"step_count":2} Apr 13 23:08:53.924164 etcd-wrapper[1669]: {"level":"warn","ts":"2026-04-13T23:08:53.880395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T23:08:53.353167Z","time spent":"526.911576ms","remote":"10.0.0.103:36582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4933,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Apr 13 23:08:54.056568 etcd-wrapper[1669]: {"level":"warn","ts":"2026-04-13T23:08:54.044182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.164792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Apr 13 23:08:54.056568 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:08:54.055022Z","caller":"traceutil/trace.go:171","msg":"trace[793834599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:341; }","duration":"179.634458ms","start":"2026-04-13T23:08:53.874892Z","end":"2026-04-13T23:08:54.054526Z","steps":["trace[793834599] 'agreement among raft nodes before linearized reading' (duration: 54.183752ms)","trace[793834599] 'range keys from in-memory index tree' (duration: 106.074962ms)"],"step_count":2} Apr 13 23:08:58.889999 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:08:58.868491Z","caller":"traceutil/trace.go:171","msg":"trace[730385956] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"215.700872ms","start":"2026-04-13T23:08:58.652461Z","end":"2026-04-13T23:08:58.868162Z","steps":["trace[730385956] 'process raft request' (duration: 183.399492ms)","trace[730385956] 'store kv pair into bolt db' {req_type:put; key:/registry/events/kube-system/kube-controller-manager-localhost.18a60d1775bce1d5; req_size:851; } (duration: 29.627632ms)"],"step_count":2} Apr 13 23:09:01.718763 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:09:01.696152Z","caller":"traceutil/trace.go:171","msg":"trace[1717602086] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"105.888596ms","start":"2026-04-13T23:09:01.590210Z","end":"2026-04-13T23:09:01.696098Z","steps":["trace[1717602086] 'process raft request' (duration: 18.637323ms)","trace[1717602086] 'check requests' (duration: 10.031313ms)","trace[1717602086] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/apiserver-l2yubf4cvcl5c7a3wrubrwqdve; req_size:671; } (duration: 68.561151ms)"],"step_count":3} Apr 13 23:09:03.899899 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:09:03.893640Z","caller":"traceutil/trace.go:171","msg":"trace[430874366] linearizableReadLoop","detail":"{readStateIndex:363; appliedIndex:362; }","duration":"109.826005ms","start":"2026-04-13T23:09:03.783632Z","end":"2026-04-13T23:09:03.893458Z","steps":["trace[430874366] 'read index received' (duration: 51.273781ms)","trace[430874366] 'applied index is now lower than readState.Index' (duration: 58.551012ms)"],"step_count":2} Apr 13 23:09:03.899899 etcd-wrapper[1669]: {"level":"warn","ts":"2026-04-13T23:09:03.894358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.706821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Apr 13 23:09:03.899899 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:09:03.894394Z","caller":"traceutil/trace.go:171","msg":"trace[21277075] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:347; }","duration":"110.7585ms","start":"2026-04-13T23:09:03.783624Z","end":"2026-04-13T23:09:03.894383Z","steps":["trace[21277075] 'agreement among raft nodes before linearized reading' (duration: 110.460794ms)"],"step_count":1} Apr 13 23:09:03.899899 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:09:03.894621Z","caller":"traceutil/trace.go:171","msg":"trace[1040459230] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"133.280104ms","start":"2026-04-13T23:09:03.761331Z","end":"2026-04-13T23:09:03.894611Z","steps":["trace[1040459230] 'process raft request' (duration: 73.89663ms)","trace[1040459230] 'compare' (duration: 46.358434ms)"],"step_count":2} Apr 13 23:09:03.996398 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:09:03.974556Z","caller":"traceutil/trace.go:171","msg":"trace[2099974554] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"125.714501ms","start":"2026-04-13T23:09:03.848770Z","end":"2026-04-13T23:09:03.974485Z","steps":["trace[2099974554] 'process raft request' (duration: 125.029876ms)"],"step_count":1} Apr 13 23:09:17.508357 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:09:17.449554Z","caller":"traceutil/trace.go:171","msg":"trace[321413042] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"119.346925ms","start":"2026-04-13T23:09:17.329418Z","end":"2026-04-13T23:09:17.448765Z","steps":["trace[321413042] 'process raft request' (duration: 97.494609ms)"],"step_count":1} Apr 13 23:12:29.380893 update_engine[1401]: I20260413 23:12:29.380173 1401 prefs.cc:52] certificate-report-to-send-update not present in /var/lib/update_engine/prefs Apr 13 23:12:29.391332 update_engine[1401]: I20260413 23:12:29.383860 1401 prefs.cc:52] certificate-report-to-send-download not present in /var/lib/update_engine/prefs Apr 13 23:12:29.391332 update_engine[1401]: I20260413 23:12:29.384328 1401 omaha_request_params.cc:62] Current group set to lts Apr 13 23:12:29.391332 update_engine[1401]: I20260413 23:12:29.385504 1401 update_attempter.cc:499] Already updated boot flags. Skipping. Apr 13 23:12:29.391332 update_engine[1401]: I20260413 23:12:29.385643 1401 update_attempter.cc:643] Scheduling an action processor start. Apr 13 23:12:29.391332 update_engine[1401]: I20260413 23:12:29.385666 1401 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Apr 13 23:12:29.391332 update_engine[1401]: I20260413 23:12:29.386554 1401 omaha_request_action.cc:271] Posting an Omaha request to disabled Apr 13 23:12:29.391332 update_engine[1401]: I20260413 23:12:29.386588 1401 omaha_request_action.cc:272] Request: Apr 13 23:12:29.391332 update_engine[1401]: Apr 13 23:12:29.391332 update_engine[1401]: Apr 13 23:12:29.391332 update_engine[1401]: Apr 13 23:12:29.391332 update_engine[1401]: Apr 13 23:12:29.391332 update_engine[1401]: Apr 13 23:12:29.391332 update_engine[1401]: Apr 13 23:12:29.391332 update_engine[1401]: Apr 13 23:12:29.391332 update_engine[1401]: Apr 13 23:12:29.391332 update_engine[1401]: I20260413 23:12:29.386595 1401 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Apr 13 23:12:29.391332 update_engine[1401]: I20260413 23:12:29.389187 1401 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Apr 13 23:12:29.397206 update_engine[1401]: I20260413 23:12:29.391458 1401 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Apr 13 23:12:29.398042 locksmithd[1447]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_CHECKING_FOR_UPDATE" NewVersion=0.0.0 NewSize=0 Apr 13 23:12:29.400128 update_engine[1401]: E20260413 23:12:29.399364 1401 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Apr 13 23:12:29.400128 update_engine[1401]: I20260413 23:12:29.399518 1401 libcurl_http_fetcher.cc:283] No HTTP response, retry 1 Apr 13 23:12:39.287706 update_engine[1401]: I20260413 23:12:39.285458 1401 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Apr 13 23:12:39.287706 update_engine[1401]: I20260413 23:12:39.287150 1401 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Apr 13 23:12:39.291288 update_engine[1401]: I20260413 23:12:39.290973 1401 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Apr 13 23:12:39.313770 update_engine[1401]: E20260413 23:12:39.312144 1401 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Apr 13 23:12:39.316234 update_engine[1401]: I20260413 23:12:39.314640 1401 libcurl_http_fetcher.cc:283] No HTTP response, retry 2 Apr 13 23:12:44.177561 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:12:44.169252Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":322} Apr 13 23:12:44.177561 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:12:44.170402Z","caller":"traceutil/trace.go:171","msg":"trace[449106232] compact","detail":"{revision:322; response_revision:389; }","duration":"101.849523ms","start":"2026-04-13T23:12:44.067841Z","end":"2026-04-13T23:12:44.169691Z","steps":["trace[449106232] 'check and update compact revision' (duration: 87.209386ms)"],"step_count":1} Apr 13 23:12:44.300295 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:12:44.287527Z","caller":"mvcc/kvstore_compaction.go:72","msg":"finished scheduled compaction","compact-revision":322,"took":"114.953393ms","hash":2907777903,"current-db-size-bytes":700416,"current-db-size":"700 kB","current-db-size-in-use-bytes":655360,"current-db-size-in-use":"655 kB"} Apr 13 23:12:44.301895 etcd-wrapper[1669]: {"level":"info","ts":"2026-04-13T23:12:44.290267Z","caller":"mvcc/hash.go:151","msg":"storing new hash","hash":2907777903,"revision":322,"compact-revision":193} Apr 13 23:12:49.295185 update_engine[1401]: I20260413 23:12:49.293919 1401 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Apr 13 23:12:49.299687 update_engine[1401]: I20260413 23:12:49.299525 1401 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Apr 13 23:12:49.299934 update_engine[1401]: I20260413 23:12:49.299887 1401 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Apr 13 23:12:49.312750 update_engine[1401]: E20260413 23:12:49.312273 1401 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Apr 13 23:12:49.313631 update_engine[1401]: I20260413 23:12:49.312945 1401 libcurl_http_fetcher.cc:283] No HTTP response, retry 3 Apr 13 23:12:59.303589 update_engine[1401]: I20260413 23:12:59.302183 1401 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Apr 13 23:12:59.303589 update_engine[1401]: I20260413 23:12:59.303174 1401 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Apr 13 23:12:59.308797 update_engine[1401]: I20260413 23:12:59.306902 1401 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Apr 13 23:12:59.323531 update_engine[1401]: E20260413 23:12:59.320785 1401 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Apr 13 23:12:59.323531 update_engine[1401]: I20260413 23:12:59.320950 1401 libcurl_http_fetcher.cc:297] Transfer resulted in an error (0), 0 bytes downloaded Apr 13 23:12:59.323531 update_engine[1401]: I20260413 23:12:59.320969 1401 omaha_request_action.cc:617] Omaha request response: Apr 13 23:12:59.323531 update_engine[1401]: E20260413 23:12:59.321188 1401 omaha_request_action.cc:636] Omaha request network transfer failed. Apr 13 23:12:59.323531 update_engine[1401]: I20260413 23:12:59.321209 1401 action_processor.cc:68] ActionProcessor::ActionComplete: OmahaRequestAction action failed. Aborting processing. Apr 13 23:12:59.323531 update_engine[1401]: I20260413 23:12:59.321213 1401 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Apr 13 23:12:59.323531 update_engine[1401]: I20260413 23:12:59.321223 1401 update_attempter.cc:306] Processing Done. Apr 13 23:12:59.323531 update_engine[1401]: E20260413 23:12:59.321236 1401 update_attempter.cc:619] Update failed. Apr 13 23:12:59.323531 update_engine[1401]: I20260413 23:12:59.321240 1401 utils.cc:600] Converting error code 2000 to kActionCodeOmahaErrorInHTTPResponse Apr 13 23:12:59.323531 update_engine[1401]: I20260413 23:12:59.321244 1401 payload_state.cc:97] Updating payload state for error code: 37 (kActionCodeOmahaErrorInHTTPResponse) Apr 13 23:12:59.323531 update_engine[1401]: I20260413 23:12:59.321249 1401 payload_state.cc:103] Ignoring failures until we get a valid Omaha response. Apr 13 23:12:59.323531 update_engine[1401]: I20260413 23:12:59.321307 1401 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Apr 13 23:12:59.323531 update_engine[1401]: I20260413 23:12:59.321324 1401 omaha_request_action.cc:271] Posting an Omaha request to disabled Apr 13 23:12:59.323531 update_engine[1401]: I20260413 23:12:59.321328 1401 omaha_request_action.cc:272] Request: Apr 13 23:12:59.323531 update_engine[1401]: Apr 13 23:12:59.323531 update_engine[1401]: Apr 13 23:12:59.323531 update_engine[1401]: Apr 13 23:12:59.328651 update_engine[1401]: Apr 13 23:12:59.328651 update_engine[1401]: Apr 13 23:12:59.328651 update_engine[1401]: Apr 13 23:12:59.328651 update_engine[1401]: I20260413 23:12:59.321336 1401 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Apr 13 23:12:59.328651 update_engine[1401]: I20260413 23:12:59.323223 1401 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Apr 13 23:12:59.328651 update_engine[1401]: I20260413 23:12:59.326061 1401 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Apr 13 23:12:59.329228 locksmithd[1447]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_REPORTING_ERROR_EVENT" NewVersion=0.0.0 NewSize=0 Apr 13 23:12:59.341328 update_engine[1401]: E20260413 23:12:59.339890 1401 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Apr 13 23:12:59.342318 update_engine[1401]: I20260413 23:12:59.341542 1401 libcurl_http_fetcher.cc:297] Transfer resulted in an error (0), 0 bytes downloaded Apr 13 23:12:59.342318 update_engine[1401]: I20260413 23:12:59.341600 1401 omaha_request_action.cc:617] Omaha request response: Apr 13 23:12:59.342318 update_engine[1401]: I20260413 23:12:59.341639 1401 action_processor.cc:65] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Apr 13 23:12:59.342318 update_engine[1401]: I20260413 23:12:59.341645 1401 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Apr 13 23:12:59.342318 update_engine[1401]: I20260413 23:12:59.341650 1401 update_attempter.cc:306] Processing Done. Apr 13 23:12:59.342318 update_engine[1401]: I20260413 23:12:59.341656 1401 update_attempter.cc:310] Error event sent. Apr 13 23:12:59.342318 update_engine[1401]: I20260413 23:12:59.341679 1401 update_check_scheduler.cc:74] Next update check in 44m11s Apr 13 23:12:59.346755 locksmithd[1447]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_IDLE" NewVersion=0.0.0 NewSize=0