May 15 12:14:42.868998 kernel: Linux version 6.12.20-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.44 p1) 2.44.0) #1 SMP PREEMPT_DYNAMIC Thu May 15 10:42:41 -00 2025 May 15 12:14:42.869027 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=48287e633374b880fa618bd42bee102ae77c50831859c6cedd6ca9e1aec3dd5c May 15 12:14:42.869040 kernel: BIOS-provided physical RAM map: May 15 12:14:42.869049 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable May 15 12:14:42.869057 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved May 15 12:14:42.869064 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved May 15 12:14:42.869074 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000009cfdbfff] usable May 15 12:14:42.869084 kernel: BIOS-e820: [mem 0x000000009cfdc000-0x000000009cffffff] reserved May 15 12:14:42.869092 kernel: BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved May 15 12:14:42.869100 kernel: BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved May 15 12:14:42.869108 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved May 15 12:14:42.869116 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved May 15 12:14:42.869124 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved May 15 12:14:42.869132 kernel: NX (Execute Disable) protection: active May 15 12:14:42.869144 kernel: APIC: Static calls initialized May 15 12:14:42.869153 kernel: SMBIOS 2.8 present. May 15 12:14:42.869161 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.16.2-debian-1.16.2-1 04/01/2014 May 15 12:14:42.869170 kernel: DMI: Memory slots populated: 1/1 May 15 12:14:42.869178 kernel: Hypervisor detected: KVM May 15 12:14:42.869187 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 May 15 12:14:42.869195 kernel: kvm-clock: using sched offset of 3757498985 cycles May 15 12:14:42.869204 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns May 15 12:14:42.869213 kernel: tsc: Detected 2794.748 MHz processor May 15 12:14:42.869224 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved May 15 12:14:42.869233 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable May 15 12:14:42.869242 kernel: last_pfn = 0x9cfdc max_arch_pfn = 0x400000000 May 15 12:14:42.869251 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs May 15 12:14:42.869260 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT May 15 12:14:42.869268 kernel: Using GB pages for direct mapping May 15 12:14:42.869277 kernel: ACPI: Early table checksum verification disabled May 15 12:14:42.869286 kernel: ACPI: RSDP 0x00000000000F59D0 000014 (v00 BOCHS ) May 15 12:14:42.869295 kernel: ACPI: RSDT 0x000000009CFE2408 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 15 12:14:42.869306 kernel: ACPI: FACP 0x000000009CFE21E8 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) May 15 12:14:42.869315 kernel: ACPI: DSDT 0x000000009CFE0040 0021A8 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 15 12:14:42.869323 kernel: ACPI: FACS 0x000000009CFE0000 000040 May 15 12:14:42.869332 kernel: ACPI: APIC 0x000000009CFE22DC 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 15 12:14:42.869341 kernel: ACPI: HPET 0x000000009CFE236C 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 15 12:14:42.869350 kernel: ACPI: MCFG 0x000000009CFE23A4 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) May 15 12:14:42.869358 kernel: ACPI: WAET 0x000000009CFE23E0 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 15 12:14:42.869367 kernel: ACPI: Reserving FACP table memory at [mem 0x9cfe21e8-0x9cfe22db] May 15 12:14:42.869382 kernel: ACPI: Reserving DSDT table memory at [mem 0x9cfe0040-0x9cfe21e7] May 15 12:14:42.869391 kernel: ACPI: Reserving FACS table memory at [mem 0x9cfe0000-0x9cfe003f] May 15 12:14:42.869400 kernel: ACPI: Reserving APIC table memory at [mem 0x9cfe22dc-0x9cfe236b] May 15 12:14:42.869409 kernel: ACPI: Reserving HPET table memory at [mem 0x9cfe236c-0x9cfe23a3] May 15 12:14:42.869418 kernel: ACPI: Reserving MCFG table memory at [mem 0x9cfe23a4-0x9cfe23df] May 15 12:14:42.869427 kernel: ACPI: Reserving WAET table memory at [mem 0x9cfe23e0-0x9cfe2407] May 15 12:14:42.869439 kernel: No NUMA configuration found May 15 12:14:42.869448 kernel: Faking a node at [mem 0x0000000000000000-0x000000009cfdbfff] May 15 12:14:42.869457 kernel: NODE_DATA(0) allocated [mem 0x9cfd4dc0-0x9cfdbfff] May 15 12:14:42.869466 kernel: Zone ranges: May 15 12:14:42.869475 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] May 15 12:14:42.869484 kernel: DMA32 [mem 0x0000000001000000-0x000000009cfdbfff] May 15 12:14:42.869493 kernel: Normal empty May 15 12:14:42.869502 kernel: Device empty May 15 12:14:42.869511 kernel: Movable zone start for each node May 15 12:14:42.869520 kernel: Early memory node ranges May 15 12:14:42.869531 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] May 15 12:14:42.869540 kernel: node 0: [mem 0x0000000000100000-0x000000009cfdbfff] May 15 12:14:42.869549 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009cfdbfff] May 15 12:14:42.869558 kernel: On node 0, zone DMA: 1 pages in unavailable ranges May 15 12:14:42.869567 kernel: On node 0, zone DMA: 97 pages in unavailable ranges May 15 12:14:42.869589 kernel: On node 0, zone DMA32: 12324 pages in unavailable ranges May 15 12:14:42.869598 kernel: ACPI: PM-Timer IO Port: 0x608 May 15 12:14:42.869607 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) May 15 12:14:42.869617 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 May 15 12:14:42.869628 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) May 15 12:14:42.869637 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) May 15 12:14:42.869646 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) May 15 12:14:42.869655 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) May 15 12:14:42.869664 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) May 15 12:14:42.869673 kernel: ACPI: Using ACPI (MADT) for SMP configuration information May 15 12:14:42.869682 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 May 15 12:14:42.869691 kernel: TSC deadline timer available May 15 12:14:42.869700 kernel: CPU topo: Max. logical packages: 1 May 15 12:14:42.869711 kernel: CPU topo: Max. logical dies: 1 May 15 12:14:42.869720 kernel: CPU topo: Max. dies per package: 1 May 15 12:14:42.869729 kernel: CPU topo: Max. threads per core: 1 May 15 12:14:42.869738 kernel: CPU topo: Num. cores per package: 4 May 15 12:14:42.869747 kernel: CPU topo: Num. threads per package: 4 May 15 12:14:42.869756 kernel: CPU topo: Allowing 4 present CPUs plus 0 hotplug CPUs May 15 12:14:42.869765 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() May 15 12:14:42.869774 kernel: kvm-guest: KVM setup pv remote TLB flush May 15 12:14:42.869783 kernel: kvm-guest: setup PV sched yield May 15 12:14:42.869792 kernel: [mem 0xc0000000-0xfed1bfff] available for PCI devices May 15 12:14:42.869804 kernel: Booting paravirtualized kernel on KVM May 15 12:14:42.869813 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns May 15 12:14:42.869822 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 May 15 12:14:42.869831 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u524288 May 15 12:14:42.869849 kernel: pcpu-alloc: s207832 r8192 d29736 u524288 alloc=1*2097152 May 15 12:14:42.869858 kernel: pcpu-alloc: [0] 0 1 2 3 May 15 12:14:42.869867 kernel: kvm-guest: PV spinlocks enabled May 15 12:14:42.869876 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) May 15 12:14:42.869887 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=48287e633374b880fa618bd42bee102ae77c50831859c6cedd6ca9e1aec3dd5c May 15 12:14:42.869899 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. May 15 12:14:42.869908 kernel: random: crng init done May 15 12:14:42.869917 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) May 15 12:14:42.869926 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) May 15 12:14:42.869935 kernel: Fallback order for Node 0: 0 May 15 12:14:42.869944 kernel: Built 1 zonelists, mobility grouping on. Total pages: 642938 May 15 12:14:42.869953 kernel: Policy zone: DMA32 May 15 12:14:42.869962 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off May 15 12:14:42.869973 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 May 15 12:14:42.869982 kernel: ftrace: allocating 40065 entries in 157 pages May 15 12:14:42.869991 kernel: ftrace: allocated 157 pages with 5 groups May 15 12:14:42.870000 kernel: Dynamic Preempt: voluntary May 15 12:14:42.870010 kernel: rcu: Preemptible hierarchical RCU implementation. May 15 12:14:42.870020 kernel: rcu: RCU event tracing is enabled. May 15 12:14:42.870029 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. May 15 12:14:42.870038 kernel: Trampoline variant of Tasks RCU enabled. May 15 12:14:42.870048 kernel: Rude variant of Tasks RCU enabled. May 15 12:14:42.870059 kernel: Tracing variant of Tasks RCU enabled. May 15 12:14:42.870068 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. May 15 12:14:42.870077 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 May 15 12:14:42.870086 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 15 12:14:42.870095 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 15 12:14:42.870105 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 15 12:14:42.870114 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 May 15 12:14:42.870123 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. May 15 12:14:42.870142 kernel: Console: colour VGA+ 80x25 May 15 12:14:42.870151 kernel: printk: legacy console [ttyS0] enabled May 15 12:14:42.870161 kernel: ACPI: Core revision 20240827 May 15 12:14:42.870170 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns May 15 12:14:42.870182 kernel: APIC: Switch to symmetric I/O mode setup May 15 12:14:42.870191 kernel: x2apic enabled May 15 12:14:42.870201 kernel: APIC: Switched APIC routing to: physical x2apic May 15 12:14:42.870210 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() May 15 12:14:42.870221 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() May 15 12:14:42.870234 kernel: kvm-guest: setup PV IPIs May 15 12:14:42.870243 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 May 15 12:14:42.870253 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x2848df6a9de, max_idle_ns: 440795280912 ns May 15 12:14:42.870262 kernel: Calibrating delay loop (skipped) preset value.. 5589.49 BogoMIPS (lpj=2794748) May 15 12:14:42.870272 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated May 15 12:14:42.870281 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 May 15 12:14:42.870291 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 May 15 12:14:42.870300 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization May 15 12:14:42.870312 kernel: Spectre V2 : Mitigation: Retpolines May 15 12:14:42.870322 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch May 15 12:14:42.870331 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT May 15 12:14:42.870341 kernel: Spectre V2 : Enabling Speculation Barrier for firmware calls May 15 12:14:42.870350 kernel: RETBleed: Mitigation: untrained return thunk May 15 12:14:42.870360 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier May 15 12:14:42.870369 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl May 15 12:14:42.870379 kernel: Speculative Return Stack Overflow: IBPB-extending microcode not applied! May 15 12:14:42.870389 kernel: Speculative Return Stack Overflow: WARNING: See https://kernel.org/doc/html/latest/admin-guide/hw-vuln/srso.html for mitigation options. May 15 12:14:42.870401 kernel: Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode May 15 12:14:42.870411 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' May 15 12:14:42.870420 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' May 15 12:14:42.870430 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' May 15 12:14:42.870439 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 May 15 12:14:42.870448 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format. May 15 12:14:42.870458 kernel: Freeing SMP alternatives memory: 32K May 15 12:14:42.870467 kernel: pid_max: default: 32768 minimum: 301 May 15 12:14:42.870477 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima May 15 12:14:42.870488 kernel: landlock: Up and running. May 15 12:14:42.870498 kernel: SELinux: Initializing. May 15 12:14:42.870507 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 15 12:14:42.870517 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 15 12:14:42.870527 kernel: smpboot: CPU0: AMD EPYC 7402P 24-Core Processor (family: 0x17, model: 0x31, stepping: 0x0) May 15 12:14:42.870536 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver. May 15 12:14:42.870546 kernel: ... version: 0 May 15 12:14:42.870555 kernel: ... bit width: 48 May 15 12:14:42.870564 kernel: ... generic registers: 6 May 15 12:14:42.870588 kernel: ... value mask: 0000ffffffffffff May 15 12:14:42.870597 kernel: ... max period: 00007fffffffffff May 15 12:14:42.870606 kernel: ... fixed-purpose events: 0 May 15 12:14:42.870616 kernel: ... event mask: 000000000000003f May 15 12:14:42.870625 kernel: signal: max sigframe size: 1776 May 15 12:14:42.870634 kernel: rcu: Hierarchical SRCU implementation. May 15 12:14:42.870644 kernel: rcu: Max phase no-delay instances is 400. May 15 12:14:42.870654 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level May 15 12:14:42.870663 kernel: smp: Bringing up secondary CPUs ... May 15 12:14:42.870675 kernel: smpboot: x86: Booting SMP configuration: May 15 12:14:42.870685 kernel: .... node #0, CPUs: #1 #2 #3 May 15 12:14:42.870709 kernel: smp: Brought up 1 node, 4 CPUs May 15 12:14:42.870719 kernel: smpboot: Total of 4 processors activated (22357.98 BogoMIPS) May 15 12:14:42.870729 kernel: Memory: 2428904K/2571752K available (14336K kernel code, 2438K rwdata, 9944K rodata, 54416K init, 2544K bss, 136908K reserved, 0K cma-reserved) May 15 12:14:42.870739 kernel: devtmpfs: initialized May 15 12:14:42.870748 kernel: x86/mm: Memory block size: 128MB May 15 12:14:42.870758 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns May 15 12:14:42.870767 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) May 15 12:14:42.870779 kernel: pinctrl core: initialized pinctrl subsystem May 15 12:14:42.870789 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 15 12:14:42.870798 kernel: audit: initializing netlink subsys (disabled) May 15 12:14:42.870808 kernel: audit: type=2000 audit(1747311280.108:1): state=initialized audit_enabled=0 res=1 May 15 12:14:42.870817 kernel: thermal_sys: Registered thermal governor 'step_wise' May 15 12:14:42.870826 kernel: thermal_sys: Registered thermal governor 'user_space' May 15 12:14:42.870836 kernel: cpuidle: using governor menu May 15 12:14:42.870853 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 15 12:14:42.870862 kernel: dca service started, version 1.12.1 May 15 12:14:42.870874 kernel: PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff] May 15 12:14:42.870883 kernel: PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry May 15 12:14:42.870893 kernel: PCI: Using configuration type 1 for base access May 15 12:14:42.870903 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. May 15 12:14:42.870912 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages May 15 12:14:42.870922 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page May 15 12:14:42.870931 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages May 15 12:14:42.870941 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page May 15 12:14:42.870950 kernel: ACPI: Added _OSI(Module Device) May 15 12:14:42.870962 kernel: ACPI: Added _OSI(Processor Device) May 15 12:14:42.870971 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 15 12:14:42.870981 kernel: ACPI: Added _OSI(Processor Aggregator Device) May 15 12:14:42.870990 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded May 15 12:14:42.871000 kernel: ACPI: Interpreter enabled May 15 12:14:42.871009 kernel: ACPI: PM: (supports S0 S3 S5) May 15 12:14:42.871019 kernel: ACPI: Using IOAPIC for interrupt routing May 15 12:14:42.871028 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug May 15 12:14:42.871038 kernel: PCI: Using E820 reservations for host bridge windows May 15 12:14:42.871050 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F May 15 12:14:42.871059 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) May 15 12:14:42.871263 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] May 15 12:14:42.871403 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] May 15 12:14:42.871538 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] May 15 12:14:42.871550 kernel: PCI host bridge to bus 0000:00 May 15 12:14:42.871704 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] May 15 12:14:42.871833 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] May 15 12:14:42.871973 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] May 15 12:14:42.872100 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xafffffff window] May 15 12:14:42.872223 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] May 15 12:14:42.872343 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] May 15 12:14:42.872467 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] May 15 12:14:42.872696 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint May 15 12:14:42.872865 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint May 15 12:14:42.873005 kernel: pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref] May 15 12:14:42.873144 kernel: pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff] May 15 12:14:42.873283 kernel: pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref] May 15 12:14:42.873420 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] May 15 12:14:42.873589 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint May 15 12:14:42.873740 kernel: pci 0000:00:02.0: BAR 0 [io 0xc0c0-0xc0df] May 15 12:14:42.873893 kernel: pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff] May 15 12:14:42.874033 kernel: pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref] May 15 12:14:42.874178 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint May 15 12:14:42.874315 kernel: pci 0000:00:03.0: BAR 0 [io 0xc000-0xc07f] May 15 12:14:42.874446 kernel: pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff] May 15 12:14:42.874592 kernel: pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref] May 15 12:14:42.874742 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint May 15 12:14:42.874887 kernel: pci 0000:00:04.0: BAR 0 [io 0xc0e0-0xc0ff] May 15 12:14:42.875022 kernel: pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff] May 15 12:14:42.875157 kernel: pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref] May 15 12:14:42.875290 kernel: pci 0000:00:04.0: ROM [mem 0xfeb80000-0xfebbffff pref] May 15 12:14:42.875433 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint May 15 12:14:42.875567 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO May 15 12:14:42.875733 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint May 15 12:14:42.875876 kernel: pci 0000:00:1f.2: BAR 4 [io 0xc100-0xc11f] May 15 12:14:42.876009 kernel: pci 0000:00:1f.2: BAR 5 [mem 0xfebd4000-0xfebd4fff] May 15 12:14:42.876152 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint May 15 12:14:42.876298 kernel: pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f] May 15 12:14:42.876314 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 May 15 12:14:42.876330 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 May 15 12:14:42.876340 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 May 15 12:14:42.876350 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 May 15 12:14:42.876361 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 May 15 12:14:42.876372 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 May 15 12:14:42.876384 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 May 15 12:14:42.876394 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 May 15 12:14:42.876405 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 May 15 12:14:42.876416 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 May 15 12:14:42.876430 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 May 15 12:14:42.876440 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 May 15 12:14:42.876449 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 May 15 12:14:42.876459 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 May 15 12:14:42.876469 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 May 15 12:14:42.876479 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 May 15 12:14:42.876489 kernel: iommu: Default domain type: Translated May 15 12:14:42.876499 kernel: iommu: DMA domain TLB invalidation policy: lazy mode May 15 12:14:42.876509 kernel: PCI: Using ACPI for IRQ routing May 15 12:14:42.876522 kernel: PCI: pci_cache_line_size set to 64 bytes May 15 12:14:42.876532 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] May 15 12:14:42.876542 kernel: e820: reserve RAM buffer [mem 0x9cfdc000-0x9fffffff] May 15 12:14:42.876707 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device May 15 12:14:42.876861 kernel: pci 0000:00:01.0: vgaarb: bridge control possible May 15 12:14:42.877005 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none May 15 12:14:42.877019 kernel: vgaarb: loaded May 15 12:14:42.877030 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 May 15 12:14:42.877046 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter May 15 12:14:42.877059 kernel: clocksource: Switched to clocksource kvm-clock May 15 12:14:42.877069 kernel: VFS: Disk quotas dquot_6.6.0 May 15 12:14:42.877079 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 15 12:14:42.877090 kernel: pnp: PnP ACPI init May 15 12:14:42.877284 kernel: system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved May 15 12:14:42.877300 kernel: pnp: PnP ACPI: found 6 devices May 15 12:14:42.877310 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns May 15 12:14:42.877320 kernel: NET: Registered PF_INET protocol family May 15 12:14:42.877334 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) May 15 12:14:42.877344 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) May 15 12:14:42.877354 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 15 12:14:42.877364 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) May 15 12:14:42.877374 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) May 15 12:14:42.877384 kernel: TCP: Hash tables configured (established 32768 bind 32768) May 15 12:14:42.877395 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) May 15 12:14:42.877405 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) May 15 12:14:42.877418 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 15 12:14:42.877428 kernel: NET: Registered PF_XDP protocol family May 15 12:14:42.877567 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] May 15 12:14:42.877717 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] May 15 12:14:42.877858 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] May 15 12:14:42.877993 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xafffffff window] May 15 12:14:42.878125 kernel: pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] May 15 12:14:42.878257 kernel: pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] May 15 12:14:42.878271 kernel: PCI: CLS 0 bytes, default 64 May 15 12:14:42.878286 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x2848df6a9de, max_idle_ns: 440795280912 ns May 15 12:14:42.878297 kernel: Initialise system trusted keyrings May 15 12:14:42.878307 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 May 15 12:14:42.878317 kernel: Key type asymmetric registered May 15 12:14:42.878327 kernel: Asymmetric key parser 'x509' registered May 15 12:14:42.878337 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) May 15 12:14:42.878347 kernel: io scheduler mq-deadline registered May 15 12:14:42.878357 kernel: io scheduler kyber registered May 15 12:14:42.878367 kernel: io scheduler bfq registered May 15 12:14:42.878379 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 May 15 12:14:42.878391 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 May 15 12:14:42.878401 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 May 15 12:14:42.878411 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 May 15 12:14:42.878421 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled May 15 12:14:42.878431 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A May 15 12:14:42.878442 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 May 15 12:14:42.878452 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 May 15 12:14:42.878462 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 May 15 12:14:42.878637 kernel: rtc_cmos 00:04: RTC can wake from S4 May 15 12:14:42.878781 kernel: rtc_cmos 00:04: registered as rtc0 May 15 12:14:42.878931 kernel: rtc_cmos 00:04: setting system clock to 2025-05-15T12:14:42 UTC (1747311282) May 15 12:14:42.878946 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 May 15 12:14:42.879080 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs May 15 12:14:42.879095 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled May 15 12:14:42.879106 kernel: NET: Registered PF_INET6 protocol family May 15 12:14:42.879116 kernel: Segment Routing with IPv6 May 15 12:14:42.879130 kernel: In-situ OAM (IOAM) with IPv6 May 15 12:14:42.879140 kernel: NET: Registered PF_PACKET protocol family May 15 12:14:42.879150 kernel: Key type dns_resolver registered May 15 12:14:42.879161 kernel: IPI shorthand broadcast: enabled May 15 12:14:42.879171 kernel: sched_clock: Marking stable (3072002864, 114486674)->(3236611347, -50121809) May 15 12:14:42.879181 kernel: registered taskstats version 1 May 15 12:14:42.879192 kernel: Loading compiled-in X.509 certificates May 15 12:14:42.879202 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.20-flatcar: 05e05785144663be6df1db78301487421c4773b6' May 15 12:14:42.879212 kernel: Demotion targets for Node 0: null May 15 12:14:42.879226 kernel: Key type .fscrypt registered May 15 12:14:42.879236 kernel: Key type fscrypt-provisioning registered May 15 12:14:42.879246 kernel: ima: No TPM chip found, activating TPM-bypass! May 15 12:14:42.879257 kernel: ima: Allocated hash algorithm: sha1 May 15 12:14:42.879268 kernel: ima: No architecture policies found May 15 12:14:42.879278 kernel: clk: Disabling unused clocks May 15 12:14:42.879288 kernel: Warning: unable to open an initial console. May 15 12:14:42.879299 kernel: Freeing unused kernel image (initmem) memory: 54416K May 15 12:14:42.879312 kernel: Write protecting the kernel read-only data: 24576k May 15 12:14:42.879322 kernel: Freeing unused kernel image (rodata/data gap) memory: 296K May 15 12:14:42.879332 kernel: Run /init as init process May 15 12:14:42.879342 kernel: with arguments: May 15 12:14:42.879352 kernel: /init May 15 12:14:42.879362 kernel: with environment: May 15 12:14:42.879372 kernel: HOME=/ May 15 12:14:42.879382 kernel: TERM=linux May 15 12:14:42.879392 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a May 15 12:14:42.879409 systemd[1]: Successfully made /usr/ read-only. May 15 12:14:42.879438 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) May 15 12:14:42.879453 systemd[1]: Detected virtualization kvm. May 15 12:14:42.879465 systemd[1]: Detected architecture x86-64. May 15 12:14:42.879476 systemd[1]: Running in initrd. May 15 12:14:42.879487 systemd[1]: No hostname configured, using default hostname. May 15 12:14:42.879501 systemd[1]: Hostname set to . May 15 12:14:42.879515 systemd[1]: Initializing machine ID from VM UUID. May 15 12:14:42.879526 systemd[1]: Queued start job for default target initrd.target. May 15 12:14:42.879537 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 15 12:14:42.879549 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 15 12:14:42.879560 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... May 15 12:14:42.879587 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 15 12:14:42.879599 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... May 15 12:14:42.879614 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... May 15 12:14:42.879627 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... May 15 12:14:42.879638 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... May 15 12:14:42.879650 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 15 12:14:42.879661 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 15 12:14:42.879672 systemd[1]: Reached target paths.target - Path Units. May 15 12:14:42.879687 systemd[1]: Reached target slices.target - Slice Units. May 15 12:14:42.879698 systemd[1]: Reached target swap.target - Swaps. May 15 12:14:42.879710 systemd[1]: Reached target timers.target - Timer Units. May 15 12:14:42.879721 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. May 15 12:14:42.879733 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 15 12:14:42.879744 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). May 15 12:14:42.879755 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. May 15 12:14:42.879766 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 15 12:14:42.879778 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 15 12:14:42.879792 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 15 12:14:42.879803 systemd[1]: Reached target sockets.target - Socket Units. May 15 12:14:42.879814 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... May 15 12:14:42.879826 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 15 12:14:42.879840 systemd[1]: Finished network-cleanup.service - Network Cleanup. May 15 12:14:42.879866 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). May 15 12:14:42.879878 systemd[1]: Starting systemd-fsck-usr.service... May 15 12:14:42.879890 systemd[1]: Starting systemd-journald.service - Journal Service... May 15 12:14:42.879904 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 15 12:14:42.879915 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 15 12:14:42.879927 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. May 15 12:14:42.879941 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 15 12:14:42.879953 systemd[1]: Finished systemd-fsck-usr.service. May 15 12:14:42.879964 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 15 12:14:42.880003 systemd-journald[220]: Collecting audit messages is disabled. May 15 12:14:42.880033 systemd-journald[220]: Journal started May 15 12:14:42.880057 systemd-journald[220]: Runtime Journal (/run/log/journal/3cdb2169c32440448a098b1c3c1c9c89) is 6M, max 48.6M, 42.5M free. May 15 12:14:42.860826 systemd-modules-load[221]: Inserted module 'overlay' May 15 12:14:42.882144 systemd[1]: Started systemd-journald.service - Journal Service. May 15 12:14:42.885686 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 15 12:14:42.893673 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. May 15 12:14:42.894598 kernel: Bridge firewalling registered May 15 12:14:42.894565 systemd-modules-load[221]: Inserted module 'br_netfilter' May 15 12:14:42.896442 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 15 12:14:42.930810 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 15 12:14:42.933610 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 15 12:14:42.937001 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 15 12:14:42.940293 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 15 12:14:42.941141 systemd-tmpfiles[237]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. May 15 12:14:42.943698 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 15 12:14:42.947279 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 15 12:14:42.956508 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 15 12:14:42.959344 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 15 12:14:42.961903 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 15 12:14:42.977787 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 15 12:14:42.979402 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... May 15 12:14:42.999871 dracut-cmdline[263]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected verity.usrhash=48287e633374b880fa618bd42bee102ae77c50831859c6cedd6ca9e1aec3dd5c May 15 12:14:43.019161 systemd-resolved[257]: Positive Trust Anchors: May 15 12:14:43.019178 systemd-resolved[257]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 15 12:14:43.019208 systemd-resolved[257]: 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 May 15 12:14:43.021860 systemd-resolved[257]: Defaulting to hostname 'linux'. May 15 12:14:43.023156 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 15 12:14:43.029836 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 15 12:14:43.133650 kernel: SCSI subsystem initialized May 15 12:14:43.144602 kernel: Loading iSCSI transport class v2.0-870. May 15 12:14:43.158645 kernel: iscsi: registered transport (tcp) May 15 12:14:43.183922 kernel: iscsi: registered transport (qla4xxx) May 15 12:14:43.184015 kernel: QLogic iSCSI HBA Driver May 15 12:14:43.210542 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 15 12:14:43.237861 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 15 12:14:43.240365 systemd[1]: Reached target network-pre.target - Preparation for Network. May 15 12:14:43.343909 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. May 15 12:14:43.346786 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... May 15 12:14:43.412640 kernel: raid6: avx2x4 gen() 28018 MB/s May 15 12:14:43.429635 kernel: raid6: avx2x2 gen() 25781 MB/s May 15 12:14:43.446785 kernel: raid6: avx2x1 gen() 22615 MB/s May 15 12:14:43.446878 kernel: raid6: using algorithm avx2x4 gen() 28018 MB/s May 15 12:14:43.464709 kernel: raid6: .... xor() 7250 MB/s, rmw enabled May 15 12:14:43.464787 kernel: raid6: using avx2x2 recovery algorithm May 15 12:14:43.487615 kernel: xor: automatically using best checksumming function avx May 15 12:14:43.710613 kernel: Btrfs loaded, zoned=no, fsverity=no May 15 12:14:43.720896 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. May 15 12:14:43.723813 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 15 12:14:43.774037 systemd-udevd[473]: Using default interface naming scheme 'v255'. May 15 12:14:43.781713 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 15 12:14:43.785689 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... May 15 12:14:43.811605 dracut-pre-trigger[477]: rd.md=0: removing MD RAID activation May 15 12:14:43.854094 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. May 15 12:14:43.856774 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 15 12:14:43.938272 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 15 12:14:43.943455 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... May 15 12:14:43.984435 kernel: cryptd: max_cpu_qlen set to 1000 May 15 12:14:43.991042 kernel: AES CTR mode by8 optimization enabled May 15 12:14:43.998601 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues May 15 12:14:44.034916 kernel: libata version 3.00 loaded. May 15 12:14:44.034939 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) May 15 12:14:44.035146 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 May 15 12:14:44.035163 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 15 12:14:44.035177 kernel: ahci 0000:00:1f.2: version 3.0 May 15 12:14:44.051177 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 May 15 12:14:44.051199 kernel: ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode May 15 12:14:44.051391 kernel: ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f) May 15 12:14:44.051562 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only May 15 12:14:44.051742 kernel: scsi host0: ahci May 15 12:14:44.051928 kernel: scsi host1: ahci May 15 12:14:44.052686 kernel: scsi host2: ahci May 15 12:14:44.052863 kernel: scsi host3: ahci May 15 12:14:44.053072 kernel: scsi host4: ahci May 15 12:14:44.053238 kernel: scsi host5: ahci May 15 12:14:44.053399 kernel: ata1: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4100 irq 34 lpm-pol 0 May 15 12:14:44.053413 kernel: ata2: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4180 irq 34 lpm-pol 0 May 15 12:14:44.053426 kernel: ata3: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4200 irq 34 lpm-pol 0 May 15 12:14:44.053445 kernel: ata4: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4280 irq 34 lpm-pol 0 May 15 12:14:44.053457 kernel: ata5: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4300 irq 34 lpm-pol 0 May 15 12:14:44.053470 kernel: ata6: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4380 irq 34 lpm-pol 0 May 15 12:14:44.032472 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 15 12:14:44.032654 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 15 12:14:44.035095 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... May 15 12:14:44.037504 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 15 12:14:44.039550 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. May 15 12:14:44.076767 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. May 15 12:14:44.087706 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. May 15 12:14:44.131802 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 15 12:14:44.153827 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. May 15 12:14:44.173659 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. May 15 12:14:44.185155 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. May 15 12:14:44.188330 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... May 15 12:14:44.241629 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 15 12:14:44.248641 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 15 12:14:44.362481 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) May 15 12:14:44.362563 kernel: ata2: SATA link down (SStatus 0 SControl 300) May 15 12:14:44.362593 kernel: ata5: SATA link down (SStatus 0 SControl 300) May 15 12:14:44.362605 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 May 15 12:14:44.362618 kernel: ata3.00: applying bridge limits May 15 12:14:44.362630 kernel: ata4: SATA link down (SStatus 0 SControl 300) May 15 12:14:44.363745 kernel: ata3.00: configured for UDMA/100 May 15 12:14:44.368623 kernel: ata1: SATA link down (SStatus 0 SControl 300) May 15 12:14:44.368647 kernel: ata6: SATA link down (SStatus 0 SControl 300) May 15 12:14:44.369615 kernel: scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 May 15 12:14:44.412621 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray May 15 12:14:44.426403 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 May 15 12:14:44.426418 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 May 15 12:14:44.839363 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. May 15 12:14:44.841306 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. May 15 12:14:44.843176 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 15 12:14:44.844393 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 15 12:14:44.847555 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... May 15 12:14:44.890948 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. May 15 12:14:45.308622 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 15 12:14:45.308809 disk-uuid[637]: The operation has completed successfully. May 15 12:14:45.345505 systemd[1]: disk-uuid.service: Deactivated successfully. May 15 12:14:45.345660 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. May 15 12:14:45.379316 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... May 15 12:14:45.404592 sh[666]: Success May 15 12:14:45.425599 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. May 15 12:14:45.425680 kernel: device-mapper: uevent: version 1.0.3 May 15 12:14:45.425698 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev May 15 12:14:45.434675 kernel: device-mapper: verity: sha256 using shash "sha256-ni" May 15 12:14:45.472719 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. May 15 12:14:45.476199 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... May 15 12:14:45.504084 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. May 15 12:14:45.509687 kernel: BTRFS info: 'norecovery' is for compatibility only, recommended to use 'rescue=nologreplay' May 15 12:14:45.509731 kernel: BTRFS: device fsid 2d504097-db49-4d66-a0d5-eeb665b21004 devid 1 transid 41 /dev/mapper/usr (253:0) scanned by mount (678) May 15 12:14:45.529989 kernel: BTRFS info (device dm-0): first mount of filesystem 2d504097-db49-4d66-a0d5-eeb665b21004 May 15 12:14:45.530078 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm May 15 12:14:45.530093 kernel: BTRFS info (device dm-0): using free-space-tree May 15 12:14:45.536087 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. May 15 12:14:45.537380 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. May 15 12:14:45.538362 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. May 15 12:14:45.539351 systemd[1]: Starting ignition-setup.service - Ignition (setup)... May 15 12:14:45.541886 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... May 15 12:14:45.578625 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 (254:6) scanned by mount (711) May 15 12:14:45.578702 kernel: BTRFS info (device vda6): first mount of filesystem afd0c70c-d15e-448c-8325-f96e3c3ed3a5 May 15 12:14:45.581107 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm May 15 12:14:45.581144 kernel: BTRFS info (device vda6): using free-space-tree May 15 12:14:45.590608 kernel: BTRFS info (device vda6): last unmount of filesystem afd0c70c-d15e-448c-8325-f96e3c3ed3a5 May 15 12:14:45.592569 systemd[1]: Finished ignition-setup.service - Ignition (setup). May 15 12:14:45.595240 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... May 15 12:14:45.690485 ignition[755]: Ignition 2.21.0 May 15 12:14:45.691716 ignition[755]: Stage: fetch-offline May 15 12:14:45.691809 ignition[755]: no configs at "/usr/lib/ignition/base.d" May 15 12:14:45.691824 ignition[755]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 15 12:14:45.692001 ignition[755]: parsed url from cmdline: "" May 15 12:14:45.692011 ignition[755]: no config URL provided May 15 12:14:45.692024 ignition[755]: reading system config file "/usr/lib/ignition/user.ign" May 15 12:14:45.692041 ignition[755]: no config at "/usr/lib/ignition/user.ign" May 15 12:14:45.692089 ignition[755]: op(1): [started] loading QEMU firmware config module May 15 12:14:45.698828 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 15 12:14:45.692101 ignition[755]: op(1): executing: "modprobe" "qemu_fw_cfg" May 15 12:14:45.702548 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 15 12:14:45.699976 ignition[755]: op(1): [finished] loading QEMU firmware config module May 15 12:14:45.705681 unknown[755]: fetched base config from "system" May 15 12:14:45.701557 ignition[755]: parsing config with SHA512: 8425c52a4e303d7b04560c90bbfc744d470a1abe039e9f1e29cea724214951ef7e2d28226eaf669bcf342e40f38d6d6ff0941a5edadd3e859a9085077e352659 May 15 12:14:45.705689 unknown[755]: fetched user config from "qemu" May 15 12:14:45.705835 ignition[755]: fetch-offline: fetch-offline passed May 15 12:14:45.705924 ignition[755]: Ignition finished successfully May 15 12:14:45.722785 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). May 15 12:14:45.754266 systemd-networkd[856]: lo: Link UP May 15 12:14:45.754277 systemd-networkd[856]: lo: Gained carrier May 15 12:14:45.755961 systemd-networkd[856]: Enumeration completed May 15 12:14:45.756357 systemd-networkd[856]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 15 12:14:45.756361 systemd-networkd[856]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 15 12:14:45.757355 systemd-networkd[856]: eth0: Link UP May 15 12:14:45.757358 systemd-networkd[856]: eth0: Gained carrier May 15 12:14:45.757366 systemd-networkd[856]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 15 12:14:45.757795 systemd[1]: Started systemd-networkd.service - Network Configuration. May 15 12:14:45.767128 systemd[1]: Reached target network.target - Network. May 15 12:14:45.769080 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). May 15 12:14:45.772478 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... May 15 12:14:45.785679 systemd-networkd[856]: eth0: DHCPv4 address 10.0.0.33/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 15 12:14:45.808160 ignition[860]: Ignition 2.21.0 May 15 12:14:45.808174 ignition[860]: Stage: kargs May 15 12:14:45.810001 ignition[860]: no configs at "/usr/lib/ignition/base.d" May 15 12:14:45.810043 ignition[860]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 15 12:14:45.811960 ignition[860]: kargs: kargs passed May 15 12:14:45.812019 ignition[860]: Ignition finished successfully May 15 12:14:45.817322 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). May 15 12:14:45.819683 systemd[1]: Starting ignition-disks.service - Ignition (disks)... May 15 12:14:45.851757 ignition[870]: Ignition 2.21.0 May 15 12:14:45.851790 ignition[870]: Stage: disks May 15 12:14:45.852006 ignition[870]: no configs at "/usr/lib/ignition/base.d" May 15 12:14:45.852017 ignition[870]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 15 12:14:45.852538 ignition[870]: disks: disks passed May 15 12:14:45.852598 ignition[870]: Ignition finished successfully May 15 12:14:45.874144 systemd[1]: Finished ignition-disks.service - Ignition (disks). May 15 12:14:45.875838 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. May 15 12:14:45.878038 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. May 15 12:14:45.880604 systemd[1]: Reached target local-fs.target - Local File Systems. May 15 12:14:45.883083 systemd[1]: Reached target sysinit.target - System Initialization. May 15 12:14:45.885333 systemd[1]: Reached target basic.target - Basic System. May 15 12:14:45.888444 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... May 15 12:14:45.924215 systemd-fsck[880]: ROOT: clean, 15/553520 files, 52789/553472 blocks May 15 12:14:45.972608 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. May 15 12:14:45.977212 systemd[1]: Mounting sysroot.mount - /sysroot... May 15 12:14:46.104605 kernel: EXT4-fs (vda9): mounted filesystem f7dea4bd-2644-4592-b85b-330f322c4d2b r/w with ordered data mode. Quota mode: none. May 15 12:14:46.105373 systemd[1]: Mounted sysroot.mount - /sysroot. May 15 12:14:46.107122 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. May 15 12:14:46.109181 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 15 12:14:46.111785 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... May 15 12:14:46.112707 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. May 15 12:14:46.112783 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). May 15 12:14:46.112822 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. May 15 12:14:46.131735 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. May 15 12:14:46.134117 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... May 15 12:14:46.137231 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 (254:6) scanned by mount (888) May 15 12:14:46.137255 kernel: BTRFS info (device vda6): first mount of filesystem afd0c70c-d15e-448c-8325-f96e3c3ed3a5 May 15 12:14:46.138609 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm May 15 12:14:46.139622 kernel: BTRFS info (device vda6): using free-space-tree May 15 12:14:46.145879 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 15 12:14:46.187280 initrd-setup-root[912]: cut: /sysroot/etc/passwd: No such file or directory May 15 12:14:46.208746 initrd-setup-root[919]: cut: /sysroot/etc/group: No such file or directory May 15 12:14:46.213915 initrd-setup-root[926]: cut: /sysroot/etc/shadow: No such file or directory May 15 12:14:46.218942 initrd-setup-root[933]: cut: /sysroot/etc/gshadow: No such file or directory May 15 12:14:46.306947 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. May 15 12:14:46.308738 systemd[1]: Starting ignition-mount.service - Ignition (mount)... May 15 12:14:46.314203 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... May 15 12:14:46.336667 kernel: BTRFS info (device vda6): last unmount of filesystem afd0c70c-d15e-448c-8325-f96e3c3ed3a5 May 15 12:14:46.369194 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. May 15 12:14:46.374170 systemd[1]: Finished ignition-mount.service - Ignition (mount). May 15 12:14:46.387724 ignition[1003]: INFO : Ignition 2.21.0 May 15 12:14:46.387724 ignition[1003]: INFO : Stage: mount May 15 12:14:46.387724 ignition[1003]: INFO : no configs at "/usr/lib/ignition/base.d" May 15 12:14:46.387724 ignition[1003]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 15 12:14:46.387724 ignition[1003]: INFO : mount: mount passed May 15 12:14:46.387724 ignition[1003]: INFO : Ignition finished successfully May 15 12:14:46.387807 systemd[1]: Starting ignition-files.service - Ignition (files)... May 15 12:14:46.509773 systemd[1]: sysroot-oem.mount: Deactivated successfully. May 15 12:14:46.511733 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 15 12:14:46.548480 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 (254:6) scanned by mount (1017) May 15 12:14:46.548536 kernel: BTRFS info (device vda6): first mount of filesystem afd0c70c-d15e-448c-8325-f96e3c3ed3a5 May 15 12:14:46.548548 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm May 15 12:14:46.549381 kernel: BTRFS info (device vda6): using free-space-tree May 15 12:14:46.553806 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 15 12:14:46.587996 ignition[1034]: INFO : Ignition 2.21.0 May 15 12:14:46.587996 ignition[1034]: INFO : Stage: files May 15 12:14:46.590000 ignition[1034]: INFO : no configs at "/usr/lib/ignition/base.d" May 15 12:14:46.590000 ignition[1034]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 15 12:14:46.592677 ignition[1034]: DEBUG : files: compiled without relabeling support, skipping May 15 12:14:46.592677 ignition[1034]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" May 15 12:14:46.592677 ignition[1034]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" May 15 12:14:46.596856 ignition[1034]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" May 15 12:14:46.596856 ignition[1034]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" May 15 12:14:46.596856 ignition[1034]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" May 15 12:14:46.596017 unknown[1034]: wrote ssh authorized keys file for user: core May 15 12:14:46.678535 ignition[1034]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" May 15 12:14:46.681229 ignition[1034]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" May 15 12:14:46.681229 ignition[1034]: INFO : files: op(4): [started] processing unit "etcd-member.service" May 15 12:14:46.685439 ignition[1034]: 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" May 15 12:14:46.694807 ignition[1034]: 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" May 15 12:14:46.694807 ignition[1034]: INFO : files: op(4): [finished] processing unit "etcd-member.service" May 15 12:14:46.694807 ignition[1034]: INFO : files: op(6): [started] processing unit "coreos-metadata.service" May 15 12:14:46.694807 ignition[1034]: INFO : files: op(6): op(7): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 15 12:14:46.703599 ignition[1034]: INFO : files: op(6): op(7): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 15 12:14:46.703599 ignition[1034]: INFO : files: op(6): [finished] processing unit "coreos-metadata.service" May 15 12:14:46.703599 ignition[1034]: INFO : files: op(8): [started] setting preset to disabled for "coreos-metadata.service" May 15 12:14:46.718250 ignition[1034]: INFO : files: op(8): op(9): [started] removing enablement symlink(s) for "coreos-metadata.service" May 15 12:14:46.724232 ignition[1034]: INFO : files: op(8): op(9): [finished] removing enablement symlink(s) for "coreos-metadata.service" May 15 12:14:46.726470 ignition[1034]: INFO : files: op(8): [finished] setting preset to disabled for "coreos-metadata.service" May 15 12:14:46.726470 ignition[1034]: INFO : files: op(a): [started] setting preset to enabled for "etcd-member.service" May 15 12:14:46.729561 ignition[1034]: INFO : files: op(a): [finished] setting preset to enabled for "etcd-member.service" May 15 12:14:46.729561 ignition[1034]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" May 15 12:14:46.729561 ignition[1034]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" May 15 12:14:46.729561 ignition[1034]: INFO : files: files passed May 15 12:14:46.729561 ignition[1034]: INFO : Ignition finished successfully May 15 12:14:46.732228 systemd[1]: Finished ignition-files.service - Ignition (files). May 15 12:14:46.736236 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... May 15 12:14:46.741471 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... May 15 12:14:46.754237 systemd[1]: ignition-quench.service: Deactivated successfully. May 15 12:14:46.754407 systemd[1]: Finished ignition-quench.service - Ignition (record completion). May 15 12:14:46.759150 initrd-setup-root-after-ignition[1063]: grep: /sysroot/oem/oem-release: No such file or directory May 15 12:14:46.763989 initrd-setup-root-after-ignition[1065]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 15 12:14:46.763989 initrd-setup-root-after-ignition[1065]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory May 15 12:14:46.768201 initrd-setup-root-after-ignition[1069]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 15 12:14:46.770606 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. May 15 12:14:46.772397 systemd[1]: Reached target ignition-complete.target - Ignition Complete. May 15 12:14:46.775143 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... May 15 12:14:46.844208 systemd[1]: initrd-parse-etc.service: Deactivated successfully. May 15 12:14:46.844382 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. May 15 12:14:46.845379 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. May 15 12:14:46.848224 systemd[1]: Reached target initrd.target - Initrd Default Target. May 15 12:14:46.848866 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. May 15 12:14:46.849914 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... May 15 12:14:46.897663 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 15 12:14:46.900213 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... May 15 12:14:46.931479 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. May 15 12:14:46.932183 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. May 15 12:14:46.932673 systemd[1]: Stopped target timers.target - Timer Units. May 15 12:14:46.933329 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. May 15 12:14:46.933463 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 15 12:14:46.942257 systemd[1]: Stopped target initrd.target - Initrd Default Target. May 15 12:14:46.942671 systemd[1]: Stopped target basic.target - Basic System. May 15 12:14:46.946136 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. May 15 12:14:46.946682 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. May 15 12:14:46.947225 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. May 15 12:14:46.947623 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. May 15 12:14:46.948240 systemd[1]: Stopped target remote-fs.target - Remote File Systems. May 15 12:14:46.948648 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. May 15 12:14:46.949291 systemd[1]: Stopped target sysinit.target - System Initialization. May 15 12:14:46.949692 systemd[1]: Stopped target local-fs.target - Local File Systems. May 15 12:14:46.950321 systemd[1]: Stopped target swap.target - Swaps. May 15 12:14:46.950961 systemd[1]: dracut-pre-mount.service: Deactivated successfully. May 15 12:14:46.951136 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. May 15 12:14:46.972759 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. May 15 12:14:46.973273 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 15 12:14:46.973969 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. May 15 12:14:46.974072 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 15 12:14:46.980482 systemd[1]: dracut-initqueue.service: Deactivated successfully. May 15 12:14:46.980683 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. May 15 12:14:46.983324 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. May 15 12:14:46.983477 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). May 15 12:14:46.986399 systemd[1]: Stopped target paths.target - Path Units. May 15 12:14:46.988545 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. May 15 12:14:46.988768 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 15 12:14:46.990998 systemd[1]: Stopped target slices.target - Slice Units. May 15 12:14:46.991339 systemd[1]: Stopped target sockets.target - Socket Units. May 15 12:14:46.991970 systemd[1]: iscsid.socket: Deactivated successfully. May 15 12:14:46.992106 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. May 15 12:14:46.997031 systemd[1]: iscsiuio.socket: Deactivated successfully. May 15 12:14:46.997120 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 15 12:14:46.998989 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. May 15 12:14:46.999146 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. May 15 12:14:47.002290 systemd[1]: ignition-files.service: Deactivated successfully. May 15 12:14:47.002658 systemd[1]: Stopped ignition-files.service - Ignition (files). May 15 12:14:47.012086 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... May 15 12:14:47.013471 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... May 15 12:14:47.015625 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. May 15 12:14:47.015892 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. May 15 12:14:47.018777 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. May 15 12:14:47.018991 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. May 15 12:14:47.025195 systemd[1]: initrd-cleanup.service: Deactivated successfully. May 15 12:14:47.028924 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. May 15 12:14:47.045622 ignition[1089]: INFO : Ignition 2.21.0 May 15 12:14:47.097653 ignition[1089]: INFO : Stage: umount May 15 12:14:47.097653 ignition[1089]: INFO : no configs at "/usr/lib/ignition/base.d" May 15 12:14:47.097653 ignition[1089]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 15 12:14:47.097653 ignition[1089]: INFO : umount: umount passed May 15 12:14:47.097653 ignition[1089]: INFO : Ignition finished successfully May 15 12:14:47.101245 systemd[1]: ignition-mount.service: Deactivated successfully. May 15 12:14:47.101405 systemd[1]: Stopped ignition-mount.service - Ignition (mount). May 15 12:14:47.103667 systemd[1]: Stopped target network.target - Network. May 15 12:14:47.104272 systemd[1]: ignition-disks.service: Deactivated successfully. May 15 12:14:47.104358 systemd[1]: Stopped ignition-disks.service - Ignition (disks). May 15 12:14:47.105193 systemd[1]: ignition-kargs.service: Deactivated successfully. May 15 12:14:47.105247 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). May 15 12:14:47.105502 systemd[1]: ignition-setup.service: Deactivated successfully. May 15 12:14:47.105559 systemd[1]: Stopped ignition-setup.service - Ignition (setup). May 15 12:14:47.110808 systemd[1]: ignition-setup-pre.service: Deactivated successfully. May 15 12:14:47.110871 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. May 15 12:14:47.111545 systemd[1]: Stopping systemd-networkd.service - Network Configuration... May 15 12:14:47.114282 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... May 15 12:14:47.116216 systemd[1]: sysroot-boot.mount: Deactivated successfully. May 15 12:14:47.125154 systemd[1]: systemd-resolved.service: Deactivated successfully. May 15 12:14:47.125300 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. May 15 12:14:47.129837 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. May 15 12:14:47.130075 systemd[1]: systemd-networkd.service: Deactivated successfully. May 15 12:14:47.130198 systemd[1]: Stopped systemd-networkd.service - Network Configuration. May 15 12:14:47.168895 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. May 15 12:14:47.169202 systemd[1]: sysroot-boot.service: Deactivated successfully. May 15 12:14:47.169346 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. May 15 12:14:47.172433 systemd[1]: Stopped target network-pre.target - Preparation for Network. May 15 12:14:47.175331 systemd[1]: systemd-networkd.socket: Deactivated successfully. May 15 12:14:47.175383 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. May 15 12:14:47.176027 systemd[1]: initrd-setup-root.service: Deactivated successfully. May 15 12:14:47.176094 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. May 15 12:14:47.177307 systemd[1]: Stopping network-cleanup.service - Network Cleanup... May 15 12:14:47.181428 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. May 15 12:14:47.181504 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 15 12:14:47.182044 systemd[1]: systemd-sysctl.service: Deactivated successfully. May 15 12:14:47.182107 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. May 15 12:14:47.187916 systemd[1]: systemd-modules-load.service: Deactivated successfully. May 15 12:14:47.187972 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. May 15 12:14:47.188346 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. May 15 12:14:47.188408 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. May 15 12:14:47.193015 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... May 15 12:14:47.212297 systemd[1]: systemd-udevd.service: Deactivated successfully. May 15 12:14:47.226941 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. May 15 12:14:47.257117 systemd[1]: network-cleanup.service: Deactivated successfully. May 15 12:14:47.257288 systemd[1]: Stopped network-cleanup.service - Network Cleanup. May 15 12:14:47.258636 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. May 15 12:14:47.258766 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. May 15 12:14:47.260625 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. May 15 12:14:47.260680 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. May 15 12:14:47.262830 systemd[1]: dracut-pre-udev.service: Deactivated successfully. May 15 12:14:47.262900 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. May 15 12:14:47.266898 systemd[1]: dracut-cmdline.service: Deactivated successfully. May 15 12:14:47.266966 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. May 15 12:14:47.267848 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 15 12:14:47.267921 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 15 12:14:47.273860 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... May 15 12:14:47.274322 systemd[1]: systemd-network-generator.service: Deactivated successfully. May 15 12:14:47.274387 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. May 15 12:14:47.310301 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. May 15 12:14:47.310402 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 15 12:14:47.314055 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. May 15 12:14:47.314109 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 15 12:14:47.317957 systemd[1]: kmod-static-nodes.service: Deactivated successfully. May 15 12:14:47.318044 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. May 15 12:14:47.318490 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 15 12:14:47.318550 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 15 12:14:47.333860 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. May 15 12:14:47.334007 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. May 15 12:14:47.334767 systemd[1]: Reached target initrd-switch-root.target - Switch Root. May 15 12:14:47.335967 systemd[1]: Starting initrd-switch-root.service - Switch Root... May 15 12:14:47.369053 systemd[1]: Switching root. May 15 12:14:47.413026 systemd-journald[220]: Journal stopped May 15 12:14:48.520394 systemd-journald[220]: Received SIGTERM from PID 1 (systemd). May 15 12:14:48.520466 kernel: SELinux: policy capability network_peer_controls=1 May 15 12:14:48.520485 kernel: SELinux: policy capability open_perms=1 May 15 12:14:48.520497 kernel: SELinux: policy capability extended_socket_class=1 May 15 12:14:48.520508 kernel: SELinux: policy capability always_check_network=0 May 15 12:14:48.520527 kernel: SELinux: policy capability cgroup_seclabel=1 May 15 12:14:48.520539 kernel: SELinux: policy capability nnp_nosuid_transition=1 May 15 12:14:48.520550 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 May 15 12:14:48.520561 kernel: SELinux: policy capability ioctl_skip_cloexec=0 May 15 12:14:48.520599 kernel: SELinux: policy capability userspace_initial_context=0 May 15 12:14:48.520611 kernel: audit: type=1403 audit(1747311287.574:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 May 15 12:14:48.520624 systemd[1]: Successfully loaded SELinux policy in 59.350ms. May 15 12:14:48.520642 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 13.660ms. May 15 12:14:48.520655 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) May 15 12:14:48.520672 systemd[1]: Detected virtualization kvm. May 15 12:14:48.520700 systemd[1]: Detected architecture x86-64. May 15 12:14:48.520712 systemd[1]: Detected first boot. May 15 12:14:48.520724 systemd[1]: Initializing machine ID from VM UUID. May 15 12:14:48.520739 zram_generator::config[1135]: No configuration found. May 15 12:14:48.520761 kernel: Guest personality initialized and is inactive May 15 12:14:48.520772 kernel: VMCI host device registered (name=vmci, major=10, minor=125) May 15 12:14:48.520784 kernel: Initialized host personality May 15 12:14:48.520795 kernel: NET: Registered PF_VSOCK protocol family May 15 12:14:48.520807 systemd[1]: Populated /etc with preset unit settings. May 15 12:14:48.520821 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. May 15 12:14:48.520833 systemd[1]: initrd-switch-root.service: Deactivated successfully. May 15 12:14:48.520845 systemd[1]: Stopped initrd-switch-root.service - Switch Root. May 15 12:14:48.520858 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. May 15 12:14:48.520872 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. May 15 12:14:48.520884 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. May 15 12:14:48.520897 systemd[1]: Created slice system-getty.slice - Slice /system/getty. May 15 12:14:48.520910 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. May 15 12:14:48.520922 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. May 15 12:14:48.520934 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. May 15 12:14:48.520952 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. May 15 12:14:48.520964 systemd[1]: Created slice user.slice - User and Session Slice. May 15 12:14:48.520982 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 15 12:14:48.520995 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 15 12:14:48.521007 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. May 15 12:14:48.521019 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. May 15 12:14:48.521031 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. May 15 12:14:48.521045 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 15 12:14:48.521058 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... May 15 12:14:48.521070 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 15 12:14:48.521084 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 15 12:14:48.521096 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. May 15 12:14:48.521109 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. May 15 12:14:48.521121 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. May 15 12:14:48.521133 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. May 15 12:14:48.521146 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 15 12:14:48.521158 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 15 12:14:48.521170 systemd[1]: Reached target slices.target - Slice Units. May 15 12:14:48.521182 systemd[1]: Reached target swap.target - Swaps. May 15 12:14:48.521194 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. May 15 12:14:48.521208 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. May 15 12:14:48.521220 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. May 15 12:14:48.521232 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 15 12:14:48.521244 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 15 12:14:48.521256 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 15 12:14:48.521268 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. May 15 12:14:48.521280 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... May 15 12:14:48.521292 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... May 15 12:14:48.521307 systemd[1]: Mounting media.mount - External Media Directory... May 15 12:14:48.521320 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 15 12:14:48.521332 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... May 15 12:14:48.521344 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... May 15 12:14:48.521356 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... May 15 12:14:48.521369 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). May 15 12:14:48.521381 systemd[1]: Reached target machines.target - Containers. May 15 12:14:48.521394 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... May 15 12:14:48.521406 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 15 12:14:48.521421 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 15 12:14:48.521433 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... May 15 12:14:48.521445 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 15 12:14:48.521463 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 15 12:14:48.521475 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 15 12:14:48.521487 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... May 15 12:14:48.521499 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 15 12:14:48.521512 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). May 15 12:14:48.521526 systemd[1]: systemd-fsck-root.service: Deactivated successfully. May 15 12:14:48.521539 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. May 15 12:14:48.521554 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. May 15 12:14:48.521566 systemd[1]: Stopped systemd-fsck-usr.service. May 15 12:14:48.521598 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). May 15 12:14:48.521610 systemd[1]: Starting systemd-journald.service - Journal Service... May 15 12:14:48.521623 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 15 12:14:48.521635 kernel: loop: module loaded May 15 12:14:48.521646 kernel: fuse: init (API version 7.41) May 15 12:14:48.521661 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 15 12:14:48.521673 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... May 15 12:14:48.521695 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... May 15 12:14:48.521708 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 15 12:14:48.521720 systemd[1]: verity-setup.service: Deactivated successfully. May 15 12:14:48.521735 systemd[1]: Stopped verity-setup.service. May 15 12:14:48.521747 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 15 12:14:48.521760 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. May 15 12:14:48.521772 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. May 15 12:14:48.521784 systemd[1]: Mounted media.mount - External Media Directory. May 15 12:14:48.521797 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. May 15 12:14:48.521831 systemd-journald[1203]: Collecting audit messages is disabled. May 15 12:14:48.521861 kernel: ACPI: bus type drm_connector registered May 15 12:14:48.521874 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. May 15 12:14:48.521886 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. May 15 12:14:48.521901 systemd-journald[1203]: Journal started May 15 12:14:48.521928 systemd-journald[1203]: Runtime Journal (/run/log/journal/3cdb2169c32440448a098b1c3c1c9c89) is 6M, max 48.6M, 42.5M free. May 15 12:14:48.225570 systemd[1]: Queued start job for default target multi-user.target. May 15 12:14:48.245113 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. May 15 12:14:48.245686 systemd[1]: systemd-journald.service: Deactivated successfully. May 15 12:14:48.523616 systemd[1]: Started systemd-journald.service - Journal Service. May 15 12:14:48.526560 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 15 12:14:48.528676 systemd[1]: modprobe@configfs.service: Deactivated successfully. May 15 12:14:48.529109 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. May 15 12:14:48.530897 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 15 12:14:48.531121 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 15 12:14:48.532947 systemd[1]: modprobe@drm.service: Deactivated successfully. May 15 12:14:48.533205 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 15 12:14:48.535020 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 15 12:14:48.535265 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 15 12:14:48.536951 systemd[1]: modprobe@fuse.service: Deactivated successfully. May 15 12:14:48.537174 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. May 15 12:14:48.538682 systemd[1]: modprobe@loop.service: Deactivated successfully. May 15 12:14:48.538986 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 15 12:14:48.540599 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 15 12:14:48.542298 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 15 12:14:48.544180 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. May 15 12:14:48.546331 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. May 15 12:14:48.563603 systemd[1]: Reached target network-pre.target - Preparation for Network. May 15 12:14:48.566606 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... May 15 12:14:48.569903 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... May 15 12:14:48.572055 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). May 15 12:14:48.572134 systemd[1]: Reached target local-fs.target - Local File Systems. May 15 12:14:48.575146 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. May 15 12:14:48.627120 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... May 15 12:14:48.628707 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 15 12:14:48.630177 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... May 15 12:14:48.633017 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... May 15 12:14:48.635116 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 15 12:14:48.636197 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... May 15 12:14:48.637345 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 15 12:14:48.638482 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 15 12:14:48.644936 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... May 15 12:14:48.649691 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 15 12:14:48.656885 systemd-journald[1203]: Time spent on flushing to /var/log/journal/3cdb2169c32440448a098b1c3c1c9c89 is 16.441ms for 947 entries. May 15 12:14:48.656885 systemd-journald[1203]: System Journal (/var/log/journal/3cdb2169c32440448a098b1c3c1c9c89) is 8M, max 195.6M, 187.6M free. May 15 12:14:48.829556 systemd-journald[1203]: Received client request to flush runtime journal. May 15 12:14:48.829649 kernel: loop0: detected capacity change from 0 to 146240 May 15 12:14:48.654533 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. May 15 12:14:48.656093 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 15 12:14:48.658698 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. May 15 12:14:48.661012 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. May 15 12:14:48.719643 systemd-tmpfiles[1254]: ACLs are not supported, ignoring. May 15 12:14:48.719656 systemd-tmpfiles[1254]: ACLs are not supported, ignoring. May 15 12:14:48.725811 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 15 12:14:48.737863 systemd[1]: Starting systemd-sysusers.service - Create System Users... May 15 12:14:48.796806 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. May 15 12:14:48.798555 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. May 15 12:14:48.806782 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... May 15 12:14:48.821218 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 15 12:14:48.831403 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. May 15 12:14:48.836614 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 15 12:14:48.848942 systemd[1]: Finished systemd-sysusers.service - Create System Users. May 15 12:14:48.880606 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 15 12:14:48.885739 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. May 15 12:14:48.890686 kernel: loop1: detected capacity change from 0 to 113872 May 15 12:14:48.912568 systemd-tmpfiles[1273]: ACLs are not supported, ignoring. May 15 12:14:48.912602 systemd-tmpfiles[1273]: ACLs are not supported, ignoring. May 15 12:14:48.918609 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 15 12:14:48.936608 kernel: loop2: detected capacity change from 0 to 146240 May 15 12:14:48.989662 kernel: loop3: detected capacity change from 0 to 113872 May 15 12:14:48.999482 (sd-merge)[1278]: Using extensions 'containerd-flatcar', 'docker-flatcar'. May 15 12:14:49.001477 (sd-merge)[1278]: Merged extensions into '/usr'. May 15 12:14:49.005227 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. May 15 12:14:49.008430 systemd[1]: Starting ensure-sysext.service... May 15 12:14:49.011330 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 15 12:14:49.031246 systemd[1]: Reload requested from client PID 1280 ('systemctl') (unit ensure-sysext.service)... May 15 12:14:49.031393 systemd[1]: Reloading... May 15 12:14:49.072362 systemd-tmpfiles[1281]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. May 15 12:14:49.072694 systemd-tmpfiles[1281]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. May 15 12:14:49.074379 systemd-tmpfiles[1281]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. May 15 12:14:49.075067 systemd-tmpfiles[1281]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. May 15 12:14:49.076154 systemd-tmpfiles[1281]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. May 15 12:14:49.076420 systemd-tmpfiles[1281]: ACLs are not supported, ignoring. May 15 12:14:49.076504 systemd-tmpfiles[1281]: ACLs are not supported, ignoring. May 15 12:14:49.086478 systemd-tmpfiles[1281]: Detected autofs mount point /boot during canonicalization of boot. May 15 12:14:49.086646 systemd-tmpfiles[1281]: Skipping /boot May 15 12:14:49.122354 systemd-tmpfiles[1281]: Detected autofs mount point /boot during canonicalization of boot. May 15 12:14:49.122384 systemd-tmpfiles[1281]: Skipping /boot May 15 12:14:49.139633 zram_generator::config[1309]: No configuration found. May 15 12:14:49.262139 ldconfig[1248]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 15 12:14:49.267921 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. May 15 12:14:49.368170 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. May 15 12:14:49.368720 systemd[1]: Reloading finished in 336 ms. May 15 12:14:49.407839 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. May 15 12:14:49.442074 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 15 12:14:49.452074 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 15 12:14:49.455070 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... May 15 12:14:49.458858 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... May 15 12:14:49.475307 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 15 12:14:49.480851 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... May 15 12:14:49.485962 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 15 12:14:49.486187 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 15 12:14:49.488984 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 15 12:14:49.491917 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 15 12:14:49.501042 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 15 12:14:49.503567 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 15 12:14:49.503731 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). May 15 12:14:49.507631 systemd[1]: Starting systemd-userdbd.service - User Database Manager... May 15 12:14:49.509103 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 15 12:14:49.513430 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 15 12:14:49.513681 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 15 12:14:49.513895 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 15 12:14:49.514009 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). May 15 12:14:49.514128 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 15 12:14:49.520697 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. May 15 12:14:49.523371 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 15 12:14:49.523899 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 15 12:14:49.525946 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 15 12:14:49.526211 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 15 12:14:49.528193 systemd[1]: modprobe@loop.service: Deactivated successfully. May 15 12:14:49.528448 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 15 12:14:49.536205 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 15 12:14:49.536492 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 15 12:14:49.538074 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 15 12:14:49.539436 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 15 12:14:49.539486 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). May 15 12:14:49.539569 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 15 12:14:49.539668 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 15 12:14:49.539736 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 15 12:14:49.540341 systemd[1]: Finished ensure-sysext.service. May 15 12:14:49.548977 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... May 15 12:14:49.550895 systemd[1]: modprobe@drm.service: Deactivated successfully. May 15 12:14:49.551152 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 15 12:14:49.561633 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. May 15 12:14:49.574671 systemd[1]: Started systemd-userdbd.service - User Database Manager. May 15 12:14:49.595919 augenrules[1391]: No rules May 15 12:14:49.597219 systemd[1]: audit-rules.service: Deactivated successfully. May 15 12:14:49.598311 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 15 12:14:49.605874 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. May 15 12:14:49.608474 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. May 15 12:14:49.613761 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 15 12:14:49.616757 systemd[1]: Starting systemd-update-done.service - Update is Completed... May 15 12:14:49.617225 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). May 15 12:14:49.667883 systemd[1]: Finished systemd-update-done.service - Update is Completed. May 15 12:14:49.679800 systemd-udevd[1400]: Using default interface naming scheme 'v255'. May 15 12:14:49.698110 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. May 15 12:14:49.699803 systemd[1]: Reached target time-set.target - System Time Set. May 15 12:14:49.702956 systemd-resolved[1351]: Positive Trust Anchors: May 15 12:14:49.702977 systemd-resolved[1351]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 15 12:14:49.703019 systemd-resolved[1351]: 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 May 15 12:14:49.703782 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 15 12:14:49.708964 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 15 12:14:49.714444 systemd-resolved[1351]: Defaulting to hostname 'linux'. May 15 12:14:49.755672 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 15 12:14:49.757042 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 15 12:14:49.758267 systemd[1]: Reached target sysinit.target - System Initialization. May 15 12:14:49.759435 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. May 15 12:14:49.760801 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. May 15 12:14:49.762093 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. May 15 12:14:49.763503 systemd[1]: Started logrotate.timer - Daily rotation of log files. May 15 12:14:49.764835 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. May 15 12:14:49.766209 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. May 15 12:14:49.767567 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). May 15 12:14:49.767648 systemd[1]: Reached target paths.target - Path Units. May 15 12:14:49.768643 systemd[1]: Reached target timers.target - Timer Units. May 15 12:14:49.770863 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. May 15 12:14:49.773818 systemd[1]: Starting docker.socket - Docker Socket for the API... May 15 12:14:49.777913 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). May 15 12:14:49.779347 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). May 15 12:14:49.780826 systemd[1]: Reached target ssh-access.target - SSH Access Available. May 15 12:14:49.790686 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. May 15 12:14:49.792839 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. May 15 12:14:49.796793 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 15 12:14:49.801041 systemd[1]: Reached target sockets.target - Socket Units. May 15 12:14:49.802667 systemd[1]: Reached target basic.target - Basic System. May 15 12:14:49.805332 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. May 15 12:14:49.805381 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. May 15 12:14:49.807416 systemd[1]: Starting dbus.service - D-Bus System Message Bus... May 15 12:14:49.811857 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... May 15 12:14:49.862854 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... May 15 12:14:49.868153 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... May 15 12:14:49.869491 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). May 15 12:14:49.873827 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... May 15 12:14:49.878807 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... May 15 12:14:49.881900 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... May 15 12:14:49.885875 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... May 15 12:14:49.890826 systemd[1]: Starting systemd-logind.service - User Login Management... May 15 12:14:49.893393 jq[1438]: false May 15 12:14:49.900923 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). May 15 12:14:49.907429 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. May 15 12:14:49.908774 systemd[1]: Starting update-engine.service - Update Engine... May 15 12:14:49.911430 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... May 15 12:14:49.914677 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. May 15 12:14:49.916772 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. May 15 12:14:49.917673 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. May 15 12:14:49.917930 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. May 15 12:14:49.921768 extend-filesystems[1440]: Found loop2 May 15 12:14:49.923035 extend-filesystems[1440]: Found loop3 May 15 12:14:49.923035 extend-filesystems[1440]: Found sr0 May 15 12:14:49.923035 extend-filesystems[1440]: Found vda May 15 12:14:49.923035 extend-filesystems[1440]: Found vda1 May 15 12:14:49.923035 extend-filesystems[1440]: Found vda2 May 15 12:14:49.923035 extend-filesystems[1440]: Found vda3 May 15 12:14:49.923035 extend-filesystems[1440]: Found usr May 15 12:14:49.923035 extend-filesystems[1440]: Found vda4 May 15 12:14:49.935054 extend-filesystems[1440]: Found vda6 May 15 12:14:49.935054 extend-filesystems[1440]: Found vda7 May 15 12:14:49.935054 extend-filesystems[1440]: Found vda9 May 15 12:14:49.926743 systemd[1]: extend-filesystems.service: Deactivated successfully. May 15 12:14:49.949905 jq[1450]: true May 15 12:14:49.932485 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. May 15 12:14:49.945733 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. May 15 12:14:49.946080 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. May 15 12:14:49.953470 google_oslogin_nss_cache[1442]: oslogin_cache_refresh[1442]: Refreshing passwd entry cache May 15 12:14:49.953970 oslogin_cache_refresh[1442]: Refreshing passwd entry cache May 15 12:14:49.960153 google_oslogin_nss_cache[1442]: oslogin_cache_refresh[1442]: Failure getting users, quitting May 15 12:14:49.960284 oslogin_cache_refresh[1442]: Failure getting users, quitting May 15 12:14:49.960403 google_oslogin_nss_cache[1442]: oslogin_cache_refresh[1442]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. May 15 12:14:49.960451 oslogin_cache_refresh[1442]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. May 15 12:14:49.962600 google_oslogin_nss_cache[1442]: oslogin_cache_refresh[1442]: Refreshing group entry cache May 15 12:14:49.962600 google_oslogin_nss_cache[1442]: oslogin_cache_refresh[1442]: Failure getting groups, quitting May 15 12:14:49.962600 google_oslogin_nss_cache[1442]: oslogin_cache_refresh[1442]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. May 15 12:14:49.960634 oslogin_cache_refresh[1442]: Refreshing group entry cache May 15 12:14:49.961438 oslogin_cache_refresh[1442]: Failure getting groups, quitting May 15 12:14:49.961450 oslogin_cache_refresh[1442]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. May 15 12:14:49.964830 systemd[1]: google-oslogin-cache.service: Deactivated successfully. May 15 12:14:49.965625 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. May 15 12:14:49.967710 update_engine[1449]: I20250515 12:14:49.967567 1449 main.cc:92] Flatcar Update Engine starting May 15 12:14:49.976113 systemd[1]: motdgen.service: Deactivated successfully. May 15 12:14:49.976441 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. May 15 12:14:49.979913 jq[1457]: true May 15 12:14:49.984448 systemd-networkd[1411]: lo: Link UP May 15 12:14:49.984464 systemd-networkd[1411]: lo: Gained carrier May 15 12:14:49.988180 systemd-networkd[1411]: Enumeration completed May 15 12:14:49.988378 systemd[1]: Started systemd-networkd.service - Network Configuration. May 15 12:14:49.989135 systemd[1]: Reached target network.target - Network. May 15 12:14:49.992897 systemd-networkd[1411]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 15 12:14:49.992913 systemd-networkd[1411]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 15 12:14:49.994494 systemd-networkd[1411]: eth0: Link UP May 15 12:14:49.994906 systemd-networkd[1411]: eth0: Gained carrier May 15 12:14:49.995016 systemd-networkd[1411]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 15 12:14:49.998350 dbus-daemon[1435]: [system] SELinux support is enabled May 15 12:14:50.001805 systemd[1]: Starting containerd.service - containerd container runtime... May 15 12:14:50.004830 update_engine[1449]: I20250515 12:14:50.004776 1449 update_check_scheduler.cc:74] Next update check in 11m20s May 15 12:14:50.005677 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... May 15 12:14:50.006867 systemd-networkd[1411]: eth0: DHCPv4 address 10.0.0.33/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 15 12:14:50.008846 systemd-timesyncd[1371]: Network configuration changed, trying to establish connection. May 15 12:14:50.730634 systemd-timesyncd[1371]: Contacted time server 10.0.0.1:123 (10.0.0.1). May 15 12:14:50.730702 systemd-timesyncd[1371]: Initial clock synchronization to Thu 2025-05-15 12:14:50.730225 UTC. May 15 12:14:50.730992 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... May 15 12:14:50.732711 systemd[1]: Started dbus.service - D-Bus System Message Bus. May 15 12:14:50.733205 systemd-resolved[1351]: Clock change detected. Flushing caches. May 15 12:14:50.736981 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). May 15 12:14:50.738238 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. May 15 12:14:50.738857 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). May 15 12:14:50.738874 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. May 15 12:14:50.746094 systemd[1]: Started update-engine.service - Update Engine. May 15 12:14:50.750418 systemd[1]: Started locksmithd.service - Cluster reboot manager. May 15 12:14:50.769404 bash[1487]: Updated "/home/core/.ssh/authorized_keys" May 15 12:14:50.773522 systemd-logind[1446]: New seat seat0. May 15 12:14:50.775466 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. May 15 12:14:50.780545 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. May 15 12:14:50.784083 (ntainerd)[1492]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 15 12:14:50.784531 systemd[1]: Started systemd-logind.service - User Login Management. May 15 12:14:50.787108 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. May 15 12:14:50.816764 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input4 May 15 12:14:50.821841 kernel: ACPI: button: Power Button [PWRF] May 15 12:14:50.861253 sshd_keygen[1467]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 May 15 12:14:50.876812 locksmithd[1488]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" May 15 12:14:50.935996 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. May 15 12:14:50.942495 systemd[1]: Starting issuegen.service - Generate /run/issue... May 15 12:14:50.960191 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt May 15 12:14:50.960596 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD May 15 12:14:51.032971 kernel: mousedev: PS/2 mouse device common for all mice May 15 12:14:51.042900 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. May 15 12:14:51.046884 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... May 15 12:14:51.048553 systemd[1]: issuegen.service: Deactivated successfully. May 15 12:14:51.048944 systemd[1]: Finished issuegen.service - Generate /run/issue. May 15 12:14:51.056820 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... May 15 12:14:51.109935 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. May 15 12:14:51.133392 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 15 12:14:51.137068 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. May 15 12:14:51.144297 systemd[1]: Started getty@tty1.service - Getty on tty1. May 15 12:14:51.184533 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. May 15 12:14:51.186101 systemd[1]: Reached target getty.target - Login Prompts. May 15 12:14:51.203008 systemd-logind[1446]: Watching system buttons on /dev/input/event2 (Power Button) May 15 12:14:51.223693 systemd-logind[1446]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) May 15 12:14:51.225878 containerd[1492]: time="2025-05-15T12:14:51Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 May 15 12:14:51.226436 kernel: kvm_amd: TSC scaling supported May 15 12:14:51.226476 kernel: kvm_amd: Nested Virtualization enabled May 15 12:14:51.226490 kernel: kvm_amd: Nested Paging enabled May 15 12:14:51.226502 kernel: kvm_amd: LBR virtualization supported May 15 12:14:51.227512 kernel: kvm_amd: Virtual VMLOAD VMSAVE supported May 15 12:14:51.227537 kernel: kvm_amd: Virtual GIF supported May 15 12:14:51.227668 containerd[1492]: time="2025-05-15T12:14:51.227644472Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 May 15 12:14:51.254064 containerd[1492]: time="2025-05-15T12:14:51.254003764Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="11.121µs" May 15 12:14:51.254064 containerd[1492]: time="2025-05-15T12:14:51.254050702Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 May 15 12:14:51.254191 containerd[1492]: time="2025-05-15T12:14:51.254074908Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 May 15 12:14:51.254366 containerd[1492]: time="2025-05-15T12:14:51.254336488Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 May 15 12:14:51.254567 containerd[1492]: time="2025-05-15T12:14:51.254529901Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 May 15 12:14:51.254598 containerd[1492]: time="2025-05-15T12:14:51.254576498Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 15 12:14:51.254684 containerd[1492]: time="2025-05-15T12:14:51.254658572Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 15 12:14:51.254684 containerd[1492]: time="2025-05-15T12:14:51.254679702Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 May 15 12:14:51.254972 containerd[1492]: time="2025-05-15T12:14:51.254944779Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 May 15 12:14:51.254972 containerd[1492]: time="2025-05-15T12:14:51.254966390Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 15 12:14:51.255023 containerd[1492]: time="2025-05-15T12:14:51.254977561Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 15 12:14:51.255023 containerd[1492]: time="2025-05-15T12:14:51.254987309Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 May 15 12:14:51.255135 containerd[1492]: time="2025-05-15T12:14:51.255110751Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 May 15 12:14:51.255446 containerd[1492]: time="2025-05-15T12:14:51.255418808Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 May 15 12:14:51.255513 containerd[1492]: time="2025-05-15T12:14:51.255489511Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 May 15 12:14:51.255513 containerd[1492]: time="2025-05-15T12:14:51.255508637Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 May 15 12:14:51.255580 containerd[1492]: time="2025-05-15T12:14:51.255558991Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 May 15 12:14:51.255814 containerd[1492]: time="2025-05-15T12:14:51.255791568Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 May 15 12:14:51.255890 containerd[1492]: time="2025-05-15T12:14:51.255869574Z" level=info msg="metadata content store policy set" policy=shared May 15 12:14:51.258222 kernel: EDAC MC: Ver: 3.0.0 May 15 12:14:51.263321 containerd[1492]: time="2025-05-15T12:14:51.263281156Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 May 15 12:14:51.263363 containerd[1492]: time="2025-05-15T12:14:51.263351618Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 May 15 12:14:51.263384 containerd[1492]: time="2025-05-15T12:14:51.263368159Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 May 15 12:14:51.263384 containerd[1492]: time="2025-05-15T12:14:51.263379631Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 May 15 12:14:51.263454 containerd[1492]: time="2025-05-15T12:14:51.263436918Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 May 15 12:14:51.263475 containerd[1492]: time="2025-05-15T12:14:51.263452247Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 May 15 12:14:51.263511 containerd[1492]: time="2025-05-15T12:14:51.263477554Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 May 15 12:14:51.263511 containerd[1492]: time="2025-05-15T12:14:51.263490529Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 May 15 12:14:51.263511 containerd[1492]: time="2025-05-15T12:14:51.263500567Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 May 15 12:14:51.263511 containerd[1492]: time="2025-05-15T12:14:51.263510656Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 May 15 12:14:51.263581 containerd[1492]: time="2025-05-15T12:14:51.263520956Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 May 15 12:14:51.263581 containerd[1492]: time="2025-05-15T12:14:51.263533068Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 May 15 12:14:51.263702 containerd[1492]: time="2025-05-15T12:14:51.263681857Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 May 15 12:14:51.263729 containerd[1492]: time="2025-05-15T12:14:51.263706894Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 May 15 12:14:51.263729 containerd[1492]: time="2025-05-15T12:14:51.263722544Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 May 15 12:14:51.263765 containerd[1492]: time="2025-05-15T12:14:51.263732553Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 May 15 12:14:51.263765 containerd[1492]: time="2025-05-15T12:14:51.263742571Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 May 15 12:14:51.263765 containerd[1492]: time="2025-05-15T12:14:51.263752811Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 May 15 12:14:51.263765 containerd[1492]: time="2025-05-15T12:14:51.263763230Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 May 15 12:14:51.263845 containerd[1492]: time="2025-05-15T12:14:51.263777597Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 May 15 12:14:51.263845 containerd[1492]: time="2025-05-15T12:14:51.263789109Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 May 15 12:14:51.263845 containerd[1492]: time="2025-05-15T12:14:51.263799478Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 May 15 12:14:51.263845 containerd[1492]: time="2025-05-15T12:14:51.263809387Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 May 15 12:14:51.263919 containerd[1492]: time="2025-05-15T12:14:51.263881021Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" May 15 12:14:51.263919 containerd[1492]: time="2025-05-15T12:14:51.263900057Z" level=info msg="Start snapshots syncer" May 15 12:14:51.263955 containerd[1492]: time="2025-05-15T12:14:51.263933650Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 May 15 12:14:51.264277 containerd[1492]: time="2025-05-15T12:14:51.264235155Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" May 15 12:14:51.264376 containerd[1492]: time="2025-05-15T12:14:51.264289858Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 May 15 12:14:51.265068 containerd[1492]: time="2025-05-15T12:14:51.265039093Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 May 15 12:14:51.265225 containerd[1492]: time="2025-05-15T12:14:51.265189876Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 May 15 12:14:51.265251 containerd[1492]: time="2025-05-15T12:14:51.265226695Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 May 15 12:14:51.265271 containerd[1492]: time="2025-05-15T12:14:51.265251412Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 May 15 12:14:51.265271 containerd[1492]: time="2025-05-15T12:14:51.265263003Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 May 15 12:14:51.265319 containerd[1492]: time="2025-05-15T12:14:51.265275326Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 May 15 12:14:51.265319 containerd[1492]: time="2025-05-15T12:14:51.265285375Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 May 15 12:14:51.265319 containerd[1492]: time="2025-05-15T12:14:51.265295544Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 May 15 12:14:51.265371 containerd[1492]: time="2025-05-15T12:14:51.265322956Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 May 15 12:14:51.265371 containerd[1492]: time="2025-05-15T12:14:51.265332744Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 May 15 12:14:51.265371 containerd[1492]: time="2025-05-15T12:14:51.265351619Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 May 15 12:14:51.265944 containerd[1492]: time="2025-05-15T12:14:51.265916810Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 May 15 12:14:51.265944 containerd[1492]: time="2025-05-15T12:14:51.265940704Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 May 15 12:14:51.265985 containerd[1492]: time="2025-05-15T12:14:51.265949250Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 15 12:14:51.265985 containerd[1492]: time="2025-05-15T12:14:51.265958728Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 15 12:14:51.265985 containerd[1492]: time="2025-05-15T12:14:51.265966723Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 May 15 12:14:51.265985 containerd[1492]: time="2025-05-15T12:14:51.265975520Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 May 15 12:14:51.265985 containerd[1492]: time="2025-05-15T12:14:51.265985699Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 May 15 12:14:51.266077 containerd[1492]: time="2025-05-15T12:14:51.266005085Z" level=info msg="runtime interface created" May 15 12:14:51.266077 containerd[1492]: time="2025-05-15T12:14:51.266011096Z" level=info msg="created NRI interface" May 15 12:14:51.266077 containerd[1492]: time="2025-05-15T12:14:51.266018861Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 May 15 12:14:51.266077 containerd[1492]: time="2025-05-15T12:14:51.266030743Z" level=info msg="Connect containerd service" May 15 12:14:51.266077 containerd[1492]: time="2025-05-15T12:14:51.266052945Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 15 12:14:51.267379 containerd[1492]: time="2025-05-15T12:14:51.267342303Z" 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" May 15 12:14:51.383572 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 15 12:14:51.443623 containerd[1492]: time="2025-05-15T12:14:51.443560318Z" level=info msg="Start subscribing containerd event" May 15 12:14:51.443761 containerd[1492]: time="2025-05-15T12:14:51.443644356Z" level=info msg="Start recovering state" May 15 12:14:51.443792 containerd[1492]: time="2025-05-15T12:14:51.443778658Z" level=info msg="Start event monitor" May 15 12:14:51.443819 containerd[1492]: time="2025-05-15T12:14:51.443801030Z" level=info msg="Start cni network conf syncer for default" May 15 12:14:51.443819 containerd[1492]: time="2025-05-15T12:14:51.443810798Z" level=info msg="Start streaming server" May 15 12:14:51.443899 containerd[1492]: time="2025-05-15T12:14:51.443826097Z" level=info msg="Registered namespace \"k8s.io\" with NRI" May 15 12:14:51.443899 containerd[1492]: time="2025-05-15T12:14:51.443840423Z" level=info msg="runtime interface starting up..." May 15 12:14:51.443899 containerd[1492]: time="2025-05-15T12:14:51.443848118Z" level=info msg="starting plugins..." May 15 12:14:51.443899 containerd[1492]: time="2025-05-15T12:14:51.443867184Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" May 15 12:14:51.444014 containerd[1492]: time="2025-05-15T12:14:51.443867414Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 15 12:14:51.444046 containerd[1492]: time="2025-05-15T12:14:51.444031492Z" level=info msg=serving... address=/run/containerd/containerd.sock May 15 12:14:51.444173 containerd[1492]: time="2025-05-15T12:14:51.444131419Z" level=info msg="containerd successfully booted in 0.218669s" May 15 12:14:51.444294 systemd[1]: Started containerd.service - containerd container runtime. May 15 12:14:51.999446 systemd-networkd[1411]: eth0: Gained IPv6LL May 15 12:14:52.003279 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. May 15 12:14:52.005578 systemd[1]: Reached target network-online.target - Network is Online. May 15 12:14:52.008881 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... May 15 12:14:52.011859 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... May 15 12:14:52.039692 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. May 15 12:14:52.058198 systemd[1]: coreos-metadata.service: Deactivated successfully. May 15 12:14:52.058476 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. May 15 12:14:52.061528 systemd[1]: Starting etcd-member.service - etcd (System Application Container)... May 15 12:14:52.062785 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. May 15 12:14:52.067593 (-wrapper)[1583]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS May 15 12:14:52.288081 systemd[1]: Starting docker.service - Docker Application Container Engine... May 15 12:14:52.311603 (dockerd)[1595]: 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 May 15 12:14:52.926127 dockerd[1595]: time="2025-05-15T12:14:52.926038954Z" level=info msg="Starting up" May 15 12:14:52.927013 dockerd[1595]: time="2025-05-15T12:14:52.926979008Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" May 15 12:14:53.509893 dockerd[1595]: time="2025-05-15T12:14:53.509821844Z" level=info msg="Loading containers: start." May 15 12:14:53.522196 kernel: Initializing XFRM netlink socket May 15 12:14:53.827416 systemd-networkd[1411]: docker0: Link UP May 15 12:14:53.833913 dockerd[1595]: time="2025-05-15T12:14:53.833848866Z" level=info msg="Loading containers: done." May 15 12:14:53.855527 dockerd[1595]: time="2025-05-15T12:14:53.855463489Z" 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 May 15 12:14:53.855693 dockerd[1595]: time="2025-05-15T12:14:53.855564418Z" level=info msg="Docker daemon" commit=bbd0a17ccc67e48d4a69393287b7fcc4f0578683 containerd-snapshotter=false storage-driver=overlay2 version=28.0.1 May 15 12:14:53.855722 dockerd[1595]: time="2025-05-15T12:14:53.855706966Z" level=info msg="Initializing buildkit" May 15 12:14:53.886782 dockerd[1595]: time="2025-05-15T12:14:53.886719967Z" level=info msg="Completed buildkit initialization" May 15 12:14:53.893674 dockerd[1595]: time="2025-05-15T12:14:53.893621202Z" level=info msg="Daemon has completed initialization" May 15 12:14:53.893841 dockerd[1595]: time="2025-05-15T12:14:53.893691033Z" level=info msg="API listen on /run/docker.sock" May 15 12:14:53.893909 systemd[1]: Started docker.service - Docker Application Container Engine. May 15 12:14:53.897714 etcd-wrapper[1587]: Error response from daemon: No such container: etcd-member May 15 12:14:53.924793 etcd-wrapper[1786]: Error response from daemon: No such container: etcd-member May 15 12:14:53.991291 etcd-wrapper[1807]: Unable to find image 'quay.io/coreos/etcd:v3.5.16' locally May 15 12:14:55.562406 etcd-wrapper[1807]: v3.5.16: Pulling from coreos/etcd May 15 12:14:55.872275 etcd-wrapper[1807]: 804c8aba2cc6: Pulling fs layer May 15 12:14:55.872419 etcd-wrapper[1807]: 2ae710cd8bfe: Pulling fs layer May 15 12:14:55.872419 etcd-wrapper[1807]: d462aa345367: Pulling fs layer May 15 12:14:55.872419 etcd-wrapper[1807]: 0f8b424aa0b9: Pulling fs layer May 15 12:14:55.872419 etcd-wrapper[1807]: d557676654e5: Pulling fs layer May 15 12:14:55.872419 etcd-wrapper[1807]: c8022d07192e: Pulling fs layer May 15 12:14:55.872419 etcd-wrapper[1807]: d858cbc252ad: Pulling fs layer May 15 12:14:55.872419 etcd-wrapper[1807]: 1069fc2daed1: Pulling fs layer May 15 12:14:55.872621 etcd-wrapper[1807]: b40161cd83fc: Pulling fs layer May 15 12:14:55.872621 etcd-wrapper[1807]: 5318d93a3a65: Pulling fs layer May 15 12:14:55.872621 etcd-wrapper[1807]: 307c1adadb60: Pulling fs layer May 15 12:14:55.872621 etcd-wrapper[1807]: fbb01d9e9dc9: Pulling fs layer May 15 12:14:55.872621 etcd-wrapper[1807]: fbfea02ac3cf: Pulling fs layer May 15 12:14:55.872621 etcd-wrapper[1807]: 8c26e4bf18e2: Pulling fs layer May 15 12:14:55.872621 etcd-wrapper[1807]: 1e59a65f8816: Pulling fs layer May 15 12:14:55.872621 etcd-wrapper[1807]: ffbd4ca5f0bd: Pulling fs layer May 15 12:14:55.872621 etcd-wrapper[1807]: 0f8b424aa0b9: Waiting May 15 12:14:55.872621 etcd-wrapper[1807]: 5318d93a3a65: Waiting May 15 12:14:55.872621 etcd-wrapper[1807]: d557676654e5: Waiting May 15 12:14:55.872621 etcd-wrapper[1807]: 307c1adadb60: Waiting May 15 12:14:55.872621 etcd-wrapper[1807]: c8022d07192e: Waiting May 15 12:14:55.872621 etcd-wrapper[1807]: fbb01d9e9dc9: Waiting May 15 12:14:55.872621 etcd-wrapper[1807]: d858cbc252ad: Waiting May 15 12:14:55.872621 etcd-wrapper[1807]: fbfea02ac3cf: Waiting May 15 12:14:55.872621 etcd-wrapper[1807]: 8c26e4bf18e2: Waiting May 15 12:14:55.872621 etcd-wrapper[1807]: 1e59a65f8816: Waiting May 15 12:14:55.872621 etcd-wrapper[1807]: 1069fc2daed1: Waiting May 15 12:14:55.872621 etcd-wrapper[1807]: b40161cd83fc: Waiting May 15 12:14:55.872621 etcd-wrapper[1807]: ffbd4ca5f0bd: Waiting May 15 12:14:56.097757 etcd-wrapper[1807]: 2ae710cd8bfe: Download complete May 15 12:14:56.102050 etcd-wrapper[1807]: 804c8aba2cc6: Download complete May 15 12:14:56.116335 etcd-wrapper[1807]: d462aa345367: Verifying Checksum May 15 12:14:56.116335 etcd-wrapper[1807]: d462aa345367: Download complete May 15 12:14:56.140350 etcd-wrapper[1807]: 804c8aba2cc6: Pull complete May 15 12:14:56.163524 systemd[1]: var-lib-docker-overlay2-4ab47ba23431b46962d6d0584a3775dbcf6c1307a486f66edcbe452c694ec8be-merged.mount: Deactivated successfully. May 15 12:14:56.190983 etcd-wrapper[1807]: 2ae710cd8bfe: Pull complete May 15 12:14:56.333691 etcd-wrapper[1807]: d557676654e5: Verifying Checksum May 15 12:14:56.333691 etcd-wrapper[1807]: d557676654e5: Download complete May 15 12:14:56.338535 etcd-wrapper[1807]: c8022d07192e: Verifying Checksum May 15 12:14:56.338535 etcd-wrapper[1807]: c8022d07192e: Download complete May 15 12:14:56.443615 etcd-wrapper[1807]: 0f8b424aa0b9: Verifying Checksum May 15 12:14:56.443615 etcd-wrapper[1807]: 0f8b424aa0b9: Download complete May 15 12:14:56.607805 etcd-wrapper[1807]: d858cbc252ad: Verifying Checksum May 15 12:14:56.607805 etcd-wrapper[1807]: d858cbc252ad: Download complete May 15 12:14:56.674952 etcd-wrapper[1807]: b40161cd83fc: Verifying Checksum May 15 12:14:56.674952 etcd-wrapper[1807]: b40161cd83fc: Download complete May 15 12:14:56.698476 etcd-wrapper[1807]: 1069fc2daed1: Verifying Checksum May 15 12:14:56.698476 etcd-wrapper[1807]: 1069fc2daed1: Download complete May 15 12:14:56.836259 etcd-wrapper[1807]: 5318d93a3a65: Verifying Checksum May 15 12:14:56.836259 etcd-wrapper[1807]: 5318d93a3a65: Download complete May 15 12:14:56.902986 etcd-wrapper[1807]: 307c1adadb60: Verifying Checksum May 15 12:14:56.902986 etcd-wrapper[1807]: 307c1adadb60: Download complete May 15 12:14:57.041725 etcd-wrapper[1807]: fbb01d9e9dc9: Verifying Checksum May 15 12:14:57.041725 etcd-wrapper[1807]: fbb01d9e9dc9: Download complete May 15 12:14:57.110757 etcd-wrapper[1807]: fbfea02ac3cf: Verifying Checksum May 15 12:14:57.110757 etcd-wrapper[1807]: fbfea02ac3cf: Download complete May 15 12:14:57.155932 systemd[1]: var-lib-docker-overlay2-2e782cccc4d8745c4787cee3a42b88883c9db0e2657d6d880d346a48e12dda43-merged.mount: Deactivated successfully. May 15 12:14:57.262305 etcd-wrapper[1807]: 8c26e4bf18e2: Verifying Checksum May 15 12:14:57.262305 etcd-wrapper[1807]: 8c26e4bf18e2: Download complete May 15 12:14:57.294832 etcd-wrapper[1807]: d462aa345367: Pull complete May 15 12:14:57.345867 etcd-wrapper[1807]: ffbd4ca5f0bd: Verifying Checksum May 15 12:14:57.345867 etcd-wrapper[1807]: ffbd4ca5f0bd: Download complete May 15 12:14:57.380238 systemd[1]: var-lib-docker-overlay2-3f88faa94818af2587c85efc3e2b99e13b6de2cac5526af3f6b51e7d877cae96-merged.mount: Deactivated successfully. May 15 12:14:57.491259 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. May 15 12:14:57.493098 systemd[1]: Started sshd@0-10.0.0.33:22-10.0.0.1:59598.service - OpenSSH per-connection server daemon (10.0.0.1:59598). May 15 12:14:57.598757 etcd-wrapper[1807]: 1e59a65f8816: Verifying Checksum May 15 12:14:57.598757 etcd-wrapper[1807]: 1e59a65f8816: Download complete May 15 12:14:57.868373 sshd[1846]: Accepted publickey for core from 10.0.0.1 port 59598 ssh2: RSA SHA256:PzvkHi2yPlEZU64C+6iShM/DNXKhqlgfV3fjiP6jttI May 15 12:14:57.871367 sshd-session[1846]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:14:57.880582 systemd[1]: Created slice user-500.slice - User Slice of UID 500. May 15 12:14:57.882051 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... May 15 12:14:57.886847 etcd-wrapper[1807]: 0f8b424aa0b9: Pull complete May 15 12:14:57.890123 systemd-logind[1446]: New session 1 of user core. May 15 12:14:57.906458 systemd[1]: var-lib-docker-overlay2-fbdfdb4e388a97591a86ea721b27dfcce2891d2cc86a66558492489733412e6a-merged.mount: Deactivated successfully. May 15 12:14:57.911288 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. May 15 12:14:57.914466 systemd[1]: Starting user@500.service - User Manager for UID 500... May 15 12:14:57.917925 etcd-wrapper[1807]: d557676654e5: Pull complete May 15 12:14:57.929385 (systemd)[1854]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) May 15 12:14:57.933933 systemd-logind[1446]: New session c1 of user core. May 15 12:14:57.952532 etcd-wrapper[1807]: c8022d07192e: Pull complete May 15 12:14:57.990338 etcd-wrapper[1807]: d858cbc252ad: Pull complete May 15 12:14:58.022220 etcd-wrapper[1807]: 1069fc2daed1: Pull complete May 15 12:14:58.057293 etcd-wrapper[1807]: b40161cd83fc: Pull complete May 15 12:14:58.090268 etcd-wrapper[1807]: 5318d93a3a65: Pull complete May 15 12:14:58.103631 systemd[1854]: Queued start job for default target default.target. May 15 12:14:58.113036 systemd[1854]: Created slice app.slice - User Application Slice. May 15 12:14:58.113067 systemd[1854]: Reached target paths.target - Paths. May 15 12:14:58.113117 systemd[1854]: Reached target timers.target - Timers. May 15 12:14:58.114905 systemd[1854]: Starting dbus.socket - D-Bus User Message Bus Socket... May 15 12:14:58.125115 etcd-wrapper[1807]: 307c1adadb60: Pull complete May 15 12:14:58.127317 systemd[1854]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 15 12:14:58.127529 systemd[1854]: Reached target sockets.target - Sockets. May 15 12:14:58.127599 systemd[1854]: Reached target basic.target - Basic System. May 15 12:14:58.127650 systemd[1854]: Reached target default.target - Main User Target. May 15 12:14:58.127693 systemd[1854]: Startup finished in 184ms. May 15 12:14:58.128346 systemd[1]: Started user@500.service - User Manager for UID 500. May 15 12:14:58.130440 systemd[1]: Started session-1.scope - Session 1 of User core. May 15 12:14:58.193384 systemd[1]: Started sshd@1-10.0.0.33:22-10.0.0.1:59604.service - OpenSSH per-connection server daemon (10.0.0.1:59604). May 15 12:14:58.245238 sshd[1902]: Accepted publickey for core from 10.0.0.1 port 59604 ssh2: RSA SHA256:PzvkHi2yPlEZU64C+6iShM/DNXKhqlgfV3fjiP6jttI May 15 12:14:58.247401 sshd-session[1902]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:14:58.254201 systemd-logind[1446]: New session 2 of user core. May 15 12:14:58.263358 systemd[1]: Started session-2.scope - Session 2 of User core. May 15 12:14:58.281117 systemd[1]: var-lib-docker-overlay2-3415e3c663b6eae6305182fb13286515175f8068c2c6b8d5809619e8d6a23995-merged.mount: Deactivated successfully. May 15 12:14:58.322805 sshd[1904]: Connection closed by 10.0.0.1 port 59604 May 15 12:14:58.323270 sshd-session[1902]: pam_unix(sshd:session): session closed for user core May 15 12:14:58.337790 systemd[1]: sshd@1-10.0.0.33:22-10.0.0.1:59604.service: Deactivated successfully. May 15 12:14:58.340227 systemd[1]: session-2.scope: Deactivated successfully. May 15 12:14:58.341007 systemd-logind[1446]: Session 2 logged out. Waiting for processes to exit. May 15 12:14:58.344085 systemd[1]: Started sshd@2-10.0.0.33:22-10.0.0.1:59610.service - OpenSSH per-connection server daemon (10.0.0.1:59610). May 15 12:14:58.344726 systemd-logind[1446]: Removed session 2. May 15 12:14:58.395240 sshd[1910]: Accepted publickey for core from 10.0.0.1 port 59610 ssh2: RSA SHA256:PzvkHi2yPlEZU64C+6iShM/DNXKhqlgfV3fjiP6jttI May 15 12:14:58.396857 sshd-session[1910]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:14:58.402178 systemd-logind[1446]: New session 3 of user core. May 15 12:14:58.412316 systemd[1]: Started session-3.scope - Session 3 of User core. May 15 12:14:58.468631 sshd[1912]: Connection closed by 10.0.0.1 port 59610 May 15 12:14:58.468973 sshd-session[1910]: pam_unix(sshd:session): session closed for user core May 15 12:14:58.473386 systemd[1]: sshd@2-10.0.0.33:22-10.0.0.1:59610.service: Deactivated successfully. May 15 12:14:58.475456 systemd[1]: session-3.scope: Deactivated successfully. May 15 12:14:58.476191 systemd-logind[1446]: Session 3 logged out. Waiting for processes to exit. May 15 12:14:58.477462 systemd-logind[1446]: Removed session 3. May 15 12:14:58.590408 etcd-wrapper[1807]: fbb01d9e9dc9: Pull complete May 15 12:14:58.693869 systemd[1]: var-lib-docker-overlay2-d86a010629c1ce109d01795bbcca75f9e0b81d2fad04fafbd799fbd0ed1847d1-merged.mount: Deactivated successfully. May 15 12:14:58.778824 etcd-wrapper[1807]: fbfea02ac3cf: Pull complete May 15 12:14:58.953653 etcd-wrapper[1807]: 8c26e4bf18e2: Pull complete May 15 12:14:58.983235 etcd-wrapper[1807]: 1e59a65f8816: Pull complete May 15 12:14:59.021660 etcd-wrapper[1807]: ffbd4ca5f0bd: Pull complete May 15 12:14:59.034199 etcd-wrapper[1807]: Digest: sha256:d967d98a12dc220a1a290794711dba7eba04b8ce465e12b02383d1bfbb33e159 May 15 12:14:59.039373 etcd-wrapper[1807]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.16 May 15 12:14:59.124022 containerd[1492]: time="2025-05-15T12:14:59.123958199Z" level=info msg="connecting to shim 35bea57834fd1e8ba17ab56b9186a297d1cb8733e3b7a90aa12294c93517c01c" address="unix:///run/containerd/s/ac5caba7e1a3d90c0249e81eb82700da5a9363c49ad459fc79c91125434c3498" namespace=moby protocol=ttrpc version=3 May 15 12:14:59.157384 systemd[1]: var-lib-docker-overlay2-d4aef9ca14b159d2176a5d7965c777aa69dae12627b1bbcc7dfdf0c7c1b656e8-merged.mount: Deactivated successfully. May 15 12:14:59.173601 systemd[1]: Started docker-35bea57834fd1e8ba17ab56b9186a297d1cb8733e3b7a90aa12294c93517c01c.scope - libcontainer container 35bea57834fd1e8ba17ab56b9186a297d1cb8733e3b7a90aa12294c93517c01c. May 15 12:14:59.200500 systemd-resolved[1351]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 15 12:14:59.287096 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:14:59.286440Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} May 15 12:14:59.287096 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:14:59.286611Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"3cdb2169c32440448a098b1c3c1c9c89"} May 15 12:14:59.287096 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:14:59.286644Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} May 15 12:14:59.287096 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:14:59.286661Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.16"} May 15 12:14:59.287096 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:14:59.286668Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} May 15 12:14:59.287096 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:14:59.286673Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} May 15 12:14:59.287096 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:14:59.286726Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} May 15 12:14:59.287096 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:14:59.286748Z","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.33:2379"]} May 15 12:14:59.287096 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:14:59.286825Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} May 15 12:14:59.287096 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:14:59.286838Z","caller":"embed/etcd.go:128","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]} May 15 12:14:59.287841 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:14:59.287475Z","caller":"embed/etcd.go:136","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} May 15 12:14:59.289175 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:14:59.287638Z","caller":"embed/etcd.go:311","msg":"starting an etcd server","etcd-version":"3.5.16","git-sha":"f20bbad","go-version":"go1.22.7","go-os":"linux","go-arch":"amd64","max-cpu-set":4,"max-cpu-available":4,"member-initialized":false,"name":"3cdb2169c32440448a098b1c3c1c9c89","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.33:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"3cdb2169c32440448a098b1c3c1c9c89=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"} May 15 12:14:59.292029 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:14:59.291701Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.483845ms"} May 15 12:14:59.299652 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:14:59.298919Z","caller":"etcdserver/raft.go:505","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"} May 15 12:14:59.299652 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:14:59.299057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"} May 15 12:14:59.299652 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:14:59.299129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"} May 15 12:14:59.299652 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:14:59.299146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} May 15 12:14:59.299652 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:14:59.299181Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"} May 15 12:14:59.299652 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:14:59.299239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} May 15 12:14:59.304770 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:14:59.304434Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} May 15 12:14:59.306062 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:14:59.305801Z","caller":"mvcc/kvstore.go:423","msg":"kvstore restored","current-rev":1} May 15 12:14:59.307530 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:14:59.307232Z","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"} May 15 12:14:59.308788 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:14:59.308448Z","caller":"etcdserver/server.go:873","msg":"starting etcd server","local-member-id":"8e9e05c52164694d","local-server-version":"3.5.16","cluster-version":"to_be_decided"} May 15 12:14:59.309360 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:14:59.308774Z","caller":"etcdserver/server.go:757","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8e9e05c52164694d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} May 15 12:14:59.309360 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:14:59.308907Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} May 15 12:14:59.309360 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:14:59.308961Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} May 15 12:14:59.309360 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:14:59.309013Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} May 15 12:14:59.309360 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:14:59.309023Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} May 15 12:14:59.310717 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:14:59.310094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} May 15 12:14:59.310717 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:14:59.310299Z","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"]} May 15 12:14:59.312525 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:14:59.312295Z","caller":"embed/etcd.go:600","msg":"serving peer traffic","address":"127.0.0.1:2380"} May 15 12:14:59.312525 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:14:59.312319Z","caller":"embed/etcd.go:572","msg":"cmux::serve","address":"127.0.0.1:2380"} May 15 12:14:59.312525 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:14:59.312337Z","caller":"embed/etcd.go:280","msg":"now serving peer/client/metrics","local-member-id":"8e9e05c52164694d","initial-advertise-peer-urls":["http://localhost:2380"],"listen-peer-urls":["http://localhost:2380"],"advertise-client-urls":["http://10.0.0.33:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} May 15 12:15:00.101471 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:15:00.100829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"} May 15 12:15:00.101471 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:15:00.100943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"} May 15 12:15:00.101471 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:15:00.100989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"} May 15 12:15:00.101471 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:15:00.101024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"} May 15 12:15:00.101471 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:15:00.101038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"} May 15 12:15:00.101471 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:15:00.101054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"} May 15 12:15:00.101471 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:15:00.101065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"} May 15 12:15:00.111388 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:15:00.110653Z","caller":"etcdserver/server.go:2140","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:3cdb2169c32440448a098b1c3c1c9c89 ClientURLs:[http://10.0.0.33:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"} May 15 12:15:00.111388 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:15:00.110745Z","caller":"etcdserver/server.go:2651","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} May 15 12:15:00.111388 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:15:00.110747Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} May 15 12:15:00.111612 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:15:00.111024Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} May 15 12:15:00.112103 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:15:00.111522Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} May 15 12:15:00.111984 systemd[1]: Started etcd-member.service - etcd (System Application Container). May 15 12:15:00.112586 systemd[1]: Reached target multi-user.target - Multi-User System. May 15 12:15:00.113254 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:15:00.112084Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} May 15 12:15:00.113254 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:15:00.112676Z","caller":"embed/serve.go:187","msg":"serving client traffic insecurely; this is strongly discouraged!","traffic":"grpc+http","address":"[::]:2379"} May 15 12:15:00.113109 systemd[1]: Startup finished in 3.170s (kernel) + 4.902s (initrd) + 11.876s (userspace) = 19.948s. May 15 12:15:00.125389 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:15:00.125113Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"} May 15 12:15:00.125389 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:15:00.125210Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} May 15 12:15:00.125389 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:15:00.125272Z","caller":"etcdserver/server.go:2675","msg":"cluster version is updated","cluster-version":"3.5"} May 15 12:15:08.485646 systemd[1]: Started sshd@3-10.0.0.33:22-10.0.0.1:40512.service - OpenSSH per-connection server daemon (10.0.0.1:40512). May 15 12:15:08.552400 sshd[1992]: Accepted publickey for core from 10.0.0.1 port 40512 ssh2: RSA SHA256:PzvkHi2yPlEZU64C+6iShM/DNXKhqlgfV3fjiP6jttI May 15 12:15:08.554290 sshd-session[1992]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:15:08.559221 systemd-logind[1446]: New session 4 of user core. May 15 12:15:08.570398 systemd[1]: Started session-4.scope - Session 4 of User core. May 15 12:15:08.629085 sshd[1994]: Connection closed by 10.0.0.1 port 40512 May 15 12:15:08.629549 sshd-session[1992]: pam_unix(sshd:session): session closed for user core May 15 12:15:08.644744 systemd[1]: sshd@3-10.0.0.33:22-10.0.0.1:40512.service: Deactivated successfully. May 15 12:15:08.646536 systemd[1]: session-4.scope: Deactivated successfully. May 15 12:15:08.647364 systemd-logind[1446]: Session 4 logged out. Waiting for processes to exit. May 15 12:15:08.650087 systemd[1]: Started sshd@4-10.0.0.33:22-10.0.0.1:40518.service - OpenSSH per-connection server daemon (10.0.0.1:40518). May 15 12:15:08.650755 systemd-logind[1446]: Removed session 4. May 15 12:15:08.720076 sshd[2000]: Accepted publickey for core from 10.0.0.1 port 40518 ssh2: RSA SHA256:PzvkHi2yPlEZU64C+6iShM/DNXKhqlgfV3fjiP6jttI May 15 12:15:08.721858 sshd-session[2000]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:15:08.727447 systemd-logind[1446]: New session 5 of user core. May 15 12:15:08.742363 systemd[1]: Started session-5.scope - Session 5 of User core. May 15 12:15:08.792412 sshd[2002]: Connection closed by 10.0.0.1 port 40518 May 15 12:15:08.792702 sshd-session[2000]: pam_unix(sshd:session): session closed for user core May 15 12:15:08.801046 systemd[1]: sshd@4-10.0.0.33:22-10.0.0.1:40518.service: Deactivated successfully. May 15 12:15:08.802856 systemd[1]: session-5.scope: Deactivated successfully. May 15 12:15:08.803706 systemd-logind[1446]: Session 5 logged out. Waiting for processes to exit. May 15 12:15:08.806195 systemd[1]: Started sshd@5-10.0.0.33:22-10.0.0.1:40532.service - OpenSSH per-connection server daemon (10.0.0.1:40532). May 15 12:15:08.807071 systemd-logind[1446]: Removed session 5. May 15 12:15:08.858331 sshd[2008]: Accepted publickey for core from 10.0.0.1 port 40532 ssh2: RSA SHA256:PzvkHi2yPlEZU64C+6iShM/DNXKhqlgfV3fjiP6jttI May 15 12:15:08.859737 sshd-session[2008]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:15:08.864180 systemd-logind[1446]: New session 6 of user core. May 15 12:15:08.875319 systemd[1]: Started session-6.scope - Session 6 of User core. May 15 12:15:08.929894 sshd[2010]: Connection closed by 10.0.0.1 port 40532 May 15 12:15:08.930367 sshd-session[2008]: pam_unix(sshd:session): session closed for user core May 15 12:15:08.939188 systemd[1]: sshd@5-10.0.0.33:22-10.0.0.1:40532.service: Deactivated successfully. May 15 12:15:08.941052 systemd[1]: session-6.scope: Deactivated successfully. May 15 12:15:08.941844 systemd-logind[1446]: Session 6 logged out. Waiting for processes to exit. May 15 12:15:08.944711 systemd[1]: Started sshd@6-10.0.0.33:22-10.0.0.1:40548.service - OpenSSH per-connection server daemon (10.0.0.1:40548). May 15 12:15:08.945274 systemd-logind[1446]: Removed session 6. May 15 12:15:09.009531 sshd[2016]: Accepted publickey for core from 10.0.0.1 port 40548 ssh2: RSA SHA256:PzvkHi2yPlEZU64C+6iShM/DNXKhqlgfV3fjiP6jttI May 15 12:15:09.011410 sshd-session[2016]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:15:09.016246 systemd-logind[1446]: New session 7 of user core. May 15 12:15:09.034445 systemd[1]: Started session-7.scope - Session 7 of User core. May 15 12:15:09.094826 sudo[2019]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 May 15 12:15:09.095145 sudo[2019]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 15 12:15:09.116982 sudo[2019]: pam_unix(sudo:session): session closed for user root May 15 12:15:09.118595 sshd[2018]: Connection closed by 10.0.0.1 port 40548 May 15 12:15:09.118977 sshd-session[2016]: pam_unix(sshd:session): session closed for user core May 15 12:15:09.137635 systemd[1]: sshd@6-10.0.0.33:22-10.0.0.1:40548.service: Deactivated successfully. May 15 12:15:09.139156 systemd[1]: session-7.scope: Deactivated successfully. May 15 12:15:09.139854 systemd-logind[1446]: Session 7 logged out. Waiting for processes to exit. May 15 12:15:09.142342 systemd[1]: Started sshd@7-10.0.0.33:22-10.0.0.1:40564.service - OpenSSH per-connection server daemon (10.0.0.1:40564). May 15 12:15:09.143119 systemd-logind[1446]: Removed session 7. May 15 12:15:09.192495 sshd[2025]: Accepted publickey for core from 10.0.0.1 port 40564 ssh2: RSA SHA256:PzvkHi2yPlEZU64C+6iShM/DNXKhqlgfV3fjiP6jttI May 15 12:15:09.193881 sshd-session[2025]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:15:09.198252 systemd-logind[1446]: New session 8 of user core. May 15 12:15:09.211300 systemd[1]: Started session-8.scope - Session 8 of User core. May 15 12:15:09.264171 sudo[2029]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules May 15 12:15:09.264562 sudo[2029]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 15 12:15:09.296401 sudo[2029]: pam_unix(sudo:session): session closed for user root May 15 12:15:09.302867 sudo[2028]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules May 15 12:15:09.303224 sudo[2028]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 15 12:15:09.313669 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 15 12:15:09.367822 augenrules[2051]: No rules May 15 12:15:09.369734 systemd[1]: audit-rules.service: Deactivated successfully. May 15 12:15:09.370066 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 15 12:15:09.371338 sudo[2028]: pam_unix(sudo:session): session closed for user root May 15 12:15:09.372975 sshd[2027]: Connection closed by 10.0.0.1 port 40564 May 15 12:15:09.373317 sshd-session[2025]: pam_unix(sshd:session): session closed for user core May 15 12:15:09.393722 systemd[1]: sshd@7-10.0.0.33:22-10.0.0.1:40564.service: Deactivated successfully. May 15 12:15:09.396079 systemd[1]: session-8.scope: Deactivated successfully. May 15 12:15:09.397043 systemd-logind[1446]: Session 8 logged out. Waiting for processes to exit. May 15 12:15:09.400475 systemd[1]: Started sshd@8-10.0.0.33:22-10.0.0.1:40578.service - OpenSSH per-connection server daemon (10.0.0.1:40578). May 15 12:15:09.401241 systemd-logind[1446]: Removed session 8. May 15 12:15:09.448499 sshd[2060]: Accepted publickey for core from 10.0.0.1 port 40578 ssh2: RSA SHA256:PzvkHi2yPlEZU64C+6iShM/DNXKhqlgfV3fjiP6jttI May 15 12:15:09.449907 sshd-session[2060]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:15:09.454469 systemd-logind[1446]: New session 9 of user core. May 15 12:15:09.464308 systemd[1]: Started session-9.scope - Session 9 of User core. May 15 12:15:09.514802 sshd[2062]: Connection closed by 10.0.0.1 port 40578 May 15 12:15:09.515511 sshd-session[2060]: pam_unix(sshd:session): session closed for user core May 15 12:15:09.523782 systemd[1]: sshd@8-10.0.0.33:22-10.0.0.1:40578.service: Deactivated successfully. May 15 12:15:09.525385 systemd[1]: session-9.scope: Deactivated successfully. May 15 12:15:09.526211 systemd-logind[1446]: Session 9 logged out. Waiting for processes to exit. May 15 12:15:09.528846 systemd[1]: Started sshd@9-10.0.0.33:22-10.0.0.1:40580.service - OpenSSH per-connection server daemon (10.0.0.1:40580). May 15 12:15:09.529618 systemd-logind[1446]: Removed session 9. May 15 12:15:09.571650 sshd[2070]: Accepted publickey for core from 10.0.0.1 port 40580 ssh2: RSA SHA256:PzvkHi2yPlEZU64C+6iShM/DNXKhqlgfV3fjiP6jttI May 15 12:15:09.573180 sshd-session[2070]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:15:09.577660 systemd-logind[1446]: New session 10 of user core. May 15 12:15:09.592291 systemd[1]: Started session-10.scope - Session 10 of User core. May 15 12:15:09.735668 sshd[2072]: Connection closed by 10.0.0.1 port 40580 May 15 12:15:09.736054 sshd-session[2070]: pam_unix(sshd:session): session closed for user core May 15 12:15:09.741070 systemd[1]: sshd@9-10.0.0.33:22-10.0.0.1:40580.service: Deactivated successfully. May 15 12:15:09.743178 systemd[1]: session-10.scope: Deactivated successfully. May 15 12:15:09.744322 systemd-logind[1446]: Session 10 logged out. Waiting for processes to exit. May 15 12:15:09.745711 systemd-logind[1446]: Removed session 10. May 15 12:15:36.190825 update_engine[1449]: I20250515 12:15:36.190701 1449 update_attempter.cc:509] Updating boot flags... May 15 12:16:05.813641 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:05.813219Z","caller":"traceutil/trace.go:171","msg":"trace[1976195547] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"164.772691ms","start":"2025-05-15T12:16:05.648434Z","end":"2025-05-15T12:16:05.813207Z","steps":["trace[1976195547] 'process raft request' (duration: 164.649901ms)"],"step_count":1} May 15 12:16:05.813641 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:05.813197Z","caller":"traceutil/trace.go:171","msg":"trace[1420282947] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"164.706596ms","start":"2025-05-15T12:16:05.648471Z","end":"2025-05-15T12:16:05.813177Z","steps":["trace[1420282947] 'process raft request' (duration: 164.656642ms)"],"step_count":1} May 15 12:16:06.376084 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:16:06.375660Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.328897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" limit:1 ","response":"range_response_count:1 size:351"} May 15 12:16:06.376084 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:06.375739Z","caller":"traceutil/trace.go:171","msg":"trace[1770213072] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:316; }","duration":"103.443902ms","start":"2025-05-15T12:16:06.272276Z","end":"2025-05-15T12:16:06.375720Z","steps":["trace[1770213072] 'range keys from in-memory index tree' (duration: 103.242364ms)"],"step_count":1} May 15 12:16:11.104130 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:11.103653Z","caller":"traceutil/trace.go:171","msg":"trace[879428902] linearizableReadLoop","detail":"{readStateIndex:378; appliedIndex:377; }","duration":"131.266258ms","start":"2025-05-15T12:16:10.972364Z","end":"2025-05-15T12:16:11.103631Z","steps":["trace[879428902] 'read index received' (duration: 131.117929ms)","trace[879428902] 'applied index is now lower than readState.Index' (duration: 147.728µs)"],"step_count":2} May 15 12:16:11.104130 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:11.103738Z","caller":"traceutil/trace.go:171","msg":"trace[2083209772] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"172.846823ms","start":"2025-05-15T12:16:10.930885Z","end":"2025-05-15T12:16:11.103732Z","steps":["trace[2083209772] 'process raft request' (duration: 172.633113ms)"],"step_count":1} May 15 12:16:11.104769 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:16:11.103909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.530222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" limit:1 ","response":"range_response_count:1 size:197"} May 15 12:16:11.104769 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:11.104067Z","caller":"traceutil/trace.go:171","msg":"trace[212503645] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:367; }","duration":"131.730389ms","start":"2025-05-15T12:16:10.972321Z","end":"2025-05-15T12:16:11.104051Z","steps":["trace[212503645] 'agreement among raft nodes before linearized reading' (duration: 131.522237ms)"],"step_count":1} May 15 12:16:15.960725 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:15.960143Z","caller":"traceutil/trace.go:171","msg":"trace[909128021] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"100.308896ms","start":"2025-05-15T12:16:15.859812Z","end":"2025-05-15T12:16:15.960121Z","steps":["trace[909128021] 'process raft request' (duration: 100.198259ms)"],"step_count":1} May 15 12:16:18.815910 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:18.815516Z","caller":"traceutil/trace.go:171","msg":"trace[9920113] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"122.555219ms","start":"2025-05-15T12:16:18.692938Z","end":"2025-05-15T12:16:18.815494Z","steps":["trace[9920113] 'process raft request' (duration: 122.39631ms)"],"step_count":1} May 15 12:16:18.875712 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:18.875300Z","caller":"traceutil/trace.go:171","msg":"trace[1595271809] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"178.387294ms","start":"2025-05-15T12:16:18.696890Z","end":"2025-05-15T12:16:18.875278Z","steps":["trace[1595271809] 'process raft request' (duration: 178.197086ms)"],"step_count":1} May 15 12:16:19.032194 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:19.030828Z","caller":"traceutil/trace.go:171","msg":"trace[1243622305] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"147.887009ms","start":"2025-05-15T12:16:18.882922Z","end":"2025-05-15T12:16:19.030809Z","steps":["trace[1243622305] 'process raft request' (duration: 59.357994ms)","trace[1243622305] 'compare' (duration: 88.392649ms)"],"step_count":2} May 15 12:16:26.138921 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:26.138490Z","caller":"traceutil/trace.go:171","msg":"trace[798180838] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"118.188039ms","start":"2025-05-15T12:16:26.020267Z","end":"2025-05-15T12:16:26.138455Z","steps":["trace[798180838] 'process raft request' (duration: 112.665416ms)"],"step_count":1} May 15 12:16:26.171076 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:26.170650Z","caller":"traceutil/trace.go:171","msg":"trace[837277358] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"126.645722ms","start":"2025-05-15T12:16:26.043986Z","end":"2025-05-15T12:16:26.170632Z","steps":["trace[837277358] 'process raft request' (duration: 126.588475ms)"],"step_count":1} May 15 12:16:26.171076 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:26.170711Z","caller":"traceutil/trace.go:171","msg":"trace[27334888] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"133.077352ms","start":"2025-05-15T12:16:26.037604Z","end":"2025-05-15T12:16:26.170681Z","steps":["trace[27334888] 'process raft request' (duration: 132.825319ms)"],"step_count":1} May 15 12:16:27.147467 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:16:27.147025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.462114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} May 15 12:16:27.147467 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:16:27.147081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.988778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dzvnt\" limit:1 ","response":"range_response_count:1 size:17425"} May 15 12:16:27.147467 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:27.147106Z","caller":"traceutil/trace.go:171","msg":"trace[49531373] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dzvnt; range_end:; response_count:1; response_revision:484; }","duration":"134.04904ms","start":"2025-05-15T12:16:27.013047Z","end":"2025-05-15T12:16:27.147096Z","steps":["trace[49531373] 'range keys from in-memory index tree' (duration: 133.764536ms)"],"step_count":1} May 15 12:16:27.147467 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:27.147108Z","caller":"traceutil/trace.go:171","msg":"trace[1511158164] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:484; }","duration":"296.594132ms","start":"2025-05-15T12:16:26.850496Z","end":"2025-05-15T12:16:27.147090Z","steps":["trace[1511158164] 'range keys from in-memory index tree' (duration: 296.391081ms)"],"step_count":1} May 15 12:16:27.147467 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:16:27.147025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.581402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-dzvnt.183fb26f4bd6e4a0\" limit:1 ","response":"range_response_count:1 size:788"} May 15 12:16:27.148086 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:27.147217Z","caller":"traceutil/trace.go:171","msg":"trace[221005923] range","detail":"{range_begin:/registry/events/kube-system/cilium-dzvnt.183fb26f4bd6e4a0; range_end:; response_count:1; response_revision:484; }","duration":"133.819581ms","start":"2025-05-15T12:16:27.013386Z","end":"2025-05-15T12:16:27.147206Z","steps":["trace[221005923] 'range keys from in-memory index tree' (duration: 133.483539ms)"],"step_count":1} May 15 12:16:27.328136 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:27.327670Z","caller":"traceutil/trace.go:171","msg":"trace[1065446872] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"169.888057ms","start":"2025-05-15T12:16:27.157755Z","end":"2025-05-15T12:16:27.327643Z","steps":["trace[1065446872] 'process raft request' (duration: 108.46871ms)","trace[1065446872] 'compare' (duration: 61.318878ms)"],"step_count":2} May 15 12:16:29.395493 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:16:29.394972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.497358ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} May 15 12:16:29.395493 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:29.395064Z","caller":"traceutil/trace.go:171","msg":"trace[1941860737] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:531; }","duration":"325.775757ms","start":"2025-05-15T12:16:29.069275Z","end":"2025-05-15T12:16:29.395051Z","steps":["trace[1941860737] 'read index received' (duration: 120.772901ms)","trace[1941860737] 'applied index is now lower than readState.Index' (duration: 205.001603ms)"],"step_count":2} May 15 12:16:29.395493 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:29.395133Z","caller":"traceutil/trace.go:171","msg":"trace[153607963] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"326.581445ms","start":"2025-05-15T12:16:29.068540Z","end":"2025-05-15T12:16:29.395122Z","steps":["trace[153607963] 'process raft request' (duration: 121.461012ms)","trace[153607963] 'compare' (duration: 204.383168ms)"],"step_count":2} May 15 12:16:29.396273 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:16:29.395226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-15T12:16:29.068522Z","time spent":"326.67712ms","remote":"10.0.0.37:50614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3671,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} May 15 12:16:29.396273 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:16:29.395385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.080016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dzvnt\" limit:1 ","response":"range_response_count:1 size:17648"} May 15 12:16:29.396273 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:29.395441Z","caller":"traceutil/trace.go:171","msg":"trace[561450701] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dzvnt; range_end:; response_count:1; response_revision:515; }","duration":"326.209205ms","start":"2025-05-15T12:16:29.069223Z","end":"2025-05-15T12:16:29.395432Z","steps":["trace[561450701] 'agreement among raft nodes before linearized reading' (duration: 326.092579ms)"],"step_count":1} May 15 12:16:29.396273 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:16:29.395503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-15T12:16:29.069203Z","time spent":"326.287295ms","remote":"10.0.0.37:50332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":17672,"request content":"key:\"/registry/pods/kube-system/cilium-dzvnt\" limit:1 "} May 15 12:16:29.399549 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:29.399007Z","caller":"traceutil/trace.go:171","msg":"trace[1826023180] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"301.278259ms","start":"2025-05-15T12:16:29.097705Z","end":"2025-05-15T12:16:29.398984Z","steps":["trace[1826023180] 'process raft request' (duration: 300.821536ms)"],"step_count":1} May 15 12:16:29.399549 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:16:29.399140Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-15T12:16:29.097686Z","time spent":"301.377881ms","remote":"10.0.0.37:50200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":177904,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} May 15 12:16:29.399549 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:29.399253Z","caller":"traceutil/trace.go:171","msg":"trace[719185140] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"270.546416ms","start":"2025-05-15T12:16:29.128607Z","end":"2025-05-15T12:16:29.399153Z","steps":["trace[719185140] 'process raft request' (duration: 270.374404ms)"],"step_count":1} May 15 12:16:29.581359 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:29.580962Z","caller":"traceutil/trace.go:171","msg":"trace[1738778051] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"173.745868ms","start":"2025-05-15T12:16:29.407192Z","end":"2025-05-15T12:16:29.580937Z","steps":["trace[1738778051] 'process raft request' (duration: 128.496847ms)","trace[1738778051] 'compare' (duration: 44.999298ms)"],"step_count":2} May 15 12:16:31.781004 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:31.780616Z","caller":"traceutil/trace.go:171","msg":"trace[516907412] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"112.596798ms","start":"2025-05-15T12:16:31.667996Z","end":"2025-05-15T12:16:31.780593Z","steps":["trace[516907412] 'process raft request' (duration: 112.470454ms)"],"step_count":1} May 15 12:16:31.923280 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:31.922802Z","caller":"traceutil/trace.go:171","msg":"trace[1420739610] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"135.905603ms","start":"2025-05-15T12:16:31.786874Z","end":"2025-05-15T12:16:31.922779Z","steps":["trace[1420739610] 'process raft request' (duration: 70.421412ms)","trace[1420739610] 'compare' (duration: 65.243276ms)"],"step_count":2} May 15 12:16:32.121641 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:32.121067Z","caller":"traceutil/trace.go:171","msg":"trace[273539387] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"118.26837ms","start":"2025-05-15T12:16:32.002779Z","end":"2025-05-15T12:16:32.121048Z","steps":["trace[273539387] 'process raft request' (duration: 118.226248ms)"],"step_count":1} May 15 12:16:32.121641 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:32.121088Z","caller":"traceutil/trace.go:171","msg":"trace[1479594475] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"192.973139ms","start":"2025-05-15T12:16:31.928087Z","end":"2025-05-15T12:16:32.121060Z","steps":["trace[1479594475] 'process raft request' (duration: 141.321018ms)","trace[1479594475] 'compare' (duration: 51.474058ms)"],"step_count":2} May 15 12:16:32.121641 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:32.121099Z","caller":"traceutil/trace.go:171","msg":"trace[1096469940] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:564; }","duration":"145.935306ms","start":"2025-05-15T12:16:31.975148Z","end":"2025-05-15T12:16:32.121083Z","steps":["trace[1096469940] 'read index received' (duration: 94.270621ms)","trace[1096469940] 'applied index is now lower than readState.Index' (duration: 51.663002ms)"],"step_count":2} May 15 12:16:32.121641 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:16:32.121211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.057453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests\" limit:1 ","response":"range_response_count:0 size:5"} May 15 12:16:32.121981 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:32.121243Z","caller":"traceutil/trace.go:171","msg":"trace[1315170090] range","detail":"{range_begin:/registry/certificatesigningrequests; range_end:; response_count:0; response_revision:549; }","duration":"146.133189ms","start":"2025-05-15T12:16:31.975102Z","end":"2025-05-15T12:16:32.121235Z","steps":["trace[1315170090] 'agreement among raft nodes before linearized reading' (duration: 146.020833ms)"],"step_count":1} May 15 12:16:32.121981 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:16:32.121281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.303512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" limit:1 ","response":"range_response_count:1 size:209"} May 15 12:16:32.121981 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:32.121305Z","caller":"traceutil/trace.go:171","msg":"trace[578693450] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:549; }","duration":"133.346194ms","start":"2025-05-15T12:16:31.987949Z","end":"2025-05-15T12:16:32.121296Z","steps":["trace[578693450] 'agreement among raft nodes before linearized reading' (duration: 133.308912ms)"],"step_count":1} May 15 12:16:33.705398 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:33.705031Z","caller":"traceutil/trace.go:171","msg":"trace[2001950905] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"174.374967ms","start":"2025-05-15T12:16:33.530642Z","end":"2025-05-15T12:16:33.705017Z","steps":["trace[2001950905] 'process raft request' (duration: 174.238094ms)"],"step_count":1} May 15 12:16:33.705398 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:33.705016Z","caller":"traceutil/trace.go:171","msg":"trace[1374788506] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:593; }","duration":"132.690943ms","start":"2025-05-15T12:16:33.572304Z","end":"2025-05-15T12:16:33.704995Z","steps":["trace[1374788506] 'read index received' (duration: 132.475217ms)","trace[1374788506] 'applied index is now lower than readState.Index' (duration: 215.085µs)"],"step_count":2} May 15 12:16:33.705398 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:16:33.705112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.788081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/10.0.0.37\" limit:1 ","response":"range_response_count:1 size:125"} May 15 12:16:33.705398 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:33.705145Z","caller":"traceutil/trace.go:171","msg":"trace[1074554004] range","detail":"{range_begin:/registry/masterleases/10.0.0.37; range_end:; response_count:1; response_revision:576; }","duration":"132.866611ms","start":"2025-05-15T12:16:33.572271Z","end":"2025-05-15T12:16:33.705137Z","steps":["trace[1074554004] 'agreement among raft nodes before linearized reading' (duration: 132.780155ms)"],"step_count":1} May 15 12:16:33.871307 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:33.870941Z","caller":"traceutil/trace.go:171","msg":"trace[1375923085] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"153.330558ms","start":"2025-05-15T12:16:33.717582Z","end":"2025-05-15T12:16:33.870912Z","steps":["trace[1375923085] 'process raft request' (duration: 67.831893ms)","trace[1375923085] 'compare' (duration: 85.275304ms)"],"step_count":2} May 15 12:16:34.083897 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:34.083522Z","caller":"traceutil/trace.go:171","msg":"trace[426648603] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"164.171002ms","start":"2025-05-15T12:16:33.919330Z","end":"2025-05-15T12:16:34.083501Z","steps":["trace[426648603] 'process raft request' (duration: 164.052664ms)"],"step_count":1} May 15 12:16:34.088514 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:34.088106Z","caller":"traceutil/trace.go:171","msg":"trace[277213154] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:597; }","duration":"156.020947ms","start":"2025-05-15T12:16:33.932070Z","end":"2025-05-15T12:16:34.088091Z","steps":["trace[277213154] 'read index received' (duration: 151.375049ms)","trace[277213154] 'applied index is now lower than readState.Index' (duration: 4.645046ms)"],"step_count":2} May 15 12:16:34.088514 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:34.088154Z","caller":"traceutil/trace.go:171","msg":"trace[54312493] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"166.92247ms","start":"2025-05-15T12:16:33.921215Z","end":"2025-05-15T12:16:34.088137Z","steps":["trace[54312493] 'process raft request' (duration: 166.817697ms)"],"step_count":1} May 15 12:16:34.088514 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:16:34.088315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.213397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xcpb2\" limit:1 ","response":"range_response_count:1 size:13772"} May 15 12:16:34.088514 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:34.088364Z","caller":"traceutil/trace.go:171","msg":"trace[1798567080] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xcpb2; range_end:; response_count:1; response_revision:580; }","duration":"156.316917ms","start":"2025-05-15T12:16:33.932033Z","end":"2025-05-15T12:16:34.088350Z","steps":["trace[1798567080] 'agreement among raft nodes before linearized reading' (duration: 156.120809ms)"],"step_count":1} May 15 12:16:38.559703 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:38.559179Z","caller":"traceutil/trace.go:171","msg":"trace[1263648120] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"267.860663ms","start":"2025-05-15T12:16:38.291269Z","end":"2025-05-15T12:16:38.559129Z","steps":["trace[1263648120] 'process raft request' (duration: 214.032458ms)","trace[1263648120] 'compare' (duration: 53.713014ms)"],"step_count":2} May 15 12:16:39.082640 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:16:39.082271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.311165ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} May 15 12:16:39.258443 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:39.257802Z","caller":"traceutil/trace.go:171","msg":"trace[1413579102] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"132.021631ms","start":"2025-05-15T12:16:39.125755Z","end":"2025-05-15T12:16:39.257777Z","steps":["trace[1413579102] 'process raft request' (duration: 112.846411ms)","trace[1413579102] 'compare' (duration: 19.034099ms)"],"step_count":2} May 15 12:16:39.397850 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:39.397151Z","caller":"traceutil/trace.go:171","msg":"trace[1492947748] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"106.20336ms","start":"2025-05-15T12:16:39.290918Z","end":"2025-05-15T12:16:39.397121Z","steps":["trace[1492947748] 'process raft request' (duration: 83.744799ms)","trace[1492947748] 'compare' (duration: 22.289618ms)"],"step_count":2} May 15 12:16:40.830662 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:40.830069Z","caller":"traceutil/trace.go:171","msg":"trace[1596918219] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"118.238133ms","start":"2025-05-15T12:16:40.711805Z","end":"2025-05-15T12:16:40.830043Z","steps":["trace[1596918219] 'process raft request' (duration: 96.188021ms)","trace[1596918219] 'compare' (duration: 21.930873ms)"],"step_count":2} May 15 12:16:42.287708 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:42.287372Z","caller":"traceutil/trace.go:171","msg":"trace[2132442277] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"158.67072ms","start":"2025-05-15T12:16:42.128633Z","end":"2025-05-15T12:16:42.287303Z","steps":["trace[2132442277] 'process raft request' (duration: 158.514831ms)"],"step_count":1} May 15 12:16:42.301665 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:42.301308Z","caller":"traceutil/trace.go:171","msg":"trace[1702695319] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"103.957026ms","start":"2025-05-15T12:16:42.197326Z","end":"2025-05-15T12:16:42.301283Z","steps":["trace[1702695319] 'process raft request' (duration: 103.768806ms)"],"step_count":1} May 15 12:16:46.827559 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:46.827230Z","caller":"traceutil/trace.go:171","msg":"trace[169693105] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:680; }","duration":"105.702093ms","start":"2025-05-15T12:16:46.721512Z","end":"2025-05-15T12:16:46.827214Z","steps":["trace[169693105] 'read index received' (duration: 105.573827ms)","trace[169693105] 'applied index is now lower than readState.Index' (duration: 127.705µs)"],"step_count":2} May 15 12:16:46.827559 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:46.827324Z","caller":"traceutil/trace.go:171","msg":"trace[645154914] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"162.05008ms","start":"2025-05-15T12:16:46.665260Z","end":"2025-05-15T12:16:46.827310Z","steps":["trace[645154914] 'process raft request' (duration: 161.83584ms)"],"step_count":1} May 15 12:16:46.828263 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:16:46.827431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.892746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 ","response":"range_response_count:1 size:493"} May 15 12:16:46.828263 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:46.827500Z","caller":"traceutil/trace.go:171","msg":"trace[2032880481] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:660; }","duration":"106.030129ms","start":"2025-05-15T12:16:46.721461Z","end":"2025-05-15T12:16:46.827491Z","steps":["trace[2032880481] 'agreement among raft nodes before linearized reading' (duration: 105.834495ms)"],"step_count":1} May 15 12:16:49.160904 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:16:49.160470Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.0331ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} May 15 12:16:53.130613 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:16:53.130123Z","caller":"traceutil/trace.go:171","msg":"trace[643041418] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"163.013675ms","start":"2025-05-15T12:16:52.967089Z","end":"2025-05-15T12:16:53.130103Z","steps":["trace[643041418] 'process raft request' (duration: 122.658259ms)","trace[643041418] 'compare' (duration: 40.267197ms)"],"step_count":2} May 15 12:17:00.442283 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:00.441573Z","caller":"traceutil/trace.go:171","msg":"trace[1794146801] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"103.176125ms","start":"2025-05-15T12:17:00.338375Z","end":"2025-05-15T12:17:00.441551Z","steps":["trace[1794146801] 'process raft request' (duration: 103.027462ms)"],"step_count":1} May 15 12:17:03.881447 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:03.880926Z","caller":"traceutil/trace.go:171","msg":"trace[1170029533] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"117.394668ms","start":"2025-05-15T12:17:03.763496Z","end":"2025-05-15T12:17:03.880891Z","steps":["trace[1170029533] 'process raft request' (duration: 117.149612ms)"],"step_count":1} May 15 12:17:04.637924 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:17:04.637507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.391874ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} May 15 12:17:04.637924 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:04.637581Z","caller":"traceutil/trace.go:171","msg":"trace[873207426] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"385.760532ms","start":"2025-05-15T12:17:04.251809Z","end":"2025-05-15T12:17:04.637570Z","steps":["trace[873207426] 'process raft request' (duration: 250.251867ms)","trace[873207426] 'compare' (duration: 135.232962ms)"],"step_count":2} May 15 12:17:04.637924 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:17:04.637615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-15T12:17:04.251783Z","time spent":"385.816438ms","remote":"10.0.0.37:50332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17717,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} May 15 12:17:05.354950 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:17:05.354491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.552145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 ","response":"range_response_count:1 size:492"} May 15 12:17:05.354950 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:05.354556Z","caller":"traceutil/trace.go:171","msg":"trace[64665926] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:729; }","duration":"108.665289ms","start":"2025-05-15T12:17:05.245877Z","end":"2025-05-15T12:17:05.354542Z","steps":["trace[64665926] 'range keys from in-memory index tree' (duration: 108.408652ms)"],"step_count":1} May 15 12:17:05.802965 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:05.802275Z","caller":"traceutil/trace.go:171","msg":"trace[419867711] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"444.987682ms","start":"2025-05-15T12:17:05.357261Z","end":"2025-05-15T12:17:05.802249Z","steps":["trace[419867711] 'process raft request' (duration: 444.830995ms)"],"step_count":1} May 15 12:17:05.802965 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:17:05.802439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-15T12:17:05.357244Z","time spent":"445.107058ms","remote":"10.0.0.37:50406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} May 15 12:17:05.837603 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:05.835996Z","caller":"traceutil/trace.go:171","msg":"trace[1899030567] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"478.108407ms","start":"2025-05-15T12:17:05.357868Z","end":"2025-05-15T12:17:05.835977Z","steps":["trace[1899030567] 'process raft request' (duration: 478.013156ms)"],"step_count":1} May 15 12:17:05.837603 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:17:05.836131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-15T12:17:05.357843Z","time spent":"478.218987ms","remote":"10.0.0.37:50598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1779,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} May 15 12:17:06.164361 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:17:06.163796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.747031ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} May 15 12:17:06.164505 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:06.164026Z","caller":"traceutil/trace.go:171","msg":"trace[1292822656] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"238.591302ms","start":"2025-05-15T12:17:05.925422Z","end":"2025-05-15T12:17:06.164014Z","steps":["trace[1292822656] 'process raft request' (duration: 238.483617ms)"],"step_count":1} May 15 12:17:06.164505 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:06.164074Z","caller":"traceutil/trace.go:171","msg":"trace[1287329541] linearizableReadLoop","detail":"{readStateIndex:759; appliedIndex:757; }","duration":"200.538062ms","start":"2025-05-15T12:17:05.963531Z","end":"2025-05-15T12:17:06.164069Z","steps":["trace[1287329541] 'read index received' (duration: 60.515617ms)","trace[1287329541] 'applied index is now lower than readState.Index' (duration: 140.021964ms)"],"step_count":2} May 15 12:17:06.164505 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:06.164109Z","caller":"traceutil/trace.go:171","msg":"trace[1392190418] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"132.475148ms","start":"2025-05-15T12:17:06.031624Z","end":"2025-05-15T12:17:06.164099Z","steps":["trace[1392190418] 'process raft request' (duration: 132.358556ms)"],"step_count":1} May 15 12:17:06.164505 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:17:06.164136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.604326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges\" limit:1 ","response":"range_response_count:0 size:5"} May 15 12:17:06.164505 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:06.164150Z","caller":"traceutil/trace.go:171","msg":"trace[1640517693] range","detail":"{range_begin:/registry/limitranges; range_end:; response_count:0; response_revision:734; }","duration":"200.649393ms","start":"2025-05-15T12:17:05.963495Z","end":"2025-05-15T12:17:06.164145Z","steps":["trace[1640517693] 'agreement among raft nodes before linearized reading' (duration: 200.612493ms)"],"step_count":1} May 15 12:17:06.164635 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:17:06.164202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.130467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments\" limit:1 ","response":"range_response_count:0 size:5"} May 15 12:17:06.164635 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:06.164218Z","caller":"traceutil/trace.go:171","msg":"trace[665015607] range","detail":"{range_begin:/registry/deployments; range_end:; response_count:0; response_revision:734; }","duration":"104.177184ms","start":"2025-05-15T12:17:06.060036Z","end":"2025-05-15T12:17:06.164214Z","steps":["trace[665015607] 'agreement among raft nodes before linearized reading' (duration: 104.152017ms)"],"step_count":1} May 15 12:17:06.164635 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:17:06.164216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.85233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} May 15 12:17:06.164635 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:06.164276Z","caller":"traceutil/trace.go:171","msg":"trace[1928010102] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:734; }","duration":"191.9214ms","start":"2025-05-15T12:17:05.972339Z","end":"2025-05-15T12:17:06.164261Z","steps":["trace[1928010102] 'agreement among raft nodes before linearized reading' (duration: 191.809288ms)"],"step_count":1} May 15 12:17:06.330331 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:06.329896Z","caller":"traceutil/trace.go:171","msg":"trace[1458901357] linearizableReadLoop","detail":"{readStateIndex:762; appliedIndex:760; }","duration":"162.001893ms","start":"2025-05-15T12:17:06.167875Z","end":"2025-05-15T12:17:06.329877Z","steps":["trace[1458901357] 'read index received' (duration: 57.420291ms)","trace[1458901357] 'applied index is now lower than readState.Index' (duration: 104.580941ms)"],"step_count":2} May 15 12:17:06.330331 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:17:06.330059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.166337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/default/nginx-deployment\" limit:1 ","response":"range_response_count:1 size:2288"} May 15 12:17:06.330750 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:06.330085Z","caller":"traceutil/trace.go:171","msg":"trace[501976121] range","detail":"{range_begin:/registry/deployments/default/nginx-deployment; range_end:; response_count:1; response_revision:736; }","duration":"162.225429ms","start":"2025-05-15T12:17:06.167853Z","end":"2025-05-15T12:17:06.330078Z","steps":["trace[501976121] 'agreement among raft nodes before linearized reading' (duration: 162.098819ms)"],"step_count":1} May 15 12:17:06.330750 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:06.330050Z","caller":"traceutil/trace.go:171","msg":"trace[238435868] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"163.758428ms","start":"2025-05-15T12:17:06.166257Z","end":"2025-05-15T12:17:06.330015Z","steps":["trace[238435868] 'process raft request' (duration: 149.944419ms)","trace[238435868] 'compare' (duration: 13.581027ms)"],"step_count":2} May 15 12:17:06.549565 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:17:06.548069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.415078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} May 15 12:17:06.549565 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:06.548275Z","caller":"traceutil/trace.go:171","msg":"trace[1835483768] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"161.712165ms","start":"2025-05-15T12:17:06.386549Z","end":"2025-05-15T12:17:06.548261Z","steps":["trace[1835483768] 'process raft request' (duration: 161.678642ms)"],"step_count":1} May 15 12:17:06.549565 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:06.548505Z","caller":"traceutil/trace.go:171","msg":"trace[761282154] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"164.953868ms","start":"2025-05-15T12:17:06.383539Z","end":"2025-05-15T12:17:06.548493Z","steps":["trace[761282154] 'process raft request' (duration: 56.047086ms)","trace[761282154] 'compare' (duration: 108.302374ms)"],"step_count":2} May 15 12:17:06.549565 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:06.548573Z","caller":"traceutil/trace.go:171","msg":"trace[760134236] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"165.027758ms","start":"2025-05-15T12:17:06.383539Z","end":"2025-05-15T12:17:06.548567Z","steps":["trace[760134236] 'process raft request' (duration: 164.646064ms)"],"step_count":1} May 15 12:17:06.848995 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:06.848577Z","caller":"traceutil/trace.go:171","msg":"trace[757925937] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"128.221795ms","start":"2025-05-15T12:17:06.720331Z","end":"2025-05-15T12:17:06.848553Z","steps":["trace[757925937] 'process raft request' (duration: 94.077693ms)","trace[757925937] 'compare' (duration: 34.031367ms)"],"step_count":2} May 15 12:17:09.112886 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:09.112535Z","caller":"traceutil/trace.go:171","msg":"trace[1481059081] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"259.512627ms","start":"2025-05-15T12:17:08.853004Z","end":"2025-05-15T12:17:09.112517Z","steps":["trace[1481059081] 'process raft request' (duration: 259.375536ms)"],"step_count":1} May 15 12:17:09.525092 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:17:09.524312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.84104ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} May 15 12:17:09.525092 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:09.524513Z","caller":"traceutil/trace.go:171","msg":"trace[1714488564] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"405.067336ms","start":"2025-05-15T12:17:09.119430Z","end":"2025-05-15T12:17:09.524497Z","steps":["trace[1714488564] 'process raft request' (duration: 404.975683ms)"],"step_count":1} May 15 12:17:09.525092 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:09.524591Z","caller":"traceutil/trace.go:171","msg":"trace[1009374843] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:775; }","duration":"543.260254ms","start":"2025-05-15T12:17:08.981236Z","end":"2025-05-15T12:17:09.524497Z","steps":["trace[1009374843] 'read index received' (duration: 131.158844ms)","trace[1009374843] 'applied index is now lower than readState.Index' (duration: 412.099777ms)"],"step_count":2} May 15 12:17:09.525092 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:17:09.524601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-15T12:17:09.119409Z","time spent":"405.135777ms","remote":"10.0.0.37:50406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} May 15 12:17:09.525423 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:17:09.524542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.291213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} May 15 12:17:09.525423 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:09.524700Z","caller":"traceutil/trace.go:171","msg":"trace[1613574286] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:751; }","duration":"543.479149ms","start":"2025-05-15T12:17:08.981210Z","end":"2025-05-15T12:17:09.524689Z","steps":["trace[1613574286] 'agreement among raft nodes before linearized reading' (duration: 543.290793ms)"],"step_count":1} May 15 12:17:09.525423 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:17:09.524832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-15T12:17:08.981193Z","time spent":"543.626448ms","remote":"10.0.0.37:50376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} May 15 12:17:09.525423 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:17:09.524910Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.941471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} May 15 12:17:09.525638 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:09.524940Z","caller":"traceutil/trace.go:171","msg":"trace[1805986015] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:751; }","duration":"310.001725ms","start":"2025-05-15T12:17:09.214932Z","end":"2025-05-15T12:17:09.524934Z","steps":["trace[1805986015] 'agreement among raft nodes before linearized reading' (duration: 309.837032ms)"],"step_count":1} May 15 12:17:09.525638 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:17:09.524961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-15T12:17:09.214913Z","time spent":"310.039908ms","remote":"10.0.0.37:50288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} May 15 12:17:14.257140 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:14.256625Z","caller":"traceutil/trace.go:171","msg":"trace[1912000669] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"107.378869ms","start":"2025-05-15T12:17:14.149228Z","end":"2025-05-15T12:17:14.256607Z","steps":["trace[1912000669] 'process raft request' (duration: 107.272658ms)"],"step_count":1} May 15 12:17:20.016417 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:20.015904Z","caller":"traceutil/trace.go:171","msg":"trace[1584947573] linearizableReadLoop","detail":"{readStateIndex:805; appliedIndex:804; }","duration":"164.908047ms","start":"2025-05-15T12:17:19.850971Z","end":"2025-05-15T12:17:20.015879Z","steps":["trace[1584947573] 'read index received' (duration: 118.159262ms)","trace[1584947573] 'applied index is now lower than readState.Index' (duration: 46.748154ms)"],"step_count":2} May 15 12:17:20.016417 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:17:20.016000Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.011573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} May 15 12:17:20.016417 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:20.015987Z","caller":"traceutil/trace.go:171","msg":"trace[2126189501] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"273.181607ms","start":"2025-05-15T12:17:19.742775Z","end":"2025-05-15T12:17:20.015957Z","steps":["trace[2126189501] 'process raft request' (duration: 226.343233ms)","trace[2126189501] 'compare' (duration: 46.678672ms)"],"step_count":2} May 15 12:17:20.016417 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:20.016022Z","caller":"traceutil/trace.go:171","msg":"trace[2064526660] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:776; }","duration":"165.08366ms","start":"2025-05-15T12:17:19.850930Z","end":"2025-05-15T12:17:20.016014Z","steps":["trace[2064526660] 'agreement among raft nodes before linearized reading' (duration: 165.035409ms)"],"step_count":1} May 15 12:17:20.237575 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:20.237125Z","caller":"traceutil/trace.go:171","msg":"trace[2101846540] linearizableReadLoop","detail":"{readStateIndex:807; appliedIndex:806; }","duration":"128.669716ms","start":"2025-05-15T12:17:20.108434Z","end":"2025-05-15T12:17:20.237103Z","steps":["trace[2101846540] 'read index received' (duration: 34.593749ms)","trace[2101846540] 'applied index is now lower than readState.Index' (duration: 94.075256ms)"],"step_count":2} May 15 12:17:20.237575 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:20.237235Z","caller":"traceutil/trace.go:171","msg":"trace[228790772] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"188.51521ms","start":"2025-05-15T12:17:20.048688Z","end":"2025-05-15T12:17:20.237203Z","steps":["trace[228790772] 'process raft request' (duration: 94.420186ms)","trace[228790772] 'compare' (duration: 93.907359ms)"],"step_count":2} May 15 12:17:20.237575 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:17:20.237302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.854495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 ","response":"range_response_count:1 size:492"} May 15 12:17:20.237575 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:20.237327Z","caller":"traceutil/trace.go:171","msg":"trace[1268257456] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:778; }","duration":"128.929206ms","start":"2025-05-15T12:17:20.108391Z","end":"2025-05-15T12:17:20.237320Z","steps":["trace[1268257456] 'agreement among raft nodes before linearized reading' (duration: 128.813457ms)"],"step_count":1} May 15 12:17:23.302524 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:23.302133Z","caller":"traceutil/trace.go:171","msg":"trace[1319742020] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"198.069048ms","start":"2025-05-15T12:17:23.104025Z","end":"2025-05-15T12:17:23.302094Z","steps":["trace[1319742020] 'process raft request' (duration: 133.036707ms)","trace[1319742020] 'compare' (duration: 64.94724ms)"],"step_count":2} May 15 12:17:23.311787 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:23.311456Z","caller":"traceutil/trace.go:171","msg":"trace[1211253653] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"206.153391ms","start":"2025-05-15T12:17:23.105283Z","end":"2025-05-15T12:17:23.311437Z","steps":["trace[1211253653] 'process raft request' (duration: 206.0633ms)"],"step_count":1} May 15 12:17:23.528830 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:17:23.528331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.029563ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} May 15 12:17:23.528830 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:23.528462Z","caller":"traceutil/trace.go:171","msg":"trace[1923428474] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"130.48519ms","start":"2025-05-15T12:17:23.397964Z","end":"2025-05-15T12:17:23.528449Z","steps":["trace[1923428474] 'compare' (duration: 120.873999ms)"],"step_count":1} May 15 12:17:40.942596 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:17:40.942157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.360179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-dynamic-volume-claim\" limit:1 ","response":"range_response_count:1 size:1720"} May 15 12:17:40.942596 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:40.942263Z","caller":"traceutil/trace.go:171","msg":"trace[115933940] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-dynamic-volume-claim; range_end:; response_count:1; response_revision:868; }","duration":"118.507237ms","start":"2025-05-15T12:17:40.823738Z","end":"2025-05-15T12:17:40.942245Z","steps":["trace[115933940] 'range keys from in-memory index tree' (duration: 118.178607ms)"],"step_count":1} May 15 12:17:54.200039 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:54.199594Z","caller":"traceutil/trace.go:171","msg":"trace[559512986] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"122.44344ms","start":"2025-05-15T12:17:54.077129Z","end":"2025-05-15T12:17:54.199573Z","steps":["trace[559512986] 'process raft request' (duration: 69.635227ms)","trace[559512986] 'compare' (duration: 52.699478ms)"],"step_count":2} May 15 12:17:54.342102 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:54.341700Z","caller":"traceutil/trace.go:171","msg":"trace[1323985066] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"122.687892ms","start":"2025-05-15T12:17:54.218962Z","end":"2025-05-15T12:17:54.341650Z","steps":["trace[1323985066] 'process raft request' (duration: 81.83383ms)","trace[1323985066] 'compare' (duration: 40.730118ms)"],"step_count":2} May 15 12:17:56.498993 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:56.498549Z","caller":"traceutil/trace.go:171","msg":"trace[877947378] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"186.90422ms","start":"2025-05-15T12:17:56.311623Z","end":"2025-05-15T12:17:56.498528Z","steps":["trace[877947378] 'process raft request' (duration: 121.97788ms)","trace[877947378] 'compare' (duration: 64.813226ms)"],"step_count":2} May 15 12:17:58.976399 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:58.976097Z","caller":"traceutil/trace.go:171","msg":"trace[2043506877] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"149.734584ms","start":"2025-05-15T12:17:58.826343Z","end":"2025-05-15T12:17:58.976078Z","steps":["trace[2043506877] 'process raft request' (duration: 78.571747ms)","trace[2043506877] 'compare' (duration: 71.058901ms)"],"step_count":2} May 15 12:17:58.984027 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:58.983743Z","caller":"traceutil/trace.go:171","msg":"trace[506197589] linearizableReadLoop","detail":"{readStateIndex:1068; appliedIndex:1066; }","duration":"133.595084ms","start":"2025-05-15T12:17:58.850134Z","end":"2025-05-15T12:17:58.983729Z","steps":["trace[506197589] 'read index received' (duration: 54.787394ms)","trace[506197589] 'applied index is now lower than readState.Index' (duration: 78.807119ms)"],"step_count":2} May 15 12:17:58.984027 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:17:58.983810Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.659296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} May 15 12:17:58.984027 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:58.983814Z","caller":"traceutil/trace.go:171","msg":"trace[1281334439] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"149.122921ms","start":"2025-05-15T12:17:58.834675Z","end":"2025-05-15T12:17:58.983797Z","steps":["trace[1281334439] 'process raft request' (duration: 148.978588ms)"],"step_count":1} May 15 12:17:58.984027 etcd-wrapper[1807]: {"level":"info","ts":"2025-05-15T12:17:58.983833Z","caller":"traceutil/trace.go:171","msg":"trace[961020094] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1029; }","duration":"133.717375ms","start":"2025-05-15T12:17:58.850110Z","end":"2025-05-15T12:17:58.983827Z","steps":["trace[961020094] 'agreement among raft nodes before linearized reading' (duration: 133.663474ms)"],"step_count":1} May 15 12:17:59.204008 etcd-wrapper[1807]: {"level":"warn","ts":"2025-05-15T12:17:59.203510Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.355055ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"}