Jun 20 19:07:54.954099 kernel: Linux version 6.6.94-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.43 p3) 2.43.1) #1 SMP PREEMPT_DYNAMIC Fri Jun 20 17:12:40 -00 2025 Jun 20 19:07:54.954123 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=c5ce7ee72c13e935b8a741ba19830125b417ea1672f46b6a215da9317cee8e17 Jun 20 19:07:54.954134 kernel: BIOS-provided physical RAM map: Jun 20 19:07:54.954141 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Jun 20 19:07:54.954148 kernel: BIOS-e820: [mem 0x0000000000100000-0x00000000007fffff] usable Jun 20 19:07:54.954154 kernel: BIOS-e820: [mem 0x0000000000800000-0x0000000000807fff] ACPI NVS Jun 20 19:07:54.954162 kernel: BIOS-e820: [mem 0x0000000000808000-0x000000000080afff] usable Jun 20 19:07:54.954169 kernel: BIOS-e820: [mem 0x000000000080b000-0x000000000080bfff] ACPI NVS Jun 20 19:07:54.954175 kernel: BIOS-e820: [mem 0x000000000080c000-0x0000000000810fff] usable Jun 20 19:07:54.954182 kernel: BIOS-e820: [mem 0x0000000000811000-0x00000000008fffff] ACPI NVS Jun 20 19:07:54.954188 kernel: BIOS-e820: [mem 0x0000000000900000-0x000000009bd3efff] usable Jun 20 19:07:54.954197 kernel: BIOS-e820: [mem 0x000000009bd3f000-0x000000009bdfffff] reserved Jun 20 19:07:54.954207 kernel: BIOS-e820: [mem 0x000000009be00000-0x000000009c8ecfff] usable Jun 20 19:07:54.954213 kernel: BIOS-e820: [mem 0x000000009c8ed000-0x000000009cb6cfff] reserved Jun 20 19:07:54.954223 kernel: BIOS-e820: [mem 0x000000009cb6d000-0x000000009cb7efff] ACPI data Jun 20 19:07:54.954231 kernel: BIOS-e820: [mem 0x000000009cb7f000-0x000000009cbfefff] ACPI NVS Jun 20 19:07:54.954240 kernel: BIOS-e820: [mem 0x000000009cbff000-0x000000009ce91fff] usable Jun 20 19:07:54.954247 kernel: BIOS-e820: [mem 0x000000009ce92000-0x000000009ce95fff] reserved Jun 20 19:07:54.954254 kernel: BIOS-e820: [mem 0x000000009ce96000-0x000000009ce97fff] ACPI NVS Jun 20 19:07:54.954261 kernel: BIOS-e820: [mem 0x000000009ce98000-0x000000009cedbfff] usable Jun 20 19:07:54.954268 kernel: BIOS-e820: [mem 0x000000009cedc000-0x000000009cf5ffff] reserved Jun 20 19:07:54.954275 kernel: BIOS-e820: [mem 0x000000009cf60000-0x000000009cffffff] ACPI NVS Jun 20 19:07:54.954283 kernel: BIOS-e820: [mem 0x00000000e0000000-0x00000000efffffff] reserved Jun 20 19:07:54.954290 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Jun 20 19:07:54.954297 kernel: BIOS-e820: [mem 0x00000000ffc00000-0x00000000ffffffff] reserved Jun 20 19:07:54.954304 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Jun 20 19:07:54.954311 kernel: NX (Execute Disable) protection: active Jun 20 19:07:54.954320 kernel: APIC: Static calls initialized Jun 20 19:07:54.954327 kernel: e820: update [mem 0x9b351018-0x9b35ac57] usable ==> usable Jun 20 19:07:54.954334 kernel: e820: update [mem 0x9b351018-0x9b35ac57] usable ==> usable Jun 20 19:07:54.954341 kernel: e820: update [mem 0x9b314018-0x9b350e57] usable ==> usable Jun 20 19:07:54.954348 kernel: e820: update [mem 0x9b314018-0x9b350e57] usable ==> usable Jun 20 19:07:54.954355 kernel: extended physical RAM map: Jun 20 19:07:54.954362 kernel: reserve setup_data: [mem 0x0000000000000000-0x000000000009ffff] usable Jun 20 19:07:54.954369 kernel: reserve setup_data: [mem 0x0000000000100000-0x00000000007fffff] usable Jun 20 19:07:54.954376 kernel: reserve setup_data: [mem 0x0000000000800000-0x0000000000807fff] ACPI NVS Jun 20 19:07:54.954384 kernel: reserve setup_data: [mem 0x0000000000808000-0x000000000080afff] usable Jun 20 19:07:54.954391 kernel: reserve setup_data: [mem 0x000000000080b000-0x000000000080bfff] ACPI NVS Jun 20 19:07:54.954398 kernel: reserve setup_data: [mem 0x000000000080c000-0x0000000000810fff] usable Jun 20 19:07:54.954408 kernel: reserve setup_data: [mem 0x0000000000811000-0x00000000008fffff] ACPI NVS Jun 20 19:07:54.954418 kernel: reserve setup_data: [mem 0x0000000000900000-0x000000009b314017] usable Jun 20 19:07:54.954426 kernel: reserve setup_data: [mem 0x000000009b314018-0x000000009b350e57] usable Jun 20 19:07:54.954433 kernel: reserve setup_data: [mem 0x000000009b350e58-0x000000009b351017] usable Jun 20 19:07:54.954440 kernel: reserve setup_data: [mem 0x000000009b351018-0x000000009b35ac57] usable Jun 20 19:07:54.954447 kernel: reserve setup_data: [mem 0x000000009b35ac58-0x000000009bd3efff] usable Jun 20 19:07:54.954459 kernel: reserve setup_data: [mem 0x000000009bd3f000-0x000000009bdfffff] reserved Jun 20 19:07:54.954467 kernel: reserve setup_data: [mem 0x000000009be00000-0x000000009c8ecfff] usable Jun 20 19:07:54.954474 kernel: reserve setup_data: [mem 0x000000009c8ed000-0x000000009cb6cfff] reserved Jun 20 19:07:54.954481 kernel: reserve setup_data: [mem 0x000000009cb6d000-0x000000009cb7efff] ACPI data Jun 20 19:07:54.954489 kernel: reserve setup_data: [mem 0x000000009cb7f000-0x000000009cbfefff] ACPI NVS Jun 20 19:07:54.954496 kernel: reserve setup_data: [mem 0x000000009cbff000-0x000000009ce91fff] usable Jun 20 19:07:54.954503 kernel: reserve setup_data: [mem 0x000000009ce92000-0x000000009ce95fff] reserved Jun 20 19:07:54.954511 kernel: reserve setup_data: [mem 0x000000009ce96000-0x000000009ce97fff] ACPI NVS Jun 20 19:07:54.954518 kernel: reserve setup_data: [mem 0x000000009ce98000-0x000000009cedbfff] usable Jun 20 19:07:54.954528 kernel: reserve setup_data: [mem 0x000000009cedc000-0x000000009cf5ffff] reserved Jun 20 19:07:54.954535 kernel: reserve setup_data: [mem 0x000000009cf60000-0x000000009cffffff] ACPI NVS Jun 20 19:07:54.954542 kernel: reserve setup_data: [mem 0x00000000e0000000-0x00000000efffffff] reserved Jun 20 19:07:54.954550 kernel: reserve setup_data: [mem 0x00000000feffc000-0x00000000feffffff] reserved Jun 20 19:07:54.954559 kernel: reserve setup_data: [mem 0x00000000ffc00000-0x00000000ffffffff] reserved Jun 20 19:07:54.954566 kernel: reserve setup_data: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Jun 20 19:07:54.954573 kernel: efi: EFI v2.7 by EDK II Jun 20 19:07:54.954581 kernel: efi: SMBIOS=0x9c988000 ACPI=0x9cb7e000 ACPI 2.0=0x9cb7e014 MEMATTR=0x9ba0d198 RNG=0x9cb73018 Jun 20 19:07:54.954588 kernel: random: crng init done Jun 20 19:07:54.954596 kernel: efi: Remove mem142: MMIO range=[0xffc00000-0xffffffff] (4MB) from e820 map Jun 20 19:07:54.954639 kernel: e820: remove [mem 0xffc00000-0xffffffff] reserved Jun 20 19:07:54.954729 kernel: secureboot: Secure boot disabled Jun 20 19:07:54.954749 kernel: SMBIOS 2.8 present. Jun 20 19:07:54.954760 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS unknown 02/02/2022 Jun 20 19:07:54.954783 kernel: Hypervisor detected: KVM Jun 20 19:07:54.954799 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Jun 20 19:07:54.954807 kernel: kvm-clock: using sched offset of 4481523366 cycles Jun 20 19:07:54.954823 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Jun 20 19:07:54.954841 kernel: tsc: Detected 2794.748 MHz processor Jun 20 19:07:54.954849 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Jun 20 19:07:54.954856 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Jun 20 19:07:54.954864 kernel: last_pfn = 0x9cedc max_arch_pfn = 0x400000000 Jun 20 19:07:54.954875 kernel: MTRR map: 4 entries (2 fixed + 2 variable; max 18), built from 8 variable MTRRs Jun 20 19:07:54.954883 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Jun 20 19:07:54.954890 kernel: Using GB pages for direct mapping Jun 20 19:07:54.954898 kernel: ACPI: Early table checksum verification disabled Jun 20 19:07:54.954906 kernel: ACPI: RSDP 0x000000009CB7E014 000024 (v02 BOCHS ) Jun 20 19:07:54.954913 kernel: ACPI: XSDT 0x000000009CB7D0E8 000054 (v01 BOCHS BXPC 00000001 01000013) Jun 20 19:07:54.954921 kernel: ACPI: FACP 0x000000009CB79000 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) Jun 20 19:07:54.954929 kernel: ACPI: DSDT 0x000000009CB7A000 0021BA (v01 BOCHS BXPC 00000001 BXPC 00000001) Jun 20 19:07:54.954937 kernel: ACPI: FACS 0x000000009CBDD000 000040 Jun 20 19:07:54.954947 kernel: ACPI: APIC 0x000000009CB78000 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) Jun 20 19:07:54.954954 kernel: ACPI: HPET 0x000000009CB77000 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Jun 20 19:07:54.954962 kernel: ACPI: MCFG 0x000000009CB76000 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Jun 20 19:07:54.954970 kernel: ACPI: WAET 0x000000009CB75000 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Jun 20 19:07:54.954977 kernel: ACPI: BGRT 0x000000009CB74000 000038 (v01 INTEL EDK2 00000002 01000013) Jun 20 19:07:54.954985 kernel: ACPI: Reserving FACP table memory at [mem 0x9cb79000-0x9cb790f3] Jun 20 19:07:54.954992 kernel: ACPI: Reserving DSDT table memory at [mem 0x9cb7a000-0x9cb7c1b9] Jun 20 19:07:54.955000 kernel: ACPI: Reserving FACS table memory at [mem 0x9cbdd000-0x9cbdd03f] Jun 20 19:07:54.955007 kernel: ACPI: Reserving APIC table memory at [mem 0x9cb78000-0x9cb7808f] Jun 20 19:07:54.955017 kernel: ACPI: Reserving HPET table memory at [mem 0x9cb77000-0x9cb77037] Jun 20 19:07:54.955025 kernel: ACPI: Reserving MCFG table memory at [mem 0x9cb76000-0x9cb7603b] Jun 20 19:07:54.955033 kernel: ACPI: Reserving WAET table memory at [mem 0x9cb75000-0x9cb75027] Jun 20 19:07:54.955040 kernel: ACPI: Reserving BGRT table memory at [mem 0x9cb74000-0x9cb74037] Jun 20 19:07:54.955047 kernel: No NUMA configuration found Jun 20 19:07:54.955055 kernel: Faking a node at [mem 0x0000000000000000-0x000000009cedbfff] Jun 20 19:07:54.955063 kernel: NODE_DATA(0) allocated [mem 0x9ce3a000-0x9ce3ffff] Jun 20 19:07:54.955070 kernel: Zone ranges: Jun 20 19:07:54.955078 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Jun 20 19:07:54.955088 kernel: DMA32 [mem 0x0000000001000000-0x000000009cedbfff] Jun 20 19:07:54.955096 kernel: Normal empty Jun 20 19:07:54.955105 kernel: Movable zone start for each node Jun 20 19:07:54.955113 kernel: Early memory node ranges Jun 20 19:07:54.955120 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Jun 20 19:07:54.955128 kernel: node 0: [mem 0x0000000000100000-0x00000000007fffff] Jun 20 19:07:54.955135 kernel: node 0: [mem 0x0000000000808000-0x000000000080afff] Jun 20 19:07:54.955143 kernel: node 0: [mem 0x000000000080c000-0x0000000000810fff] Jun 20 19:07:54.955150 kernel: node 0: [mem 0x0000000000900000-0x000000009bd3efff] Jun 20 19:07:54.955160 kernel: node 0: [mem 0x000000009be00000-0x000000009c8ecfff] Jun 20 19:07:54.955168 kernel: node 0: [mem 0x000000009cbff000-0x000000009ce91fff] Jun 20 19:07:54.955175 kernel: node 0: [mem 0x000000009ce98000-0x000000009cedbfff] Jun 20 19:07:54.955183 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009cedbfff] Jun 20 19:07:54.955190 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Jun 20 19:07:54.955198 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Jun 20 19:07:54.955214 kernel: On node 0, zone DMA: 8 pages in unavailable ranges Jun 20 19:07:54.955224 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Jun 20 19:07:54.955232 kernel: On node 0, zone DMA: 239 pages in unavailable ranges Jun 20 19:07:54.955239 kernel: On node 0, zone DMA32: 193 pages in unavailable ranges Jun 20 19:07:54.955247 kernel: On node 0, zone DMA32: 786 pages in unavailable ranges Jun 20 19:07:54.955257 kernel: On node 0, zone DMA32: 6 pages in unavailable ranges Jun 20 19:07:54.955268 kernel: On node 0, zone DMA32: 12580 pages in unavailable ranges Jun 20 19:07:54.955276 kernel: ACPI: PM-Timer IO Port: 0x608 Jun 20 19:07:54.955284 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Jun 20 19:07:54.955292 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Jun 20 19:07:54.955300 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Jun 20 19:07:54.955310 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Jun 20 19:07:54.955318 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Jun 20 19:07:54.955326 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Jun 20 19:07:54.955334 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Jun 20 19:07:54.955341 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Jun 20 19:07:54.955349 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Jun 20 19:07:54.955357 kernel: TSC deadline timer available Jun 20 19:07:54.955365 kernel: smpboot: Allowing 4 CPUs, 0 hotplug CPUs Jun 20 19:07:54.955372 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Jun 20 19:07:54.955383 kernel: kvm-guest: KVM setup pv remote TLB flush Jun 20 19:07:54.955391 kernel: kvm-guest: setup PV sched yield Jun 20 19:07:54.955398 kernel: [mem 0x9d000000-0xdfffffff] available for PCI devices Jun 20 19:07:54.955406 kernel: Booting paravirtualized kernel on KVM Jun 20 19:07:54.955414 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Jun 20 19:07:54.955422 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 Jun 20 19:07:54.955430 kernel: percpu: Embedded 58 pages/cpu s197096 r8192 d32280 u524288 Jun 20 19:07:54.955438 kernel: pcpu-alloc: s197096 r8192 d32280 u524288 alloc=1*2097152 Jun 20 19:07:54.955446 kernel: pcpu-alloc: [0] 0 1 2 3 Jun 20 19:07:54.955456 kernel: kvm-guest: PV spinlocks enabled Jun 20 19:07:54.955464 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Jun 20 19:07:54.955473 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=c5ce7ee72c13e935b8a741ba19830125b417ea1672f46b6a215da9317cee8e17 Jun 20 19:07:54.955481 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Jun 20 19:07:54.955489 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jun 20 19:07:54.955499 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jun 20 19:07:54.955507 kernel: Fallback order for Node 0: 0 Jun 20 19:07:54.955514 kernel: Built 1 zonelists, mobility grouping on. Total pages: 629460 Jun 20 19:07:54.955522 kernel: Policy zone: DMA32 Jun 20 19:07:54.955532 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jun 20 19:07:54.955541 kernel: Memory: 2387720K/2565800K available (14336K kernel code, 2295K rwdata, 22872K rodata, 43488K init, 1588K bss, 177824K reserved, 0K cma-reserved) Jun 20 19:07:54.955549 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Jun 20 19:07:54.955556 kernel: ftrace: allocating 37938 entries in 149 pages Jun 20 19:07:54.955564 kernel: ftrace: allocated 149 pages with 4 groups Jun 20 19:07:54.955572 kernel: Dynamic Preempt: voluntary Jun 20 19:07:54.955580 kernel: rcu: Preemptible hierarchical RCU implementation. Jun 20 19:07:54.955588 kernel: rcu: RCU event tracing is enabled. Jun 20 19:07:54.955599 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Jun 20 19:07:54.955614 kernel: Trampoline variant of Tasks RCU enabled. Jun 20 19:07:54.955621 kernel: Rude variant of Tasks RCU enabled. Jun 20 19:07:54.955630 kernel: Tracing variant of Tasks RCU enabled. Jun 20 19:07:54.955638 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jun 20 19:07:54.955646 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Jun 20 19:07:54.955654 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 Jun 20 19:07:54.955662 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jun 20 19:07:54.955681 kernel: Console: colour dummy device 80x25 Jun 20 19:07:54.955689 kernel: printk: console [ttyS0] enabled Jun 20 19:07:54.955699 kernel: ACPI: Core revision 20230628 Jun 20 19:07:54.955707 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Jun 20 19:07:54.955715 kernel: APIC: Switch to symmetric I/O mode setup Jun 20 19:07:54.955723 kernel: x2apic enabled Jun 20 19:07:54.955730 kernel: APIC: Switched APIC routing to: physical x2apic Jun 20 19:07:54.955741 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() Jun 20 19:07:54.955749 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() Jun 20 19:07:54.955757 kernel: kvm-guest: setup PV IPIs Jun 20 19:07:54.955764 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Jun 20 19:07:54.955775 kernel: tsc: Marking TSC unstable due to TSCs unsynchronized Jun 20 19:07:54.955783 kernel: Calibrating delay loop (skipped) preset value.. 5589.49 BogoMIPS (lpj=2794748) Jun 20 19:07:54.955791 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Jun 20 19:07:54.955799 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 Jun 20 19:07:54.955806 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 Jun 20 19:07:54.955814 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Jun 20 19:07:54.955822 kernel: Spectre V2 : Mitigation: Retpolines Jun 20 19:07:54.955830 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Jun 20 19:07:54.955838 kernel: Spectre V2 : Enabling Speculation Barrier for firmware calls Jun 20 19:07:54.955848 kernel: RETBleed: Mitigation: untrained return thunk Jun 20 19:07:54.955856 kernel: Spectre V2 : User space: Vulnerable Jun 20 19:07:54.955863 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Jun 20 19:07:54.955871 kernel: Speculative Return Stack Overflow: IBPB-extending microcode not applied! Jun 20 19:07:54.955880 kernel: Speculative Return Stack Overflow: WARNING: See https://kernel.org/doc/html/latest/admin-guide/hw-vuln/srso.html for mitigation options. Jun 20 19:07:54.955891 kernel: x86/bugs: return thunk changed Jun 20 19:07:54.955918 kernel: Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode Jun 20 19:07:54.955946 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Jun 20 19:07:54.955964 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Jun 20 19:07:54.955976 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Jun 20 19:07:54.955990 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Jun 20 19:07:54.955998 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format. Jun 20 19:07:54.956012 kernel: Freeing SMP alternatives memory: 32K Jun 20 19:07:54.956026 kernel: pid_max: default: 32768 minimum: 301 Jun 20 19:07:54.956041 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Jun 20 19:07:54.956055 kernel: landlock: Up and running. Jun 20 19:07:54.956069 kernel: SELinux: Initializing. Jun 20 19:07:54.956083 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jun 20 19:07:54.956100 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jun 20 19:07:54.956108 kernel: smpboot: CPU0: AMD EPYC 7402P 24-Core Processor (family: 0x17, model: 0x31, stepping: 0x0) Jun 20 19:07:54.956116 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Jun 20 19:07:54.956124 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Jun 20 19:07:54.956132 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Jun 20 19:07:54.956140 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver. Jun 20 19:07:54.956148 kernel: ... version: 0 Jun 20 19:07:54.956155 kernel: ... bit width: 48 Jun 20 19:07:54.956165 kernel: ... generic registers: 6 Jun 20 19:07:54.956173 kernel: ... value mask: 0000ffffffffffff Jun 20 19:07:54.956181 kernel: ... max period: 00007fffffffffff Jun 20 19:07:54.956189 kernel: ... fixed-purpose events: 0 Jun 20 19:07:54.956196 kernel: ... event mask: 000000000000003f Jun 20 19:07:54.956204 kernel: signal: max sigframe size: 1776 Jun 20 19:07:54.956212 kernel: rcu: Hierarchical SRCU implementation. Jun 20 19:07:54.956220 kernel: rcu: Max phase no-delay instances is 400. Jun 20 19:07:54.956227 kernel: smp: Bringing up secondary CPUs ... Jun 20 19:07:54.956237 kernel: smpboot: x86: Booting SMP configuration: Jun 20 19:07:54.956245 kernel: .... node #0, CPUs: #1 #2 #3 Jun 20 19:07:54.956253 kernel: smp: Brought up 1 node, 4 CPUs Jun 20 19:07:54.956261 kernel: smpboot: Max logical packages: 1 Jun 20 19:07:54.956268 kernel: smpboot: Total of 4 processors activated (22357.98 BogoMIPS) Jun 20 19:07:54.956276 kernel: devtmpfs: initialized Jun 20 19:07:54.956284 kernel: x86/mm: Memory block size: 128MB Jun 20 19:07:54.956292 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x00800000-0x00807fff] (32768 bytes) Jun 20 19:07:54.956299 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x0080b000-0x0080bfff] (4096 bytes) Jun 20 19:07:54.956310 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x00811000-0x008fffff] (978944 bytes) Jun 20 19:07:54.956317 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9cb7f000-0x9cbfefff] (524288 bytes) Jun 20 19:07:54.956325 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9ce96000-0x9ce97fff] (8192 bytes) Jun 20 19:07:54.956333 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9cf60000-0x9cffffff] (655360 bytes) Jun 20 19:07:54.956341 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jun 20 19:07:54.956349 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Jun 20 19:07:54.956357 kernel: pinctrl core: initialized pinctrl subsystem Jun 20 19:07:54.956364 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jun 20 19:07:54.956381 kernel: audit: initializing netlink subsys (disabled) Jun 20 19:07:54.956394 kernel: audit: type=2000 audit(1750446473.177:1): state=initialized audit_enabled=0 res=1 Jun 20 19:07:54.956412 kernel: thermal_sys: Registered thermal governor 'step_wise' Jun 20 19:07:54.956421 kernel: thermal_sys: Registered thermal governor 'user_space' Jun 20 19:07:54.956428 kernel: cpuidle: using governor menu Jun 20 19:07:54.956436 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jun 20 19:07:54.956444 kernel: dca service started, version 1.12.1 Jun 20 19:07:54.956454 kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xe0000000-0xefffffff] (base 0xe0000000) Jun 20 19:07:54.956462 kernel: PCI: Using configuration type 1 for base access Jun 20 19:07:54.956469 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Jun 20 19:07:54.956481 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Jun 20 19:07:54.956489 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Jun 20 19:07:54.956497 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Jun 20 19:07:54.956504 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Jun 20 19:07:54.956512 kernel: ACPI: Added _OSI(Module Device) Jun 20 19:07:54.956520 kernel: ACPI: Added _OSI(Processor Device) Jun 20 19:07:54.956527 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jun 20 19:07:54.956535 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jun 20 19:07:54.956543 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Jun 20 19:07:54.956553 kernel: ACPI: Interpreter enabled Jun 20 19:07:54.956561 kernel: ACPI: PM: (supports S0 S3 S5) Jun 20 19:07:54.956569 kernel: ACPI: Using IOAPIC for interrupt routing Jun 20 19:07:54.956576 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Jun 20 19:07:54.956584 kernel: PCI: Using E820 reservations for host bridge windows Jun 20 19:07:54.956592 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F Jun 20 19:07:54.956605 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Jun 20 19:07:54.956851 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Jun 20 19:07:54.957001 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] Jun 20 19:07:54.957133 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] Jun 20 19:07:54.957144 kernel: PCI host bridge to bus 0000:00 Jun 20 19:07:54.957301 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Jun 20 19:07:54.957432 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Jun 20 19:07:54.957566 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Jun 20 19:07:54.957759 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xdfffffff window] Jun 20 19:07:54.957943 kernel: pci_bus 0000:00: root bus resource [mem 0xf0000000-0xfebfffff window] Jun 20 19:07:54.958066 kernel: pci_bus 0000:00: root bus resource [mem 0x380000000000-0x3807ffffffff window] Jun 20 19:07:54.958191 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Jun 20 19:07:54.958353 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 Jun 20 19:07:54.958501 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 Jun 20 19:07:54.958661 kernel: pci 0000:00:01.0: reg 0x10: [mem 0xc0000000-0xc0ffffff pref] Jun 20 19:07:54.958897 kernel: pci 0000:00:01.0: reg 0x18: [mem 0xc1044000-0xc1044fff] Jun 20 19:07:54.959027 kernel: pci 0000:00:01.0: reg 0x30: [mem 0xffff0000-0xffffffff pref] Jun 20 19:07:54.959156 kernel: pci 0000:00:01.0: BAR 0: assigned to efifb Jun 20 19:07:54.959286 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Jun 20 19:07:54.959460 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 Jun 20 19:07:54.959594 kernel: pci 0000:00:02.0: reg 0x10: [io 0x6100-0x611f] Jun 20 19:07:54.959776 kernel: pci 0000:00:02.0: reg 0x14: [mem 0xc1043000-0xc1043fff] Jun 20 19:07:54.959918 kernel: pci 0000:00:02.0: reg 0x20: [mem 0x380000000000-0x380000003fff 64bit pref] Jun 20 19:07:54.960066 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 Jun 20 19:07:54.960198 kernel: pci 0000:00:03.0: reg 0x10: [io 0x6000-0x607f] Jun 20 19:07:54.960326 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xc1042000-0xc1042fff] Jun 20 19:07:54.960459 kernel: pci 0000:00:03.0: reg 0x20: [mem 0x380000004000-0x380000007fff 64bit pref] Jun 20 19:07:54.960613 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 Jun 20 19:07:54.960764 kernel: pci 0000:00:04.0: reg 0x10: [io 0x60e0-0x60ff] Jun 20 19:07:54.960902 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xc1041000-0xc1041fff] Jun 20 19:07:54.961031 kernel: pci 0000:00:04.0: reg 0x20: [mem 0x380000008000-0x38000000bfff 64bit pref] Jun 20 19:07:54.961161 kernel: pci 0000:00:04.0: reg 0x30: [mem 0xfffc0000-0xffffffff pref] Jun 20 19:07:54.961309 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 Jun 20 19:07:54.961441 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO Jun 20 19:07:54.961616 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 Jun 20 19:07:54.961775 kernel: pci 0000:00:1f.2: reg 0x20: [io 0x60c0-0x60df] Jun 20 19:07:54.961926 kernel: pci 0000:00:1f.2: reg 0x24: [mem 0xc1040000-0xc1040fff] Jun 20 19:07:54.962075 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 Jun 20 19:07:54.962209 kernel: pci 0000:00:1f.3: reg 0x20: [io 0x6080-0x60bf] Jun 20 19:07:54.962221 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Jun 20 19:07:54.962229 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Jun 20 19:07:54.962237 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Jun 20 19:07:54.962245 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Jun 20 19:07:54.962258 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 Jun 20 19:07:54.962265 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 Jun 20 19:07:54.962273 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 Jun 20 19:07:54.962281 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 Jun 20 19:07:54.962290 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 Jun 20 19:07:54.962298 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 Jun 20 19:07:54.962305 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 Jun 20 19:07:54.962313 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 Jun 20 19:07:54.962322 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 Jun 20 19:07:54.962333 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 Jun 20 19:07:54.962341 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 Jun 20 19:07:54.962348 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 Jun 20 19:07:54.962356 kernel: iommu: Default domain type: Translated Jun 20 19:07:54.962364 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Jun 20 19:07:54.962372 kernel: efivars: Registered efivars operations Jun 20 19:07:54.962380 kernel: PCI: Using ACPI for IRQ routing Jun 20 19:07:54.962388 kernel: PCI: pci_cache_line_size set to 64 bytes Jun 20 19:07:54.962395 kernel: e820: reserve RAM buffer [mem 0x0080b000-0x008fffff] Jun 20 19:07:54.962406 kernel: e820: reserve RAM buffer [mem 0x00811000-0x008fffff] Jun 20 19:07:54.962413 kernel: e820: reserve RAM buffer [mem 0x9b314018-0x9bffffff] Jun 20 19:07:54.962421 kernel: e820: reserve RAM buffer [mem 0x9b351018-0x9bffffff] Jun 20 19:07:54.962429 kernel: e820: reserve RAM buffer [mem 0x9bd3f000-0x9bffffff] Jun 20 19:07:54.962437 kernel: e820: reserve RAM buffer [mem 0x9c8ed000-0x9fffffff] Jun 20 19:07:54.962445 kernel: e820: reserve RAM buffer [mem 0x9ce92000-0x9fffffff] Jun 20 19:07:54.962453 kernel: e820: reserve RAM buffer [mem 0x9cedc000-0x9fffffff] Jun 20 19:07:54.962584 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device Jun 20 19:07:54.962784 kernel: pci 0000:00:01.0: vgaarb: bridge control possible Jun 20 19:07:54.962920 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Jun 20 19:07:54.962931 kernel: vgaarb: loaded Jun 20 19:07:54.962939 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Jun 20 19:07:54.962948 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Jun 20 19:07:54.962956 kernel: clocksource: Switched to clocksource kvm-clock Jun 20 19:07:54.962964 kernel: VFS: Disk quotas dquot_6.6.0 Jun 20 19:07:54.962972 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jun 20 19:07:54.962980 kernel: pnp: PnP ACPI init Jun 20 19:07:54.963146 kernel: system 00:05: [mem 0xe0000000-0xefffffff window] has been reserved Jun 20 19:07:54.963158 kernel: pnp: PnP ACPI: found 6 devices Jun 20 19:07:54.963166 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Jun 20 19:07:54.963174 kernel: NET: Registered PF_INET protocol family Jun 20 19:07:54.963182 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Jun 20 19:07:54.963210 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Jun 20 19:07:54.963222 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jun 20 19:07:54.963230 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Jun 20 19:07:54.963241 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Jun 20 19:07:54.963249 kernel: TCP: Hash tables configured (established 32768 bind 32768) Jun 20 19:07:54.963258 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Jun 20 19:07:54.963269 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Jun 20 19:07:54.963277 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jun 20 19:07:54.963286 kernel: NET: Registered PF_XDP protocol family Jun 20 19:07:54.963420 kernel: pci 0000:00:04.0: can't claim BAR 6 [mem 0xfffc0000-0xffffffff pref]: no compatible bridge window Jun 20 19:07:54.963551 kernel: pci 0000:00:04.0: BAR 6: assigned [mem 0x9d000000-0x9d03ffff pref] Jun 20 19:07:54.963699 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Jun 20 19:07:54.963842 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Jun 20 19:07:54.963961 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Jun 20 19:07:54.964080 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xdfffffff window] Jun 20 19:07:54.964296 kernel: pci_bus 0000:00: resource 8 [mem 0xf0000000-0xfebfffff window] Jun 20 19:07:54.964429 kernel: pci_bus 0000:00: resource 9 [mem 0x380000000000-0x3807ffffffff window] Jun 20 19:07:54.964441 kernel: PCI: CLS 0 bytes, default 64 Jun 20 19:07:54.964457 kernel: Initialise system trusted keyrings Jun 20 19:07:54.964468 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Jun 20 19:07:54.964482 kernel: Key type asymmetric registered Jun 20 19:07:54.964490 kernel: Asymmetric key parser 'x509' registered Jun 20 19:07:54.964498 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Jun 20 19:07:54.964507 kernel: io scheduler mq-deadline registered Jun 20 19:07:54.964515 kernel: io scheduler kyber registered Jun 20 19:07:54.964523 kernel: io scheduler bfq registered Jun 20 19:07:54.964531 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Jun 20 19:07:54.964540 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 Jun 20 19:07:54.964548 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 Jun 20 19:07:54.964559 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 Jun 20 19:07:54.964568 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jun 20 19:07:54.964579 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Jun 20 19:07:54.964587 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Jun 20 19:07:54.964598 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Jun 20 19:07:54.964615 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Jun 20 19:07:54.964862 kernel: rtc_cmos 00:04: RTC can wake from S4 Jun 20 19:07:54.964876 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Jun 20 19:07:54.965016 kernel: rtc_cmos 00:04: registered as rtc0 Jun 20 19:07:54.965165 kernel: rtc_cmos 00:04: setting system clock to 2025-06-20T19:07:54 UTC (1750446474) Jun 20 19:07:54.965321 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram Jun 20 19:07:54.965344 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled Jun 20 19:07:54.965363 kernel: efifb: probing for efifb Jun 20 19:07:54.965381 kernel: efifb: framebuffer at 0xc0000000, using 4000k, total 4000k Jun 20 19:07:54.965389 kernel: efifb: mode is 1280x800x32, linelength=5120, pages=1 Jun 20 19:07:54.965397 kernel: efifb: scrolling: redraw Jun 20 19:07:54.965405 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Jun 20 19:07:54.965414 kernel: Console: switching to colour frame buffer device 160x50 Jun 20 19:07:54.965422 kernel: fb0: EFI VGA frame buffer device Jun 20 19:07:54.965430 kernel: pstore: Using crash dump compression: deflate Jun 20 19:07:54.965438 kernel: pstore: Registered efi_pstore as persistent store backend Jun 20 19:07:54.965446 kernel: NET: Registered PF_INET6 protocol family Jun 20 19:07:54.965458 kernel: Segment Routing with IPv6 Jun 20 19:07:54.965466 kernel: In-situ OAM (IOAM) with IPv6 Jun 20 19:07:54.965474 kernel: NET: Registered PF_PACKET protocol family Jun 20 19:07:54.965482 kernel: Key type dns_resolver registered Jun 20 19:07:54.965490 kernel: IPI shorthand broadcast: enabled Jun 20 19:07:54.965499 kernel: sched_clock: Marking stable (1293009804, 152144794)->(1478650977, -33496379) Jun 20 19:07:54.965507 kernel: registered taskstats version 1 Jun 20 19:07:54.965515 kernel: Loading compiled-in X.509 certificates Jun 20 19:07:54.965523 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.94-flatcar: 583832681762bbd3c2cbcca308896cbba88c4497' Jun 20 19:07:54.965531 kernel: Key type .fscrypt registered Jun 20 19:07:54.965544 kernel: Key type fscrypt-provisioning registered Jun 20 19:07:54.965553 kernel: ima: No TPM chip found, activating TPM-bypass! Jun 20 19:07:54.965561 kernel: ima: Allocated hash algorithm: sha1 Jun 20 19:07:54.965569 kernel: ima: No architecture policies found Jun 20 19:07:54.965577 kernel: clk: Disabling unused clocks Jun 20 19:07:54.965585 kernel: Freeing unused kernel image (initmem) memory: 43488K Jun 20 19:07:54.965594 kernel: Write protecting the kernel read-only data: 38912k Jun 20 19:07:54.965608 kernel: Freeing unused kernel image (rodata/data gap) memory: 1704K Jun 20 19:07:54.965619 kernel: Run /init as init process Jun 20 19:07:54.965627 kernel: with arguments: Jun 20 19:07:54.965636 kernel: /init Jun 20 19:07:54.965644 kernel: with environment: Jun 20 19:07:54.965652 kernel: HOME=/ Jun 20 19:07:54.965660 kernel: TERM=linux Jun 20 19:07:54.965680 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Jun 20 19:07:54.965690 systemd[1]: Successfully made /usr/ read-only. Jun 20 19:07:54.965701 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) Jun 20 19:07:54.965714 systemd[1]: Detected virtualization kvm. Jun 20 19:07:54.965727 systemd[1]: Detected architecture x86-64. Jun 20 19:07:54.965738 systemd[1]: Running in initrd. Jun 20 19:07:54.965747 systemd[1]: No hostname configured, using default hostname. Jun 20 19:07:54.965756 systemd[1]: Hostname set to . Jun 20 19:07:54.965764 systemd[1]: Initializing machine ID from VM UUID. Jun 20 19:07:54.965773 systemd[1]: Queued start job for default target initrd.target. Jun 20 19:07:54.965802 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jun 20 19:07:54.965811 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jun 20 19:07:54.965826 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Jun 20 19:07:54.965836 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jun 20 19:07:54.966146 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Jun 20 19:07:54.966160 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Jun 20 19:07:54.966170 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Jun 20 19:07:54.966183 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Jun 20 19:07:54.966192 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jun 20 19:07:54.966201 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jun 20 19:07:54.966210 systemd[1]: Reached target paths.target - Path Units. Jun 20 19:07:54.966218 systemd[1]: Reached target slices.target - Slice Units. Jun 20 19:07:54.966227 systemd[1]: Reached target swap.target - Swaps. Jun 20 19:07:54.966236 systemd[1]: Reached target timers.target - Timer Units. Jun 20 19:07:54.966245 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Jun 20 19:07:54.966253 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jun 20 19:07:54.966265 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jun 20 19:07:54.966274 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Jun 20 19:07:54.966287 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jun 20 19:07:54.966302 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jun 20 19:07:54.966312 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jun 20 19:07:54.966329 systemd[1]: Reached target sockets.target - Socket Units. Jun 20 19:07:54.966339 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Jun 20 19:07:54.966348 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jun 20 19:07:54.966360 systemd[1]: Finished network-cleanup.service - Network Cleanup. Jun 20 19:07:54.966377 systemd[1]: Starting systemd-fsck-usr.service... Jun 20 19:07:54.966387 systemd[1]: Starting systemd-journald.service - Journal Service... Jun 20 19:07:54.966396 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jun 20 19:07:54.966405 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jun 20 19:07:54.966413 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Jun 20 19:07:54.966422 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jun 20 19:07:54.966441 systemd[1]: Finished systemd-fsck-usr.service. Jun 20 19:07:54.966461 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jun 20 19:07:54.967162 systemd-journald[194]: Collecting audit messages is disabled. Jun 20 19:07:54.967192 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jun 20 19:07:54.967202 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jun 20 19:07:54.967211 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jun 20 19:07:54.967220 systemd-journald[194]: Journal started Jun 20 19:07:54.967241 systemd-journald[194]: Runtime Journal (/run/log/journal/823fc40cd78849a0897f641a28f10194) is 6M, max 48.2M, 42.2M free. Jun 20 19:07:54.956453 systemd-modules-load[195]: Inserted module 'overlay' Jun 20 19:07:54.969701 systemd[1]: Started systemd-journald.service - Journal Service. Jun 20 19:07:54.984145 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jun 20 19:07:54.988856 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jun 20 19:07:54.991927 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jun 20 19:07:54.999696 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Jun 20 19:07:55.001777 systemd-modules-load[195]: Inserted module 'br_netfilter' Jun 20 19:07:55.002719 kernel: Bridge firewalling registered Jun 20 19:07:55.004217 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jun 20 19:07:55.005330 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jun 20 19:07:55.005878 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jun 20 19:07:55.013324 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jun 20 19:07:55.015911 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Jun 20 19:07:55.018221 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jun 20 19:07:55.020942 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jun 20 19:07:55.032999 dracut-cmdline[227]: dracut-dracut-053 Jun 20 19:07:55.036916 dracut-cmdline[227]: Using kernel command line parameters: rd.driver.pre=btrfs rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected verity.usrhash=c5ce7ee72c13e935b8a741ba19830125b417ea1672f46b6a215da9317cee8e17 Jun 20 19:07:55.065280 systemd-resolved[230]: Positive Trust Anchors: Jun 20 19:07:55.065303 systemd-resolved[230]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jun 20 19:07:55.065340 systemd-resolved[230]: 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 Jun 20 19:07:55.068923 systemd-resolved[230]: Defaulting to hostname 'linux'. Jun 20 19:07:55.070503 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jun 20 19:07:55.076547 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jun 20 19:07:55.133708 kernel: SCSI subsystem initialized Jun 20 19:07:55.142698 kernel: Loading iSCSI transport class v2.0-870. Jun 20 19:07:55.153704 kernel: iscsi: registered transport (tcp) Jun 20 19:07:55.175697 kernel: iscsi: registered transport (qla4xxx) Jun 20 19:07:55.175739 kernel: QLogic iSCSI HBA Driver Jun 20 19:07:55.264282 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Jun 20 19:07:55.276790 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Jun 20 19:07:55.302059 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jun 20 19:07:55.302092 kernel: device-mapper: uevent: version 1.0.3 Jun 20 19:07:55.303114 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Jun 20 19:07:55.344701 kernel: raid6: avx2x4 gen() 30399 MB/s Jun 20 19:07:55.361693 kernel: raid6: avx2x2 gen() 31010 MB/s Jun 20 19:07:55.378781 kernel: raid6: avx2x1 gen() 25833 MB/s Jun 20 19:07:55.378803 kernel: raid6: using algorithm avx2x2 gen() 31010 MB/s Jun 20 19:07:55.396793 kernel: raid6: .... xor() 19781 MB/s, rmw enabled Jun 20 19:07:55.396818 kernel: raid6: using avx2x2 recovery algorithm Jun 20 19:07:55.417699 kernel: xor: automatically using best checksumming function avx Jun 20 19:07:55.570708 kernel: Btrfs loaded, zoned=no, fsverity=no Jun 20 19:07:55.585140 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Jun 20 19:07:55.595919 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jun 20 19:07:55.613371 systemd-udevd[413]: Using default interface naming scheme 'v255'. Jun 20 19:07:55.619336 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jun 20 19:07:55.627846 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Jun 20 19:07:55.641410 dracut-pre-trigger[422]: rd.md=0: removing MD RAID activation Jun 20 19:07:55.674176 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Jun 20 19:07:55.687835 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jun 20 19:07:55.757880 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jun 20 19:07:55.769875 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Jun 20 19:07:55.785174 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Jun 20 19:07:55.789114 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jun 20 19:07:55.792266 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jun 20 19:07:55.795188 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jun 20 19:07:55.802718 kernel: cryptd: max_cpu_qlen set to 1000 Jun 20 19:07:55.806820 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Jun 20 19:07:55.817150 kernel: AVX2 version of gcm_enc/dec engaged. Jun 20 19:07:55.817194 kernel: AES CTR mode by8 optimization enabled Jun 20 19:07:55.817063 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Jun 20 19:07:55.830714 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues Jun 20 19:07:55.832698 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Jun 20 19:07:55.832946 kernel: libata version 3.00 loaded. Jun 20 19:07:55.838257 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jun 20 19:07:55.841289 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jun 20 19:07:55.841708 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jun 20 19:07:55.845391 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jun 20 19:07:55.849105 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jun 20 19:07:55.849240 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jun 20 19:07:55.854332 kernel: ahci 0000:00:1f.2: version 3.0 Jun 20 19:07:55.854541 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 Jun 20 19:07:55.853060 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Jun 20 19:07:55.861780 kernel: ahci 0000:00:1f.2: AHCI 0001.0000 32 slots 6 ports 1.5 Gbps 0x3f impl SATA mode Jun 20 19:07:55.862058 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only Jun 20 19:07:55.862887 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jun 20 19:07:55.896958 kernel: scsi host0: ahci Jun 20 19:07:55.897217 kernel: scsi host1: ahci Jun 20 19:07:55.897373 kernel: BTRFS: device fsid 5ff786f3-14e2-4689-ad32-ff903cf13f91 devid 1 transid 38 /dev/vda3 scanned by (udev-worker) (470) Jun 20 19:07:55.898083 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Jun 20 19:07:55.910555 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Jun 20 19:07:55.914179 kernel: scsi host2: ahci Jun 20 19:07:55.931623 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Jun 20 19:07:55.942015 kernel: scsi host3: ahci Jun 20 19:07:55.942220 kernel: scsi host4: ahci Jun 20 19:07:55.932930 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Jun 20 19:07:55.950660 kernel: scsi host5: ahci Jun 20 19:07:55.950878 kernel: ata1: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040100 irq 34 Jun 20 19:07:55.950891 kernel: ata2: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040180 irq 34 Jun 20 19:07:55.950905 kernel: ata3: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040200 irq 34 Jun 20 19:07:55.950915 kernel: ata4: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040280 irq 34 Jun 20 19:07:55.950925 kernel: ata5: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040300 irq 34 Jun 20 19:07:55.950941 kernel: ata6: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040380 irq 34 Jun 20 19:07:55.938869 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Jun 20 19:07:55.957403 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by (udev-worker) (473) Jun 20 19:07:55.956059 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jun 20 19:07:55.956191 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jun 20 19:07:55.959011 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jun 20 19:07:55.960658 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jun 20 19:07:55.978021 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Jun 20 19:07:55.983553 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jun 20 19:07:55.993883 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jun 20 19:07:56.007721 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jun 20 19:07:56.263711 kernel: ata6: SATA link down (SStatus 0 SControl 300) Jun 20 19:07:56.263773 kernel: ata5: SATA link down (SStatus 0 SControl 300) Jun 20 19:07:56.263785 kernel: ata4: SATA link down (SStatus 0 SControl 300) Jun 20 19:07:56.264706 kernel: ata1: SATA link down (SStatus 0 SControl 300) Jun 20 19:07:56.265699 kernel: ata2: SATA link down (SStatus 0 SControl 300) Jun 20 19:07:56.266700 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Jun 20 19:07:56.267918 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 Jun 20 19:07:56.267931 kernel: ata3.00: applying bridge limits Jun 20 19:07:56.268687 kernel: ata3.00: configured for UDMA/100 Jun 20 19:07:56.270696 kernel: scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 Jun 20 19:07:56.325716 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray Jun 20 19:07:56.326069 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Jun 20 19:07:56.339766 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 Jun 20 19:07:56.978416 disk-uuid[564]: The operation has completed successfully. Jun 20 19:07:56.979724 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jun 20 19:07:57.011789 systemd[1]: disk-uuid.service: Deactivated successfully. Jun 20 19:07:57.011921 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Jun 20 19:07:57.056900 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Jun 20 19:07:57.060609 sh[599]: Success Jun 20 19:07:57.074717 kernel: device-mapper: verity: sha256 using implementation "sha256-ni" Jun 20 19:07:57.114152 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Jun 20 19:07:57.128267 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Jun 20 19:07:57.130993 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Jun 20 19:07:57.142942 kernel: BTRFS info (device dm-0): first mount of filesystem 5ff786f3-14e2-4689-ad32-ff903cf13f91 Jun 20 19:07:57.142973 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Jun 20 19:07:57.142984 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Jun 20 19:07:57.143955 kernel: BTRFS info (device dm-0): disabling log replay at mount time Jun 20 19:07:57.144701 kernel: BTRFS info (device dm-0): using free space tree Jun 20 19:07:57.149832 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Jun 20 19:07:57.150599 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Jun 20 19:07:57.163869 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Jun 20 19:07:57.165584 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Jun 20 19:07:57.186774 kernel: BTRFS info (device vda6): first mount of filesystem 0d4ae0d2-6537-4cbd-8c37-7b929dcf3a9f Jun 20 19:07:57.186830 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Jun 20 19:07:57.186849 kernel: BTRFS info (device vda6): using free space tree Jun 20 19:07:57.190715 kernel: BTRFS info (device vda6): auto enabling async discard Jun 20 19:07:57.195693 kernel: BTRFS info (device vda6): last unmount of filesystem 0d4ae0d2-6537-4cbd-8c37-7b929dcf3a9f Jun 20 19:07:57.201061 systemd[1]: Finished ignition-setup.service - Ignition (setup). Jun 20 19:07:57.209888 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Jun 20 19:07:57.356213 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jun 20 19:07:57.376885 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jun 20 19:07:57.381820 ignition[690]: Ignition 2.20.0 Jun 20 19:07:57.381839 ignition[690]: Stage: fetch-offline Jun 20 19:07:57.381907 ignition[690]: no configs at "/usr/lib/ignition/base.d" Jun 20 19:07:57.381925 ignition[690]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jun 20 19:07:57.382094 ignition[690]: parsed url from cmdline: "" Jun 20 19:07:57.382099 ignition[690]: no config URL provided Jun 20 19:07:57.382106 ignition[690]: reading system config file "/usr/lib/ignition/user.ign" Jun 20 19:07:57.382120 ignition[690]: no config at "/usr/lib/ignition/user.ign" Jun 20 19:07:57.382161 ignition[690]: op(1): [started] loading QEMU firmware config module Jun 20 19:07:57.382168 ignition[690]: op(1): executing: "modprobe" "qemu_fw_cfg" Jun 20 19:07:57.390917 ignition[690]: op(1): [finished] loading QEMU firmware config module Jun 20 19:07:57.392158 ignition[690]: parsing config with SHA512: dd65103183f757098b22ced346bdd88e629298978b757495ec30fe0d5640d64c161a71af2efde21445dc99624b4fbf3f69fb41da9ea15bf75ef089b4ab957ef6 Jun 20 19:07:57.404058 unknown[690]: fetched base config from "system" Jun 20 19:07:57.404074 unknown[690]: fetched user config from "qemu" Jun 20 19:07:57.404345 ignition[690]: fetch-offline: fetch-offline passed Jun 20 19:07:57.404459 ignition[690]: Ignition finished successfully Jun 20 19:07:57.407220 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Jun 20 19:07:57.416363 systemd-networkd[783]: lo: Link UP Jun 20 19:07:57.416378 systemd-networkd[783]: lo: Gained carrier Jun 20 19:07:57.418785 systemd-networkd[783]: Enumeration completed Jun 20 19:07:57.418920 systemd[1]: Started systemd-networkd.service - Network Configuration. Jun 20 19:07:57.419329 systemd-networkd[783]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 20 19:07:57.419336 systemd-networkd[783]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jun 20 19:07:57.421172 systemd-networkd[783]: eth0: Link UP Jun 20 19:07:57.421176 systemd-networkd[783]: eth0: Gained carrier Jun 20 19:07:57.421183 systemd-networkd[783]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 20 19:07:57.421908 systemd[1]: Reached target network.target - Network. Jun 20 19:07:57.423978 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Jun 20 19:07:57.433867 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Jun 20 19:07:57.437755 systemd-networkd[783]: eth0: DHCPv4 address 10.0.0.63/16, gateway 10.0.0.1 acquired from 10.0.0.1 Jun 20 19:07:57.460478 ignition[788]: Ignition 2.20.0 Jun 20 19:07:57.460493 ignition[788]: Stage: kargs Jun 20 19:07:57.461541 ignition[788]: no configs at "/usr/lib/ignition/base.d" Jun 20 19:07:57.461587 ignition[788]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jun 20 19:07:57.463060 ignition[788]: kargs: kargs passed Jun 20 19:07:57.463159 ignition[788]: Ignition finished successfully Jun 20 19:07:57.470020 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Jun 20 19:07:57.476908 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Jun 20 19:07:57.497268 ignition[797]: Ignition 2.20.0 Jun 20 19:07:57.497280 ignition[797]: Stage: disks Jun 20 19:07:57.497460 ignition[797]: no configs at "/usr/lib/ignition/base.d" Jun 20 19:07:57.497472 ignition[797]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jun 20 19:07:57.500462 systemd[1]: Finished ignition-disks.service - Ignition (disks). Jun 20 19:07:57.498063 ignition[797]: disks: disks passed Jun 20 19:07:57.502135 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Jun 20 19:07:57.498109 ignition[797]: Ignition finished successfully Jun 20 19:07:57.503999 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jun 20 19:07:57.505862 systemd[1]: Reached target local-fs.target - Local File Systems. Jun 20 19:07:57.507911 systemd[1]: Reached target sysinit.target - System Initialization. Jun 20 19:07:57.507977 systemd[1]: Reached target basic.target - Basic System. Jun 20 19:07:57.514827 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Jun 20 19:07:57.531680 systemd-fsck[806]: ROOT: clean, 14/553520 files, 52654/553472 blocks Jun 20 19:07:57.537716 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Jun 20 19:07:57.545763 systemd[1]: Mounting sysroot.mount - /sysroot... Jun 20 19:07:57.648693 kernel: EXT4-fs (vda9): mounted filesystem 943f8432-3dc9-4e22-b9bd-c29bf6a1f5e1 r/w with ordered data mode. Quota mode: none. Jun 20 19:07:57.648942 systemd[1]: Mounted sysroot.mount - /sysroot. Jun 20 19:07:57.650345 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Jun 20 19:07:57.677750 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jun 20 19:07:57.679603 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Jun 20 19:07:57.681933 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Jun 20 19:07:57.682022 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jun 20 19:07:57.688705 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 scanned by mount (814) Jun 20 19:07:57.688726 kernel: BTRFS info (device vda6): first mount of filesystem 0d4ae0d2-6537-4cbd-8c37-7b929dcf3a9f Jun 20 19:07:57.682065 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Jun 20 19:07:57.694423 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Jun 20 19:07:57.694441 kernel: BTRFS info (device vda6): using free space tree Jun 20 19:07:57.694452 kernel: BTRFS info (device vda6): auto enabling async discard Jun 20 19:07:57.690008 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Jun 20 19:07:57.695660 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Jun 20 19:07:57.699253 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jun 20 19:07:57.735322 initrd-setup-root[838]: cut: /sysroot/etc/passwd: No such file or directory Jun 20 19:07:57.741049 initrd-setup-root[845]: cut: /sysroot/etc/group: No such file or directory Jun 20 19:07:57.745849 initrd-setup-root[852]: cut: /sysroot/etc/shadow: No such file or directory Jun 20 19:07:57.750455 initrd-setup-root[859]: cut: /sysroot/etc/gshadow: No such file or directory Jun 20 19:07:57.852487 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Jun 20 19:07:57.864792 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Jun 20 19:07:57.866740 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Jun 20 19:07:57.875693 kernel: BTRFS info (device vda6): last unmount of filesystem 0d4ae0d2-6537-4cbd-8c37-7b929dcf3a9f Jun 20 19:07:57.891838 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Jun 20 19:07:57.928178 ignition[928]: INFO : Ignition 2.20.0 Jun 20 19:07:57.928178 ignition[928]: INFO : Stage: mount Jun 20 19:07:57.930125 ignition[928]: INFO : no configs at "/usr/lib/ignition/base.d" Jun 20 19:07:57.930125 ignition[928]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jun 20 19:07:57.930125 ignition[928]: INFO : mount: mount passed Jun 20 19:07:57.930125 ignition[928]: INFO : Ignition finished successfully Jun 20 19:07:57.931547 systemd[1]: Finished ignition-mount.service - Ignition (mount). Jun 20 19:07:57.943791 systemd[1]: Starting ignition-files.service - Ignition (files)... Jun 20 19:07:58.142910 systemd[1]: sysroot-oem.mount: Deactivated successfully. Jun 20 19:07:58.155038 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jun 20 19:07:58.162716 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/vda6 scanned by mount (940) Jun 20 19:07:58.162791 kernel: BTRFS info (device vda6): first mount of filesystem 0d4ae0d2-6537-4cbd-8c37-7b929dcf3a9f Jun 20 19:07:58.164766 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Jun 20 19:07:58.164805 kernel: BTRFS info (device vda6): using free space tree Jun 20 19:07:58.168722 kernel: BTRFS info (device vda6): auto enabling async discard Jun 20 19:07:58.170270 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jun 20 19:07:58.247506 ignition[957]: INFO : Ignition 2.20.0 Jun 20 19:07:58.247506 ignition[957]: INFO : Stage: files Jun 20 19:07:58.247506 ignition[957]: INFO : no configs at "/usr/lib/ignition/base.d" Jun 20 19:07:58.247506 ignition[957]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jun 20 19:07:58.252438 ignition[957]: DEBUG : files: compiled without relabeling support, skipping Jun 20 19:07:58.252438 ignition[957]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jun 20 19:07:58.252438 ignition[957]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jun 20 19:07:58.256802 ignition[957]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jun 20 19:07:58.256802 ignition[957]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jun 20 19:07:58.256802 ignition[957]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jun 20 19:07:58.256802 ignition[957]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Jun 20 19:07:58.256802 ignition[957]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jun 20 19:07:58.256802 ignition[957]: INFO : files: op(4): [started] processing unit "etcd-member.service" Jun 20 19:07:58.256802 ignition[957]: 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" Jun 20 19:07:58.253724 unknown[957]: wrote ssh authorized keys file for user: core Jun 20 19:07:58.270151 ignition[957]: 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" Jun 20 19:07:58.270151 ignition[957]: INFO : files: op(4): [finished] processing unit "etcd-member.service" Jun 20 19:07:58.270151 ignition[957]: INFO : files: op(6): [started] processing unit "coreos-metadata.service" Jun 20 19:07:58.270151 ignition[957]: INFO : files: op(6): op(7): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Jun 20 19:07:58.270151 ignition[957]: INFO : files: op(6): op(7): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Jun 20 19:07:58.270151 ignition[957]: INFO : files: op(6): [finished] processing unit "coreos-metadata.service" Jun 20 19:07:58.270151 ignition[957]: INFO : files: op(8): [started] setting preset to disabled for "coreos-metadata.service" Jun 20 19:07:58.283002 ignition[957]: INFO : files: op(8): op(9): [started] removing enablement symlink(s) for "coreos-metadata.service" Jun 20 19:07:58.286545 ignition[957]: INFO : files: op(8): op(9): [finished] removing enablement symlink(s) for "coreos-metadata.service" Jun 20 19:07:58.288137 ignition[957]: INFO : files: op(8): [finished] setting preset to disabled for "coreos-metadata.service" Jun 20 19:07:58.288137 ignition[957]: INFO : files: op(a): [started] setting preset to enabled for "etcd-member.service" Jun 20 19:07:58.288137 ignition[957]: INFO : files: op(a): [finished] setting preset to enabled for "etcd-member.service" Jun 20 19:07:58.288137 ignition[957]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Jun 20 19:07:58.288137 ignition[957]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Jun 20 19:07:58.288137 ignition[957]: INFO : files: files passed Jun 20 19:07:58.288137 ignition[957]: INFO : Ignition finished successfully Jun 20 19:07:58.289964 systemd[1]: Finished ignition-files.service - Ignition (files). Jun 20 19:07:58.298926 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Jun 20 19:07:58.301210 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Jun 20 19:07:58.303237 systemd[1]: ignition-quench.service: Deactivated successfully. Jun 20 19:07:58.303369 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Jun 20 19:07:58.311175 initrd-setup-root-after-ignition[985]: grep: /sysroot/oem/oem-release: No such file or directory Jun 20 19:07:58.315029 initrd-setup-root-after-ignition[987]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jun 20 19:07:58.316880 initrd-setup-root-after-ignition[991]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jun 20 19:07:58.319945 initrd-setup-root-after-ignition[987]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Jun 20 19:07:58.318571 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Jun 20 19:07:58.320839 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Jun 20 19:07:58.329853 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Jun 20 19:07:58.364929 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jun 20 19:07:58.365226 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Jun 20 19:07:58.367454 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Jun 20 19:07:58.369568 systemd[1]: Reached target initrd.target - Initrd Default Target. Jun 20 19:07:58.369688 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Jun 20 19:07:58.370546 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Jun 20 19:07:58.389895 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jun 20 19:07:58.401970 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Jun 20 19:07:58.413397 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Jun 20 19:07:58.415874 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Jun 20 19:07:58.417311 systemd[1]: Stopped target timers.target - Timer Units. Jun 20 19:07:58.419261 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jun 20 19:07:58.419395 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jun 20 19:07:58.422048 systemd[1]: Stopped target initrd.target - Initrd Default Target. Jun 20 19:07:58.423787 systemd[1]: Stopped target basic.target - Basic System. Jun 20 19:07:58.425995 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Jun 20 19:07:58.428075 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Jun 20 19:07:58.430128 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Jun 20 19:07:58.432361 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Jun 20 19:07:58.434551 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Jun 20 19:07:58.436845 systemd[1]: Stopped target sysinit.target - System Initialization. Jun 20 19:07:58.439099 systemd[1]: Stopped target local-fs.target - Local File Systems. Jun 20 19:07:58.441149 systemd[1]: Stopped target swap.target - Swaps. Jun 20 19:07:58.442948 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jun 20 19:07:58.443182 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Jun 20 19:07:58.445388 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Jun 20 19:07:58.446857 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jun 20 19:07:58.448912 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Jun 20 19:07:58.449081 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jun 20 19:07:58.451276 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jun 20 19:07:58.451449 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Jun 20 19:07:58.453816 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jun 20 19:07:58.453993 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Jun 20 19:07:58.455999 systemd[1]: Stopped target paths.target - Path Units. Jun 20 19:07:58.457730 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jun 20 19:07:58.463782 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jun 20 19:07:58.465484 systemd[1]: Stopped target slices.target - Slice Units. Jun 20 19:07:58.467397 systemd[1]: Stopped target sockets.target - Socket Units. Jun 20 19:07:58.469852 systemd[1]: iscsid.socket: Deactivated successfully. Jun 20 19:07:58.470006 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Jun 20 19:07:58.471710 systemd[1]: iscsiuio.socket: Deactivated successfully. Jun 20 19:07:58.471834 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jun 20 19:07:58.473639 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jun 20 19:07:58.473853 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Jun 20 19:07:58.475692 systemd[1]: ignition-files.service: Deactivated successfully. Jun 20 19:07:58.475856 systemd[1]: Stopped ignition-files.service - Ignition (files). Jun 20 19:07:58.497040 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Jun 20 19:07:58.499040 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jun 20 19:07:58.499222 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Jun 20 19:07:58.502643 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Jun 20 19:07:58.504548 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jun 20 19:07:58.504820 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Jun 20 19:07:58.506982 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jun 20 19:07:58.507126 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Jun 20 19:07:58.511571 ignition[1011]: INFO : Ignition 2.20.0 Jun 20 19:07:58.511571 ignition[1011]: INFO : Stage: umount Jun 20 19:07:58.513320 ignition[1011]: INFO : no configs at "/usr/lib/ignition/base.d" Jun 20 19:07:58.513320 ignition[1011]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jun 20 19:07:58.513320 ignition[1011]: INFO : umount: umount passed Jun 20 19:07:58.513320 ignition[1011]: INFO : Ignition finished successfully Jun 20 19:07:58.514744 systemd[1]: ignition-mount.service: Deactivated successfully. Jun 20 19:07:58.514873 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Jun 20 19:07:58.517350 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jun 20 19:07:58.517515 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Jun 20 19:07:58.522104 systemd[1]: Stopped target network.target - Network. Jun 20 19:07:58.523773 systemd[1]: ignition-disks.service: Deactivated successfully. Jun 20 19:07:58.523853 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Jun 20 19:07:58.526074 systemd[1]: ignition-kargs.service: Deactivated successfully. Jun 20 19:07:58.526136 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Jun 20 19:07:58.527904 systemd[1]: ignition-setup.service: Deactivated successfully. Jun 20 19:07:58.527973 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Jun 20 19:07:58.529884 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Jun 20 19:07:58.530064 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Jun 20 19:07:58.532443 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Jun 20 19:07:58.534249 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Jun 20 19:07:58.537626 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jun 20 19:07:58.538498 systemd[1]: sysroot-boot.service: Deactivated successfully. Jun 20 19:07:58.538743 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Jun 20 19:07:58.541191 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jun 20 19:07:58.541326 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Jun 20 19:07:58.543869 systemd[1]: systemd-resolved.service: Deactivated successfully. Jun 20 19:07:58.544063 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Jun 20 19:07:58.548541 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Jun 20 19:07:58.549002 systemd[1]: systemd-networkd.service: Deactivated successfully. Jun 20 19:07:58.549198 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Jun 20 19:07:58.552616 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Jun 20 19:07:58.553932 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jun 20 19:07:58.554010 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Jun 20 19:07:58.567750 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Jun 20 19:07:58.568699 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jun 20 19:07:58.568757 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jun 20 19:07:58.570961 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jun 20 19:07:58.571015 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Jun 20 19:07:58.573142 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jun 20 19:07:58.573194 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Jun 20 19:07:58.575730 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Jun 20 19:07:58.575783 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Jun 20 19:07:58.578056 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Jun 20 19:07:58.589984 systemd[1]: network-cleanup.service: Deactivated successfully. Jun 20 19:07:58.590154 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Jun 20 19:07:58.599451 systemd[1]: systemd-udevd.service: Deactivated successfully. Jun 20 19:07:58.600548 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Jun 20 19:07:58.603355 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jun 20 19:07:58.603413 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Jun 20 19:07:58.606493 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jun 20 19:07:58.606550 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Jun 20 19:07:58.609533 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jun 20 19:07:58.610481 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Jun 20 19:07:58.612746 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jun 20 19:07:58.612803 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Jun 20 19:07:58.615947 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jun 20 19:07:58.616921 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jun 20 19:07:58.631787 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Jun 20 19:07:58.633999 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Jun 20 19:07:58.634057 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jun 20 19:07:58.637608 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jun 20 19:07:58.638639 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jun 20 19:07:58.641346 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jun 20 19:07:58.642511 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Jun 20 19:07:58.645275 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Jun 20 19:07:58.660793 systemd[1]: Starting initrd-switch-root.service - Switch Root... Jun 20 19:07:58.669770 systemd[1]: Switching root. Jun 20 19:07:58.704788 systemd-journald[194]: Journal stopped Jun 20 19:07:59.730502 systemd-journald[194]: Received SIGTERM from PID 1 (systemd). Jun 20 19:07:59.730582 kernel: SELinux: policy capability network_peer_controls=1 Jun 20 19:07:59.730597 kernel: SELinux: policy capability open_perms=1 Jun 20 19:07:59.730615 kernel: SELinux: policy capability extended_socket_class=1 Jun 20 19:07:59.730631 kernel: SELinux: policy capability always_check_network=0 Jun 20 19:07:59.730646 kernel: SELinux: policy capability cgroup_seclabel=1 Jun 20 19:07:59.730658 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jun 20 19:07:59.730682 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jun 20 19:07:59.730694 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jun 20 19:07:59.730706 kernel: audit: type=1403 audit(1750446478.798:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Jun 20 19:07:59.730720 systemd[1]: Successfully loaded SELinux policy in 42.288ms. Jun 20 19:07:59.730749 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 14.446ms. Jun 20 19:07:59.730762 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) Jun 20 19:07:59.730778 systemd[1]: Detected virtualization kvm. Jun 20 19:07:59.730790 systemd[1]: Detected architecture x86-64. Jun 20 19:07:59.730803 systemd[1]: Detected first boot. Jun 20 19:07:59.730816 systemd[1]: Initializing machine ID from VM UUID. Jun 20 19:07:59.730828 zram_generator::config[1057]: No configuration found. Jun 20 19:07:59.730842 kernel: Guest personality initialized and is inactive Jun 20 19:07:59.730854 kernel: VMCI host device registered (name=vmci, major=10, minor=125) Jun 20 19:07:59.730865 kernel: Initialized host personality Jun 20 19:07:59.730877 kernel: NET: Registered PF_VSOCK protocol family Jun 20 19:07:59.730892 systemd[1]: Populated /etc with preset unit settings. Jun 20 19:07:59.730906 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Jun 20 19:07:59.730919 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jun 20 19:07:59.730931 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Jun 20 19:07:59.730946 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jun 20 19:07:59.730962 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Jun 20 19:07:59.730974 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Jun 20 19:07:59.730987 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jun 20 19:07:59.731000 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jun 20 19:07:59.731012 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Jun 20 19:07:59.731026 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Jun 20 19:07:59.731039 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jun 20 19:07:59.731051 systemd[1]: Created slice user.slice - User and Session Slice. Jun 20 19:07:59.731064 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jun 20 19:07:59.731080 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jun 20 19:07:59.731092 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jun 20 19:07:59.731105 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Jun 20 19:07:59.731118 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jun 20 19:07:59.731131 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jun 20 19:07:59.731144 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Jun 20 19:07:59.731161 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jun 20 19:07:59.731177 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Jun 20 19:07:59.731190 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Jun 20 19:07:59.731202 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Jun 20 19:07:59.731214 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jun 20 19:07:59.731227 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jun 20 19:07:59.731245 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jun 20 19:07:59.731258 systemd[1]: Reached target slices.target - Slice Units. Jun 20 19:07:59.731270 systemd[1]: Reached target swap.target - Swaps. Jun 20 19:07:59.731283 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jun 20 19:07:59.731298 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Jun 20 19:07:59.731311 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Jun 20 19:07:59.731324 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jun 20 19:07:59.731336 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jun 20 19:07:59.731349 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jun 20 19:07:59.731361 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Jun 20 19:07:59.731374 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Jun 20 19:07:59.731387 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jun 20 19:07:59.731399 systemd[1]: Mounting media.mount - External Media Directory... Jun 20 19:07:59.731411 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 20 19:07:59.731427 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jun 20 19:07:59.731440 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jun 20 19:07:59.731452 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Jun 20 19:07:59.731465 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). Jun 20 19:07:59.731484 systemd[1]: Reached target machines.target - Containers. Jun 20 19:07:59.731497 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Jun 20 19:07:59.731510 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jun 20 19:07:59.731523 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jun 20 19:07:59.731538 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jun 20 19:07:59.731551 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jun 20 19:07:59.731563 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jun 20 19:07:59.731576 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jun 20 19:07:59.731589 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jun 20 19:07:59.731602 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jun 20 19:07:59.731615 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jun 20 19:07:59.731628 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jun 20 19:07:59.731643 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Jun 20 19:07:59.731656 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jun 20 19:07:59.732806 systemd[1]: Stopped systemd-fsck-usr.service. Jun 20 19:07:59.732825 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). Jun 20 19:07:59.732839 kernel: fuse: init (API version 7.39) Jun 20 19:07:59.732852 systemd[1]: Starting systemd-journald.service - Journal Service... Jun 20 19:07:59.732864 kernel: loop: module loaded Jun 20 19:07:59.732877 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jun 20 19:07:59.732890 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jun 20 19:07:59.732907 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jun 20 19:07:59.732920 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Jun 20 19:07:59.732933 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jun 20 19:07:59.732969 systemd-journald[1128]: Collecting audit messages is disabled. Jun 20 19:07:59.733007 systemd[1]: verity-setup.service: Deactivated successfully. Jun 20 19:07:59.733021 systemd[1]: Stopped verity-setup.service. Jun 20 19:07:59.733035 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 20 19:07:59.733048 systemd-journald[1128]: Journal started Jun 20 19:07:59.733072 systemd-journald[1128]: Runtime Journal (/run/log/journal/823fc40cd78849a0897f641a28f10194) is 6M, max 48.2M, 42.2M free. Jun 20 19:07:59.475384 systemd[1]: Queued start job for default target multi-user.target. Jun 20 19:07:59.489905 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Jun 20 19:07:59.490414 systemd[1]: systemd-journald.service: Deactivated successfully. Jun 20 19:07:59.734706 systemd[1]: Started systemd-journald.service - Journal Service. Jun 20 19:07:59.736799 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Jun 20 19:07:59.739700 kernel: ACPI: bus type drm_connector registered Jun 20 19:07:59.740803 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jun 20 19:07:59.743049 systemd[1]: Mounted media.mount - External Media Directory. Jun 20 19:07:59.764876 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jun 20 19:07:59.766097 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jun 20 19:07:59.767336 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Jun 20 19:07:59.768657 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jun 20 19:07:59.770238 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jun 20 19:07:59.770465 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jun 20 19:07:59.771982 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jun 20 19:07:59.772215 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jun 20 19:07:59.773736 systemd[1]: modprobe@drm.service: Deactivated successfully. Jun 20 19:07:59.773974 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jun 20 19:07:59.775336 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jun 20 19:07:59.775602 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jun 20 19:07:59.777301 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jun 20 19:07:59.777549 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jun 20 19:07:59.778992 systemd[1]: modprobe@loop.service: Deactivated successfully. Jun 20 19:07:59.779217 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jun 20 19:07:59.781339 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jun 20 19:07:59.783080 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jun 20 19:07:59.794210 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jun 20 19:07:59.801683 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Jun 20 19:07:59.808089 systemd[1]: Reached target network-pre.target - Preparation for Network. Jun 20 19:07:59.822838 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jun 20 19:07:59.825710 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jun 20 19:07:59.827014 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jun 20 19:07:59.827052 systemd[1]: Reached target local-fs.target - Local File Systems. Jun 20 19:07:59.829277 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Jun 20 19:07:59.831881 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Jun 20 19:07:59.835322 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Jun 20 19:07:59.836574 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jun 20 19:07:59.838945 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Jun 20 19:07:59.845049 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jun 20 19:07:59.846389 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jun 20 19:07:59.850910 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Jun 20 19:07:59.854115 systemd-journald[1128]: Time spent on flushing to /var/log/journal/823fc40cd78849a0897f641a28f10194 is 16.490ms for 1017 entries. Jun 20 19:07:59.854115 systemd-journald[1128]: System Journal (/var/log/journal/823fc40cd78849a0897f641a28f10194) is 8M, max 195.6M, 187.6M free. Jun 20 19:07:59.887597 systemd-journald[1128]: Received client request to flush runtime journal. Jun 20 19:07:59.852525 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jun 20 19:07:59.854878 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jun 20 19:07:59.861189 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Jun 20 19:07:59.867093 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Jun 20 19:07:59.868888 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jun 20 19:07:59.870456 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jun 20 19:07:59.872448 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Jun 20 19:07:59.945413 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jun 20 19:07:59.951200 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Jun 20 19:07:59.965893 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Jun 20 19:07:59.978580 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Jun 20 19:07:59.979702 kernel: loop0: detected capacity change from 0 to 138176 Jun 20 19:07:59.983623 systemd[1]: Starting systemd-sysusers.service - Create System Users... Jun 20 19:07:59.986273 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jun 20 19:07:59.994613 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Jun 20 19:07:59.995759 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jun 20 19:08:00.015172 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Jun 20 19:08:00.023007 udevadm[1189]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Jun 20 19:08:00.027801 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jun 20 19:08:00.049928 systemd[1]: Finished systemd-sysusers.service - Create System Users. Jun 20 19:08:00.057701 kernel: loop1: detected capacity change from 0 to 147912 Jun 20 19:08:00.060006 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jun 20 19:08:00.108849 systemd-tmpfiles[1196]: ACLs are not supported, ignoring. Jun 20 19:08:00.109417 systemd-tmpfiles[1196]: ACLs are not supported, ignoring. Jun 20 19:08:00.118378 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jun 20 19:08:00.121698 kernel: loop2: detected capacity change from 0 to 138176 Jun 20 19:08:00.134696 kernel: loop3: detected capacity change from 0 to 147912 Jun 20 19:08:00.148923 (sd-merge)[1200]: Using extensions 'containerd-flatcar', 'docker-flatcar'. Jun 20 19:08:00.151170 (sd-merge)[1200]: Merged extensions into '/usr'. Jun 20 19:08:00.166210 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Jun 20 19:08:00.177884 systemd[1]: Starting ensure-sysext.service... Jun 20 19:08:00.180860 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jun 20 19:08:00.191300 systemd[1]: Reload requested from client PID 1202 ('systemctl') (unit ensure-sysext.service)... Jun 20 19:08:00.191322 systemd[1]: Reloading... Jun 20 19:08:00.215762 systemd-tmpfiles[1203]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jun 20 19:08:00.216601 systemd-tmpfiles[1203]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Jun 20 19:08:00.218174 systemd-tmpfiles[1203]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jun 20 19:08:00.218522 systemd-tmpfiles[1203]: ACLs are not supported, ignoring. Jun 20 19:08:00.218604 systemd-tmpfiles[1203]: ACLs are not supported, ignoring. Jun 20 19:08:00.224481 systemd-tmpfiles[1203]: Detected autofs mount point /boot during canonicalization of boot. Jun 20 19:08:00.224575 systemd-tmpfiles[1203]: Skipping /boot Jun 20 19:08:00.253209 systemd-tmpfiles[1203]: Detected autofs mount point /boot during canonicalization of boot. Jun 20 19:08:00.253358 systemd-tmpfiles[1203]: Skipping /boot Jun 20 19:08:00.305262 zram_generator::config[1236]: No configuration found. Jun 20 19:08:00.443298 ldconfig[1171]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jun 20 19:08:00.445807 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. Jun 20 19:08:00.511450 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jun 20 19:08:00.511599 systemd[1]: Reloading finished in 319 ms. Jun 20 19:08:00.530442 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Jun 20 19:08:00.546195 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jun 20 19:08:00.562917 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jun 20 19:08:00.565374 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Jun 20 19:08:00.569481 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Jun 20 19:08:00.577938 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jun 20 19:08:00.581259 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jun 20 19:08:00.585579 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 20 19:08:00.585779 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jun 20 19:08:00.588501 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jun 20 19:08:00.591270 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jun 20 19:08:00.652857 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jun 20 19:08:00.656063 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jun 20 19:08:00.656188 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). Jun 20 19:08:00.661242 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Jun 20 19:08:00.662309 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 20 19:08:00.663629 systemd[1]: modprobe@loop.service: Deactivated successfully. Jun 20 19:08:00.664428 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jun 20 19:08:00.666300 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jun 20 19:08:00.666846 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jun 20 19:08:00.668770 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jun 20 19:08:00.669276 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jun 20 19:08:00.684114 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Jun 20 19:08:00.692564 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 20 19:08:00.692807 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jun 20 19:08:00.702125 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jun 20 19:08:00.704628 augenrules[1305]: No rules Jun 20 19:08:00.704753 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jun 20 19:08:00.707385 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jun 20 19:08:00.708506 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jun 20 19:08:00.708803 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). Jun 20 19:08:00.708958 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 20 19:08:00.711350 systemd[1]: audit-rules.service: Deactivated successfully. Jun 20 19:08:00.711657 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jun 20 19:08:00.713621 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jun 20 19:08:00.713885 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jun 20 19:08:00.715925 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jun 20 19:08:00.716163 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jun 20 19:08:00.718073 systemd[1]: modprobe@loop.service: Deactivated successfully. Jun 20 19:08:00.718320 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jun 20 19:08:00.723199 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jun 20 19:08:00.730539 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 20 19:08:00.735901 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jun 20 19:08:00.737119 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jun 20 19:08:00.740955 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jun 20 19:08:00.745951 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jun 20 19:08:00.748168 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jun 20 19:08:00.751921 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jun 20 19:08:00.753135 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jun 20 19:08:00.753248 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). Jun 20 19:08:00.753388 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 20 19:08:00.754622 systemd[1]: Started systemd-userdbd.service - User Database Manager. Jun 20 19:08:00.757413 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Jun 20 19:08:00.762490 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). Jun 20 19:08:00.764718 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jun 20 19:08:00.765228 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jun 20 19:08:00.767307 systemd[1]: Finished ensure-sysext.service. Jun 20 19:08:00.768573 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jun 20 19:08:00.769948 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jun 20 19:08:00.771505 systemd[1]: modprobe@drm.service: Deactivated successfully. Jun 20 19:08:00.771912 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jun 20 19:08:00.775468 systemd[1]: modprobe@loop.service: Deactivated successfully. Jun 20 19:08:00.775761 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jun 20 19:08:00.776460 augenrules[1317]: /sbin/augenrules: No change Jun 20 19:08:00.782434 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Jun 20 19:08:00.784801 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jun 20 19:08:00.784913 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jun 20 19:08:00.785344 augenrules[1349]: No rules Jun 20 19:08:00.794820 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Jun 20 19:08:00.797863 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jun 20 19:08:00.800511 systemd[1]: Starting systemd-update-done.service - Update is Completed... Jun 20 19:08:00.801954 systemd[1]: audit-rules.service: Deactivated successfully. Jun 20 19:08:00.802222 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jun 20 19:08:00.820957 systemd[1]: Finished systemd-update-done.service - Update is Completed. Jun 20 19:08:00.837804 systemd-udevd[1356]: Using default interface naming scheme 'v255'. Jun 20 19:08:00.841034 systemd-resolved[1276]: Positive Trust Anchors: Jun 20 19:08:00.841066 systemd-resolved[1276]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jun 20 19:08:00.841110 systemd-resolved[1276]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Jun 20 19:08:00.846588 systemd-resolved[1276]: Defaulting to hostname 'linux'. Jun 20 19:08:00.852595 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jun 20 19:08:00.855371 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jun 20 19:08:00.879534 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jun 20 19:08:00.892603 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jun 20 19:08:00.916516 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Jun 20 19:08:00.929178 systemd[1]: Reached target time-set.target - System Time Set. Jun 20 19:08:00.960728 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (1377) Jun 20 19:08:00.962381 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Jun 20 19:08:01.036292 systemd-networkd[1364]: lo: Link UP Jun 20 19:08:01.036309 systemd-networkd[1364]: lo: Gained carrier Jun 20 19:08:01.039308 systemd-networkd[1364]: Enumeration completed Jun 20 19:08:01.039454 systemd[1]: Started systemd-networkd.service - Network Configuration. Jun 20 19:08:01.040650 systemd-networkd[1364]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 20 19:08:01.040661 systemd-networkd[1364]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jun 20 19:08:01.044163 systemd-networkd[1364]: eth0: Link UP Jun 20 19:08:01.044177 systemd-networkd[1364]: eth0: Gained carrier Jun 20 19:08:01.044193 systemd-networkd[1364]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 20 19:08:01.046750 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Jun 20 19:08:01.048162 systemd[1]: Reached target network.target - Network. Jun 20 19:08:01.054696 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 Jun 20 19:08:01.064708 kernel: ACPI: button: Power Button [PWRF] Jun 20 19:08:01.065940 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Jun 20 19:08:01.069079 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Jun 20 19:08:01.069120 systemd-networkd[1364]: eth0: DHCPv4 address 10.0.0.63/16, gateway 10.0.0.1 acquired from 10.0.0.1 Jun 20 19:08:01.072055 systemd-timesyncd[1355]: Network configuration changed, trying to establish connection. Jun 20 19:08:01.072800 systemd-timesyncd[1355]: Contacted time server 10.0.0.1:123 (10.0.0.1). Jun 20 19:08:01.072846 systemd-timesyncd[1355]: Initial clock synchronization to Fri 2025-06-20 19:08:00.902483 UTC. Jun 20 19:08:01.073892 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Jun 20 19:08:01.085315 kernel: i801_smbus 0000:00:1f.3: Enabling SMBus device Jun 20 19:08:01.087774 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Jun 20 19:08:01.087982 kernel: i2c i2c-0: 1/1 memory slots populated (from DMI) Jun 20 19:08:01.088192 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Jun 20 19:08:01.084748 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Jun 20 19:08:01.099698 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 Jun 20 19:08:01.103475 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Jun 20 19:08:01.133941 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jun 20 19:08:01.193874 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jun 20 19:08:01.194473 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jun 20 19:08:01.211697 kernel: mousedev: PS/2 mouse device common for all mice Jun 20 19:08:01.215890 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jun 20 19:08:01.224104 kernel: kvm_amd: TSC scaling supported Jun 20 19:08:01.224142 kernel: kvm_amd: Nested Virtualization enabled Jun 20 19:08:01.224156 kernel: kvm_amd: Nested Paging enabled Jun 20 19:08:01.224169 kernel: kvm_amd: LBR virtualization supported Jun 20 19:08:01.225188 kernel: kvm_amd: Virtual VMLOAD VMSAVE supported Jun 20 19:08:01.225205 kernel: kvm_amd: Virtual GIF supported Jun 20 19:08:01.247710 kernel: EDAC MC: Ver: 3.0.0 Jun 20 19:08:01.279022 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jun 20 19:08:01.282123 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Jun 20 19:08:01.292821 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Jun 20 19:08:01.301417 lvm[1412]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jun 20 19:08:01.378425 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Jun 20 19:08:01.380082 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jun 20 19:08:01.381238 systemd[1]: Reached target sysinit.target - System Initialization. Jun 20 19:08:01.382416 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Jun 20 19:08:01.383774 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Jun 20 19:08:01.385260 systemd[1]: Started logrotate.timer - Daily rotation of log files. Jun 20 19:08:01.386456 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Jun 20 19:08:01.387736 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jun 20 19:08:01.388977 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jun 20 19:08:01.389005 systemd[1]: Reached target paths.target - Path Units. Jun 20 19:08:01.389914 systemd[1]: Reached target timers.target - Timer Units. Jun 20 19:08:01.391738 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jun 20 19:08:01.394645 systemd[1]: Starting docker.socket - Docker Socket for the API... Jun 20 19:08:01.399293 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Jun 20 19:08:01.400766 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Jun 20 19:08:01.402030 systemd[1]: Reached target ssh-access.target - SSH Access Available. Jun 20 19:08:01.406620 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Jun 20 19:08:01.408258 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Jun 20 19:08:01.410848 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Jun 20 19:08:01.412524 systemd[1]: Listening on docker.socket - Docker Socket for the API. Jun 20 19:08:01.413764 systemd[1]: Reached target sockets.target - Socket Units. Jun 20 19:08:01.414827 systemd[1]: Reached target basic.target - Basic System. Jun 20 19:08:01.415864 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Jun 20 19:08:01.415899 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Jun 20 19:08:01.417192 systemd[1]: Starting containerd.service - containerd container runtime... Jun 20 19:08:01.419406 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jun 20 19:08:01.421761 lvm[1416]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jun 20 19:08:01.423776 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Jun 20 19:08:01.427139 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Jun 20 19:08:01.428264 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). Jun 20 19:08:01.432047 jq[1419]: false Jun 20 19:08:01.432873 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Jun 20 19:08:01.435897 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Jun 20 19:08:01.439206 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Jun 20 19:08:01.445976 systemd[1]: Starting systemd-logind.service - User Login Management... Jun 20 19:08:01.448027 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jun 20 19:08:01.448908 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jun 20 19:08:01.449613 systemd[1]: Starting update-engine.service - Update Engine... Jun 20 19:08:01.451049 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Jun 20 19:08:01.452705 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Jun 20 19:08:01.455258 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jun 20 19:08:01.455531 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Jun 20 19:08:01.455899 systemd[1]: motdgen.service: Deactivated successfully. Jun 20 19:08:01.456156 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Jun 20 19:08:01.458079 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jun 20 19:08:01.458341 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Jun 20 19:08:01.460402 extend-filesystems[1420]: Found loop2 Jun 20 19:08:01.461370 extend-filesystems[1420]: Found loop3 Jun 20 19:08:01.461370 extend-filesystems[1420]: Found sr0 Jun 20 19:08:01.461370 extend-filesystems[1420]: Found vda Jun 20 19:08:01.461370 extend-filesystems[1420]: Found vda1 Jun 20 19:08:01.461370 extend-filesystems[1420]: Found vda2 Jun 20 19:08:01.461370 extend-filesystems[1420]: Found vda3 Jun 20 19:08:01.461370 extend-filesystems[1420]: Found usr Jun 20 19:08:01.461370 extend-filesystems[1420]: Found vda4 Jun 20 19:08:01.461370 extend-filesystems[1420]: Found vda6 Jun 20 19:08:01.461370 extend-filesystems[1420]: Found vda7 Jun 20 19:08:01.461370 extend-filesystems[1420]: Found vda9 Jun 20 19:08:01.461370 extend-filesystems[1420]: Checking size of /dev/vda9 Jun 20 19:08:01.467365 systemd[1]: Started dbus.service - D-Bus System Message Bus. Jun 20 19:08:01.466999 dbus-daemon[1418]: [system] SELinux support is enabled Jun 20 19:08:01.476423 jq[1432]: true Jun 20 19:08:01.485183 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). Jun 20 19:08:01.485222 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Jun 20 19:08:01.486791 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). Jun 20 19:08:01.486811 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Jun 20 19:08:01.490137 jq[1442]: true Jun 20 19:08:01.492576 extend-filesystems[1420]: Old size kept for /dev/vda9 Jun 20 19:08:01.498410 update_engine[1431]: I20250620 19:08:01.496757 1431 main.cc:92] Flatcar Update Engine starting Jun 20 19:08:01.497215 systemd[1]: extend-filesystems.service: Deactivated successfully. Jun 20 19:08:01.497544 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Jun 20 19:08:01.498071 (ntainerd)[1445]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Jun 20 19:08:01.499228 update_engine[1431]: I20250620 19:08:01.499186 1431 update_check_scheduler.cc:74] Next update check in 9m18s Jun 20 19:08:01.502468 systemd[1]: Started update-engine.service - Update Engine. Jun 20 19:08:01.506722 systemd[1]: Started locksmithd.service - Cluster reboot manager. Jun 20 19:08:01.524714 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (1371) Jun 20 19:08:01.593513 systemd-logind[1429]: Watching system buttons on /dev/input/event1 (Power Button) Jun 20 19:08:01.593562 systemd-logind[1429]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Jun 20 19:08:01.594154 systemd-logind[1429]: New seat seat0. Jun 20 19:08:01.597872 systemd[1]: Started systemd-logind.service - User Login Management. Jun 20 19:08:01.605533 bash[1467]: Updated "/home/core/.ssh/authorized_keys" Jun 20 19:08:01.614353 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Jun 20 19:08:01.616397 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Jun 20 19:08:01.624334 locksmithd[1457]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jun 20 19:08:01.673515 sshd_keygen[1444]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jun 20 19:08:01.703930 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Jun 20 19:08:01.711894 systemd[1]: Starting issuegen.service - Generate /run/issue... Jun 20 19:08:01.719601 systemd[1]: issuegen.service: Deactivated successfully. Jun 20 19:08:01.719883 systemd[1]: Finished issuegen.service - Generate /run/issue. Jun 20 19:08:01.723016 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jun 20 19:08:01.796818 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jun 20 19:08:01.805057 systemd[1]: Started getty@tty1.service - Getty on tty1. Jun 20 19:08:01.807344 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Jun 20 19:08:01.808925 systemd[1]: Reached target getty.target - Login Prompts. Jun 20 19:08:02.076665 containerd[1445]: time="2025-06-20T19:08:02.076396337Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Jun 20 19:08:02.100379 containerd[1445]: time="2025-06-20T19:08:02.100310622Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Jun 20 19:08:02.102688 containerd[1445]: time="2025-06-20T19:08:02.102634204Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.94-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Jun 20 19:08:02.102688 containerd[1445]: time="2025-06-20T19:08:02.102686958Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Jun 20 19:08:02.102773 containerd[1445]: time="2025-06-20T19:08:02.102705364Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Jun 20 19:08:02.102977 containerd[1445]: time="2025-06-20T19:08:02.102950200Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Jun 20 19:08:02.103000 containerd[1445]: time="2025-06-20T19:08:02.102988019Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Jun 20 19:08:02.103102 containerd[1445]: time="2025-06-20T19:08:02.103075300Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Jun 20 19:08:02.103102 containerd[1445]: time="2025-06-20T19:08:02.103096067Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Jun 20 19:08:02.103413 containerd[1445]: time="2025-06-20T19:08:02.103384323Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Jun 20 19:08:02.103413 containerd[1445]: time="2025-06-20T19:08:02.103405375Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Jun 20 19:08:02.103453 containerd[1445]: time="2025-06-20T19:08:02.103423094Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Jun 20 19:08:02.103453 containerd[1445]: time="2025-06-20T19:08:02.103433743Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Jun 20 19:08:02.103577 containerd[1445]: time="2025-06-20T19:08:02.103551155Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Jun 20 19:08:02.103893 containerd[1445]: time="2025-06-20T19:08:02.103865640Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Jun 20 19:08:02.104059 containerd[1445]: time="2025-06-20T19:08:02.104034786Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Jun 20 19:08:02.104059 containerd[1445]: time="2025-06-20T19:08:02.104051535Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Jun 20 19:08:02.104211 containerd[1445]: time="2025-06-20T19:08:02.104184851Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Jun 20 19:08:02.104285 containerd[1445]: time="2025-06-20T19:08:02.104262658Z" level=info msg="metadata content store policy set" policy=shared Jun 20 19:08:02.109453 containerd[1445]: time="2025-06-20T19:08:02.109421058Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Jun 20 19:08:02.109489 containerd[1445]: time="2025-06-20T19:08:02.109478803Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Jun 20 19:08:02.109509 containerd[1445]: time="2025-06-20T19:08:02.109496796Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Jun 20 19:08:02.109529 containerd[1445]: time="2025-06-20T19:08:02.109514770Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Jun 20 19:08:02.109549 containerd[1445]: time="2025-06-20T19:08:02.109531999Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Jun 20 19:08:02.109746 containerd[1445]: time="2025-06-20T19:08:02.109721119Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Jun 20 19:08:02.110052 containerd[1445]: time="2025-06-20T19:08:02.110011354Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Jun 20 19:08:02.110207 containerd[1445]: time="2025-06-20T19:08:02.110181874Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Jun 20 19:08:02.110207 containerd[1445]: time="2025-06-20T19:08:02.110203309Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Jun 20 19:08:02.110246 containerd[1445]: time="2025-06-20T19:08:02.110220498Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Jun 20 19:08:02.110246 containerd[1445]: time="2025-06-20T19:08:02.110235383Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Jun 20 19:08:02.110296 containerd[1445]: time="2025-06-20T19:08:02.110249140Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Jun 20 19:08:02.110296 containerd[1445]: time="2025-06-20T19:08:02.110262947Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Jun 20 19:08:02.110296 containerd[1445]: time="2025-06-20T19:08:02.110287235Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Jun 20 19:08:02.110350 containerd[1445]: time="2025-06-20T19:08:02.110302806Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Jun 20 19:08:02.110350 containerd[1445]: time="2025-06-20T19:08:02.110316416Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Jun 20 19:08:02.110350 containerd[1445]: time="2025-06-20T19:08:02.110331556Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Jun 20 19:08:02.110350 containerd[1445]: time="2025-06-20T19:08:02.110343685Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Jun 20 19:08:02.110428 containerd[1445]: time="2025-06-20T19:08:02.110374387Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Jun 20 19:08:02.110428 containerd[1445]: time="2025-06-20T19:08:02.110388743Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Jun 20 19:08:02.110428 containerd[1445]: time="2025-06-20T19:08:02.110401804Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Jun 20 19:08:02.110428 containerd[1445]: time="2025-06-20T19:08:02.110415463Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Jun 20 19:08:02.110514 containerd[1445]: time="2025-06-20T19:08:02.110431044Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Jun 20 19:08:02.110514 containerd[1445]: time="2025-06-20T19:08:02.110445831Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Jun 20 19:08:02.110514 containerd[1445]: time="2025-06-20T19:08:02.110458127Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Jun 20 19:08:02.110514 containerd[1445]: time="2025-06-20T19:08:02.110472718Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Jun 20 19:08:02.110514 containerd[1445]: time="2025-06-20T19:08:02.110489073Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Jun 20 19:08:02.110605 containerd[1445]: time="2025-06-20T19:08:02.110516803Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Jun 20 19:08:02.110605 containerd[1445]: time="2025-06-20T19:08:02.110531159Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Jun 20 19:08:02.110605 containerd[1445]: time="2025-06-20T19:08:02.110543181Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Jun 20 19:08:02.110605 containerd[1445]: time="2025-06-20T19:08:02.110555644Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Jun 20 19:08:02.110605 containerd[1445]: time="2025-06-20T19:08:02.110569087Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Jun 20 19:08:02.110605 containerd[1445]: time="2025-06-20T19:08:02.110594200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Jun 20 19:08:02.110739 containerd[1445]: time="2025-06-20T19:08:02.110608545Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Jun 20 19:08:02.110739 containerd[1445]: time="2025-06-20T19:08:02.110621115Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Jun 20 19:08:02.112004 containerd[1445]: time="2025-06-20T19:08:02.111972454Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Jun 20 19:08:02.112031 containerd[1445]: time="2025-06-20T19:08:02.112003439Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Jun 20 19:08:02.112031 containerd[1445]: time="2025-06-20T19:08:02.112014853Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Jun 20 19:08:02.112031 containerd[1445]: time="2025-06-20T19:08:02.112026943Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Jun 20 19:08:02.112097 containerd[1445]: time="2025-06-20T19:08:02.112036671Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Jun 20 19:08:02.112097 containerd[1445]: time="2025-06-20T19:08:02.112053467Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Jun 20 19:08:02.112097 containerd[1445]: time="2025-06-20T19:08:02.112071823Z" level=info msg="NRI interface is disabled by configuration." Jun 20 19:08:02.112097 containerd[1445]: time="2025-06-20T19:08:02.112084503Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Jun 20 19:08:02.112478 containerd[1445]: time="2025-06-20T19:08:02.112429012Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Jun 20 19:08:02.112478 containerd[1445]: time="2025-06-20T19:08:02.112480422Z" level=info msg="Connect containerd service" Jun 20 19:08:02.112736 containerd[1445]: time="2025-06-20T19:08:02.112537707Z" level=info msg="using legacy CRI server" Jun 20 19:08:02.112736 containerd[1445]: time="2025-06-20T19:08:02.112549130Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Jun 20 19:08:02.112736 containerd[1445]: time="2025-06-20T19:08:02.112710266Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Jun 20 19:08:02.113485 containerd[1445]: time="2025-06-20T19:08:02.113454756Z" 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" Jun 20 19:08:02.113704 containerd[1445]: time="2025-06-20T19:08:02.113609223Z" level=info msg="Start subscribing containerd event" Jun 20 19:08:02.113730 containerd[1445]: time="2025-06-20T19:08:02.113707299Z" level=info msg="Start recovering state" Jun 20 19:08:02.113816 containerd[1445]: time="2025-06-20T19:08:02.113801569Z" level=info msg="Start event monitor" Jun 20 19:08:02.113843 containerd[1445]: time="2025-06-20T19:08:02.113834301Z" level=info msg="Start snapshots syncer" Jun 20 19:08:02.113864 containerd[1445]: time="2025-06-20T19:08:02.113845705Z" level=info msg="Start cni network conf syncer for default" Jun 20 19:08:02.113864 containerd[1445]: time="2025-06-20T19:08:02.113857177Z" level=info msg="Start streaming server" Jun 20 19:08:02.113912 containerd[1445]: time="2025-06-20T19:08:02.113858031Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jun 20 19:08:02.113933 containerd[1445]: time="2025-06-20T19:08:02.113916265Z" level=info msg=serving... address=/run/containerd/containerd.sock Jun 20 19:08:02.114154 systemd[1]: Started containerd.service - containerd container runtime. Jun 20 19:08:02.115462 containerd[1445]: time="2025-06-20T19:08:02.115420189Z" level=info msg="containerd successfully booted in 0.082001s" Jun 20 19:08:02.176912 systemd-networkd[1364]: eth0: Gained IPv6LL Jun 20 19:08:02.180928 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Jun 20 19:08:02.182735 systemd[1]: Reached target network-online.target - Network is Online. Jun 20 19:08:02.201896 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Jun 20 19:08:02.211388 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Jun 20 19:08:02.233955 systemd[1]: coreos-metadata.service: Deactivated successfully. Jun 20 19:08:02.234257 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Jun 20 19:08:02.237283 systemd[1]: Starting etcd-member.service - etcd (System Application Container)... Jun 20 19:08:02.238543 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Jun 20 19:08:02.239155 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Jun 20 19:08:02.241909 (-wrapper)[1516]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS Jun 20 19:08:02.604909 systemd[1]: Starting docker.service - Docker Application Container Engine... Jun 20 19:08:02.606106 (dockerd)[1529]: 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 Jun 20 19:08:03.389896 dockerd[1529]: time="2025-06-20T19:08:03.389771897Z" level=info msg="Starting up" Jun 20 19:08:04.104630 dockerd[1529]: time="2025-06-20T19:08:04.104545731Z" level=info msg="Loading containers: start." Jun 20 19:08:04.362784 kernel: Initializing XFRM netlink socket Jun 20 19:08:04.470861 systemd-networkd[1364]: docker0: Link UP Jun 20 19:08:04.673816 dockerd[1529]: time="2025-06-20T19:08:04.673698071Z" level=info msg="Loading containers: done." Jun 20 19:08:04.708039 dockerd[1529]: time="2025-06-20T19:08:04.707911793Z" 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 Jun 20 19:08:04.708271 dockerd[1529]: time="2025-06-20T19:08:04.708250075Z" level=info msg="Docker daemon" commit=41ca978a0a5400cc24b274137efa9f25517fcc0b containerd-snapshotter=false storage-driver=overlay2 version=27.3.1 Jun 20 19:08:04.708507 dockerd[1529]: time="2025-06-20T19:08:04.708458777Z" level=info msg="Daemon has completed initialization" Jun 20 19:08:04.761110 dockerd[1529]: time="2025-06-20T19:08:04.760890269Z" level=info msg="API listen on /run/docker.sock" Jun 20 19:08:04.761195 systemd[1]: Started docker.service - Docker Application Container Engine. Jun 20 19:08:04.764240 etcd-wrapper[1521]: Error response from daemon: No such container: etcd-member Jun 20 19:08:04.788886 etcd-wrapper[1706]: Error response from daemon: No such container: etcd-member Jun 20 19:08:04.831725 etcd-wrapper[1725]: Unable to find image 'quay.io/coreos/etcd:v3.5.16' locally Jun 20 19:08:05.989431 etcd-wrapper[1725]: v3.5.16: Pulling from coreos/etcd Jun 20 19:08:06.282209 etcd-wrapper[1725]: 804c8aba2cc6: Pulling fs layer Jun 20 19:08:06.282943 etcd-wrapper[1725]: 2ae710cd8bfe: Pulling fs layer Jun 20 19:08:06.282943 etcd-wrapper[1725]: d462aa345367: Pulling fs layer Jun 20 19:08:06.282943 etcd-wrapper[1725]: 0f8b424aa0b9: Pulling fs layer Jun 20 19:08:06.282943 etcd-wrapper[1725]: d557676654e5: Pulling fs layer Jun 20 19:08:06.282943 etcd-wrapper[1725]: c8022d07192e: Pulling fs layer Jun 20 19:08:06.282943 etcd-wrapper[1725]: d858cbc252ad: Pulling fs layer Jun 20 19:08:06.282943 etcd-wrapper[1725]: 1069fc2daed1: Pulling fs layer Jun 20 19:08:06.282943 etcd-wrapper[1725]: b40161cd83fc: Pulling fs layer Jun 20 19:08:06.282943 etcd-wrapper[1725]: 5318d93a3a65: Pulling fs layer Jun 20 19:08:06.282943 etcd-wrapper[1725]: 307c1adadb60: Pulling fs layer Jun 20 19:08:06.282943 etcd-wrapper[1725]: fbb01d9e9dc9: Pulling fs layer Jun 20 19:08:06.282943 etcd-wrapper[1725]: fbfea02ac3cf: Pulling fs layer Jun 20 19:08:06.282943 etcd-wrapper[1725]: 8c26e4bf18e2: Pulling fs layer Jun 20 19:08:06.282943 etcd-wrapper[1725]: 1e59a65f8816: Pulling fs layer Jun 20 19:08:06.282943 etcd-wrapper[1725]: ffbd4ca5f0bd: Pulling fs layer Jun 20 19:08:06.282943 etcd-wrapper[1725]: 0f8b424aa0b9: Waiting Jun 20 19:08:06.282943 etcd-wrapper[1725]: d557676654e5: Waiting Jun 20 19:08:06.282943 etcd-wrapper[1725]: c8022d07192e: Waiting Jun 20 19:08:06.282943 etcd-wrapper[1725]: d858cbc252ad: Waiting Jun 20 19:08:06.282943 etcd-wrapper[1725]: 1069fc2daed1: Waiting Jun 20 19:08:06.282943 etcd-wrapper[1725]: b40161cd83fc: Waiting Jun 20 19:08:06.282943 etcd-wrapper[1725]: 5318d93a3a65: Waiting Jun 20 19:08:06.282943 etcd-wrapper[1725]: 307c1adadb60: Waiting Jun 20 19:08:06.282943 etcd-wrapper[1725]: fbb01d9e9dc9: Waiting Jun 20 19:08:06.282943 etcd-wrapper[1725]: fbfea02ac3cf: Waiting Jun 20 19:08:06.282943 etcd-wrapper[1725]: 8c26e4bf18e2: Waiting Jun 20 19:08:06.282943 etcd-wrapper[1725]: 1e59a65f8816: Waiting Jun 20 19:08:06.282943 etcd-wrapper[1725]: ffbd4ca5f0bd: Waiting Jun 20 19:08:06.532453 etcd-wrapper[1725]: d462aa345367: Verifying Checksum Jun 20 19:08:06.532453 etcd-wrapper[1725]: d462aa345367: Download complete Jun 20 19:08:06.556020 etcd-wrapper[1725]: 804c8aba2cc6: Download complete Jun 20 19:08:06.579383 etcd-wrapper[1725]: 2ae710cd8bfe: Verifying Checksum Jun 20 19:08:06.579383 etcd-wrapper[1725]: 2ae710cd8bfe: Download complete Jun 20 19:08:06.587630 etcd-wrapper[1725]: 804c8aba2cc6: Pull complete Jun 20 19:08:06.609236 systemd[1]: var-lib-docker-overlay2-ffd13d3ef13518f88a2b25c7cc9aaa13619e1eff7072b58887bfa4ef30cbbe5d-merged.mount: Deactivated successfully. Jun 20 19:08:06.630442 etcd-wrapper[1725]: 2ae710cd8bfe: Pull complete Jun 20 19:08:06.772541 etcd-wrapper[1725]: 0f8b424aa0b9: Verifying Checksum Jun 20 19:08:06.772541 etcd-wrapper[1725]: 0f8b424aa0b9: Download complete Jun 20 19:08:06.777512 etcd-wrapper[1725]: d557676654e5: Verifying Checksum Jun 20 19:08:06.777512 etcd-wrapper[1725]: d557676654e5: Download complete Jun 20 19:08:06.797314 etcd-wrapper[1725]: c8022d07192e: Download complete Jun 20 19:08:06.995850 etcd-wrapper[1725]: 1069fc2daed1: Verifying Checksum Jun 20 19:08:06.995850 etcd-wrapper[1725]: 1069fc2daed1: Download complete Jun 20 19:08:07.015871 etcd-wrapper[1725]: b40161cd83fc: Verifying Checksum Jun 20 19:08:07.016044 etcd-wrapper[1725]: b40161cd83fc: Download complete Jun 20 19:08:07.152842 etcd-wrapper[1725]: d858cbc252ad: Verifying Checksum Jun 20 19:08:07.152842 etcd-wrapper[1725]: d858cbc252ad: Download complete Jun 20 19:08:07.208093 etcd-wrapper[1725]: d462aa345367: Pull complete Jun 20 19:08:07.235791 etcd-wrapper[1725]: 307c1adadb60: Verifying Checksum Jun 20 19:08:07.235791 etcd-wrapper[1725]: 307c1adadb60: Download complete Jun 20 19:08:07.241539 etcd-wrapper[1725]: 0f8b424aa0b9: Pull complete Jun 20 19:08:07.268433 etcd-wrapper[1725]: d557676654e5: Pull complete Jun 20 19:08:07.290852 etcd-wrapper[1725]: c8022d07192e: Pull complete Jun 20 19:08:07.315470 etcd-wrapper[1725]: d858cbc252ad: Pull complete Jun 20 19:08:07.344172 etcd-wrapper[1725]: 1069fc2daed1: Pull complete Jun 20 19:08:07.397559 etcd-wrapper[1725]: 5318d93a3a65: Verifying Checksum Jun 20 19:08:07.397559 etcd-wrapper[1725]: 5318d93a3a65: Download complete Jun 20 19:08:07.403632 etcd-wrapper[1725]: b40161cd83fc: Pull complete Jun 20 19:08:07.429949 etcd-wrapper[1725]: 5318d93a3a65: Pull complete Jun 20 19:08:07.471093 etcd-wrapper[1725]: 307c1adadb60: Pull complete Jun 20 19:08:07.483847 etcd-wrapper[1725]: fbb01d9e9dc9: Verifying Checksum Jun 20 19:08:07.483847 etcd-wrapper[1725]: fbb01d9e9dc9: Download complete Jun 20 19:08:07.597066 etcd-wrapper[1725]: fbfea02ac3cf: Verifying Checksum Jun 20 19:08:07.597230 etcd-wrapper[1725]: fbfea02ac3cf: Download complete Jun 20 19:08:07.603529 systemd[1]: var-lib-docker-overlay2-349f02f6d565ecc30b65182f4648b3f84c2bca132d9f7460fcb575ed2219725f-merged.mount: Deactivated successfully. Jun 20 19:08:07.652157 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Jun 20 19:08:07.654317 systemd[1]: Started sshd@0-10.0.0.63:22-10.0.0.1:48742.service - OpenSSH per-connection server daemon (10.0.0.1:48742). Jun 20 19:08:07.672403 etcd-wrapper[1725]: 8c26e4bf18e2: Verifying Checksum Jun 20 19:08:07.672403 etcd-wrapper[1725]: 8c26e4bf18e2: Download complete Jun 20 19:08:07.716489 systemd[1]: var-lib-docker-overlay2-f5dbc72c7204f4538f38c8fb7e70c5ea5b5a6294fc2caac88b51f341621a319e-merged.mount: Deactivated successfully. Jun 20 19:08:07.717411 sshd[1805]: Accepted publickey for core from 10.0.0.1 port 48742 ssh2: RSA SHA256:MS4ZB+NXMKC/lcLDWuAEy3gUtvg/FgWyfqDxz1BfR1g Jun 20 19:08:07.765043 etcd-wrapper[1725]: 1e59a65f8816: Download complete Jun 20 19:08:07.765624 sshd-session[1805]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:08:07.773573 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Jun 20 19:08:07.783899 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Jun 20 19:08:07.789720 systemd-logind[1429]: New session 1 of user core. Jun 20 19:08:07.833395 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Jun 20 19:08:07.852920 systemd[1]: Starting user@500.service - User Manager for UID 500... Jun 20 19:08:07.856544 (systemd)[1809]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jun 20 19:08:07.859089 systemd-logind[1429]: New session c1 of user core. Jun 20 19:08:07.861142 etcd-wrapper[1725]: fbb01d9e9dc9: Pull complete Jun 20 19:08:08.021688 etcd-wrapper[1725]: ffbd4ca5f0bd: Verifying Checksum Jun 20 19:08:08.022228 etcd-wrapper[1725]: ffbd4ca5f0bd: Download complete Jun 20 19:08:08.034196 systemd[1809]: Queued start job for default target default.target. Jun 20 19:08:08.043136 systemd[1809]: Created slice app.slice - User Application Slice. Jun 20 19:08:08.043350 systemd[1809]: Reached target paths.target - Paths. Jun 20 19:08:08.043401 systemd[1809]: Reached target timers.target - Timers. Jun 20 19:08:08.045274 systemd[1809]: Starting dbus.socket - D-Bus User Message Bus Socket... Jun 20 19:08:08.060726 systemd[1809]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jun 20 19:08:08.060865 systemd[1809]: Reached target sockets.target - Sockets. Jun 20 19:08:08.060906 systemd[1809]: Reached target basic.target - Basic System. Jun 20 19:08:08.060952 systemd[1809]: Reached target default.target - Main User Target. Jun 20 19:08:08.060998 systemd[1809]: Startup finished in 193ms. Jun 20 19:08:08.062772 systemd[1]: Started user@500.service - User Manager for UID 500. Jun 20 19:08:08.072084 systemd[1]: Started session-1.scope - Session 1 of User core. Jun 20 19:08:08.086023 systemd[1]: var-lib-docker-overlay2-1fa33bd5675418b296a38fa265c8245057baccedf86db0183e7d20f86b46fb8f-merged.mount: Deactivated successfully. Jun 20 19:08:08.135693 systemd[1]: Started sshd@1-10.0.0.63:22-10.0.0.1:48758.service - OpenSSH per-connection server daemon (10.0.0.1:48758). Jun 20 19:08:08.198854 etcd-wrapper[1725]: fbfea02ac3cf: Pull complete Jun 20 19:08:08.230980 sshd[1824]: Accepted publickey for core from 10.0.0.1 port 48758 ssh2: RSA SHA256:MS4ZB+NXMKC/lcLDWuAEy3gUtvg/FgWyfqDxz1BfR1g Jun 20 19:08:08.232936 sshd-session[1824]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:08:08.239147 systemd-logind[1429]: New session 2 of user core. Jun 20 19:08:08.248833 systemd[1]: Started session-2.scope - Session 2 of User core. Jun 20 19:08:08.307017 sshd[1832]: Connection closed by 10.0.0.1 port 48758 Jun 20 19:08:08.307417 sshd-session[1824]: pam_unix(sshd:session): session closed for user core Jun 20 19:08:08.320109 systemd[1]: sshd@1-10.0.0.63:22-10.0.0.1:48758.service: Deactivated successfully. Jun 20 19:08:08.323090 systemd[1]: session-2.scope: Deactivated successfully. Jun 20 19:08:08.323924 systemd-logind[1429]: Session 2 logged out. Waiting for processes to exit. Jun 20 19:08:08.334090 systemd[1]: Started sshd@2-10.0.0.63:22-10.0.0.1:48766.service - OpenSSH per-connection server daemon (10.0.0.1:48766). Jun 20 19:08:08.336088 systemd-logind[1429]: Removed session 2. Jun 20 19:08:08.366069 sshd[1837]: Accepted publickey for core from 10.0.0.1 port 48766 ssh2: RSA SHA256:MS4ZB+NXMKC/lcLDWuAEy3gUtvg/FgWyfqDxz1BfR1g Jun 20 19:08:08.367753 sshd-session[1837]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:08:08.373493 systemd-logind[1429]: New session 3 of user core. Jun 20 19:08:08.382217 etcd-wrapper[1725]: 8c26e4bf18e2: Pull complete Jun 20 19:08:08.383823 systemd[1]: Started session-3.scope - Session 3 of User core. Jun 20 19:08:08.408798 etcd-wrapper[1725]: 1e59a65f8816: Pull complete Jun 20 19:08:08.433936 etcd-wrapper[1725]: ffbd4ca5f0bd: Pull complete Jun 20 19:08:08.439312 sshd[1840]: Connection closed by 10.0.0.1 port 48766 Jun 20 19:08:08.439832 sshd-session[1837]: pam_unix(sshd:session): session closed for user core Jun 20 19:08:08.443447 systemd[1]: sshd@2-10.0.0.63:22-10.0.0.1:48766.service: Deactivated successfully. Jun 20 19:08:08.445454 etcd-wrapper[1725]: Digest: sha256:d967d98a12dc220a1a290794711dba7eba04b8ce465e12b02383d1bfbb33e159 Jun 20 19:08:08.446809 systemd[1]: session-3.scope: Deactivated successfully. Jun 20 19:08:08.447912 systemd-logind[1429]: Session 3 logged out. Waiting for processes to exit. Jun 20 19:08:08.448832 systemd-logind[1429]: Removed session 3. Jun 20 19:08:08.449451 etcd-wrapper[1725]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.16 Jun 20 19:08:08.631149 containerd[1445]: time="2025-06-20T19:08:08.630525069Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jun 20 19:08:08.631149 containerd[1445]: time="2025-06-20T19:08:08.630597329Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jun 20 19:08:08.631149 containerd[1445]: time="2025-06-20T19:08:08.630611162Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jun 20 19:08:08.631149 containerd[1445]: time="2025-06-20T19:08:08.630748667Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jun 20 19:08:08.662802 systemd[1]: Started docker-ab6cfbfe1d26edb6afd98a4a97fceaad1775ae552870c40fe5edf3ed4fc3688a.scope - libcontainer container ab6cfbfe1d26edb6afd98a4a97fceaad1775ae552870c40fe5edf3ed4fc3688a. Jun 20 19:08:08.776368 systemd-resolved[1276]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Jun 20 19:08:08.819193 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:08.818760Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} Jun 20 19:08:08.819193 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:08.818951Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"823fc40cd78849a0897f641a28f10194"} Jun 20 19:08:08.819748 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:08:08.818975Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} Jun 20 19:08:08.819748 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:08:08.818995Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.16"} Jun 20 19:08:08.819748 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:08:08.819000Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} Jun 20 19:08:08.819748 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:08:08.819005Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} Jun 20 19:08:08.819748 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:08:08.819059Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Jun 20 19:08:08.819748 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:08.819086Z","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.63:2379"]} Jun 20 19:08:08.819748 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:08:08.819165Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Jun 20 19:08:08.819748 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:08.819177Z","caller":"embed/etcd.go:128","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]} Jun 20 19:08:08.820895 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:08.820225Z","caller":"embed/etcd.go:136","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} Jun 20 19:08:08.820895 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:08.820559Z","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":"823fc40cd78849a0897f641a28f10194","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.63:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"823fc40cd78849a0897f641a28f10194=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"} Jun 20 19:08:08.823591 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:08.823370Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.306934ms"} Jun 20 19:08:08.827446 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:08.827246Z","caller":"etcdserver/raft.go:505","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"} Jun 20 19:08:08.827446 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:08.827300Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"} Jun 20 19:08:08.827446 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:08.827345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"} Jun 20 19:08:08.827562 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:08.827353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} Jun 20 19:08:08.827562 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:08.827369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"} Jun 20 19:08:08.827562 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:08.827397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Jun 20 19:08:08.832232 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:08:08.832033Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} Jun 20 19:08:08.834159 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:08.833968Z","caller":"mvcc/kvstore.go:423","msg":"kvstore restored","current-rev":1} Jun 20 19:08:08.835130 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:08.834950Z","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"} Jun 20 19:08:08.835969 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:08.835803Z","caller":"etcdserver/server.go:873","msg":"starting etcd server","local-member-id":"8e9e05c52164694d","local-server-version":"3.5.16","cluster-version":"to_be_decided"} Jun 20 19:08:08.836223 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:08.836061Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Jun 20 19:08:08.836424 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:08.836157Z","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"} Jun 20 19:08:08.836424 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:08.836210Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} Jun 20 19:08:08.836424 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:08.836298Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} Jun 20 19:08:08.836424 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:08.836307Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} Jun 20 19:08:08.837054 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:08.836902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Jun 20 19:08:08.837192 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:08.836999Z","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"]} Jun 20 19:08:08.838331 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:08.838103Z","caller":"embed/etcd.go:600","msg":"serving peer traffic","address":"127.0.0.1:2380"} Jun 20 19:08:08.838331 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:08.838120Z","caller":"embed/etcd.go:572","msg":"cmux::serve","address":"127.0.0.1:2380"} Jun 20 19:08:08.838331 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:08.838190Z","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.63:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} Jun 20 19:08:09.228806 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:09.228257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"} Jun 20 19:08:09.228806 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:09.228343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"} Jun 20 19:08:09.228806 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:09.228408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"} Jun 20 19:08:09.228806 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:09.228432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"} Jun 20 19:08:09.228806 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:09.228442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"} Jun 20 19:08:09.228806 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:09.228453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"} Jun 20 19:08:09.228806 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:09.228463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"} Jun 20 19:08:09.229806 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:09.229502Z","caller":"etcdserver/server.go:2140","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:823fc40cd78849a0897f641a28f10194 ClientURLs:[http://10.0.0.63:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"} Jun 20 19:08:09.229806 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:09.229511Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} Jun 20 19:08:09.230113 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:09.229527Z","caller":"etcdserver/server.go:2651","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} Jun 20 19:08:09.230113 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:09.229765Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} Jun 20 19:08:09.230318 systemd[1]: Started etcd-member.service - etcd (System Application Container). Jun 20 19:08:09.231275 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:09.229944Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} Jun 20 19:08:09.231275 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:09.230235Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Jun 20 19:08:09.231275 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:09.230488Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"} Jun 20 19:08:09.231275 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:09.230600Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} Jun 20 19:08:09.231275 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:09.230747Z","caller":"etcdserver/server.go:2675","msg":"cluster version is updated","cluster-version":"3.5"} Jun 20 19:08:09.231275 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:08:09.230785Z","caller":"embed/serve.go:187","msg":"serving client traffic insecurely; this is strongly discouraged!","traffic":"grpc+http","address":"[::]:2379"} Jun 20 19:08:09.231081 systemd[1]: Reached target multi-user.target - Multi-User System. Jun 20 19:08:09.231607 systemd[1]: Startup finished in 1.473s (kernel) + 4.040s (initrd) + 10.473s (userspace) = 15.986s. Jun 20 19:08:18.398968 systemd[1]: Started sshd@3-10.0.0.63:22-10.0.0.1:48696.service - OpenSSH per-connection server daemon (10.0.0.1:48696). Jun 20 19:08:18.438154 sshd[1906]: Accepted publickey for core from 10.0.0.1 port 48696 ssh2: RSA SHA256:MS4ZB+NXMKC/lcLDWuAEy3gUtvg/FgWyfqDxz1BfR1g Jun 20 19:08:18.440013 sshd-session[1906]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:08:18.444793 systemd-logind[1429]: New session 4 of user core. Jun 20 19:08:18.454809 systemd[1]: Started session-4.scope - Session 4 of User core. Jun 20 19:08:18.509915 sshd[1908]: Connection closed by 10.0.0.1 port 48696 Jun 20 19:08:18.510558 sshd-session[1906]: pam_unix(sshd:session): session closed for user core Jun 20 19:08:18.523897 systemd[1]: sshd@3-10.0.0.63:22-10.0.0.1:48696.service: Deactivated successfully. Jun 20 19:08:18.526032 systemd[1]: session-4.scope: Deactivated successfully. Jun 20 19:08:18.527944 systemd-logind[1429]: Session 4 logged out. Waiting for processes to exit. Jun 20 19:08:18.544059 systemd[1]: Started sshd@4-10.0.0.63:22-10.0.0.1:48712.service - OpenSSH per-connection server daemon (10.0.0.1:48712). Jun 20 19:08:18.545220 systemd-logind[1429]: Removed session 4. Jun 20 19:08:18.577374 sshd[1913]: Accepted publickey for core from 10.0.0.1 port 48712 ssh2: RSA SHA256:MS4ZB+NXMKC/lcLDWuAEy3gUtvg/FgWyfqDxz1BfR1g Jun 20 19:08:18.578950 sshd-session[1913]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:08:18.584463 systemd-logind[1429]: New session 5 of user core. Jun 20 19:08:18.595817 systemd[1]: Started session-5.scope - Session 5 of User core. Jun 20 19:08:18.645785 sshd[1916]: Connection closed by 10.0.0.1 port 48712 Jun 20 19:08:18.646205 sshd-session[1913]: pam_unix(sshd:session): session closed for user core Jun 20 19:08:18.658535 systemd[1]: sshd@4-10.0.0.63:22-10.0.0.1:48712.service: Deactivated successfully. Jun 20 19:08:18.660528 systemd[1]: session-5.scope: Deactivated successfully. Jun 20 19:08:18.662053 systemd-logind[1429]: Session 5 logged out. Waiting for processes to exit. Jun 20 19:08:18.663544 systemd[1]: Started sshd@5-10.0.0.63:22-10.0.0.1:48724.service - OpenSSH per-connection server daemon (10.0.0.1:48724). Jun 20 19:08:18.664504 systemd-logind[1429]: Removed session 5. Jun 20 19:08:18.700860 sshd[1921]: Accepted publickey for core from 10.0.0.1 port 48724 ssh2: RSA SHA256:MS4ZB+NXMKC/lcLDWuAEy3gUtvg/FgWyfqDxz1BfR1g Jun 20 19:08:18.702923 sshd-session[1921]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:08:18.707777 systemd-logind[1429]: New session 6 of user core. Jun 20 19:08:18.718932 systemd[1]: Started session-6.scope - Session 6 of User core. Jun 20 19:08:18.773211 sshd[1924]: Connection closed by 10.0.0.1 port 48724 Jun 20 19:08:18.773717 sshd-session[1921]: pam_unix(sshd:session): session closed for user core Jun 20 19:08:18.790294 systemd[1]: sshd@5-10.0.0.63:22-10.0.0.1:48724.service: Deactivated successfully. Jun 20 19:08:18.792613 systemd[1]: session-6.scope: Deactivated successfully. Jun 20 19:08:18.794725 systemd-logind[1429]: Session 6 logged out. Waiting for processes to exit. Jun 20 19:08:18.797010 systemd[1]: Started sshd@6-10.0.0.63:22-10.0.0.1:48740.service - OpenSSH per-connection server daemon (10.0.0.1:48740). Jun 20 19:08:18.798878 systemd-logind[1429]: Removed session 6. Jun 20 19:08:18.837267 sshd[1929]: Accepted publickey for core from 10.0.0.1 port 48740 ssh2: RSA SHA256:MS4ZB+NXMKC/lcLDWuAEy3gUtvg/FgWyfqDxz1BfR1g Jun 20 19:08:18.838868 sshd-session[1929]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:08:18.844046 systemd-logind[1429]: New session 7 of user core. Jun 20 19:08:18.854868 systemd[1]: Started session-7.scope - Session 7 of User core. Jun 20 19:08:18.916993 sudo[1933]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Jun 20 19:08:18.917372 sudo[1933]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jun 20 19:08:18.935659 sudo[1933]: pam_unix(sudo:session): session closed for user root Jun 20 19:08:18.937439 sshd[1932]: Connection closed by 10.0.0.1 port 48740 Jun 20 19:08:18.938073 sshd-session[1929]: pam_unix(sshd:session): session closed for user core Jun 20 19:08:18.957893 systemd[1]: sshd@6-10.0.0.63:22-10.0.0.1:48740.service: Deactivated successfully. Jun 20 19:08:18.959986 systemd[1]: session-7.scope: Deactivated successfully. Jun 20 19:08:18.961780 systemd-logind[1429]: Session 7 logged out. Waiting for processes to exit. Jun 20 19:08:18.974897 systemd[1]: Started sshd@7-10.0.0.63:22-10.0.0.1:48748.service - OpenSSH per-connection server daemon (10.0.0.1:48748). Jun 20 19:08:18.975711 systemd-logind[1429]: Removed session 7. Jun 20 19:08:19.007180 sshd[1938]: Accepted publickey for core from 10.0.0.1 port 48748 ssh2: RSA SHA256:MS4ZB+NXMKC/lcLDWuAEy3gUtvg/FgWyfqDxz1BfR1g Jun 20 19:08:19.008804 sshd-session[1938]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:08:19.013700 systemd-logind[1429]: New session 8 of user core. Jun 20 19:08:19.024790 systemd[1]: Started session-8.scope - Session 8 of User core. Jun 20 19:08:19.080077 sudo[1943]: 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 Jun 20 19:08:19.080422 sudo[1943]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jun 20 19:08:19.084170 sudo[1943]: pam_unix(sudo:session): session closed for user root Jun 20 19:08:19.091152 sudo[1942]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Jun 20 19:08:19.091497 sudo[1942]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jun 20 19:08:19.110936 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jun 20 19:08:19.142908 augenrules[1965]: No rules Jun 20 19:08:19.144852 systemd[1]: audit-rules.service: Deactivated successfully. Jun 20 19:08:19.145186 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jun 20 19:08:19.146342 sudo[1942]: pam_unix(sudo:session): session closed for user root Jun 20 19:08:19.147817 sshd[1941]: Connection closed by 10.0.0.1 port 48748 Jun 20 19:08:19.148217 sshd-session[1938]: pam_unix(sshd:session): session closed for user core Jun 20 19:08:19.160461 systemd[1]: sshd@7-10.0.0.63:22-10.0.0.1:48748.service: Deactivated successfully. Jun 20 19:08:19.162451 systemd[1]: session-8.scope: Deactivated successfully. Jun 20 19:08:19.164180 systemd-logind[1429]: Session 8 logged out. Waiting for processes to exit. Jun 20 19:08:19.165550 systemd[1]: Started sshd@8-10.0.0.63:22-10.0.0.1:48760.service - OpenSSH per-connection server daemon (10.0.0.1:48760). Jun 20 19:08:19.166283 systemd-logind[1429]: Removed session 8. Jun 20 19:08:19.209763 sshd[1973]: Accepted publickey for core from 10.0.0.1 port 48760 ssh2: RSA SHA256:MS4ZB+NXMKC/lcLDWuAEy3gUtvg/FgWyfqDxz1BfR1g Jun 20 19:08:19.211196 sshd-session[1973]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:08:19.215621 systemd-logind[1429]: New session 9 of user core. Jun 20 19:08:19.224789 systemd[1]: Started session-9.scope - Session 9 of User core. Jun 20 19:08:19.275561 sshd[1976]: Connection closed by 10.0.0.1 port 48760 Jun 20 19:08:19.276021 sshd-session[1973]: pam_unix(sshd:session): session closed for user core Jun 20 19:08:19.289697 systemd[1]: sshd@8-10.0.0.63:22-10.0.0.1:48760.service: Deactivated successfully. Jun 20 19:08:19.291529 systemd[1]: session-9.scope: Deactivated successfully. Jun 20 19:08:19.293575 systemd-logind[1429]: Session 9 logged out. Waiting for processes to exit. Jun 20 19:08:19.305908 systemd[1]: Started sshd@9-10.0.0.63:22-10.0.0.1:48770.service - OpenSSH per-connection server daemon (10.0.0.1:48770). Jun 20 19:08:19.306951 systemd-logind[1429]: Removed session 9. Jun 20 19:08:19.339035 sshd[1983]: Accepted publickey for core from 10.0.0.1 port 48770 ssh2: RSA SHA256:MS4ZB+NXMKC/lcLDWuAEy3gUtvg/FgWyfqDxz1BfR1g Jun 20 19:08:19.340412 sshd-session[1983]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:08:19.344803 systemd-logind[1429]: New session 10 of user core. Jun 20 19:08:19.353789 systemd[1]: Started session-10.scope - Session 10 of User core. Jun 20 19:08:19.454372 sshd[1986]: Connection closed by 10.0.0.1 port 48770 Jun 20 19:08:19.454735 sshd-session[1983]: pam_unix(sshd:session): session closed for user core Jun 20 19:08:19.459445 systemd[1]: sshd@9-10.0.0.63:22-10.0.0.1:48770.service: Deactivated successfully. Jun 20 19:08:19.461508 systemd[1]: session-10.scope: Deactivated successfully. Jun 20 19:08:19.462286 systemd-logind[1429]: Session 10 logged out. Waiting for processes to exit. Jun 20 19:08:19.463097 systemd-logind[1429]: Removed session 10. Jun 20 19:08:47.273041 update_engine[1431]: I20250620 19:08:47.272905 1431 update_attempter.cc:509] Updating boot flags... Jun 20 19:08:47.306791 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (2005) Jun 20 19:08:47.337696 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (2009) Jun 20 19:08:47.369699 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (2009) Jun 20 19:09:07.729380 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:07.728375Z","caller":"traceutil/trace.go:171","msg":"trace[119791470] transaction","detail":"{read_only:false; response_revision:147; number_of_response:1; }","duration":"116.327806ms","start":"2025-06-20T19:09:07.612020Z","end":"2025-06-20T19:09:07.728348Z","steps":["trace[119791470] 'process raft request' (duration: 74.067955ms)","trace[119791470] 'compare' (duration: 42.159332ms)"],"step_count":2} Jun 20 19:09:09.548115 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:09.547694Z","caller":"traceutil/trace.go:171","msg":"trace[1077943478] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"156.508239ms","start":"2025-06-20T19:09:09.391137Z","end":"2025-06-20T19:09:09.547645Z","steps":["trace[1077943478] 'process raft request' (duration: 127.635975ms)","trace[1077943478] 'compare' (duration: 28.765954ms)"],"step_count":2} Jun 20 19:09:09.591600 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:09.591058Z","caller":"traceutil/trace.go:171","msg":"trace[1510166935] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"113.80649ms","start":"2025-06-20T19:09:09.477242Z","end":"2025-06-20T19:09:09.591049Z","steps":["trace[1510166935] 'process raft request' (duration: 113.783758ms)"],"step_count":1} Jun 20 19:09:09.591600 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:09.591078Z","caller":"traceutil/trace.go:171","msg":"trace[971535] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"199.301271ms","start":"2025-06-20T19:09:09.391754Z","end":"2025-06-20T19:09:09.591055Z","steps":["trace[971535] 'process raft request' (duration: 199.107306ms)"],"step_count":1} Jun 20 19:09:09.591600 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:09.591127Z","caller":"traceutil/trace.go:171","msg":"trace[1778736728] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"199.337448ms","start":"2025-06-20T19:09:09.391785Z","end":"2025-06-20T19:09:09.591123Z","steps":["trace[1778736728] 'process raft request' (duration: 199.172868ms)"],"step_count":1} Jun 20 19:09:09.591600 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:09.591118Z","caller":"traceutil/trace.go:171","msg":"trace[1705329597] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"199.292625ms","start":"2025-06-20T19:09:09.391783Z","end":"2025-06-20T19:09:09.591075Z","steps":["trace[1705329597] 'process raft request' (duration: 199.147331ms)"],"step_count":1} Jun 20 19:09:09.591600 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:09.591190Z","caller":"traceutil/trace.go:171","msg":"trace[486823243] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"151.779066ms","start":"2025-06-20T19:09:09.439404Z","end":"2025-06-20T19:09:09.591183Z","steps":["trace[486823243] 'process raft request' (duration: 151.567739ms)"],"step_count":1} Jun 20 19:09:09.591801 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:09.591158Z","caller":"traceutil/trace.go:171","msg":"trace[1538892622] transaction","detail":"{read_only:false; number_of_response:0; response_revision:257; }","duration":"151.598707ms","start":"2025-06-20T19:09:09.439549Z","end":"2025-06-20T19:09:09.591148Z","steps":["trace[1538892622] 'process raft request' (duration: 151.457923ms)"],"step_count":1} Jun 20 19:09:09.725999 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:09.725579Z","caller":"traceutil/trace.go:171","msg":"trace[840838756] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"106.794664ms","start":"2025-06-20T19:09:09.618767Z","end":"2025-06-20T19:09:09.725562Z","steps":["trace[840838756] 'process raft request' (duration: 106.765069ms)"],"step_count":1} Jun 20 19:09:09.725999 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:09.725612Z","caller":"traceutil/trace.go:171","msg":"trace[1220315012] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"106.830131ms","start":"2025-06-20T19:09:09.618756Z","end":"2025-06-20T19:09:09.725586Z","steps":["trace[1220315012] 'process raft request' (duration: 31.271936ms)","trace[1220315012] 'compare' (duration: 75.41158ms)"],"step_count":2} Jun 20 19:09:15.077440 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:15.077056Z","caller":"traceutil/trace.go:171","msg":"trace[1455811636] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"104.296296ms","start":"2025-06-20T19:09:14.972740Z","end":"2025-06-20T19:09:15.077037Z","steps":["trace[1455811636] 'process raft request' (duration: 72.82117ms)","trace[1455811636] 'compare' (duration: 31.378996ms)"],"step_count":2} Jun 20 19:09:20.589019 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:20.586775Z","caller":"traceutil/trace.go:171","msg":"trace[1975765429] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"188.487557ms","start":"2025-06-20T19:09:20.398241Z","end":"2025-06-20T19:09:20.586729Z","steps":["trace[1975765429] 'process raft request' (duration: 188.158599ms)"],"step_count":1} Jun 20 19:09:26.352704 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:26.352114Z","caller":"traceutil/trace.go:171","msg":"trace[2008655129] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:618; }","duration":"128.887113ms","start":"2025-06-20T19:09:26.223204Z","end":"2025-06-20T19:09:26.352091Z","steps":["trace[2008655129] 'read index received' (duration: 112.52576ms)","trace[2008655129] 'applied index is now lower than readState.Index' (duration: 16.360521ms)"],"step_count":2} Jun 20 19:09:26.352704 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:26.352174Z","caller":"traceutil/trace.go:171","msg":"trace[1686503824] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"161.941242ms","start":"2025-06-20T19:09:26.190212Z","end":"2025-06-20T19:09:26.352154Z","steps":["trace[1686503824] 'process raft request' (duration: 145.450525ms)","trace[1686503824] 'compare' (duration: 16.259783ms)"],"step_count":2} Jun 20 19:09:26.352704 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:26.352231Z","caller":"traceutil/trace.go:171","msg":"trace[564631154] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"155.274877ms","start":"2025-06-20T19:09:26.196931Z","end":"2025-06-20T19:09:26.352206Z","steps":["trace[564631154] 'process raft request' (duration: 155.113573ms)"],"step_count":1} Jun 20 19:09:26.353623 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:09:26.352522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.483301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/tigera-operator/whisker-backend-key-pair\" limit:1 ","response":"range_response_count:1 size:4806"} Jun 20 19:09:26.353623 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:26.352712Z","caller":"traceutil/trace.go:171","msg":"trace[379329379] range","detail":"{range_begin:/registry/secrets/tigera-operator/whisker-backend-key-pair; range_end:; response_count:1; response_revision:602; }","duration":"162.854008ms","start":"2025-06-20T19:09:26.189843Z","end":"2025-06-20T19:09:26.352697Z","steps":["trace[379329379] 'agreement among raft nodes before linearized reading' (duration: 162.415464ms)"],"step_count":1} Jun 20 19:09:26.353623 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:09:26.352367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.791731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/calico-system\" limit:1 ","response":"range_response_count:1 size:764"} Jun 20 19:09:26.353623 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:26.352885Z","caller":"traceutil/trace.go:171","msg":"trace[560691691] range","detail":"{range_begin:/registry/namespaces/calico-system; range_end:; response_count:1; response_revision:602; }","duration":"152.340793ms","start":"2025-06-20T19:09:26.200474Z","end":"2025-06-20T19:09:26.352815Z","steps":["trace[560691691] 'agreement among raft nodes before linearized reading' (duration: 151.733923ms)"],"step_count":1} Jun 20 19:09:26.360686 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:26.359898Z","caller":"traceutil/trace.go:171","msg":"trace[1982047658] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"133.394271ms","start":"2025-06-20T19:09:26.226487Z","end":"2025-06-20T19:09:26.359881Z","steps":["trace[1982047658] 'process raft request' (duration: 133.160493ms)"],"step_count":1} Jun 20 19:09:26.360686 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:09:26.360035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.005311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/tigera-operator/calico-apiserver-certs\" limit:1 ","response":"range_response_count:1 size:4751"} Jun 20 19:09:26.360686 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:26.360061Z","caller":"traceutil/trace.go:171","msg":"trace[1627711930] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"129.3272ms","start":"2025-06-20T19:09:26.230729Z","end":"2025-06-20T19:09:26.360056Z","steps":["trace[1627711930] 'process raft request' (duration: 129.096927ms)"],"step_count":1} Jun 20 19:09:26.360686 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:26.360072Z","caller":"traceutil/trace.go:171","msg":"trace[1210858870] range","detail":"{range_begin:/registry/secrets/tigera-operator/calico-apiserver-certs; range_end:; response_count:1; response_revision:604; }","duration":"133.058692ms","start":"2025-06-20T19:09:26.227005Z","end":"2025-06-20T19:09:26.360064Z","steps":["trace[1210858870] 'agreement among raft nodes before linearized reading' (duration: 132.941933ms)"],"step_count":1} Jun 20 19:09:26.360686 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:09:26.360117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.094096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/tigera-operator/goldmane-key-pair\" limit:1 ","response":"range_response_count:1 size:4715"} Jun 20 19:09:26.360926 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:26.360131Z","caller":"traceutil/trace.go:171","msg":"trace[1348554502] range","detail":"{range_begin:/registry/secrets/tigera-operator/goldmane-key-pair; range_end:; response_count:1; response_revision:604; }","duration":"135.115015ms","start":"2025-06-20T19:09:26.225010Z","end":"2025-06-20T19:09:26.360125Z","steps":["trace[1348554502] 'agreement among raft nodes before linearized reading' (duration: 135.075932ms)"],"step_count":1} Jun 20 19:09:26.360926 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:09:26.360124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.237786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/calico-system/active-operator\" limit:1 ","response":"range_response_count:1 size:518"} Jun 20 19:09:26.360926 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:09:26.360145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.924632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/operator.tigera.io/installations/default\" limit:1 ","response":"range_response_count:1 size:3643"} Jun 20 19:09:26.360926 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:09:26.360044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.349622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/calico-system/tigera-operator-secrets\" limit:1 ","response":"range_response_count:1 size:663"} Jun 20 19:09:26.360926 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:26.360165Z","caller":"traceutil/trace.go:171","msg":"trace[1282713784] range","detail":"{range_begin:/registry/configmaps/calico-system/active-operator; range_end:; response_count:1; response_revision:604; }","duration":"135.300995ms","start":"2025-06-20T19:09:26.224854Z","end":"2025-06-20T19:09:26.360155Z","steps":["trace[1282713784] 'agreement among raft nodes before linearized reading' (duration: 135.175259ms)"],"step_count":1} Jun 20 19:09:26.361067 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:26.360167Z","caller":"traceutil/trace.go:171","msg":"trace[451280064] range","detail":"{range_begin:/registry/operator.tigera.io/installations/default; range_end:; response_count:1; response_revision:604; }","duration":"130.952052ms","start":"2025-06-20T19:09:26.229209Z","end":"2025-06-20T19:09:26.360161Z","steps":["trace[451280064] 'agreement among raft nodes before linearized reading' (duration: 130.906076ms)"],"step_count":1} Jun 20 19:09:26.361067 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:26.360170Z","caller":"traceutil/trace.go:171","msg":"trace[448282838] range","detail":"{range_begin:/registry/rolebindings/calico-system/tigera-operator-secrets; range_end:; response_count:1; response_revision:604; }","duration":"129.506606ms","start":"2025-06-20T19:09:26.230657Z","end":"2025-06-20T19:09:26.360163Z","steps":["trace[448282838] 'agreement among raft nodes before linearized reading' (duration: 129.338581ms)"],"step_count":1} Jun 20 19:09:26.743473 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:26.743102Z","caller":"traceutil/trace.go:171","msg":"trace[437614295] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:679; }","duration":"105.351719ms","start":"2025-06-20T19:09:26.637737Z","end":"2025-06-20T19:09:26.743088Z","steps":["trace[437614295] 'read index received' (duration: 34.427631ms)","trace[437614295] 'applied index is now lower than readState.Index' (duration: 70.923708ms)"],"step_count":2} Jun 20 19:09:26.743473 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:26.743171Z","caller":"traceutil/trace.go:171","msg":"trace[202865946] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"107.760204ms","start":"2025-06-20T19:09:26.635405Z","end":"2025-06-20T19:09:26.743166Z","steps":["trace[202865946] 'process raft request' (duration: 107.659455ms)"],"step_count":1} Jun 20 19:09:26.743828 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:09:26.743309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.563307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/calico-apiserver-webhook-reader\" limit:1 ","response":"range_response_count:1 size:663"} Jun 20 19:09:26.743828 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:26.743296Z","caller":"traceutil/trace.go:171","msg":"trace[1139536163] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"108.085707ms","start":"2025-06-20T19:09:26.635181Z","end":"2025-06-20T19:09:26.743267Z","steps":["trace[1139536163] 'process raft request' (duration: 37.006787ms)","trace[1139536163] 'compare' (duration: 70.799765ms)"],"step_count":2} Jun 20 19:09:26.743828 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:26.743339Z","caller":"traceutil/trace.go:171","msg":"trace[2088203354] range","detail":"{range_begin:/registry/clusterrolebindings/calico-apiserver-webhook-reader; range_end:; response_count:1; response_revision:663; }","duration":"105.617469ms","start":"2025-06-20T19:09:26.637714Z","end":"2025-06-20T19:09:26.743331Z","steps":["trace[2088203354] 'agreement among raft nodes before linearized reading' (duration: 105.536667ms)"],"step_count":1} Jun 20 19:09:27.039349 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:09:27.038729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.20059ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Jun 20 19:09:27.039349 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:27.038810Z","caller":"traceutil/trace.go:171","msg":"trace[940752684] linearizableReadLoop","detail":"{readStateIndex:697; appliedIndex:696; }","duration":"195.554139ms","start":"2025-06-20T19:09:26.843244Z","end":"2025-06-20T19:09:27.038798Z","steps":["trace[940752684] 'read index received' (duration: 74.931197ms)","trace[940752684] 'applied index is now lower than readState.Index' (duration: 120.62191ms)"],"step_count":2} Jun 20 19:09:27.039349 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:27.038851Z","caller":"traceutil/trace.go:171","msg":"trace[681711974] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"196.582293ms","start":"2025-06-20T19:09:26.842238Z","end":"2025-06-20T19:09:27.038820Z","steps":["trace[681711974] 'process raft request' (duration: 75.855003ms)","trace[681711974] 'compare' (duration: 120.111372ms)"],"step_count":2} Jun 20 19:09:27.039349 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:09:27.038943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.689324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/calico-system/calico-typha-6bb966569b\" limit:1 ","response":"range_response_count:1 size:6222"} Jun 20 19:09:27.039716 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:27.038969Z","caller":"traceutil/trace.go:171","msg":"trace[1528918812] range","detail":"{range_begin:/registry/replicasets/calico-system/calico-typha-6bb966569b; range_end:; response_count:1; response_revision:679; }","duration":"195.729609ms","start":"2025-06-20T19:09:26.843233Z","end":"2025-06-20T19:09:27.038963Z","steps":["trace[1528918812] 'agreement among raft nodes before linearized reading' (duration: 195.616167ms)"],"step_count":1} Jun 20 19:09:27.072827 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:27.072258Z","caller":"traceutil/trace.go:171","msg":"trace[1720903373] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"227.803146ms","start":"2025-06-20T19:09:26.844437Z","end":"2025-06-20T19:09:27.072240Z","steps":["trace[1720903373] 'process raft request' (duration: 227.681758ms)"],"step_count":1} Jun 20 19:09:27.073269 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:09:27.072447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.698078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/calico-apiserver/calico-apiserver\" limit:1 ","response":"range_response_count:1 size:971"} Jun 20 19:09:27.073269 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:09:27.072440Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.662321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/calico-node\" limit:1 ","response":"range_response_count:1 size:1903"} Jun 20 19:09:27.073269 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:09:27.072526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.936645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/tigera-operator/whisker-backend-key-pair\" limit:1 ","response":"range_response_count:1 size:4806"} Jun 20 19:09:27.073269 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:27.072527Z","caller":"traceutil/trace.go:171","msg":"trace[1933534457] range","detail":"{range_begin:/registry/clusterroles/calico-node; range_end:; response_count:1; response_revision:680; }","duration":"228.765074ms","start":"2025-06-20T19:09:26.843746Z","end":"2025-06-20T19:09:27.072511Z","steps":["trace[1933534457] 'agreement among raft nodes before linearized reading' (duration: 228.597028ms)"],"step_count":1} Jun 20 19:09:27.073269 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:27.072547Z","caller":"traceutil/trace.go:171","msg":"trace[1606281378] range","detail":"{range_begin:/registry/secrets/tigera-operator/whisker-backend-key-pair; range_end:; response_count:1; response_revision:680; }","duration":"228.978013ms","start":"2025-06-20T19:09:26.843563Z","end":"2025-06-20T19:09:27.072541Z","steps":["trace[1606281378] 'agreement among raft nodes before linearized reading' (duration: 228.926246ms)"],"step_count":1} Jun 20 19:09:27.073477 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:09:27.072389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.44264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/tigera-operator/whisker-backend-key-pair\" limit:1 ","response":"range_response_count:1 size:4806"} Jun 20 19:09:27.073477 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:27.072592Z","caller":"traceutil/trace.go:171","msg":"trace[41028648] range","detail":"{range_begin:/registry/secrets/tigera-operator/whisker-backend-key-pair; range_end:; response_count:1; response_revision:680; }","duration":"195.671399ms","start":"2025-06-20T19:09:26.876900Z","end":"2025-06-20T19:09:27.072571Z","steps":["trace[41028648] 'agreement among raft nodes before linearized reading' (duration: 195.351698ms)"],"step_count":1} Jun 20 19:09:27.073477 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:09:27.072732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.211801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/calico-apiserver/tigera-operator-secrets\" limit:1 ","response":"range_response_count:1 size:666"} Jun 20 19:09:27.073477 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:27.072761Z","caller":"traceutil/trace.go:171","msg":"trace[1643962580] range","detail":"{range_begin:/registry/rolebindings/calico-apiserver/tigera-operator-secrets; range_end:; response_count:1; response_revision:680; }","duration":"139.278516ms","start":"2025-06-20T19:09:26.933475Z","end":"2025-06-20T19:09:27.072754Z","steps":["trace[1643962580] 'agreement among raft nodes before linearized reading' (duration: 139.222581ms)"],"step_count":1} Jun 20 19:09:27.073477 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:09:27.072776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.677197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/calico-apiserver-auth-reader\" limit:1 ","response":"range_response_count:1 size:679"} Jun 20 19:09:27.073632 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:27.072813Z","caller":"traceutil/trace.go:171","msg":"trace[1380823012] range","detail":"{range_begin:/registry/rolebindings/kube-system/calico-apiserver-auth-reader; range_end:; response_count:1; response_revision:680; }","duration":"210.73216ms","start":"2025-06-20T19:09:26.862072Z","end":"2025-06-20T19:09:27.072805Z","steps":["trace[1380823012] 'agreement among raft nodes before linearized reading' (duration: 210.590424ms)"],"step_count":1} Jun 20 19:09:27.073632 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:27.072478Z","caller":"traceutil/trace.go:171","msg":"trace[540188887] range","detail":"{range_begin:/registry/poddisruptionbudgets/calico-apiserver/calico-apiserver; range_end:; response_count:1; response_revision:680; }","duration":"228.748863ms","start":"2025-06-20T19:09:26.843719Z","end":"2025-06-20T19:09:27.072468Z","steps":["trace[540188887] 'agreement among raft nodes before linearized reading' (duration: 228.696305ms)"],"step_count":1} Jun 20 19:09:27.073632 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:09:27.072894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.720448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/calico-system/goldmane\" limit:1 ","response":"range_response_count:1 size:597"} Jun 20 19:09:27.073632 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:27.072928Z","caller":"traceutil/trace.go:171","msg":"trace[1564698005] range","detail":"{range_begin:/registry/networkpolicies/calico-system/goldmane; range_end:; response_count:1; response_revision:680; }","duration":"228.782686ms","start":"2025-06-20T19:09:26.844128Z","end":"2025-06-20T19:09:27.072911Z","steps":["trace[1564698005] 'agreement among raft nodes before linearized reading' (duration: 228.698528ms)"],"step_count":1} Jun 20 19:09:27.264588 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:27.264170Z","caller":"traceutil/trace.go:171","msg":"trace[1533454861] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"120.022811ms","start":"2025-06-20T19:09:27.144119Z","end":"2025-06-20T19:09:27.264141Z","steps":["trace[1533454861] 'process raft request' (duration: 119.901512ms)"],"step_count":1} Jun 20 19:09:27.264588 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:09:27.264334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.381867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/calico-apiserver/calico-apiserver\" limit:1 ","response":"range_response_count:1 size:971"} Jun 20 19:09:27.264588 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:27.264375Z","caller":"traceutil/trace.go:171","msg":"trace[328564276] range","detail":"{range_begin:/registry/poddisruptionbudgets/calico-apiserver/calico-apiserver; range_end:; response_count:1; response_revision:684; }","duration":"108.44672ms","start":"2025-06-20T19:09:27.155914Z","end":"2025-06-20T19:09:27.264361Z","steps":["trace[328564276] 'agreement among raft nodes before linearized reading' (duration: 108.365027ms)"],"step_count":1} Jun 20 19:09:38.706192 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:38.705745Z","caller":"traceutil/trace.go:171","msg":"trace[630211937] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"103.879633ms","start":"2025-06-20T19:09:38.601846Z","end":"2025-06-20T19:09:38.705726Z","steps":["trace[630211937] 'process raft request' (duration: 102.197526ms)"],"step_count":1} Jun 20 19:09:38.916294 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:38.915961Z","caller":"traceutil/trace.go:171","msg":"trace[1826272007] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"138.991938ms","start":"2025-06-20T19:09:38.776949Z","end":"2025-06-20T19:09:38.915941Z","steps":["trace[1826272007] 'process raft request' (duration: 41.33994ms)","trace[1826272007] 'compare' (duration: 97.551203ms)"],"step_count":2} Jun 20 19:09:41.869843 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:41.869480Z","caller":"traceutil/trace.go:171","msg":"trace[1786487371] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"114.101399ms","start":"2025-06-20T19:09:41.755336Z","end":"2025-06-20T19:09:41.869437Z","steps":["trace[1786487371] 'process raft request' (duration: 50.261087ms)","trace[1786487371] 'compare' (duration: 63.689659ms)"],"step_count":2} Jun 20 19:09:47.169844 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:47.169362Z","caller":"traceutil/trace.go:171","msg":"trace[2098517538] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"117.733971ms","start":"2025-06-20T19:09:47.051607Z","end":"2025-06-20T19:09:47.169341Z","steps":["trace[2098517538] 'process raft request' (duration: 42.048694ms)","trace[2098517538] 'compare' (duration: 75.595704ms)"],"step_count":2} Jun 20 19:09:47.386596 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:09:47.386241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.855568ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Jun 20 19:09:47.386596 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:47.386326Z","caller":"traceutil/trace.go:171","msg":"trace[120843289] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"150.34625ms","start":"2025-06-20T19:09:47.235966Z","end":"2025-06-20T19:09:47.386313Z","steps":["trace[120843289] 'process raft request' (duration: 34.243064ms)","trace[120843289] 'compare' (duration: 115.78908ms)"],"step_count":2} Jun 20 19:09:47.595230 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:09:47.594820Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.784268ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Jun 20 19:09:47.595230 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:47.594901Z","caller":"traceutil/trace.go:171","msg":"trace[1814883372] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"125.914404ms","start":"2025-06-20T19:09:47.468970Z","end":"2025-06-20T19:09:47.594884Z","steps":["trace[1814883372] 'process raft request' (duration: 23.000818ms)","trace[1814883372] 'compare' (duration: 102.717278ms)"],"step_count":2} Jun 20 19:09:47.617232 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:47.616972Z","caller":"traceutil/trace.go:171","msg":"trace[407466295] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1015; }","duration":"112.132621ms","start":"2025-06-20T19:09:47.504829Z","end":"2025-06-20T19:09:47.616962Z","steps":["trace[407466295] 'read index received' (duration: 112.032718ms)","trace[407466295] 'applied index is now lower than readState.Index' (duration: 99.472µs)"],"step_count":2} Jun 20 19:09:47.617232 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:47.617011Z","caller":"traceutil/trace.go:171","msg":"trace[2081204352] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"113.712857ms","start":"2025-06-20T19:09:47.503286Z","end":"2025-06-20T19:09:47.616999Z","steps":["trace[2081204352] 'process raft request' (duration: 113.62095ms)"],"step_count":1} Jun 20 19:09:47.617403 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:09:47.617074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.242443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/calico-system/calico-node\" limit:1 ","response":"range_response_count:1 size:11398"} Jun 20 19:09:47.617403 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:47.617093Z","caller":"traceutil/trace.go:171","msg":"trace[2062352231] range","detail":"{range_begin:/registry/daemonsets/calico-system/calico-node; range_end:; response_count:1; response_revision:992; }","duration":"112.283513ms","start":"2025-06-20T19:09:47.504804Z","end":"2025-06-20T19:09:47.617088Z","steps":["trace[2062352231] 'agreement among raft nodes before linearized reading' (duration: 112.210943ms)"],"step_count":1} Jun 20 19:09:49.609488 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:49.608993Z","caller":"traceutil/trace.go:171","msg":"trace[527280417] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"123.148045ms","start":"2025-06-20T19:09:49.485829Z","end":"2025-06-20T19:09:49.608977Z","steps":["trace[527280417] 'process raft request' (duration: 123.065306ms)"],"step_count":1} Jun 20 19:09:49.609488 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:09:49.609063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.216363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/10.0.0.76\" limit:1 ","response":"range_response_count:1 size:2124"} Jun 20 19:09:49.609488 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:49.609043Z","caller":"traceutil/trace.go:171","msg":"trace[1819518711] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"128.097424ms","start":"2025-06-20T19:09:49.480900Z","end":"2025-06-20T19:09:49.608997Z","steps":["trace[1819518711] 'process raft request' (duration: 85.666466ms)","trace[1819518711] 'compare' (duration: 42.220474ms)"],"step_count":2} Jun 20 19:09:49.609488 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:49.608984Z","caller":"traceutil/trace.go:171","msg":"trace[1748718766] linearizableReadLoop","detail":"{readStateIndex:1027; appliedIndex:1026; }","duration":"126.139024ms","start":"2025-06-20T19:09:49.482829Z","end":"2025-06-20T19:09:49.608968Z","steps":["trace[1748718766] 'read index received' (duration: 14.868148ms)","trace[1748718766] 'applied index is now lower than readState.Index' (duration: 111.270265ms)"],"step_count":2} Jun 20 19:09:49.609488 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:49.609104Z","caller":"traceutil/trace.go:171","msg":"trace[1346507856] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1004; }","duration":"112.108419ms","start":"2025-06-20T19:09:49.496991Z","end":"2025-06-20T19:09:49.609100Z","steps":["trace[1346507856] 'process raft request' (duration: 111.954231ms)"],"step_count":1} Jun 20 19:09:49.610177 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:49.609043Z","caller":"traceutil/trace.go:171","msg":"trace[1912987033] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"121.938908ms","start":"2025-06-20T19:09:49.487087Z","end":"2025-06-20T19:09:49.609025Z","steps":["trace[1912987033] 'process raft request' (duration: 121.831271ms)"],"step_count":1} Jun 20 19:09:49.610177 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:49.609086Z","caller":"traceutil/trace.go:171","msg":"trace[684090288] range","detail":"{range_begin:/registry/minions/10.0.0.76; range_end:; response_count:1; response_revision:1004; }","duration":"126.263174ms","start":"2025-06-20T19:09:49.482818Z","end":"2025-06-20T19:09:49.609081Z","steps":["trace[684090288] 'agreement among raft nodes before linearized reading' (duration: 126.198859ms)"],"step_count":1} Jun 20 19:09:49.610177 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:09:49.609465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.478645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/10.0.0.76.184ad5e585390bd7\" limit:1 ","response":"range_response_count:0 size:5"} Jun 20 19:09:49.610177 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:49.609498Z","caller":"traceutil/trace.go:171","msg":"trace[823581399] range","detail":"{range_begin:/registry/events/default/10.0.0.76.184ad5e585390bd7; range_end:; response_count:0; response_revision:1005; }","duration":"109.533862ms","start":"2025-06-20T19:09:49.499957Z","end":"2025-06-20T19:09:49.609491Z","steps":["trace[823581399] 'agreement among raft nodes before linearized reading' (duration: 109.4495ms)"],"step_count":1} Jun 20 19:09:49.972250 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:09:49.971883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.546918ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Jun 20 19:09:49.972250 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:49.971960Z","caller":"traceutil/trace.go:171","msg":"trace[949751631] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"306.745504ms","start":"2025-06-20T19:09:49.665203Z","end":"2025-06-20T19:09:49.971949Z","steps":["trace[949751631] 'process raft request' (duration: 140.076571ms)","trace[949751631] 'compare' (duration: 166.451594ms)"],"step_count":2} Jun 20 19:09:49.972250 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:09:49.971998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-06-20T19:09:49.665197Z","time spent":"306.78497ms","remote":"10.0.0.66:37416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4871,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Jun 20 19:09:49.992535 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:49.992052Z","caller":"traceutil/trace.go:171","msg":"trace[2099877348] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"308.928917ms","start":"2025-06-20T19:09:49.683103Z","end":"2025-06-20T19:09:49.992032Z","steps":["trace[2099877348] 'process raft request' (duration: 308.731717ms)"],"step_count":1} Jun 20 19:09:49.992535 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:49.992091Z","caller":"traceutil/trace.go:171","msg":"trace[1315211037] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"308.339022ms","start":"2025-06-20T19:09:49.683732Z","end":"2025-06-20T19:09:49.992071Z","steps":["trace[1315211037] 'process raft request' (duration: 308.258217ms)"],"step_count":1} Jun 20 19:09:49.992535 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:09:49.992170Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-06-20T19:09:49.683711Z","time spent":"308.421882ms","remote":"10.0.0.66:37416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11383,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Jun 20 19:09:49.992535 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:09:49.992174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-06-20T19:09:49.683085Z","time spent":"309.025753ms","remote":"10.0.0.66:37020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":717,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} Jun 20 19:09:50.205731 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:09:50.205364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.109497ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Jun 20 19:09:50.205731 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:50.205433Z","caller":"traceutil/trace.go:171","msg":"trace[731115110] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"186.907327ms","start":"2025-06-20T19:09:50.018514Z","end":"2025-06-20T19:09:50.205422Z","steps":["trace[731115110] 'process raft request' (duration: 58.696724ms)","trace[731115110] 'compare' (duration: 127.997482ms)"],"step_count":2} Jun 20 19:09:52.255398 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:52.254950Z","caller":"traceutil/trace.go:171","msg":"trace[1928025078] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"129.125815ms","start":"2025-06-20T19:09:52.125801Z","end":"2025-06-20T19:09:52.254927Z","steps":["trace[1928025078] 'process raft request' (duration: 78.954926ms)","trace[1928025078] 'compare' (duration: 50.06789ms)"],"step_count":2} Jun 20 19:09:54.895133 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:54.894769Z","caller":"traceutil/trace.go:171","msg":"trace[1067148350] linearizableReadLoop","detail":"{readStateIndex:1199; appliedIndex:1198; }","duration":"101.374385ms","start":"2025-06-20T19:09:54.793374Z","end":"2025-06-20T19:09:54.894748Z","steps":["trace[1067148350] 'read index received' (duration: 84.180199ms)","trace[1067148350] 'applied index is now lower than readState.Index' (duration: 17.193645ms)"],"step_count":2} Jun 20 19:09:54.895133 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:54.894813Z","caller":"traceutil/trace.go:171","msg":"trace[1506062721] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"122.293099ms","start":"2025-06-20T19:09:54.772495Z","end":"2025-06-20T19:09:54.894788Z","steps":["trace[1506062721] 'process raft request' (duration: 105.066972ms)","trace[1506062721] 'compare' (duration: 17.095817ms)"],"step_count":2} Jun 20 19:09:54.895840 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:09:54.895038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.593658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/operator.tigera.io/installations/default\" limit:1 ","response":"range_response_count:1 size:3643"} Jun 20 19:09:54.895840 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:54.895149Z","caller":"traceutil/trace.go:171","msg":"trace[963872773] range","detail":"{range_begin:/registry/operator.tigera.io/installations/default; range_end:; response_count:1; response_revision:1168; }","duration":"101.776757ms","start":"2025-06-20T19:09:54.793357Z","end":"2025-06-20T19:09:54.895134Z","steps":["trace[963872773] 'agreement among raft nodes before linearized reading' (duration: 101.458436ms)"],"step_count":1} Jun 20 19:09:57.040578 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:57.039924Z","caller":"traceutil/trace.go:171","msg":"trace[1911585897] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"189.163189ms","start":"2025-06-20T19:09:56.850737Z","end":"2025-06-20T19:09:57.039901Z","steps":["trace[1911585897] 'process raft request' (duration: 100.218425ms)","trace[1911585897] 'compare' (duration: 88.776943ms)"],"step_count":2} Jun 20 19:09:57.041135 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:09:57.040178Z","caller":"traceutil/trace.go:171","msg":"trace[1600261462] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"140.843037ms","start":"2025-06-20T19:09:56.899313Z","end":"2025-06-20T19:09:57.040156Z","steps":["trace[1600261462] 'process raft request' (duration: 140.55734ms)"],"step_count":1} Jun 20 19:10:00.953121 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:10:00.952732Z","caller":"traceutil/trace.go:171","msg":"trace[818132335] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"143.157343ms","start":"2025-06-20T19:10:00.809555Z","end":"2025-06-20T19:10:00.952712Z","steps":["trace[818132335] 'process raft request' (duration: 121.92973ms)","trace[818132335] 'compare' (duration: 21.12665ms)"],"step_count":2} Jun 20 19:10:05.017049 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:10:05.016599Z","caller":"traceutil/trace.go:171","msg":"trace[1701496422] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"124.345782ms","start":"2025-06-20T19:10:04.892232Z","end":"2025-06-20T19:10:05.016578Z","steps":["trace[1701496422] 'process raft request' (duration: 103.853368ms)","trace[1701496422] 'compare' (duration: 20.392584ms)"],"step_count":2} Jun 20 19:10:37.465054 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:10:37.464543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.142151ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} Jun 20 19:10:37.465054 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:10:37.464632Z","caller":"traceutil/trace.go:171","msg":"trace[690702346] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1493; }","duration":"127.368845ms","start":"2025-06-20T19:10:37.337252Z","end":"2025-06-20T19:10:37.464621Z","steps":["trace[690702346] 'compare' (duration: 107.098369ms)"],"step_count":1} Jun 20 19:10:37.535605 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:10:37.535217Z","caller":"traceutil/trace.go:171","msg":"trace[1281208810] transaction","detail":"{read_only:false; response_revision:1494; number_of_response:1; }","duration":"190.363613ms","start":"2025-06-20T19:10:37.344837Z","end":"2025-06-20T19:10:37.535201Z","steps":["trace[1281208810] 'process raft request' (duration: 190.25124ms)"],"step_count":1} Jun 20 19:10:37.658197 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:10:37.657708Z","caller":"traceutil/trace.go:171","msg":"trace[397524698] transaction","detail":"{read_only:false; response_revision:1496; number_of_response:1; }","duration":"118.737464ms","start":"2025-06-20T19:10:37.538952Z","end":"2025-06-20T19:10:37.657689Z","steps":["trace[397524698] 'process raft request' (duration: 118.673333ms)"],"step_count":1} Jun 20 19:10:37.658197 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:10:37.657734Z","caller":"traceutil/trace.go:171","msg":"trace[275908165] transaction","detail":"{read_only:false; response_revision:1495; number_of_response:1; }","duration":"157.743718ms","start":"2025-06-20T19:10:37.499956Z","end":"2025-06-20T19:10:37.657699Z","steps":["trace[275908165] 'process raft request' (duration: 75.040187ms)","trace[275908165] 'compare' (duration: 82.524182ms)"],"step_count":2} Jun 20 19:10:37.658197 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:10:37.657739Z","caller":"traceutil/trace.go:171","msg":"trace[85885511] linearizableReadLoop","detail":"{readStateIndex:1685; appliedIndex:1684; }","duration":"122.520449ms","start":"2025-06-20T19:10:37.535185Z","end":"2025-06-20T19:10:37.657705Z","steps":["trace[85885511] 'read index received' (duration: 39.807009ms)","trace[85885511] 'applied index is now lower than readState.Index' (duration: 82.709332ms)"],"step_count":2} Jun 20 19:10:37.658436 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:10:37.657925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.281769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Jun 20 19:10:37.658436 etcd-wrapper[1725]: {"level":"warn","ts":"2025-06-20T19:10:37.657934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.374271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} Jun 20 19:10:37.658436 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:10:37.657959Z","caller":"traceutil/trace.go:171","msg":"trace[490471420] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1496; }","duration":"192.437892ms","start":"2025-06-20T19:10:37.465513Z","end":"2025-06-20T19:10:37.657951Z","steps":["trace[490471420] 'agreement among raft nodes before linearized reading' (duration: 192.345166ms)"],"step_count":1} Jun 20 19:10:37.658436 etcd-wrapper[1725]: {"level":"info","ts":"2025-06-20T19:10:37.657968Z","caller":"traceutil/trace.go:171","msg":"trace[527133086] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1496; }","duration":"120.344699ms","start":"2025-06-20T19:10:37.537604Z","end":"2025-06-20T19:10:37.657949Z","steps":["trace[527133086] 'agreement among raft nodes before linearized reading' (duration: 120.282781ms)"],"step_count":1}