May 27 17:42:50.887173 kernel: Linux version 6.12.30-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.44 p1) 2.44.0) #1 SMP PREEMPT_DYNAMIC Tue May 27 15:32:02 -00 2025
May 27 17:42:50.887214 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=daa3e2d55cc4a7ff0ec15aa9bb0c07df9999cb4e3041f3adad1b1101efdea101
May 27 17:42:50.887232 kernel: BIOS-provided physical RAM map:
May 27 17:42:50.887241 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000002ffff] usable
May 27 17:42:50.887249 kernel: BIOS-e820: [mem 0x0000000000030000-0x000000000004ffff] reserved
May 27 17:42:50.887257 kernel: BIOS-e820: [mem 0x0000000000050000-0x000000000009efff] usable
May 27 17:42:50.887268 kernel: BIOS-e820: [mem 0x000000000009f000-0x000000000009ffff] reserved
May 27 17:42:50.887276 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000009b8ecfff] usable
May 27 17:42:50.887285 kernel: BIOS-e820: [mem 0x000000009b8ed000-0x000000009bb6cfff] reserved
May 27 17:42:50.887293 kernel: BIOS-e820: [mem 0x000000009bb6d000-0x000000009bb7efff] ACPI data
May 27 17:42:50.887303 kernel: BIOS-e820: [mem 0x000000009bb7f000-0x000000009bbfefff] ACPI NVS
May 27 17:42:50.887315 kernel: BIOS-e820: [mem 0x000000009bbff000-0x000000009bfb0fff] usable
May 27 17:42:50.887324 kernel: BIOS-e820: [mem 0x000000009bfb1000-0x000000009bfb4fff] reserved
May 27 17:42:50.887334 kernel: BIOS-e820: [mem 0x000000009bfb5000-0x000000009bfb6fff] ACPI NVS
May 27 17:42:50.887345 kernel: BIOS-e820: [mem 0x000000009bfb7000-0x000000009bffffff] usable
May 27 17:42:50.887355 kernel: BIOS-e820: [mem 0x000000009c000000-0x000000009cffffff] reserved
May 27 17:42:50.887366 kernel: BIOS-e820: [mem 0x00000000e0000000-0x00000000efffffff] reserved
May 27 17:42:50.887376 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
May 27 17:42:50.887386 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved
May 27 17:42:50.887395 kernel: NX (Execute Disable) protection: active
May 27 17:42:50.887404 kernel: APIC: Static calls initialized
May 27 17:42:50.887413 kernel: e820: update [mem 0x9a13f018-0x9a148c57] usable ==> usable
May 27 17:42:50.887422 kernel: e820: update [mem 0x9a102018-0x9a13ee57] usable ==> usable
May 27 17:42:50.887432 kernel: extended physical RAM map:
May 27 17:42:50.887442 kernel: reserve setup_data: [mem 0x0000000000000000-0x000000000002ffff] usable
May 27 17:42:50.887451 kernel: reserve setup_data: [mem 0x0000000000030000-0x000000000004ffff] reserved
May 27 17:42:50.887460 kernel: reserve setup_data: [mem 0x0000000000050000-0x000000000009efff] usable
May 27 17:42:50.887473 kernel: reserve setup_data: [mem 0x000000000009f000-0x000000000009ffff] reserved
May 27 17:42:50.887482 kernel: reserve setup_data: [mem 0x0000000000100000-0x000000009a102017] usable
May 27 17:42:50.887490 kernel: reserve setup_data: [mem 0x000000009a102018-0x000000009a13ee57] usable
May 27 17:42:50.887499 kernel: reserve setup_data: [mem 0x000000009a13ee58-0x000000009a13f017] usable
May 27 17:42:50.887508 kernel: reserve setup_data: [mem 0x000000009a13f018-0x000000009a148c57] usable
May 27 17:42:50.887517 kernel: reserve setup_data: [mem 0x000000009a148c58-0x000000009b8ecfff] usable
May 27 17:42:50.887527 kernel: reserve setup_data: [mem 0x000000009b8ed000-0x000000009bb6cfff] reserved
May 27 17:42:50.887538 kernel: reserve setup_data: [mem 0x000000009bb6d000-0x000000009bb7efff] ACPI data
May 27 17:42:50.887548 kernel: reserve setup_data: [mem 0x000000009bb7f000-0x000000009bbfefff] ACPI NVS
May 27 17:42:50.887558 kernel: reserve setup_data: [mem 0x000000009bbff000-0x000000009bfb0fff] usable
May 27 17:42:50.887568 kernel: reserve setup_data: [mem 0x000000009bfb1000-0x000000009bfb4fff] reserved
May 27 17:42:50.887580 kernel: reserve setup_data: [mem 0x000000009bfb5000-0x000000009bfb6fff] ACPI NVS
May 27 17:42:50.887591 kernel: reserve setup_data: [mem 0x000000009bfb7000-0x000000009bffffff] usable
May 27 17:42:50.887606 kernel: reserve setup_data: [mem 0x000000009c000000-0x000000009cffffff] reserved
May 27 17:42:50.887617 kernel: reserve setup_data: [mem 0x00000000e0000000-0x00000000efffffff] reserved
May 27 17:42:50.887628 kernel: reserve setup_data: [mem 0x00000000feffc000-0x00000000feffffff] reserved
May 27 17:42:50.887637 kernel: reserve setup_data: [mem 0x000000fd00000000-0x000000ffffffffff] reserved
May 27 17:42:50.887649 kernel: efi: EFI v2.7 by EDK II
May 27 17:42:50.887659 kernel: efi: SMBIOS=0x9b9d5000 ACPI=0x9bb7e000 ACPI 2.0=0x9bb7e014 MEMATTR=0x9a1af018 RNG=0x9bb73018 
May 27 17:42:50.887668 kernel: random: crng init done
May 27 17:42:50.887678 kernel: Kernel is locked down from EFI Secure Boot; see man kernel_lockdown.7
May 27 17:42:50.887688 kernel: secureboot: Secure boot enabled
May 27 17:42:50.887698 kernel: SMBIOS 2.8 present.
May 27 17:42:50.887708 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS unknown 02/02/2022
May 27 17:42:50.887718 kernel: DMI: Memory slots populated: 1/1
May 27 17:42:50.887729 kernel: Hypervisor detected: KVM
May 27 17:42:50.887739 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00
May 27 17:42:50.887749 kernel: kvm-clock: using sched offset of 5148124314 cycles
May 27 17:42:50.887763 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
May 27 17:42:50.887774 kernel: tsc: Detected 2794.748 MHz processor
May 27 17:42:50.887784 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
May 27 17:42:50.887795 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable
May 27 17:42:50.887805 kernel: last_pfn = 0x9c000 max_arch_pfn = 0x400000000
May 27 17:42:50.887815 kernel: MTRR map: 4 entries (2 fixed + 2 variable; max 18), built from 8 variable MTRRs
May 27 17:42:50.887825 kernel: x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT  
May 27 17:42:50.887836 kernel: Using GB pages for direct mapping
May 27 17:42:50.887846 kernel: ACPI: Early table checksum verification disabled
May 27 17:42:50.887859 kernel: ACPI: RSDP 0x000000009BB7E014 000024 (v02 BOCHS )
May 27 17:42:50.887869 kernel: ACPI: XSDT 0x000000009BB7D0E8 000054 (v01 BOCHS  BXPC     00000001      01000013)
May 27 17:42:50.887879 kernel: ACPI: FACP 0x000000009BB79000 0000F4 (v03 BOCHS  BXPC     00000001 BXPC 00000001)
May 27 17:42:50.887889 kernel: ACPI: DSDT 0x000000009BB7A000 002237 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
May 27 17:42:50.887899 kernel: ACPI: FACS 0x000000009BBDD000 000040
May 27 17:42:50.887909 kernel: ACPI: APIC 0x000000009BB78000 000090 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
May 27 17:42:50.887919 kernel: ACPI: HPET 0x000000009BB77000 000038 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
May 27 17:42:50.887928 kernel: ACPI: MCFG 0x000000009BB76000 00003C (v01 BOCHS  BXPC     00000001 BXPC 00000001)
May 27 17:42:50.887941 kernel: ACPI: WAET 0x000000009BB75000 000028 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
May 27 17:42:50.887951 kernel: ACPI: BGRT 0x000000009BB74000 000038 (v01 INTEL  EDK2     00000002      01000013)
May 27 17:42:50.887961 kernel: ACPI: Reserving FACP table memory at [mem 0x9bb79000-0x9bb790f3]
May 27 17:42:50.887972 kernel: ACPI: Reserving DSDT table memory at [mem 0x9bb7a000-0x9bb7c236]
May 27 17:42:50.887982 kernel: ACPI: Reserving FACS table memory at [mem 0x9bbdd000-0x9bbdd03f]
May 27 17:42:50.887993 kernel: ACPI: Reserving APIC table memory at [mem 0x9bb78000-0x9bb7808f]
May 27 17:42:50.888004 kernel: ACPI: Reserving HPET table memory at [mem 0x9bb77000-0x9bb77037]
May 27 17:42:50.888014 kernel: ACPI: Reserving MCFG table memory at [mem 0x9bb76000-0x9bb7603b]
May 27 17:42:50.888025 kernel: ACPI: Reserving WAET table memory at [mem 0x9bb75000-0x9bb75027]
May 27 17:42:50.888038 kernel: ACPI: Reserving BGRT table memory at [mem 0x9bb74000-0x9bb74037]
May 27 17:42:50.888048 kernel: No NUMA configuration found
May 27 17:42:50.888081 kernel: Faking a node at [mem 0x0000000000000000-0x000000009bffffff]
May 27 17:42:50.888091 kernel: NODE_DATA(0) allocated [mem 0x9bf57dc0-0x9bf5efff]
May 27 17:42:50.888102 kernel: Zone ranges:
May 27 17:42:50.888112 kernel:   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
May 27 17:42:50.888123 kernel:   DMA32    [mem 0x0000000001000000-0x000000009bffffff]
May 27 17:42:50.888132 kernel:   Normal   empty
May 27 17:42:50.888142 kernel:   Device   empty
May 27 17:42:50.888152 kernel: Movable zone start for each node
May 27 17:42:50.888165 kernel: Early memory node ranges
May 27 17:42:50.888194 kernel:   node   0: [mem 0x0000000000001000-0x000000000002ffff]
May 27 17:42:50.888213 kernel:   node   0: [mem 0x0000000000050000-0x000000000009efff]
May 27 17:42:50.888222 kernel:   node   0: [mem 0x0000000000100000-0x000000009b8ecfff]
May 27 17:42:50.888232 kernel:   node   0: [mem 0x000000009bbff000-0x000000009bfb0fff]
May 27 17:42:50.888242 kernel:   node   0: [mem 0x000000009bfb7000-0x000000009bffffff]
May 27 17:42:50.888253 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009bffffff]
May 27 17:42:50.888262 kernel: On node 0, zone DMA: 1 pages in unavailable ranges
May 27 17:42:50.888272 kernel: On node 0, zone DMA: 32 pages in unavailable ranges
May 27 17:42:50.888290 kernel: On node 0, zone DMA: 97 pages in unavailable ranges
May 27 17:42:50.888300 kernel: On node 0, zone DMA32: 786 pages in unavailable ranges
May 27 17:42:50.888311 kernel: On node 0, zone DMA32: 6 pages in unavailable ranges
May 27 17:42:50.888320 kernel: On node 0, zone DMA32: 16384 pages in unavailable ranges
May 27 17:42:50.888330 kernel: ACPI: PM-Timer IO Port: 0x608
May 27 17:42:50.888340 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
May 27 17:42:50.888350 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
May 27 17:42:50.888360 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
May 27 17:42:50.888370 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
May 27 17:42:50.888383 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
May 27 17:42:50.888393 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
May 27 17:42:50.888403 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
May 27 17:42:50.888412 kernel: ACPI: Using ACPI (MADT) for SMP configuration information
May 27 17:42:50.888422 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000
May 27 17:42:50.888431 kernel: TSC deadline timer available
May 27 17:42:50.888441 kernel: CPU topo: Max. logical packages:   1
May 27 17:42:50.888451 kernel: CPU topo: Max. logical dies:       1
May 27 17:42:50.888464 kernel: CPU topo: Max. dies per package:   1
May 27 17:42:50.888483 kernel: CPU topo: Max. threads per core:   1
May 27 17:42:50.888493 kernel: CPU topo: Num. cores per package:     4
May 27 17:42:50.888503 kernel: CPU topo: Num. threads per package:   4
May 27 17:42:50.888515 kernel: CPU topo: Allowing 4 present CPUs plus 0 hotplug CPUs
May 27 17:42:50.888525 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()
May 27 17:42:50.888536 kernel: kvm-guest: KVM setup pv remote TLB flush
May 27 17:42:50.888546 kernel: kvm-guest: setup PV sched yield
May 27 17:42:50.888555 kernel: [mem 0x9d000000-0xdfffffff] available for PCI devices
May 27 17:42:50.888566 kernel: Booting paravirtualized kernel on KVM
May 27 17:42:50.888574 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
May 27 17:42:50.888582 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1
May 27 17:42:50.888589 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u524288
May 27 17:42:50.888597 kernel: pcpu-alloc: s207832 r8192 d29736 u524288 alloc=1*2097152
May 27 17:42:50.888604 kernel: pcpu-alloc: [0] 0 1 2 3 
May 27 17:42:50.888612 kernel: kvm-guest: PV spinlocks enabled
May 27 17:42:50.888620 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear)
May 27 17:42:50.888629 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=daa3e2d55cc4a7ff0ec15aa9bb0c07df9999cb4e3041f3adad1b1101efdea101
May 27 17:42:50.888641 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space.
May 27 17:42:50.888651 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
May 27 17:42:50.888661 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
May 27 17:42:50.888671 kernel: Fallback order for Node 0: 0 
May 27 17:42:50.888678 kernel: Built 1 zonelists, mobility grouping on.  Total pages: 638054
May 27 17:42:50.888686 kernel: Policy zone: DMA32
May 27 17:42:50.888694 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off
May 27 17:42:50.888701 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
May 27 17:42:50.888711 kernel: ftrace: allocating 40081 entries in 157 pages
May 27 17:42:50.888719 kernel: ftrace: allocated 157 pages with 5 groups
May 27 17:42:50.888727 kernel: Dynamic Preempt: voluntary
May 27 17:42:50.888734 kernel: rcu: Preemptible hierarchical RCU implementation.
May 27 17:42:50.888743 kernel: rcu:         RCU event tracing is enabled.
May 27 17:42:50.888751 kernel: rcu:         RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4.
May 27 17:42:50.888759 kernel:         Trampoline variant of Tasks RCU enabled.
May 27 17:42:50.888766 kernel:         Rude variant of Tasks RCU enabled.
May 27 17:42:50.888774 kernel:         Tracing variant of Tasks RCU enabled.
May 27 17:42:50.888784 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
May 27 17:42:50.888792 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
May 27 17:42:50.888800 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4.
May 27 17:42:50.888807 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4.
May 27 17:42:50.888815 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4.
May 27 17:42:50.888823 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16
May 27 17:42:50.888831 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention.
May 27 17:42:50.888838 kernel: Console: colour dummy device 80x25
May 27 17:42:50.888846 kernel: printk: legacy console [ttyS0] enabled
May 27 17:42:50.888856 kernel: ACPI: Core revision 20240827
May 27 17:42:50.888864 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
May 27 17:42:50.888872 kernel: APIC: Switch to symmetric I/O mode setup
May 27 17:42:50.888879 kernel: x2apic enabled
May 27 17:42:50.888887 kernel: APIC: Switched APIC routing to: physical x2apic
May 27 17:42:50.888894 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask()
May 27 17:42:50.888902 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself()
May 27 17:42:50.888910 kernel: kvm-guest: setup PV IPIs
May 27 17:42:50.888917 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
May 27 17:42:50.888927 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x2848df6a9de, max_idle_ns: 440795280912 ns
May 27 17:42:50.888935 kernel: Calibrating delay loop (skipped) preset value.. 5589.49 BogoMIPS (lpj=2794748)
May 27 17:42:50.888943 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated
May 27 17:42:50.888951 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127
May 27 17:42:50.888958 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0
May 27 17:42:50.888966 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
May 27 17:42:50.888974 kernel: Spectre V2 : Mitigation: Retpolines
May 27 17:42:50.888981 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT
May 27 17:42:50.888989 kernel: Spectre V2 : Enabling Speculation Barrier for firmware calls
May 27 17:42:50.888999 kernel: RETBleed: Mitigation: untrained return thunk
May 27 17:42:50.889006 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier
May 27 17:42:50.889014 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl
May 27 17:42:50.889023 kernel: Speculative Return Stack Overflow: IBPB-extending microcode not applied!
May 27 17:42:50.889035 kernel: Speculative Return Stack Overflow: WARNING: See https://kernel.org/doc/html/latest/admin-guide/hw-vuln/srso.html for mitigation options.
May 27 17:42:50.889045 kernel: x86/bugs: return thunk changed
May 27 17:42:50.889075 kernel: Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode
May 27 17:42:50.889083 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
May 27 17:42:50.889094 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
May 27 17:42:50.889103 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
May 27 17:42:50.889148 kernel: x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256
May 27 17:42:50.889157 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format.
May 27 17:42:50.889165 kernel: Freeing SMP alternatives memory: 32K
May 27 17:42:50.889177 kernel: pid_max: default: 32768 minimum: 301
May 27 17:42:50.889188 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima
May 27 17:42:50.889200 kernel: landlock: Up and running.
May 27 17:42:50.889214 kernel: SELinux:  Initializing.
May 27 17:42:50.889226 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
May 27 17:42:50.889234 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
May 27 17:42:50.889242 kernel: smpboot: CPU0: AMD EPYC 7402P 24-Core Processor (family: 0x17, model: 0x31, stepping: 0x0)
May 27 17:42:50.889249 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver.
May 27 17:42:50.889257 kernel: ... version:                0
May 27 17:42:50.889265 kernel: ... bit width:              48
May 27 17:42:50.889272 kernel: ... generic registers:      6
May 27 17:42:50.889280 kernel: ... value mask:             0000ffffffffffff
May 27 17:42:50.889288 kernel: ... max period:             00007fffffffffff
May 27 17:42:50.889297 kernel: ... fixed-purpose events:   0
May 27 17:42:50.889305 kernel: ... event mask:             000000000000003f
May 27 17:42:50.889312 kernel: signal: max sigframe size: 1776
May 27 17:42:50.889320 kernel: rcu: Hierarchical SRCU implementation.
May 27 17:42:50.889328 kernel: rcu:         Max phase no-delay instances is 400.
May 27 17:42:50.889336 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level
May 27 17:42:50.889343 kernel: smp: Bringing up secondary CPUs ...
May 27 17:42:50.889352 kernel: smpboot: x86: Booting SMP configuration:
May 27 17:42:50.889363 kernel: .... node  #0, CPUs:      #1 #2 #3
May 27 17:42:50.889373 kernel: smp: Brought up 1 node, 4 CPUs
May 27 17:42:50.889390 kernel: smpboot: Total of 4 processors activated (22357.98 BogoMIPS)
May 27 17:42:50.889404 kernel: Memory: 2409216K/2552216K available (14336K kernel code, 2430K rwdata, 9952K rodata, 54416K init, 2552K bss, 137064K reserved, 0K cma-reserved)
May 27 17:42:50.889414 kernel: devtmpfs: initialized
May 27 17:42:50.889424 kernel: x86/mm: Memory block size: 128MB
May 27 17:42:50.889434 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9bb7f000-0x9bbfefff] (524288 bytes)
May 27 17:42:50.889445 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9bfb5000-0x9bfb6fff] (8192 bytes)
May 27 17:42:50.889461 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
May 27 17:42:50.889474 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
May 27 17:42:50.889494 kernel: pinctrl core: initialized pinctrl subsystem
May 27 17:42:50.889507 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family
May 27 17:42:50.889517 kernel: audit: initializing netlink subsys (disabled)
May 27 17:42:50.889529 kernel: audit: type=2000 audit(1748367768.214:1): state=initialized audit_enabled=0 res=1
May 27 17:42:50.889539 kernel: thermal_sys: Registered thermal governor 'step_wise'
May 27 17:42:50.889549 kernel: thermal_sys: Registered thermal governor 'user_space'
May 27 17:42:50.889558 kernel: cpuidle: using governor menu
May 27 17:42:50.889566 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
May 27 17:42:50.889574 kernel: dca service started, version 1.12.1
May 27 17:42:50.889586 kernel: PCI: ECAM [mem 0xe0000000-0xefffffff] (base 0xe0000000) for domain 0000 [bus 00-ff]
May 27 17:42:50.889594 kernel: PCI: Using configuration type 1 for base access
May 27 17:42:50.889602 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.
May 27 17:42:50.889609 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages
May 27 17:42:50.889617 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page
May 27 17:42:50.889625 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
May 27 17:42:50.889632 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page
May 27 17:42:50.889640 kernel: ACPI: Added _OSI(Module Device)
May 27 17:42:50.889647 kernel: ACPI: Added _OSI(Processor Device)
May 27 17:42:50.889657 kernel: ACPI: Added _OSI(3.0 _SCP Extensions)
May 27 17:42:50.889664 kernel: ACPI: Added _OSI(Processor Aggregator Device)
May 27 17:42:50.889672 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded
May 27 17:42:50.889679 kernel: ACPI: Interpreter enabled
May 27 17:42:50.889687 kernel: ACPI: PM: (supports S0 S5)
May 27 17:42:50.889694 kernel: ACPI: Using IOAPIC for interrupt routing
May 27 17:42:50.889702 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
May 27 17:42:50.889710 kernel: PCI: Using E820 reservations for host bridge windows
May 27 17:42:50.889717 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F
May 27 17:42:50.889727 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
May 27 17:42:50.889933 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]
May 27 17:42:50.890111 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]
May 27 17:42:50.890236 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]
May 27 17:42:50.890247 kernel: PCI host bridge to bus 0000:00
May 27 17:42:50.890375 kernel: pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
May 27 17:42:50.890482 kernel: pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
May 27 17:42:50.890596 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
May 27 17:42:50.890700 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xdfffffff window]
May 27 17:42:50.890842 kernel: pci_bus 0000:00: root bus resource [mem 0xf0000000-0xfebfffff window]
May 27 17:42:50.890959 kernel: pci_bus 0000:00: root bus resource [mem 0x380000000000-0x3807ffffffff window]
May 27 17:42:50.891127 kernel: pci_bus 0000:00: root bus resource [bus 00-ff]
May 27 17:42:50.891330 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint
May 27 17:42:50.891481 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint
May 27 17:42:50.891601 kernel: pci 0000:00:01.0: BAR 0 [mem 0xc0000000-0xc0ffffff pref]
May 27 17:42:50.891715 kernel: pci 0000:00:01.0: BAR 2 [mem 0xc1044000-0xc1044fff]
May 27 17:42:50.891829 kernel: pci 0000:00:01.0: ROM [mem 0xffff0000-0xffffffff pref]
May 27 17:42:50.891944 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
May 27 17:42:50.892151 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint
May 27 17:42:50.892292 kernel: pci 0000:00:02.0: BAR 0 [io  0x6100-0x611f]
May 27 17:42:50.892427 kernel: pci 0000:00:02.0: BAR 1 [mem 0xc1043000-0xc1043fff]
May 27 17:42:50.892545 kernel: pci 0000:00:02.0: BAR 4 [mem 0x380000000000-0x380000003fff 64bit pref]
May 27 17:42:50.892670 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint
May 27 17:42:50.892787 kernel: pci 0000:00:03.0: BAR 0 [io  0x6000-0x607f]
May 27 17:42:50.892902 kernel: pci 0000:00:03.0: BAR 1 [mem 0xc1042000-0xc1042fff]
May 27 17:42:50.893030 kernel: pci 0000:00:03.0: BAR 4 [mem 0x380000004000-0x380000007fff 64bit pref]
May 27 17:42:50.893183 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint
May 27 17:42:50.893305 kernel: pci 0000:00:04.0: BAR 0 [io  0x60e0-0x60ff]
May 27 17:42:50.893419 kernel: pci 0000:00:04.0: BAR 1 [mem 0xc1041000-0xc1041fff]
May 27 17:42:50.893533 kernel: pci 0000:00:04.0: BAR 4 [mem 0x380000008000-0x38000000bfff 64bit pref]
May 27 17:42:50.893648 kernel: pci 0000:00:04.0: ROM [mem 0xfffc0000-0xffffffff pref]
May 27 17:42:50.893771 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint
May 27 17:42:50.893885 kernel: pci 0000:00:1f.0: quirk: [io  0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO
May 27 17:42:50.894024 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint
May 27 17:42:50.894165 kernel: pci 0000:00:1f.2: BAR 4 [io  0x60c0-0x60df]
May 27 17:42:50.894280 kernel: pci 0000:00:1f.2: BAR 5 [mem 0xc1040000-0xc1040fff]
May 27 17:42:50.894400 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint
May 27 17:42:50.894514 kernel: pci 0000:00:1f.3: BAR 4 [io  0x6080-0x60bf]
May 27 17:42:50.894525 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10
May 27 17:42:50.894533 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10
May 27 17:42:50.894545 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11
May 27 17:42:50.894552 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11
May 27 17:42:50.894560 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10
May 27 17:42:50.894568 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10
May 27 17:42:50.894576 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11
May 27 17:42:50.894584 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11
May 27 17:42:50.894593 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16
May 27 17:42:50.894603 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17
May 27 17:42:50.894614 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18
May 27 17:42:50.894628 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19
May 27 17:42:50.894636 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20
May 27 17:42:50.894644 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21
May 27 17:42:50.894655 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22
May 27 17:42:50.894665 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23
May 27 17:42:50.894676 kernel: iommu: Default domain type: Translated
May 27 17:42:50.894684 kernel: iommu: DMA domain TLB invalidation policy: lazy mode
May 27 17:42:50.894692 kernel: efivars: Registered efivars operations
May 27 17:42:50.894700 kernel: PCI: Using ACPI for IRQ routing
May 27 17:42:50.894708 kernel: PCI: pci_cache_line_size set to 64 bytes
May 27 17:42:50.894719 kernel: e820: reserve RAM buffer [mem 0x0009f000-0x0009ffff]
May 27 17:42:50.894727 kernel: e820: reserve RAM buffer [mem 0x9a102018-0x9bffffff]
May 27 17:42:50.894734 kernel: e820: reserve RAM buffer [mem 0x9a13f018-0x9bffffff]
May 27 17:42:50.894742 kernel: e820: reserve RAM buffer [mem 0x9b8ed000-0x9bffffff]
May 27 17:42:50.894749 kernel: e820: reserve RAM buffer [mem 0x9bfb1000-0x9bffffff]
May 27 17:42:50.894895 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device
May 27 17:42:50.895029 kernel: pci 0000:00:01.0: vgaarb: bridge control possible
May 27 17:42:50.895169 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
May 27 17:42:50.895184 kernel: vgaarb: loaded
May 27 17:42:50.895193 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
May 27 17:42:50.895200 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter
May 27 17:42:50.895209 kernel: clocksource: Switched to clocksource kvm-clock
May 27 17:42:50.895217 kernel: VFS: Disk quotas dquot_6.6.0
May 27 17:42:50.895224 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
May 27 17:42:50.895232 kernel: pnp: PnP ACPI init
May 27 17:42:50.895385 kernel: system 00:05: [mem 0xe0000000-0xefffffff window] has been reserved
May 27 17:42:50.895401 kernel: pnp: PnP ACPI: found 6 devices
May 27 17:42:50.895410 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
May 27 17:42:50.895418 kernel: NET: Registered PF_INET protocol family
May 27 17:42:50.895426 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)
May 27 17:42:50.895434 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)
May 27 17:42:50.895442 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
May 27 17:42:50.895450 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)
May 27 17:42:50.895458 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear)
May 27 17:42:50.895466 kernel: TCP: Hash tables configured (established 32768 bind 32768)
May 27 17:42:50.895476 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)
May 27 17:42:50.895484 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)
May 27 17:42:50.895492 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family
May 27 17:42:50.895500 kernel: NET: Registered PF_XDP protocol family
May 27 17:42:50.895616 kernel: pci 0000:00:04.0: ROM [mem 0xfffc0000-0xffffffff pref]: can't claim; no compatible bridge window
May 27 17:42:50.895732 kernel: pci 0000:00:04.0: ROM [mem 0x9d000000-0x9d03ffff pref]: assigned
May 27 17:42:50.895842 kernel: pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
May 27 17:42:50.895948 kernel: pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
May 27 17:42:50.896094 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
May 27 17:42:50.896206 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xdfffffff window]
May 27 17:42:50.896325 kernel: pci_bus 0000:00: resource 8 [mem 0xf0000000-0xfebfffff window]
May 27 17:42:50.896429 kernel: pci_bus 0000:00: resource 9 [mem 0x380000000000-0x3807ffffffff window]
May 27 17:42:50.896439 kernel: PCI: CLS 0 bytes, default 64
May 27 17:42:50.896447 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x2848df6a9de, max_idle_ns: 440795280912 ns
May 27 17:42:50.896455 kernel: Initialise system trusted keyrings
May 27 17:42:50.896463 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0
May 27 17:42:50.896471 kernel: Key type asymmetric registered
May 27 17:42:50.896482 kernel: Asymmetric key parser 'x509' registered
May 27 17:42:50.896504 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
May 27 17:42:50.896515 kernel: io scheduler mq-deadline registered
May 27 17:42:50.896526 kernel: io scheduler kyber registered
May 27 17:42:50.896537 kernel: io scheduler bfq registered
May 27 17:42:50.896548 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00
May 27 17:42:50.896559 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22
May 27 17:42:50.896570 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23
May 27 17:42:50.896581 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20
May 27 17:42:50.896595 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
May 27 17:42:50.896606 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
May 27 17:42:50.896618 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
May 27 17:42:50.896628 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1
May 27 17:42:50.896639 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12
May 27 17:42:50.896794 kernel: rtc_cmos 00:04: RTC can wake from S4
May 27 17:42:50.896813 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
May 27 17:42:50.896956 kernel: rtc_cmos 00:04: registered as rtc0
May 27 17:42:50.897119 kernel: rtc_cmos 00:04: setting system clock to 2025-05-27T17:42:50 UTC (1748367770)
May 27 17:42:50.897266 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram
May 27 17:42:50.897287 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled
May 27 17:42:50.897301 kernel: efifb: probing for efifb
May 27 17:42:50.897319 kernel: efifb: framebuffer at 0xc0000000, using 4000k, total 4000k
May 27 17:42:50.897329 kernel: efifb: mode is 1280x800x32, linelength=5120, pages=1
May 27 17:42:50.897339 kernel: efifb: scrolling: redraw
May 27 17:42:50.897350 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0
May 27 17:42:50.897360 kernel: Console: switching to colour frame buffer device 160x50
May 27 17:42:50.897372 kernel: fb0: EFI VGA frame buffer device
May 27 17:42:50.897383 kernel: pstore: Using crash dump compression: deflate
May 27 17:42:50.897396 kernel: pstore: Registered efi_pstore as persistent store backend
May 27 17:42:50.897406 kernel: NET: Registered PF_INET6 protocol family
May 27 17:42:50.897416 kernel: Segment Routing with IPv6
May 27 17:42:50.897429 kernel: In-situ OAM (IOAM) with IPv6
May 27 17:42:50.897439 kernel: NET: Registered PF_PACKET protocol family
May 27 17:42:50.897448 kernel: Key type dns_resolver registered
May 27 17:42:50.897457 kernel: IPI shorthand broadcast: enabled
May 27 17:42:50.897465 kernel: sched_clock: Marking stable (3834002313, 155434097)->(4021620875, -32184465)
May 27 17:42:50.897474 kernel: registered taskstats version 1
May 27 17:42:50.897482 kernel: Loading compiled-in X.509 certificates
May 27 17:42:50.897490 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.30-flatcar: 9507e5c390e18536b38d58c90da64baf0ac9837c'
May 27 17:42:50.897498 kernel: Demotion targets for Node 0: null
May 27 17:42:50.897508 kernel: Key type .fscrypt registered
May 27 17:42:50.897516 kernel: Key type fscrypt-provisioning registered
May 27 17:42:50.897524 kernel: ima: No TPM chip found, activating TPM-bypass!
May 27 17:42:50.897533 kernel: ima: Allocated hash algorithm: sha1
May 27 17:42:50.897541 kernel: ima: No architecture policies found
May 27 17:42:50.897549 kernel: clk: Disabling unused clocks
May 27 17:42:50.897557 kernel: Warning: unable to open an initial console.
May 27 17:42:50.897565 kernel: Freeing unused kernel image (initmem) memory: 54416K
May 27 17:42:50.897573 kernel: Write protecting the kernel read-only data: 24576k
May 27 17:42:50.897584 kernel: Freeing unused kernel image (rodata/data gap) memory: 288K
May 27 17:42:50.897592 kernel: Run /init as init process
May 27 17:42:50.897600 kernel:   with arguments:
May 27 17:42:50.897608 kernel:     /init
May 27 17:42:50.897616 kernel:   with environment:
May 27 17:42:50.897624 kernel:     HOME=/
May 27 17:42:50.897632 kernel:     TERM=linux
May 27 17:42:50.897641 kernel:     BOOT_IMAGE=/flatcar/vmlinuz-a
May 27 17:42:50.897656 systemd[1]: Successfully made /usr/ read-only.
May 27 17:42:50.897670 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE)
May 27 17:42:50.897679 systemd[1]: Detected virtualization kvm.
May 27 17:42:50.897688 systemd[1]: Detected architecture x86-64.
May 27 17:42:50.897696 systemd[1]: Running in initrd.
May 27 17:42:50.897705 systemd[1]: No hostname configured, using default hostname.
May 27 17:42:50.897714 systemd[1]: Hostname set to <localhost>.
May 27 17:42:50.897722 systemd[1]: Initializing machine ID from VM UUID.
May 27 17:42:50.897732 systemd[1]: Queued start job for default target initrd.target.
May 27 17:42:50.897741 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
May 27 17:42:50.897750 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
May 27 17:42:50.897760 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM...
May 27 17:42:50.897769 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM...
May 27 17:42:50.897778 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT...
May 27 17:42:50.897787 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A...
May 27 17:42:50.897799 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132...
May 27 17:42:50.897808 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr...
May 27 17:42:50.897817 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
May 27 17:42:50.897826 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes.
May 27 17:42:50.897835 systemd[1]: Reached target paths.target - Path Units.
May 27 17:42:50.897843 systemd[1]: Reached target slices.target - Slice Units.
May 27 17:42:50.897852 systemd[1]: Reached target swap.target - Swaps.
May 27 17:42:50.897860 systemd[1]: Reached target timers.target - Timer Units.
May 27 17:42:50.897873 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket.
May 27 17:42:50.897881 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket.
May 27 17:42:50.897890 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log).
May 27 17:42:50.897898 systemd[1]: Listening on systemd-journald.socket - Journal Sockets.
May 27 17:42:50.897907 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket.
May 27 17:42:50.897916 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket.
May 27 17:42:50.897925 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket.
May 27 17:42:50.897934 systemd[1]: Reached target sockets.target - Socket Units.
May 27 17:42:50.897945 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup...
May 27 17:42:50.897953 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes...
May 27 17:42:50.897962 systemd[1]: Finished network-cleanup.service - Network Cleanup.
May 27 17:42:50.897971 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply).
May 27 17:42:50.897980 systemd[1]: Starting systemd-fsck-usr.service...
May 27 17:42:50.897989 systemd[1]: Starting systemd-journald.service - Journal Service...
May 27 17:42:50.897998 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules...
May 27 17:42:50.898007 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
May 27 17:42:50.898015 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup.
May 27 17:42:50.898027 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes.
May 27 17:42:50.898035 systemd[1]: Finished systemd-fsck-usr.service.
May 27 17:42:50.898097 systemd-journald[219]: Collecting audit messages is disabled.
May 27 17:42:50.898123 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully...
May 27 17:42:50.898133 systemd-journald[219]: Journal started
May 27 17:42:50.898154 systemd-journald[219]: Runtime Journal (/run/log/journal/5584fcede1904795a5322be231bd9024) is 6M, max 48.2M, 42.2M free.
May 27 17:42:50.886858 systemd-modules-load[221]: Inserted module 'overlay'
May 27 17:42:50.900228 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup.
May 27 17:42:50.903681 systemd[1]: Started systemd-journald.service - Journal Service.
May 27 17:42:50.907814 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters...
May 27 17:42:50.913196 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories...
May 27 17:42:50.919104 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
May 27 17:42:50.921814 systemd-modules-load[221]: Inserted module 'br_netfilter'
May 27 17:42:50.922921 kernel: Bridge firewalling registered
May 27 17:42:50.927370 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully.
May 27 17:42:50.928224 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules.
May 27 17:42:50.931179 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables...
May 27 17:42:50.934180 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev...
May 27 17:42:50.943611 systemd-tmpfiles[239]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring.
May 27 17:42:50.948762 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables.
May 27 17:42:50.951232 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories.
May 27 17:42:50.954284 systemd[1]: Starting systemd-resolved.service - Network Name Resolution...
May 27 17:42:50.954963 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
May 27 17:42:50.955593 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters.
May 27 17:42:50.958561 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook...
May 27 17:42:50.987169 dracut-cmdline[261]: Using kernel command line parameters:  rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1   rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected verity.usrhash=daa3e2d55cc4a7ff0ec15aa9bb0c07df9999cb4e3041f3adad1b1101efdea101
May 27 17:42:51.005192 systemd-resolved[259]: Positive Trust Anchors:
May 27 17:42:51.005207 systemd-resolved[259]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
May 27 17:42:51.005237 systemd-resolved[259]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test
May 27 17:42:51.008699 systemd-resolved[259]: Defaulting to hostname 'linux'.
May 27 17:42:51.010228 systemd[1]: Started systemd-resolved.service - Network Name Resolution.
May 27 17:42:51.016564 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups.
May 27 17:42:51.111112 kernel: SCSI subsystem initialized
May 27 17:42:51.123106 kernel: Loading iSCSI transport class v2.0-870.
May 27 17:42:51.137111 kernel: iscsi: registered transport (tcp)
May 27 17:42:51.163112 kernel: iscsi: registered transport (qla4xxx)
May 27 17:42:51.163197 kernel: QLogic iSCSI HBA Driver
May 27 17:42:51.187540 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line...
May 27 17:42:51.219149 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line.
May 27 17:42:51.220758 systemd[1]: Reached target network-pre.target - Preparation for Network.
May 27 17:42:51.279304 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook.
May 27 17:42:51.281869 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook...
May 27 17:42:51.347130 kernel: raid6: avx2x4   gen() 27517 MB/s
May 27 17:42:51.364110 kernel: raid6: avx2x2   gen() 24052 MB/s
May 27 17:42:51.381484 kernel: raid6: avx2x1   gen() 18492 MB/s
May 27 17:42:51.381569 kernel: raid6: using algorithm avx2x4 gen() 27517 MB/s
May 27 17:42:51.399510 kernel: raid6: .... xor() 6486 MB/s, rmw enabled
May 27 17:42:51.399617 kernel: raid6: using avx2x2 recovery algorithm
May 27 17:42:51.424104 kernel: xor: automatically using best checksumming function   avx       
May 27 17:42:51.613332 kernel: Btrfs loaded, zoned=no, fsverity=no
May 27 17:42:51.623992 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook.
May 27 17:42:51.626876 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files...
May 27 17:42:51.660674 systemd-udevd[471]: Using default interface naming scheme 'v255'.
May 27 17:42:51.666699 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files.
May 27 17:42:51.671552 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook...
May 27 17:42:51.709739 dracut-pre-trigger[481]: rd.md=0: removing MD RAID activation
May 27 17:42:51.746802 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook.
May 27 17:42:51.749219 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices...
May 27 17:42:51.847248 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices.
May 27 17:42:51.851829 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook...
May 27 17:42:51.901105 kernel: cryptd: max_cpu_qlen set to 1000
May 27 17:42:51.913202 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues
May 27 17:42:51.920701 kernel: AES CTR mode by8 optimization enabled
May 27 17:42:51.920742 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB)
May 27 17:42:51.923450 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
May 27 17:42:51.923626 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup.
May 27 17:42:51.928109 kernel:  vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9
May 27 17:42:51.931075 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3
May 27 17:42:51.936071 kernel: libata version 3.00 loaded.
May 27 17:42:51.938281 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup...
May 27 17:42:51.946263 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
May 27 17:42:51.949205 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully.
May 27 17:42:51.963106 kernel: ahci 0000:00:1f.2: version 3.0
May 27 17:42:51.963378 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16
May 27 17:42:51.969085 kernel: ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode
May 27 17:42:51.969289 kernel: ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)
May 27 17:42:51.969440 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only 
May 27 17:42:51.975102 kernel: scsi host0: ahci
May 27 17:42:51.975472 kernel: scsi host1: ahci
May 27 17:42:51.977096 kernel: scsi host2: ahci
May 27 17:42:51.979273 kernel: scsi host3: ahci
May 27 17:42:51.979436 kernel: scsi host4: ahci
May 27 17:42:51.984754 kernel: scsi host5: ahci
May 27 17:42:51.986129 kernel: ata1: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040100 irq 34 lpm-pol 0
May 27 17:42:51.986147 kernel: ata2: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040180 irq 34 lpm-pol 0
May 27 17:42:51.986161 kernel: ata3: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040200 irq 34 lpm-pol 0
May 27 17:42:51.986174 kernel: ata4: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040280 irq 34 lpm-pol 0
May 27 17:42:51.986194 kernel: ata5: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040300 irq 34 lpm-pol 0
May 27 17:42:51.986208 kernel: ata6: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040380 irq 34 lpm-pol 0
May 27 17:42:51.991900 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT.
May 27 17:42:52.010487 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM.
May 27 17:42:52.029286 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM.
May 27 17:42:52.038708 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A.
May 27 17:42:52.041238 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132.
May 27 17:42:52.044733 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary...
May 27 17:42:52.046890 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
May 27 17:42:52.046945 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup.
May 27 17:42:52.050329 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup...
May 27 17:42:52.068857 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
May 27 17:42:52.071364 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully.
May 27 17:42:52.091831 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup.
May 27 17:42:52.116118 kernel:  vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9
May 27 17:42:52.294457 kernel: ata5: SATA link down (SStatus 0 SControl 300)
May 27 17:42:52.294538 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
May 27 17:42:52.296359 kernel: ata4: SATA link down (SStatus 0 SControl 300)
May 27 17:42:52.296444 kernel: ata1: SATA link down (SStatus 0 SControl 300)
May 27 17:42:52.296455 kernel: ata2: SATA link down (SStatus 0 SControl 300)
May 27 17:42:52.298093 kernel: ata6: SATA link down (SStatus 0 SControl 300)
May 27 17:42:52.298120 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
May 27 17:42:52.299133 kernel: ata3.00: applying bridge limits
May 27 17:42:52.300087 kernel: ata3.00: configured for UDMA/100
May 27 17:42:52.300120 kernel: scsi 2:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM     2.5+ PQ: 0 ANSI: 5
May 27 17:42:52.358140 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
May 27 17:42:52.358549 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20
May 27 17:42:52.380088 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0
May 27 17:42:52.776938 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook.
May 27 17:42:52.779724 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems.
May 27 17:42:52.782531 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes.
May 27 17:42:52.784670 systemd[1]: Reached target remote-fs.target - Remote File Systems.
May 27 17:42:52.786571 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook...
May 27 17:42:52.824491 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook.
May 27 17:42:53.128093 kernel:  vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9
May 27 17:42:53.128353 disk-uuid[640]: The operation has completed successfully.
May 27 17:42:53.165170 systemd[1]: disk-uuid.service: Deactivated successfully.
May 27 17:42:53.165321 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary.
May 27 17:42:53.201511 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr...
May 27 17:42:53.224311 sh[669]: Success
May 27 17:42:53.246409 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.
May 27 17:42:53.246490 kernel: device-mapper: uevent: version 1.0.3
May 27 17:42:53.247731 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev
May 27 17:42:53.259104 kernel: device-mapper: verity: sha256 using shash "sha256-ni"
May 27 17:42:53.296678 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr.
May 27 17:42:53.301485 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr...
May 27 17:42:53.327643 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr.
May 27 17:42:53.336121 kernel: BTRFS info: 'norecovery' is for compatibility only, recommended to use 'rescue=nologreplay'
May 27 17:42:53.336191 kernel: BTRFS: device fsid 7caef027-0915-4c01-a3d5-28eff70f7ebd devid 1 transid 39 /dev/mapper/usr (253:0) scanned by mount (681)
May 27 17:42:53.337786 kernel: BTRFS info (device dm-0): first mount of filesystem 7caef027-0915-4c01-a3d5-28eff70f7ebd
May 27 17:42:53.337823 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm
May 27 17:42:53.338884 kernel: BTRFS info (device dm-0): using free-space-tree
May 27 17:42:53.344756 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr.
May 27 17:42:53.345787 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System.
May 27 17:42:53.347780 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met.
May 27 17:42:53.348711 systemd[1]: Starting ignition-setup.service - Ignition (setup)...
May 27 17:42:53.350591 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline...
May 27 17:42:53.379090 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 (254:6) scanned by mount (714)
May 27 17:42:53.379156 kernel: BTRFS info (device vda6): first mount of filesystem be856aed-e34b-4b7b-be8a-0716b27db212
May 27 17:42:53.381682 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm
May 27 17:42:53.381719 kernel: BTRFS info (device vda6): using free-space-tree
May 27 17:42:53.390089 kernel: BTRFS info (device vda6): last unmount of filesystem be856aed-e34b-4b7b-be8a-0716b27db212
May 27 17:42:53.391183 systemd[1]: Finished ignition-setup.service - Ignition (setup).
May 27 17:42:53.394469 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)...
May 27 17:42:53.487354 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline.
May 27 17:42:53.490965 systemd[1]: Starting systemd-networkd.service - Network Configuration...
May 27 17:42:53.512464 ignition[761]: Ignition 2.21.0
May 27 17:42:53.512475 ignition[761]: Stage: fetch-offline
May 27 17:42:53.512503 ignition[761]: no configs at "/usr/lib/ignition/base.d"
May 27 17:42:53.512511 ignition[761]: no config dir at "/usr/lib/ignition/base.platform.d/qemu"
May 27 17:42:53.512601 ignition[761]: parsed url from cmdline: ""
May 27 17:42:53.512605 ignition[761]: no config URL provided
May 27 17:42:53.512609 ignition[761]: reading system config file "/usr/lib/ignition/user.ign"
May 27 17:42:53.512617 ignition[761]: no config at "/usr/lib/ignition/user.ign"
May 27 17:42:53.512638 ignition[761]: op(1): [started]  loading QEMU firmware config module
May 27 17:42:53.512643 ignition[761]: op(1): executing: "modprobe" "qemu_fw_cfg"
May 27 17:42:53.522754 ignition[761]: op(1): [finished] loading QEMU firmware config module
May 27 17:42:53.524271 ignition[761]: parsing config with SHA512: 002d54a76629b253e9c712ce77fbd0bfc68b08c9e352b36728d29635748d6ad8b336b3bbbe97ed74d4ffb2beedb82f0894b2d621fa310bd52662645860a24f1d
May 27 17:42:53.533985 unknown[761]: fetched base config from "system"
May 27 17:42:53.534747 unknown[761]: fetched user config from "qemu"
May 27 17:42:53.534896 ignition[761]: fetch-offline: fetch-offline passed
May 27 17:42:53.535013 ignition[761]: Ignition finished successfully
May 27 17:42:53.537549 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline).
May 27 17:42:53.547253 systemd-networkd[856]: lo: Link UP
May 27 17:42:53.547266 systemd-networkd[856]: lo: Gained carrier
May 27 17:42:53.548944 systemd-networkd[856]: Enumeration completed
May 27 17:42:53.549134 systemd[1]: Started systemd-networkd.service - Network Configuration.
May 27 17:42:53.549419 systemd-networkd[856]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
May 27 17:42:53.549424 systemd-networkd[856]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
May 27 17:42:53.550888 systemd-networkd[856]: eth0: Link UP
May 27 17:42:53.550892 systemd-networkd[856]: eth0: Gained carrier
May 27 17:42:53.550902 systemd-networkd[856]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
May 27 17:42:53.551154 systemd[1]: Reached target network.target - Network.
May 27 17:42:53.551666 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json).
May 27 17:42:53.554393 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)...
May 27 17:42:53.652143 systemd-networkd[856]: eth0: DHCPv4 address 10.0.0.73/16, gateway 10.0.0.1 acquired from 10.0.0.1
May 27 17:42:53.679601 ignition[862]: Ignition 2.21.0
May 27 17:42:53.679620 ignition[862]: Stage: kargs
May 27 17:42:53.679788 ignition[862]: no configs at "/usr/lib/ignition/base.d"
May 27 17:42:53.679803 ignition[862]: no config dir at "/usr/lib/ignition/base.platform.d/qemu"
May 27 17:42:53.680921 ignition[862]: kargs: kargs passed
May 27 17:42:53.681013 ignition[862]: Ignition finished successfully
May 27 17:42:53.686841 systemd[1]: Finished ignition-kargs.service - Ignition (kargs).
May 27 17:42:53.689332 systemd[1]: Starting ignition-disks.service - Ignition (disks)...
May 27 17:42:53.744748 ignition[871]: Ignition 2.21.0
May 27 17:42:53.744763 ignition[871]: Stage: disks
May 27 17:42:53.745704 ignition[871]: no configs at "/usr/lib/ignition/base.d"
May 27 17:42:53.745715 ignition[871]: no config dir at "/usr/lib/ignition/base.platform.d/qemu"
May 27 17:42:53.746542 ignition[871]: disks: disks passed
May 27 17:42:53.746610 ignition[871]: Ignition finished successfully
May 27 17:42:53.753080 systemd[1]: Finished ignition-disks.service - Ignition (disks).
May 27 17:42:53.755536 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device.
May 27 17:42:53.756038 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems.
May 27 17:42:53.756363 systemd[1]: Reached target local-fs.target - Local File Systems.
May 27 17:42:53.756713 systemd[1]: Reached target sysinit.target - System Initialization.
May 27 17:42:53.757094 systemd[1]: Reached target basic.target - Basic System.
May 27 17:42:53.766330 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT...
May 27 17:42:53.821972 systemd-fsck[881]: ROOT: clean, 15/553520 files, 52789/553472 blocks
May 27 17:42:53.829405 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT.
May 27 17:42:53.831066 systemd[1]: Mounting sysroot.mount - /sysroot...
May 27 17:42:53.982079 kernel: EXT4-fs (vda9): mounted filesystem bf93e767-f532-4480-b210-a196f7ac181e r/w with ordered data mode. Quota mode: none.
May 27 17:42:53.982313 systemd[1]: Mounted sysroot.mount - /sysroot.
May 27 17:42:53.983220 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System.
May 27 17:42:53.985932 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem...
May 27 17:42:53.987904 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr...
May 27 17:42:53.989104 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met.
May 27 17:42:53.989151 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot).
May 27 17:42:53.989177 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup.
May 27 17:42:54.003157 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr.
May 27 17:42:54.005568 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup...
May 27 17:42:54.009902 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 (254:6) scanned by mount (889)
May 27 17:42:54.009925 kernel: BTRFS info (device vda6): first mount of filesystem be856aed-e34b-4b7b-be8a-0716b27db212
May 27 17:42:54.009937 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm
May 27 17:42:54.011401 kernel: BTRFS info (device vda6): using free-space-tree
May 27 17:42:54.015029 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem.
May 27 17:42:54.043446 initrd-setup-root[913]: cut: /sysroot/etc/passwd: No such file or directory
May 27 17:42:54.047951 initrd-setup-root[920]: cut: /sysroot/etc/group: No such file or directory
May 27 17:42:54.052338 initrd-setup-root[927]: cut: /sysroot/etc/shadow: No such file or directory
May 27 17:42:54.056243 initrd-setup-root[934]: cut: /sysroot/etc/gshadow: No such file or directory
May 27 17:42:54.161133 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup.
May 27 17:42:54.163485 systemd[1]: Starting ignition-mount.service - Ignition (mount)...
May 27 17:42:54.164592 systemd[1]: Starting sysroot-boot.service - /sysroot/boot...
May 27 17:42:54.186127 kernel: BTRFS info (device vda6): last unmount of filesystem be856aed-e34b-4b7b-be8a-0716b27db212
May 27 17:42:54.206241 systemd[1]: Finished sysroot-boot.service - /sysroot/boot.
May 27 17:42:54.240107 ignition[1003]: INFO     : Ignition 2.21.0
May 27 17:42:54.240107 ignition[1003]: INFO     : Stage: mount
May 27 17:42:54.241933 ignition[1003]: INFO     : no configs at "/usr/lib/ignition/base.d"
May 27 17:42:54.241933 ignition[1003]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/qemu"
May 27 17:42:54.241933 ignition[1003]: INFO     : mount: mount passed
May 27 17:42:54.241933 ignition[1003]: INFO     : Ignition finished successfully
May 27 17:42:54.244193 systemd[1]: Finished ignition-mount.service - Ignition (mount).
May 27 17:42:54.246111 systemd[1]: Starting ignition-files.service - Ignition (files)...
May 27 17:42:54.334820 systemd[1]: sysroot-oem.mount: Deactivated successfully.
May 27 17:42:54.336759 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem...
May 27 17:42:54.364132 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 (254:6) scanned by mount (1015)
May 27 17:42:54.364204 kernel: BTRFS info (device vda6): first mount of filesystem be856aed-e34b-4b7b-be8a-0716b27db212
May 27 17:42:54.364219 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm
May 27 17:42:54.365810 kernel: BTRFS info (device vda6): using free-space-tree
May 27 17:42:54.369857 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem.
May 27 17:42:54.401983 ignition[1032]: INFO     : Ignition 2.21.0
May 27 17:42:54.401983 ignition[1032]: INFO     : Stage: files
May 27 17:42:54.404131 ignition[1032]: INFO     : no configs at "/usr/lib/ignition/base.d"
May 27 17:42:54.404131 ignition[1032]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/qemu"
May 27 17:42:54.404131 ignition[1032]: DEBUG    : files: compiled without relabeling support, skipping
May 27 17:42:54.408529 ignition[1032]: INFO     : files: ensureUsers: op(1): [started]  creating or modifying user "core"
May 27 17:42:54.408529 ignition[1032]: DEBUG    : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core"
May 27 17:42:54.413808 ignition[1032]: INFO     : files: ensureUsers: op(1): [finished] creating or modifying user "core"
May 27 17:42:54.415640 ignition[1032]: INFO     : files: ensureUsers: op(2): [started]  adding ssh keys to user "core"
May 27 17:42:54.417622 unknown[1032]: wrote ssh authorized keys file for user: core
May 27 17:42:54.418873 ignition[1032]: INFO     : files: ensureUsers: op(2): [finished] adding ssh keys to user "core"
May 27 17:42:54.425000 ignition[1032]: INFO     : files: createFilesystemsFiles: createFiles: op(3): [started]  writing file "/sysroot/etc/flatcar/update.conf"
May 27 17:42:54.427260 ignition[1032]: INFO     : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf"
May 27 17:42:54.427260 ignition[1032]: INFO     : files: op(4): [started]  processing unit "etcd-member.service"
May 27 17:42:54.427260 ignition[1032]: INFO     : files: op(4): op(5): [started]  writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf"
May 27 17:42:54.433180 ignition[1032]: INFO     : files: op(4): op(5): [finished] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf"
May 27 17:42:54.433180 ignition[1032]: INFO     : files: op(4): [finished] processing unit "etcd-member.service"
May 27 17:42:54.433180 ignition[1032]: INFO     : files: op(6): [started]  processing unit "coreos-metadata.service"
May 27 17:42:54.433180 ignition[1032]: INFO     : files: op(6): op(7): [started]  writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service"
May 27 17:42:54.440620 ignition[1032]: INFO     : files: op(6): op(7): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service"
May 27 17:42:54.440620 ignition[1032]: INFO     : files: op(6): [finished] processing unit "coreos-metadata.service"
May 27 17:42:54.440620 ignition[1032]: INFO     : files: op(8): [started]  setting preset to disabled for "coreos-metadata.service"
May 27 17:42:54.453132 ignition[1032]: INFO     : files: op(8): op(9): [started]  removing enablement symlink(s) for "coreos-metadata.service"
May 27 17:42:54.459831 ignition[1032]: INFO     : files: op(8): op(9): [finished] removing enablement symlink(s) for "coreos-metadata.service"
May 27 17:42:54.461796 ignition[1032]: INFO     : files: op(8): [finished] setting preset to disabled for "coreos-metadata.service"
May 27 17:42:54.461796 ignition[1032]: INFO     : files: op(a): [started]  setting preset to enabled for "etcd-member.service"
May 27 17:42:54.461796 ignition[1032]: INFO     : files: op(a): [finished] setting preset to enabled for "etcd-member.service"
May 27 17:42:54.466160 ignition[1032]: INFO     : files: createResultFile: createFiles: op(b): [started]  writing file "/sysroot/etc/.ignition-result.json"
May 27 17:42:54.466160 ignition[1032]: INFO     : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json"
May 27 17:42:54.466160 ignition[1032]: INFO     : files: files passed
May 27 17:42:54.466160 ignition[1032]: INFO     : Ignition finished successfully
May 27 17:42:54.465674 systemd[1]: Finished ignition-files.service - Ignition (files).
May 27 17:42:54.470587 systemd[1]: Starting ignition-quench.service - Ignition (record completion)...
May 27 17:42:54.475021 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion...
May 27 17:42:54.487965 systemd[1]: ignition-quench.service: Deactivated successfully.
May 27 17:42:54.488130 systemd[1]: Finished ignition-quench.service - Ignition (record completion).
May 27 17:42:54.491869 initrd-setup-root-after-ignition[1061]: grep: /sysroot/oem/oem-release: No such file or directory
May 27 17:42:54.494816 initrd-setup-root-after-ignition[1063]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory
May 27 17:42:54.494816 initrd-setup-root-after-ignition[1063]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory
May 27 17:42:54.498315 initrd-setup-root-after-ignition[1067]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory
May 27 17:42:54.497883 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion.
May 27 17:42:54.498676 systemd[1]: Reached target ignition-complete.target - Ignition Complete.
May 27 17:42:54.503421 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root...
May 27 17:42:54.577894 systemd[1]: initrd-parse-etc.service: Deactivated successfully.
May 27 17:42:54.578077 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root.
May 27 17:42:54.585415 systemd[1]: Reached target initrd-fs.target - Initrd File Systems.
May 27 17:42:54.589795 systemd[1]: Reached target initrd.target - Initrd Default Target.
May 27 17:42:54.590374 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met.
May 27 17:42:54.591416 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook...
May 27 17:42:54.627961 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook.
May 27 17:42:54.633040 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons...
May 27 17:42:54.668497 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups.
May 27 17:42:54.669665 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes.
May 27 17:42:54.670218 systemd[1]: Stopped target timers.target - Timer Units.
May 27 17:42:54.671147 systemd[1]: dracut-pre-pivot.service: Deactivated successfully.
May 27 17:42:54.671333 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook.
May 27 17:42:54.672508 systemd[1]: Stopped target initrd.target - Initrd Default Target.
May 27 17:42:54.685133 systemd[1]: Stopped target basic.target - Basic System.
May 27 17:42:54.687224 systemd-networkd[856]: eth0: Gained IPv6LL
May 27 17:42:54.688665 systemd[1]: Stopped target ignition-complete.target - Ignition Complete.
May 27 17:42:54.691710 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup.
May 27 17:42:54.696803 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device.
May 27 17:42:54.697522 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System.
May 27 17:42:54.697843 systemd[1]: Stopped target remote-fs.target - Remote File Systems.
May 27 17:42:54.698358 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems.
May 27 17:42:54.698879 systemd[1]: Stopped target sysinit.target - System Initialization.
May 27 17:42:54.699377 systemd[1]: Stopped target local-fs.target - Local File Systems.
May 27 17:42:54.699713 systemd[1]: Stopped target swap.target - Swaps.
May 27 17:42:54.700026 systemd[1]: dracut-pre-mount.service: Deactivated successfully.
May 27 17:42:54.700163 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook.
May 27 17:42:54.700884 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes.
May 27 17:42:54.701386 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
May 27 17:42:54.701679 systemd[1]: clevis-luks-askpass.path: Deactivated successfully.
May 27 17:42:54.701798 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
May 27 17:42:54.719633 systemd[1]: dracut-initqueue.service: Deactivated successfully.
May 27 17:42:54.719798 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook.
May 27 17:42:54.724614 systemd[1]: ignition-fetch-offline.service: Deactivated successfully.
May 27 17:42:54.724745 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline).
May 27 17:42:54.726667 systemd[1]: Stopped target paths.target - Path Units.
May 27 17:42:54.730677 systemd[1]: systemd-ask-password-console.path: Deactivated successfully.
May 27 17:42:54.734141 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
May 27 17:42:54.736931 systemd[1]: Stopped target slices.target - Slice Units.
May 27 17:42:54.737478 systemd[1]: Stopped target sockets.target - Socket Units.
May 27 17:42:54.737809 systemd[1]: iscsid.socket: Deactivated successfully.
May 27 17:42:54.737903 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket.
May 27 17:42:54.742341 systemd[1]: iscsiuio.socket: Deactivated successfully.
May 27 17:42:54.743302 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket.
May 27 17:42:54.744547 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully.
May 27 17:42:54.744710 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion.
May 27 17:42:54.746594 systemd[1]: ignition-files.service: Deactivated successfully.
May 27 17:42:54.746701 systemd[1]: Stopped ignition-files.service - Ignition (files).
May 27 17:42:54.751987 systemd[1]: Stopping ignition-mount.service - Ignition (mount)...
May 27 17:42:54.753573 systemd[1]: kmod-static-nodes.service: Deactivated successfully.
May 27 17:42:54.753721 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes.
May 27 17:42:54.755270 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot...
May 27 17:42:54.757515 systemd[1]: systemd-udev-trigger.service: Deactivated successfully.
May 27 17:42:54.757690 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices.
May 27 17:42:54.760997 systemd[1]: dracut-pre-trigger.service: Deactivated successfully.
May 27 17:42:54.761167 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook.
May 27 17:42:54.765786 systemd[1]: initrd-cleanup.service: Deactivated successfully.
May 27 17:42:54.772342 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons.
May 27 17:42:54.789368 ignition[1087]: INFO     : Ignition 2.21.0
May 27 17:42:54.789368 ignition[1087]: INFO     : Stage: umount
May 27 17:42:54.792834 ignition[1087]: INFO     : no configs at "/usr/lib/ignition/base.d"
May 27 17:42:54.792834 ignition[1087]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/qemu"
May 27 17:42:54.795814 ignition[1087]: INFO     : umount: umount passed
May 27 17:42:54.796789 ignition[1087]: INFO     : Ignition finished successfully
May 27 17:42:54.797526 systemd[1]: sysroot-boot.mount: Deactivated successfully.
May 27 17:42:54.800785 systemd[1]: ignition-mount.service: Deactivated successfully.
May 27 17:42:54.800911 systemd[1]: Stopped ignition-mount.service - Ignition (mount).
May 27 17:42:54.801904 systemd[1]: Stopped target network.target - Network.
May 27 17:42:54.803686 systemd[1]: ignition-disks.service: Deactivated successfully.
May 27 17:42:54.803758 systemd[1]: Stopped ignition-disks.service - Ignition (disks).
May 27 17:42:54.804132 systemd[1]: ignition-kargs.service: Deactivated successfully.
May 27 17:42:54.804190 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs).
May 27 17:42:54.804729 systemd[1]: ignition-setup.service: Deactivated successfully.
May 27 17:42:54.804781 systemd[1]: Stopped ignition-setup.service - Ignition (setup).
May 27 17:42:54.805314 systemd[1]: ignition-setup-pre.service: Deactivated successfully.
May 27 17:42:54.805358 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup.
May 27 17:42:54.805821 systemd[1]: Stopping systemd-networkd.service - Network Configuration...
May 27 17:42:54.814403 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution...
May 27 17:42:54.815502 systemd[1]: sysroot-boot.service: Deactivated successfully.
May 27 17:42:54.815630 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot.
May 27 17:42:54.818937 systemd[1]: initrd-setup-root.service: Deactivated successfully.
May 27 17:42:54.819029 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup.
May 27 17:42:54.824120 systemd[1]: systemd-networkd.service: Deactivated successfully.
May 27 17:42:54.824254 systemd[1]: Stopped systemd-networkd.service - Network Configuration.
May 27 17:42:54.828595 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully.
May 27 17:42:54.828793 systemd[1]: Stopped target network-pre.target - Preparation for Network.
May 27 17:42:54.829464 systemd[1]: systemd-networkd.socket: Deactivated successfully.
May 27 17:42:54.829500 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket.
May 27 17:42:54.835802 systemd[1]: Stopping network-cleanup.service - Network Cleanup...
May 27 17:42:54.838352 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully.
May 27 17:42:54.838411 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline.
May 27 17:42:54.838758 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files...
May 27 17:42:54.839524 systemd[1]: systemd-resolved.service: Deactivated successfully.
May 27 17:42:54.847600 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution.
May 27 17:42:54.852174 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully.
May 27 17:42:54.852770 systemd[1]: systemd-sysctl.service: Deactivated successfully.
May 27 17:42:54.852847 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables.
May 27 17:42:54.855800 systemd[1]: systemd-modules-load.service: Deactivated successfully.
May 27 17:42:54.855850 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules.
May 27 17:42:54.856446 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.
May 27 17:42:54.856488 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories.
May 27 17:42:54.865854 systemd[1]: systemd-udevd.service: Deactivated successfully.
May 27 17:42:54.866044 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files.
May 27 17:42:54.866703 systemd[1]: systemd-udevd-control.socket: Deactivated successfully.
May 27 17:42:54.866747 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket.
May 27 17:42:54.869401 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully.
May 27 17:42:54.869435 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket.
May 27 17:42:54.869709 systemd[1]: dracut-pre-udev.service: Deactivated successfully.
May 27 17:42:54.869750 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook.
May 27 17:42:54.876652 systemd[1]: dracut-cmdline.service: Deactivated successfully.
May 27 17:42:54.876707 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook.
May 27 17:42:54.879382 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully.
May 27 17:42:54.879454 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters.
May 27 17:42:54.884574 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database...
May 27 17:42:54.885028 systemd[1]: systemd-network-generator.service: Deactivated successfully.
May 27 17:42:54.885103 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line.
May 27 17:42:54.888948 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.
May 27 17:42:54.889016 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
May 27 17:42:54.892470 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
May 27 17:42:54.892532 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup.
May 27 17:42:54.896700 systemd[1]: network-cleanup.service: Deactivated successfully.
May 27 17:42:54.896817 systemd[1]: Stopped network-cleanup.service - Network Cleanup.
May 27 17:42:54.897635 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.
May 27 17:42:54.897740 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database.
May 27 17:42:54.901363 systemd[1]: Reached target initrd-switch-root.target - Switch Root.
May 27 17:42:54.903322 systemd[1]: Starting initrd-switch-root.service - Switch Root...
May 27 17:42:54.930326 systemd[1]: Switching root.
May 27 17:42:54.959775 systemd-journald[219]: Journal stopped
May 27 17:42:55.901169 systemd-journald[219]: Received SIGTERM from PID 1 (systemd).
May 27 17:42:55.901238 kernel: SELinux:  policy capability network_peer_controls=1
May 27 17:42:55.901270 kernel: SELinux:  policy capability open_perms=1
May 27 17:42:55.901282 kernel: SELinux:  policy capability extended_socket_class=1
May 27 17:42:55.901293 kernel: SELinux:  policy capability always_check_network=0
May 27 17:42:55.901304 kernel: SELinux:  policy capability cgroup_seclabel=1
May 27 17:42:55.901316 kernel: SELinux:  policy capability nnp_nosuid_transition=1
May 27 17:42:55.901327 kernel: SELinux:  policy capability genfs_seclabel_symlinks=0
May 27 17:42:55.901339 kernel: SELinux:  policy capability ioctl_skip_cloexec=0
May 27 17:42:55.901352 kernel: SELinux:  policy capability userspace_initial_context=0
May 27 17:42:55.901365 kernel: audit: type=1403 audit(1748367775.054:2): auid=4294967295 ses=4294967295 lsm=selinux res=1
May 27 17:42:55.901383 systemd[1]: Successfully loaded SELinux policy in 46.592ms.
May 27 17:42:55.901398 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 12.298ms.
May 27 17:42:55.901411 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE)
May 27 17:42:55.901426 systemd[1]: Detected virtualization kvm.
May 27 17:42:55.901438 systemd[1]: Detected architecture x86-64.
May 27 17:42:55.901451 systemd[1]: Detected first boot.
May 27 17:42:55.901463 systemd[1]: Initializing machine ID from VM UUID.
May 27 17:42:55.901475 zram_generator::config[1133]: No configuration found.
May 27 17:42:55.901493 kernel: Guest personality initialized and is inactive
May 27 17:42:55.901505 kernel: VMCI host device registered (name=vmci, major=10, minor=125)
May 27 17:42:55.901516 kernel: Initialized host personality
May 27 17:42:55.901528 kernel: NET: Registered PF_VSOCK protocol family
May 27 17:42:55.901539 systemd[1]: Populated /etc with preset unit settings.
May 27 17:42:55.901554 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully.
May 27 17:42:55.901566 systemd[1]: initrd-switch-root.service: Deactivated successfully.
May 27 17:42:55.901578 systemd[1]: Stopped initrd-switch-root.service - Switch Root.
May 27 17:42:55.901590 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.
May 27 17:42:55.901602 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config.
May 27 17:42:55.901614 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run.
May 27 17:42:55.901627 systemd[1]: Created slice system-getty.slice - Slice /system/getty.
May 27 17:42:55.901639 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe.
May 27 17:42:55.901651 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty.
May 27 17:42:55.901665 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit.
May 27 17:42:55.901678 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck.
May 27 17:42:55.901689 systemd[1]: Created slice user.slice - User and Session Slice.
May 27 17:42:55.901702 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
May 27 17:42:55.901714 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
May 27 17:42:55.901726 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch.
May 27 17:42:55.901737 systemd[1]: Set up automount boot.automount - Boot partition Automount Point.
May 27 17:42:55.901750 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point.
May 27 17:42:55.901764 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM...
May 27 17:42:55.901776 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0...
May 27 17:42:55.901788 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
May 27 17:42:55.901800 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes.
May 27 17:42:55.901811 systemd[1]: Stopped target initrd-switch-root.target - Switch Root.
May 27 17:42:55.901823 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems.
May 27 17:42:55.901836 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System.
May 27 17:42:55.901847 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes.
May 27 17:42:55.901862 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes.
May 27 17:42:55.901873 systemd[1]: Reached target remote-fs.target - Remote File Systems.
May 27 17:42:55.901886 systemd[1]: Reached target slices.target - Slice Units.
May 27 17:42:55.901898 systemd[1]: Reached target swap.target - Swaps.
May 27 17:42:55.901910 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes.
May 27 17:42:55.901922 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket.
May 27 17:42:55.901944 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption.
May 27 17:42:55.901956 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket.
May 27 17:42:55.901968 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket.
May 27 17:42:55.901980 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket.
May 27 17:42:55.901995 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket.
May 27 17:42:55.902007 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System...
May 27 17:42:55.902019 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System...
May 27 17:42:55.902031 systemd[1]: Mounting media.mount - External Media Directory...
May 27 17:42:55.902043 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen).
May 27 17:42:55.902071 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System...
May 27 17:42:55.902083 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System...
May 27 17:42:55.902095 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp...
May 27 17:42:55.902110 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw).
May 27 17:42:55.902121 systemd[1]: Reached target machines.target - Containers.
May 27 17:42:55.902133 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files...
May 27 17:42:55.902145 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
May 27 17:42:55.902163 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes...
May 27 17:42:55.902176 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs...
May 27 17:42:55.902188 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
May 27 17:42:55.902200 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm...
May 27 17:42:55.902212 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
May 27 17:42:55.902237 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse...
May 27 17:42:55.902253 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
May 27 17:42:55.902268 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf).
May 27 17:42:55.902284 systemd[1]: systemd-fsck-root.service: Deactivated successfully.
May 27 17:42:55.902299 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device.
May 27 17:42:55.902314 systemd[1]: systemd-fsck-usr.service: Deactivated successfully.
May 27 17:42:55.902329 systemd[1]: Stopped systemd-fsck-usr.service.
May 27 17:42:55.902345 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67).
May 27 17:42:55.902363 kernel: fuse: init (API version 7.41)
May 27 17:42:55.902378 systemd[1]: Starting systemd-journald.service - Journal Service...
May 27 17:42:55.902394 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules...
May 27 17:42:55.902409 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line...
May 27 17:42:55.902425 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems...
May 27 17:42:55.902440 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials...
May 27 17:42:55.902455 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices...
May 27 17:42:55.902474 systemd[1]: verity-setup.service: Deactivated successfully.
May 27 17:42:55.902489 systemd[1]: Stopped verity-setup.service.
May 27 17:42:55.902507 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen).
May 27 17:42:55.902523 kernel: ACPI: bus type drm_connector registered
May 27 17:42:55.902538 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System.
May 27 17:42:55.902554 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System.
May 27 17:42:55.902573 kernel: loop: module loaded
May 27 17:42:55.902588 systemd[1]: Mounted media.mount - External Media Directory.
May 27 17:42:55.902603 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System.
May 27 17:42:55.902645 systemd-journald[1208]: Collecting audit messages is disabled.
May 27 17:42:55.902668 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System.
May 27 17:42:55.902684 systemd-journald[1208]: Journal started
May 27 17:42:55.902707 systemd-journald[1208]: Runtime Journal (/run/log/journal/5584fcede1904795a5322be231bd9024) is 6M, max 48.2M, 42.2M free.
May 27 17:42:55.615298 systemd[1]: Queued start job for default target multi-user.target.
May 27 17:42:55.635253 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6.
May 27 17:42:55.635764 systemd[1]: systemd-journald.service: Deactivated successfully.
May 27 17:42:55.906228 systemd[1]: Started systemd-journald.service - Journal Service.
May 27 17:42:55.907657 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp.
May 27 17:42:55.909264 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files.
May 27 17:42:55.911074 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes.
May 27 17:42:55.912762 systemd[1]: modprobe@configfs.service: Deactivated successfully.
May 27 17:42:55.913005 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs.
May 27 17:42:55.914739 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
May 27 17:42:55.914965 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
May 27 17:42:55.916531 systemd[1]: modprobe@drm.service: Deactivated successfully.
May 27 17:42:55.916743 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm.
May 27 17:42:55.918264 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
May 27 17:42:55.918470 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
May 27 17:42:55.920391 systemd[1]: modprobe@fuse.service: Deactivated successfully.
May 27 17:42:55.920605 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse.
May 27 17:42:55.922220 systemd[1]: modprobe@loop.service: Deactivated successfully.
May 27 17:42:55.922431 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
May 27 17:42:55.924122 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules.
May 27 17:42:55.925718 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line.
May 27 17:42:55.927439 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems.
May 27 17:42:55.929236 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials.
May 27 17:42:55.945242 systemd[1]: Reached target network-pre.target - Preparation for Network.
May 27 17:42:55.948165 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System...
May 27 17:42:55.951155 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System...
May 27 17:42:55.952459 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).
May 27 17:42:55.952503 systemd[1]: Reached target local-fs.target - Local File Systems.
May 27 17:42:55.954834 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management.
May 27 17:42:55.962875 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache...
May 27 17:42:55.964341 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
May 27 17:42:55.967583 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database...
May 27 17:42:55.974334 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage...
May 27 17:42:55.976467 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
May 27 17:42:55.982608 systemd-journald[1208]: Time spent on flushing to /var/log/journal/5584fcede1904795a5322be231bd9024 is 16.020ms for 1003 entries.
May 27 17:42:55.982608 systemd-journald[1208]: System Journal (/var/log/journal/5584fcede1904795a5322be231bd9024) is 8M, max 195.6M, 187.6M free.
May 27 17:42:56.021267 systemd-journald[1208]: Received client request to flush runtime journal.
May 27 17:42:55.979978 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed...
May 27 17:42:55.981434 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met.
May 27 17:42:55.983859 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables...
May 27 17:42:55.988896 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/...
May 27 17:42:55.999814 systemd[1]: Starting systemd-sysusers.service - Create System Users...
May 27 17:42:56.006149 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices.
May 27 17:42:56.010122 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System.
May 27 17:42:56.011872 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System.
May 27 17:42:56.023145 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed.
May 27 17:42:56.024091 kernel: loop0: detected capacity change from 0 to 113872
May 27 17:42:56.027906 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage.
May 27 17:42:56.032686 systemd[1]: Reached target first-boot-complete.target - First Boot Complete.
May 27 17:42:56.036236 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk...
May 27 17:42:56.038173 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables.
May 27 17:42:56.048102 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher
May 27 17:42:56.058699 systemd[1]: Finished systemd-sysusers.service - Create System Users.
May 27 17:42:56.063368 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev...
May 27 17:42:56.070082 kernel: loop1: detected capacity change from 0 to 146240
May 27 17:42:56.075795 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk.
May 27 17:42:56.105095 kernel: loop2: detected capacity change from 0 to 113872
May 27 17:42:56.105286 systemd-tmpfiles[1268]: ACLs are not supported, ignoring.
May 27 17:42:56.105310 systemd-tmpfiles[1268]: ACLs are not supported, ignoring.
May 27 17:42:56.113570 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
May 27 17:42:56.123084 kernel: loop3: detected capacity change from 0 to 146240
May 27 17:42:56.136218 (sd-merge)[1272]: Using extensions 'containerd-flatcar', 'docker-flatcar'.
May 27 17:42:56.136719 (sd-merge)[1272]: Merged extensions into '/usr'.
May 27 17:42:56.141081 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/.
May 27 17:42:56.144549 systemd[1]: Starting ensure-sysext.service...
May 27 17:42:56.147486 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories...
May 27 17:42:56.167436 systemd[1]: Reload requested from client PID 1275 ('systemctl') (unit ensure-sysext.service)...
May 27 17:42:56.167458 systemd[1]: Reloading...
May 27 17:42:56.175168 systemd-tmpfiles[1276]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring.
May 27 17:42:56.175564 systemd-tmpfiles[1276]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring.
May 27 17:42:56.175862 systemd-tmpfiles[1276]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring.
May 27 17:42:56.176164 systemd-tmpfiles[1276]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring.
May 27 17:42:56.177145 systemd-tmpfiles[1276]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring.
May 27 17:42:56.177403 systemd-tmpfiles[1276]: ACLs are not supported, ignoring.
May 27 17:42:56.177469 systemd-tmpfiles[1276]: ACLs are not supported, ignoring.
May 27 17:42:56.186106 systemd-tmpfiles[1276]: Detected autofs mount point /boot during canonicalization of boot.
May 27 17:42:56.186125 systemd-tmpfiles[1276]: Skipping /boot
May 27 17:42:56.203310 systemd-tmpfiles[1276]: Detected autofs mount point /boot during canonicalization of boot.
May 27 17:42:56.203326 systemd-tmpfiles[1276]: Skipping /boot
May 27 17:42:56.225081 zram_generator::config[1304]: No configuration found.
May 27 17:42:56.342559 ldconfig[1247]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start.
May 27 17:42:56.343658 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly.
May 27 17:42:56.426752 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
May 27 17:42:56.426864 systemd[1]: Reloading finished in 258 ms.
May 27 17:42:56.459861 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache.
May 27 17:42:56.479849 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories.
May 27 17:42:56.488950 systemd[1]: Starting audit-rules.service - Load Audit Rules...
May 27 17:42:56.491190 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs...
May 27 17:42:56.494946 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog...
May 27 17:42:56.507269 systemd[1]: Starting systemd-resolved.service - Network Name Resolution...
May 27 17:42:56.512229 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP...
May 27 17:42:56.518002 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
May 27 17:42:56.519434 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
May 27 17:42:56.523621 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
May 27 17:42:56.528870 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
May 27 17:42:56.530367 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
May 27 17:42:56.530577 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67).
May 27 17:42:56.536815 systemd[1]: Starting systemd-userdbd.service - User Database Manager...
May 27 17:42:56.539821 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog.
May 27 17:42:56.543434 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
May 27 17:42:56.548476 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
May 27 17:42:56.550862 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
May 27 17:42:56.551165 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
May 27 17:42:56.553627 systemd[1]: modprobe@loop.service: Deactivated successfully.
May 27 17:42:56.553997 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
May 27 17:42:56.567977 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
May 27 17:42:56.570285 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
May 27 17:42:56.572560 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
May 27 17:42:56.576489 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
May 27 17:42:56.577707 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
May 27 17:42:56.577949 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67).
May 27 17:42:56.582524 augenrules[1378]: No rules
May 27 17:42:56.585148 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP.
May 27 17:42:56.587721 systemd[1]: audit-rules.service: Deactivated successfully.
May 27 17:42:56.588080 systemd[1]: Finished audit-rules.service - Load Audit Rules.
May 27 17:42:56.589855 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
May 27 17:42:56.590104 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
May 27 17:42:56.591832 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
May 27 17:42:56.592049 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
May 27 17:42:56.593738 systemd[1]: modprobe@loop.service: Deactivated successfully.
May 27 17:42:56.593955 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
May 27 17:42:56.604681 systemd[1]: Starting audit-rules.service - Load Audit Rules...
May 27 17:42:56.606303 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
May 27 17:42:56.607368 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
May 27 17:42:56.611227 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm...
May 27 17:42:56.616336 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
May 27 17:42:56.619343 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
May 27 17:42:56.620502 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
May 27 17:42:56.620611 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67).
May 27 17:42:56.622195 systemd[1]: Started systemd-userdbd.service - User Database Manager.
May 27 17:42:56.624049 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs.
May 27 17:42:56.626786 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
May 27 17:42:56.626999 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
May 27 17:42:56.628535 systemd[1]: modprobe@drm.service: Deactivated successfully.
May 27 17:42:56.628738 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm.
May 27 17:42:56.635368 systemd[1]: Finished ensure-sysext.service.
May 27 17:42:56.638373 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
May 27 17:42:56.638601 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
May 27 17:42:56.640260 systemd[1]: modprobe@loop.service: Deactivated successfully.
May 27 17:42:56.640483 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
May 27 17:42:56.645506 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen).
May 27 17:42:56.646152 augenrules[1388]: /sbin/augenrules: No change
May 27 17:42:56.645576 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
May 27 17:42:56.645644 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met.
May 27 17:42:56.648573 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization...
May 27 17:42:56.649793 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt).
May 27 17:42:56.649818 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen).
May 27 17:42:56.653886 augenrules[1421]: No rules
May 27 17:42:56.655387 systemd[1]: audit-rules.service: Deactivated successfully.
May 27 17:42:56.657268 systemd[1]: Finished audit-rules.service - Load Audit Rules.
May 27 17:42:56.666647 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database.
May 27 17:42:56.671749 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files...
May 27 17:42:56.674317 systemd[1]: Starting systemd-update-done.service - Update is Completed...
May 27 17:42:56.696786 systemd[1]: Finished systemd-update-done.service - Update is Completed.
May 27 17:42:56.698324 systemd-resolved[1346]: Positive Trust Anchors:
May 27 17:42:56.698333 systemd-resolved[1346]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
May 27 17:42:56.698367 systemd-resolved[1346]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test
May 27 17:42:56.702128 systemd-resolved[1346]: Defaulting to hostname 'linux'.
May 27 17:42:56.704043 systemd[1]: Started systemd-resolved.service - Network Name Resolution.
May 27 17:42:56.705491 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups.
May 27 17:42:56.714442 systemd-udevd[1428]: Using default interface naming scheme 'v255'.
May 27 17:42:56.733139 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files.
May 27 17:42:56.738011 systemd[1]: Starting systemd-networkd.service - Network Configuration...
May 27 17:42:56.739163 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization.
May 27 17:42:56.741198 systemd[1]: Reached target sysinit.target - System Initialization.
May 27 17:42:56.742385 systemd[1]: Started motdgen.path - Watch for update engine configuration changes.
May 27 17:42:56.743835 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data.
May 27 17:42:56.746507 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer.
May 27 17:42:56.747706 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories.
May 27 17:42:56.748982 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate).
May 27 17:42:56.749010 systemd[1]: Reached target paths.target - Path Units.
May 27 17:42:56.749945 systemd[1]: Reached target time-set.target - System Time Set.
May 27 17:42:56.751126 systemd[1]: Started logrotate.timer - Daily rotation of log files.
May 27 17:42:56.752590 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information..
May 27 17:42:56.753854 systemd[1]: Reached target timers.target - Timer Units.
May 27 17:42:56.756079 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket.
May 27 17:42:56.759230 systemd[1]: Starting docker.socket - Docker Socket for the API...
May 27 17:42:56.765600 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).
May 27 17:42:56.767090 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK).
May 27 17:42:56.769123 systemd[1]: Reached target ssh-access.target - SSH Access Available.
May 27 17:42:56.776938 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket.
May 27 17:42:56.778505 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket.
May 27 17:42:56.781871 systemd[1]: Listening on docker.socket - Docker Socket for the API.
May 27 17:42:56.785640 systemd[1]: Reached target sockets.target - Socket Units.
May 27 17:42:56.788109 systemd[1]: Reached target basic.target - Basic System.
May 27 17:42:56.789181 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met.
May 27 17:42:56.789208 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met.
May 27 17:42:56.791982 systemd[1]: Starting dbus.service - D-Bus System Message Bus...
May 27 17:42:56.794356 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown...
May 27 17:42:56.797100 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit...
May 27 17:42:56.799196 systemd[1]: Starting extend-filesystems.service - Extend Filesystems...
May 27 17:42:56.799622 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment).
May 27 17:42:56.801258 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh...
May 27 17:42:56.807957 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd...
May 27 17:42:56.810504 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline...
May 27 17:42:56.813841 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys...
May 27 17:42:56.814340 google_oslogin_nss_cache[1466]: oslogin_cache_refresh[1466]: Refreshing passwd entry cache
May 27 17:42:56.814295 oslogin_cache_refresh[1466]: Refreshing passwd entry cache
May 27 17:42:56.819275 oslogin_cache_refresh[1466]: Failure getting users, quitting
May 27 17:42:56.820293 systemd[1]: Starting systemd-logind.service - User Login Management...
May 27 17:42:56.823938 google_oslogin_nss_cache[1466]: oslogin_cache_refresh[1466]: Failure getting users, quitting
May 27 17:42:56.823938 google_oslogin_nss_cache[1466]: oslogin_cache_refresh[1466]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak.
May 27 17:42:56.823938 google_oslogin_nss_cache[1466]: oslogin_cache_refresh[1466]: Refreshing group entry cache
May 27 17:42:56.823938 google_oslogin_nss_cache[1466]: oslogin_cache_refresh[1466]: Failure getting groups, quitting
May 27 17:42:56.823938 google_oslogin_nss_cache[1466]: oslogin_cache_refresh[1466]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak.
May 27 17:42:56.819289 oslogin_cache_refresh[1466]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak.
May 27 17:42:56.822280 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0).
May 27 17:42:56.819327 oslogin_cache_refresh[1466]: Refreshing group entry cache
May 27 17:42:56.822793 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details.
May 27 17:42:56.819767 oslogin_cache_refresh[1466]: Failure getting groups, quitting
May 27 17:42:56.819776 oslogin_cache_refresh[1466]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak.
May 27 17:42:56.826401 systemd[1]: Starting update-engine.service - Update Engine...
May 27 17:42:56.828544 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition...
May 27 17:42:56.831368 jq[1464]: false
May 27 17:42:56.838301 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown.
May 27 17:42:56.845174 jq[1479]: true
May 27 17:42:56.853734 update_engine[1478]: I20250527 17:42:56.853662  1478 main.cc:92] Flatcar Update Engine starting
May 27 17:42:56.853743 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'.
May 27 17:42:56.854046 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped.
May 27 17:42:56.855152 systemd[1]: google-oslogin-cache.service: Deactivated successfully.
May 27 17:42:56.855404 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh.
May 27 17:42:56.856942 systemd[1]: motdgen.service: Deactivated successfully.
May 27 17:42:56.857207 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd.
May 27 17:42:56.859063 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully.
May 27 17:42:56.859299 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline.
May 27 17:42:56.873580 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped.
May 27 17:42:56.886687 jq[1484]: true
May 27 17:42:56.899551 extend-filesystems[1465]: Found loop2
May 27 17:42:56.902672 extend-filesystems[1465]: Found loop3
May 27 17:42:56.902672 extend-filesystems[1465]: Found sr0
May 27 17:42:56.902672 extend-filesystems[1465]: Found vda
May 27 17:42:56.902672 extend-filesystems[1465]: Found vda1
May 27 17:42:56.902672 extend-filesystems[1465]: Found vda2
May 27 17:42:56.902672 extend-filesystems[1465]: Found vda3
May 27 17:42:56.902672 extend-filesystems[1465]: Found usr
May 27 17:42:56.902672 extend-filesystems[1465]: Found vda4
May 27 17:42:56.902672 extend-filesystems[1465]: Found vda6
May 27 17:42:56.902672 extend-filesystems[1465]: Found vda7
May 27 17:42:56.902672 extend-filesystems[1465]: Found vda9
May 27 17:42:56.901292 systemd[1]: extend-filesystems.service: Deactivated successfully.
May 27 17:42:56.913375 dbus-daemon[1462]: [system] SELinux support is enabled
May 27 17:42:56.901630 systemd[1]: Finished extend-filesystems.service - Extend Filesystems.
May 27 17:42:56.929438 update_engine[1478]: I20250527 17:42:56.928133  1478 update_check_scheduler.cc:74] Next update check in 2m3s
May 27 17:42:56.913506 systemd[1]: Started dbus.service - D-Bus System Message Bus.
May 27 17:42:56.924606 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml).
May 27 17:42:56.924626 systemd[1]: Reached target system-config.target - Load system-provided cloud configs.
May 27 17:42:56.926319 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url).
May 27 17:42:56.926333 systemd[1]: Reached target user-config.target - Load user-provided cloud configs.
May 27 17:42:56.929765 systemd[1]: Started update-engine.service - Update Engine.
May 27 17:42:56.933638 systemd[1]: Started locksmithd.service - Cluster reboot manager.
May 27 17:42:56.990278 bash[1516]: Updated "/home/core/.ssh/authorized_keys"
May 27 17:42:56.989901 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition.
May 27 17:42:57.000276 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met.
May 27 17:42:57.010091 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input4
May 27 17:42:57.015077 kernel: ACPI: button: Power Button [PWRF]
May 27 17:42:57.012183 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM.
May 27 17:42:57.023174 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM...
May 27 17:42:57.036304 kernel: i801_smbus 0000:00:1f.3: Enabling SMBus device
May 27 17:42:57.036566 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt
May 27 17:42:57.037882 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD
May 27 17:42:57.042447 systemd-logind[1472]: New seat seat0.
May 27 17:42:57.048398 systemd[1]: Started systemd-logind.service - User Login Management.
May 27 17:42:57.052021 locksmithd[1511]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot"
May 27 17:42:57.052287 kernel: mousedev: PS/2 mouse device common for all mice
May 27 17:42:57.061790 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM.
May 27 17:42:57.065486 systemd-networkd[1437]: lo: Link UP
May 27 17:42:57.065499 systemd-networkd[1437]: lo: Gained carrier
May 27 17:42:57.067246 systemd-networkd[1437]: Enumeration completed
May 27 17:42:57.067745 systemd-networkd[1437]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
May 27 17:42:57.067757 systemd-networkd[1437]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
May 27 17:42:57.068183 systemd[1]: Started systemd-networkd.service - Network Configuration.
May 27 17:42:57.069981 systemd[1]: Reached target network.target - Network.
May 27 17:42:57.070515 systemd-networkd[1437]: eth0: Link UP
May 27 17:42:57.070754 systemd-networkd[1437]: eth0: Gained carrier
May 27 17:42:57.070772 systemd-networkd[1437]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
May 27 17:42:57.074219 systemd[1]: Starting containerd.service - containerd container runtime...
May 27 17:42:57.077433 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd...
May 27 17:42:57.082345 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured...
May 27 17:42:57.093144 systemd-networkd[1437]: eth0: DHCPv4 address 10.0.0.73/16, gateway 10.0.0.1 acquired from 10.0.0.1
May 27 17:42:57.094630 systemd-timesyncd[1418]: Network configuration changed, trying to establish connection.
May 27 17:42:58.556061 systemd-timesyncd[1418]: Contacted time server 10.0.0.1:123 (10.0.0.1).
May 27 17:42:58.556102 systemd-timesyncd[1418]: Initial clock synchronization to Tue 2025-05-27 17:42:58.555939 UTC.
May 27 17:42:58.563339 systemd-resolved[1346]: Clock change detected. Flushing caches.
May 27 17:42:58.581013 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd.
May 27 17:42:58.593667 (ntainerd)[1548]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR
May 27 17:42:58.647546 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
May 27 17:42:58.683459 systemd-logind[1472]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)
May 27 17:42:58.689005 systemd-logind[1472]: Watching system buttons on /dev/input/event2 (Power Button)
May 27 17:42:58.690960 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
May 27 17:42:58.692377 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup.
May 27 17:42:58.703400 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
May 27 17:42:58.942861 kernel: kvm_amd: TSC scaling supported
May 27 17:42:58.942949 kernel: kvm_amd: Nested Virtualization enabled
May 27 17:42:58.942963 kernel: kvm_amd: Nested Paging enabled
May 27 17:42:58.942975 kernel: kvm_amd: LBR virtualization supported
May 27 17:42:58.942987 kernel: kvm_amd: Virtual VMLOAD VMSAVE supported
May 27 17:42:58.944521 kernel: kvm_amd: Virtual GIF supported
May 27 17:42:58.981352 kernel: EDAC MC: Ver: 3.0.0
May 27 17:42:58.998975 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup.
May 27 17:42:59.040749 containerd[1548]: time="2025-05-27T17:42:59Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8
May 27 17:42:59.041504 containerd[1548]: time="2025-05-27T17:42:59.041469287Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4
May 27 17:42:59.052970 containerd[1548]: time="2025-05-27T17:42:59.052910934Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="18.495µs"
May 27 17:42:59.052970 containerd[1548]: time="2025-05-27T17:42:59.052955117Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1
May 27 17:42:59.052970 containerd[1548]: time="2025-05-27T17:42:59.052979132Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1
May 27 17:42:59.053256 containerd[1548]: time="2025-05-27T17:42:59.053232758Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1
May 27 17:42:59.053280 containerd[1548]: time="2025-05-27T17:42:59.053264187Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1
May 27 17:42:59.053331 containerd[1548]: time="2025-05-27T17:42:59.053296027Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1
May 27 17:42:59.053415 containerd[1548]: time="2025-05-27T17:42:59.053389552Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1
May 27 17:42:59.053415 containerd[1548]: time="2025-05-27T17:42:59.053408137Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1
May 27 17:42:59.053791 containerd[1548]: time="2025-05-27T17:42:59.053758043Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1
May 27 17:42:59.053791 containerd[1548]: time="2025-05-27T17:42:59.053779573Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1
May 27 17:42:59.053833 containerd[1548]: time="2025-05-27T17:42:59.053793429Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1
May 27 17:42:59.053833 containerd[1548]: time="2025-05-27T17:42:59.053804881Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1
May 27 17:42:59.053946 containerd[1548]: time="2025-05-27T17:42:59.053919626Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1
May 27 17:42:59.054222 containerd[1548]: time="2025-05-27T17:42:59.054190213Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1
May 27 17:42:59.054273 containerd[1548]: time="2025-05-27T17:42:59.054244605Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1
May 27 17:42:59.054273 containerd[1548]: time="2025-05-27T17:42:59.054264914Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1
May 27 17:42:59.054340 containerd[1548]: time="2025-05-27T17:42:59.054288588Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1
May 27 17:42:59.054768 containerd[1548]: time="2025-05-27T17:42:59.054740195Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1
May 27 17:42:59.054869 containerd[1548]: time="2025-05-27T17:42:59.054839191Z" level=info msg="metadata content store policy set" policy=shared
May 27 17:42:59.066118 sshd_keygen[1482]: ssh-keygen: generating new host keys: RSA ECDSA ED25519
May 27 17:42:59.094694 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys.
May 27 17:42:59.097017 containerd[1548]: time="2025-05-27T17:42:59.096959351Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1
May 27 17:42:59.097099 containerd[1548]: time="2025-05-27T17:42:59.097040754Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1
May 27 17:42:59.097099 containerd[1548]: time="2025-05-27T17:42:59.097056774Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1
May 27 17:42:59.097099 containerd[1548]: time="2025-05-27T17:42:59.097068746Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1
May 27 17:42:59.097099 containerd[1548]: time="2025-05-27T17:42:59.097083193Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1
May 27 17:42:59.097099 containerd[1548]: time="2025-05-27T17:42:59.097094404Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1
May 27 17:42:59.097200 containerd[1548]: time="2025-05-27T17:42:59.097111216Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1
May 27 17:42:59.097200 containerd[1548]: time="2025-05-27T17:42:59.097123559Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1
May 27 17:42:59.097200 containerd[1548]: time="2025-05-27T17:42:59.097173633Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1
May 27 17:42:59.097200 containerd[1548]: time="2025-05-27T17:42:59.097185725Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1
May 27 17:42:59.097200 containerd[1548]: time="2025-05-27T17:42:59.097196486Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1
May 27 17:42:59.097332 containerd[1548]: time="2025-05-27T17:42:59.097212155Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2
May 27 17:42:59.097460 containerd[1548]: time="2025-05-27T17:42:59.097428551Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1
May 27 17:42:59.097493 containerd[1548]: time="2025-05-27T17:42:59.097460471Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1
May 27 17:42:59.097493 containerd[1548]: time="2025-05-27T17:42:59.097483083Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1
May 27 17:42:59.097530 containerd[1548]: time="2025-05-27T17:42:59.097496829Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1
May 27 17:42:59.097530 containerd[1548]: time="2025-05-27T17:42:59.097517898Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1
May 27 17:42:59.097574 containerd[1548]: time="2025-05-27T17:42:59.097531414Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1
May 27 17:42:59.097574 containerd[1548]: time="2025-05-27T17:42:59.097546903Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1
May 27 17:42:59.097574 containerd[1548]: time="2025-05-27T17:42:59.097559967Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1
May 27 17:42:59.097634 containerd[1548]: time="2025-05-27T17:42:59.097573553Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1
May 27 17:42:59.097634 containerd[1548]: time="2025-05-27T17:42:59.097587870Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1
May 27 17:42:59.097634 containerd[1548]: time="2025-05-27T17:42:59.097609069Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1
May 27 17:42:59.097728 containerd[1548]: time="2025-05-27T17:42:59.097705290Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\""
May 27 17:42:59.097751 containerd[1548]: time="2025-05-27T17:42:59.097734905Z" level=info msg="Start snapshots syncer"
May 27 17:42:59.097800 containerd[1548]: time="2025-05-27T17:42:59.097779218Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1
May 27 17:42:59.097951 systemd[1]: Starting issuegen.service - Generate /run/issue...
May 27 17:42:59.098163 containerd[1548]: time="2025-05-27T17:42:59.098100942Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}"
May 27 17:42:59.098288 containerd[1548]: time="2025-05-27T17:42:59.098187204Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1
May 27 17:42:59.098288 containerd[1548]: time="2025-05-27T17:42:59.098279146Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1
May 27 17:42:59.098635 containerd[1548]: time="2025-05-27T17:42:59.098427775Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1
May 27 17:42:59.098635 containerd[1548]: time="2025-05-27T17:42:59.098466307Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1
May 27 17:42:59.098635 containerd[1548]: time="2025-05-27T17:42:59.098480694Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1
May 27 17:42:59.098635 containerd[1548]: time="2025-05-27T17:42:59.098497696Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1
May 27 17:42:59.098635 containerd[1548]: time="2025-05-27T17:42:59.098517363Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1
May 27 17:42:59.098635 containerd[1548]: time="2025-05-27T17:42:59.098532661Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1
May 27 17:42:59.098635 containerd[1548]: time="2025-05-27T17:42:59.098547028Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1
May 27 17:42:59.098635 containerd[1548]: time="2025-05-27T17:42:59.098586703Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1
May 27 17:42:59.098635 containerd[1548]: time="2025-05-27T17:42:59.098603955Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1
May 27 17:42:59.098635 containerd[1548]: time="2025-05-27T17:42:59.098618933Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1
May 27 17:42:59.099342 containerd[1548]: time="2025-05-27T17:42:59.099295161Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1
May 27 17:42:59.099391 containerd[1548]: time="2025-05-27T17:42:59.099348902Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1
May 27 17:42:59.099391 containerd[1548]: time="2025-05-27T17:42:59.099361746Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1
May 27 17:42:59.099391 containerd[1548]: time="2025-05-27T17:42:59.099375211Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1
May 27 17:42:59.099391 containerd[1548]: time="2025-05-27T17:42:59.099385851Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1
May 27 17:42:59.099475 containerd[1548]: time="2025-05-27T17:42:59.099399868Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1
May 27 17:42:59.099475 containerd[1548]: time="2025-05-27T17:42:59.099413694Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1
May 27 17:42:59.099475 containerd[1548]: time="2025-05-27T17:42:59.099436266Z" level=info msg="runtime interface created"
May 27 17:42:59.099475 containerd[1548]: time="2025-05-27T17:42:59.099443500Z" level=info msg="created NRI interface"
May 27 17:42:59.099475 containerd[1548]: time="2025-05-27T17:42:59.099453689Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1
May 27 17:42:59.099475 containerd[1548]: time="2025-05-27T17:42:59.099466002Z" level=info msg="Connect containerd service"
May 27 17:42:59.099584 containerd[1548]: time="2025-05-27T17:42:59.099495928Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this"
May 27 17:42:59.100440 containerd[1548]: time="2025-05-27T17:42:59.100413148Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config"
May 27 17:42:59.137490 systemd[1]: issuegen.service: Deactivated successfully.
May 27 17:42:59.137778 systemd[1]: Finished issuegen.service - Generate /run/issue.
May 27 17:42:59.140848 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions...
May 27 17:42:59.182819 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions.
May 27 17:42:59.202891 systemd[1]: Started getty@tty1.service - Getty on tty1.
May 27 17:42:59.219675 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0.
May 27 17:42:59.221000 systemd[1]: Reached target getty.target - Login Prompts.
May 27 17:42:59.347978 containerd[1548]: time="2025-05-27T17:42:59.347897660Z" level=info msg="Start subscribing containerd event"
May 27 17:42:59.347978 containerd[1548]: time="2025-05-27T17:42:59.347976217Z" level=info msg="Start recovering state"
May 27 17:42:59.348185 containerd[1548]: time="2025-05-27T17:42:59.348127631Z" level=info msg="Start event monitor"
May 27 17:42:59.348185 containerd[1548]: time="2025-05-27T17:42:59.348145765Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc
May 27 17:42:59.348272 containerd[1548]: time="2025-05-27T17:42:59.348168518Z" level=info msg="Start cni network conf syncer for default"
May 27 17:42:59.348332 containerd[1548]: time="2025-05-27T17:42:59.348276099Z" level=info msg="Start streaming server"
May 27 17:42:59.348332 containerd[1548]: time="2025-05-27T17:42:59.348243188Z" level=info msg=serving... address=/run/containerd/containerd.sock
May 27 17:42:59.348385 containerd[1548]: time="2025-05-27T17:42:59.348293652Z" level=info msg="Registered namespace \"k8s.io\" with NRI"
May 27 17:42:59.348385 containerd[1548]: time="2025-05-27T17:42:59.348348706Z" level=info msg="runtime interface starting up..."
May 27 17:42:59.348385 containerd[1548]: time="2025-05-27T17:42:59.348357342Z" level=info msg="starting plugins..."
May 27 17:42:59.348470 containerd[1548]: time="2025-05-27T17:42:59.348386166Z" level=info msg="Synchronizing NRI (plugin) with current runtime state"
May 27 17:42:59.348607 containerd[1548]: time="2025-05-27T17:42:59.348574679Z" level=info msg="containerd successfully booted in 0.308354s"
May 27 17:42:59.348734 systemd[1]: Started containerd.service - containerd container runtime.
May 27 17:42:59.923567 systemd-networkd[1437]: eth0: Gained IPv6LL
May 27 17:42:59.926837 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured.
May 27 17:42:59.928928 systemd[1]: Reached target network-online.target - Network is Online.
May 27 17:42:59.931755 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent...
May 27 17:42:59.934114 systemd[1]: Starting nvidia.service - NVIDIA Configure Service...
May 27 17:42:59.978846 systemd[1]: Finished nvidia.service - NVIDIA Configure Service.
May 27 17:42:59.995191 systemd[1]: coreos-metadata.service: Deactivated successfully.
May 27 17:42:59.995479 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent.
May 27 17:42:59.998427 systemd[1]: Starting etcd-member.service - etcd (System Application Container)...
May 27 17:42:59.999607 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met.
May 27 17:43:00.017084 (-wrapper)[1614]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS
May 27 17:43:00.293710 systemd[1]: Starting docker.service - Docker Application Container Engine...
May 27 17:43:00.309645 (dockerd)[1626]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU
May 27 17:43:00.911243 dockerd[1626]: time="2025-05-27T17:43:00.911141009Z" level=info msg="Starting up"
May 27 17:43:00.914258 dockerd[1626]: time="2025-05-27T17:43:00.914199785Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider"
May 27 17:43:01.395002 dockerd[1626]: time="2025-05-27T17:43:01.394880533Z" level=info msg="Loading containers: start."
May 27 17:43:01.406364 kernel: Initializing XFRM netlink socket
May 27 17:43:01.671486 systemd-networkd[1437]: docker0: Link UP
May 27 17:43:01.677801 dockerd[1626]: time="2025-05-27T17:43:01.677750786Z" level=info msg="Loading containers: done."
May 27 17:43:01.693361 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck1873678443-merged.mount: Deactivated successfully.
May 27 17:43:01.695296 dockerd[1626]: time="2025-05-27T17:43:01.695241117Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2
May 27 17:43:01.695385 dockerd[1626]: time="2025-05-27T17:43:01.695365740Z" level=info msg="Docker daemon" commit=bbd0a17ccc67e48d4a69393287b7fcc4f0578683 containerd-snapshotter=false storage-driver=overlay2 version=28.0.1
May 27 17:43:01.695528 dockerd[1626]: time="2025-05-27T17:43:01.695502667Z" level=info msg="Initializing buildkit"
May 27 17:43:01.727200 dockerd[1626]: time="2025-05-27T17:43:01.727146441Z" level=info msg="Completed buildkit initialization"
May 27 17:43:01.733202 dockerd[1626]: time="2025-05-27T17:43:01.733159307Z" level=info msg="Daemon has completed initialization"
May 27 17:43:01.733349 dockerd[1626]: time="2025-05-27T17:43:01.733234838Z" level=info msg="API listen on /run/docker.sock"
May 27 17:43:01.733496 systemd[1]: Started docker.service - Docker Application Container Engine.
May 27 17:43:01.735997 etcd-wrapper[1618]: Error response from daemon: No such container: etcd-member
May 27 17:43:01.789935 etcd-wrapper[1817]: Error response from daemon: No such container: etcd-member
May 27 17:43:01.822446 etcd-wrapper[1838]: Unable to find image 'quay.io/coreos/etcd:v3.5.16' locally
May 27 17:43:03.024027 etcd-wrapper[1838]: v3.5.16: Pulling from coreos/etcd
May 27 17:43:03.257672 etcd-wrapper[1838]: 804c8aba2cc6: Pulling fs layer
May 27 17:43:03.257672 etcd-wrapper[1838]: 2ae710cd8bfe: Pulling fs layer
May 27 17:43:03.257672 etcd-wrapper[1838]: d462aa345367: Pulling fs layer
May 27 17:43:03.257672 etcd-wrapper[1838]: 0f8b424aa0b9: Pulling fs layer
May 27 17:43:03.257672 etcd-wrapper[1838]: d557676654e5: Pulling fs layer
May 27 17:43:03.257672 etcd-wrapper[1838]: c8022d07192e: Pulling fs layer
May 27 17:43:03.257672 etcd-wrapper[1838]: d858cbc252ad: Pulling fs layer
May 27 17:43:03.257672 etcd-wrapper[1838]: 1069fc2daed1: Pulling fs layer
May 27 17:43:03.257672 etcd-wrapper[1838]: b40161cd83fc: Pulling fs layer
May 27 17:43:03.257672 etcd-wrapper[1838]: 5318d93a3a65: Pulling fs layer
May 27 17:43:03.258000 etcd-wrapper[1838]: 307c1adadb60: Pulling fs layer
May 27 17:43:03.258000 etcd-wrapper[1838]: fbb01d9e9dc9: Pulling fs layer
May 27 17:43:03.258000 etcd-wrapper[1838]: fbfea02ac3cf: Pulling fs layer
May 27 17:43:03.258000 etcd-wrapper[1838]: 8c26e4bf18e2: Pulling fs layer
May 27 17:43:03.258000 etcd-wrapper[1838]: 1e59a65f8816: Pulling fs layer
May 27 17:43:03.258000 etcd-wrapper[1838]: ffbd4ca5f0bd: Pulling fs layer
May 27 17:43:03.258000 etcd-wrapper[1838]: d557676654e5: Waiting
May 27 17:43:03.258000 etcd-wrapper[1838]: c8022d07192e: Waiting
May 27 17:43:03.258000 etcd-wrapper[1838]: d858cbc252ad: Waiting
May 27 17:43:03.258000 etcd-wrapper[1838]: 0f8b424aa0b9: Waiting
May 27 17:43:03.258000 etcd-wrapper[1838]: 1069fc2daed1: Waiting
May 27 17:43:03.258000 etcd-wrapper[1838]: b40161cd83fc: Waiting
May 27 17:43:03.258000 etcd-wrapper[1838]: fbb01d9e9dc9: Waiting
May 27 17:43:03.258000 etcd-wrapper[1838]: 5318d93a3a65: Waiting
May 27 17:43:03.258000 etcd-wrapper[1838]: fbfea02ac3cf: Waiting
May 27 17:43:03.258000 etcd-wrapper[1838]: 307c1adadb60: Waiting
May 27 17:43:03.258000 etcd-wrapper[1838]: 8c26e4bf18e2: Waiting
May 27 17:43:03.258000 etcd-wrapper[1838]: 1e59a65f8816: Waiting
May 27 17:43:03.258000 etcd-wrapper[1838]: ffbd4ca5f0bd: Waiting
May 27 17:43:03.492668 etcd-wrapper[1838]: 2ae710cd8bfe: Verifying Checksum
May 27 17:43:03.492668 etcd-wrapper[1838]: 2ae710cd8bfe: Download complete
May 27 17:43:03.497908 etcd-wrapper[1838]: 804c8aba2cc6: Download complete
May 27 17:43:03.532198 etcd-wrapper[1838]: 804c8aba2cc6: Pull complete
May 27 17:43:03.549969 systemd[1]: var-lib-docker-overlay2-517dd423e055eda92eb3f79f7ab97e80659d37b4d8f64be7621f8a2a9749285b-merged.mount: Deactivated successfully.
May 27 17:43:03.567746 etcd-wrapper[1838]: 2ae710cd8bfe: Pull complete
May 27 17:43:03.720072 etcd-wrapper[1838]: d557676654e5: Verifying Checksum
May 27 17:43:03.720072 etcd-wrapper[1838]: d557676654e5: Download complete
May 27 17:43:03.720490 etcd-wrapper[1838]: 0f8b424aa0b9: Verifying Checksum
May 27 17:43:03.720490 etcd-wrapper[1838]: 0f8b424aa0b9: Download complete
May 27 17:43:03.809937 etcd-wrapper[1838]: d462aa345367: Verifying Checksum
May 27 17:43:03.809937 etcd-wrapper[1838]: d462aa345367: Download complete
May 27 17:43:03.958120 etcd-wrapper[1838]: d858cbc252ad: Verifying Checksum
May 27 17:43:03.958120 etcd-wrapper[1838]: d858cbc252ad: Download complete
May 27 17:43:03.976533 etcd-wrapper[1838]: c8022d07192e: Verifying Checksum
May 27 17:43:03.976533 etcd-wrapper[1838]: c8022d07192e: Download complete
May 27 17:43:04.022220 etcd-wrapper[1838]: 1069fc2daed1: Download complete
May 27 17:43:04.235317 etcd-wrapper[1838]: b40161cd83fc: Verifying Checksum
May 27 17:43:04.235317 etcd-wrapper[1838]: b40161cd83fc: Download complete
May 27 17:43:04.312745 etcd-wrapper[1838]: 307c1adadb60: Verifying Checksum
May 27 17:43:04.312745 etcd-wrapper[1838]: 307c1adadb60: Download complete
May 27 17:43:04.468060 etcd-wrapper[1838]: d462aa345367: Pull complete
May 27 17:43:04.502232 etcd-wrapper[1838]: 0f8b424aa0b9: Pull complete
May 27 17:43:04.519802 etcd-wrapper[1838]: fbb01d9e9dc9: Verifying Checksum
May 27 17:43:04.519802 etcd-wrapper[1838]: fbb01d9e9dc9: Download complete
May 27 17:43:04.545289 systemd[1]: var-lib-docker-overlay2-535e5402fe00fb113490f765f241190ee65657055d1f269a00235d7ecc460acd-merged.mount: Deactivated successfully.
May 27 17:43:04.557192 etcd-wrapper[1838]: d557676654e5: Pull complete
May 27 17:43:04.571066 systemd[1]: var-lib-docker-overlay2-b2e9de95bde85ec4464fdc270db312336904e7cfa9112421d5071b22b8c2c7c2-merged.mount: Deactivated successfully.
May 27 17:43:04.582142 etcd-wrapper[1838]: c8022d07192e: Pull complete
May 27 17:43:04.596030 systemd[1]: var-lib-docker-overlay2-7be14ea5736f3849a7fd7548e9e63042854982d5bf047aaa90bc5bef7a4b4bb9-merged.mount: Deactivated successfully.
May 27 17:43:04.604758 etcd-wrapper[1838]: d858cbc252ad: Pull complete
May 27 17:43:04.630783 etcd-wrapper[1838]: 1069fc2daed1: Pull complete
May 27 17:43:04.654799 etcd-wrapper[1838]: b40161cd83fc: Pull complete
May 27 17:43:04.704958 etcd-wrapper[1838]: fbfea02ac3cf: Verifying Checksum
May 27 17:43:04.704958 etcd-wrapper[1838]: fbfea02ac3cf: Download complete
May 27 17:43:04.884750 etcd-wrapper[1838]: 8c26e4bf18e2: Verifying Checksum
May 27 17:43:04.884750 etcd-wrapper[1838]: 8c26e4bf18e2: Download complete
May 27 17:43:04.928790 etcd-wrapper[1838]: 1e59a65f8816: Download complete
May 27 17:43:05.170716 etcd-wrapper[1838]: 5318d93a3a65: Verifying Checksum
May 27 17:43:05.170716 etcd-wrapper[1838]: 5318d93a3a65: Download complete
May 27 17:43:05.180109 etcd-wrapper[1838]: ffbd4ca5f0bd: Verifying Checksum
May 27 17:43:05.180109 etcd-wrapper[1838]: ffbd4ca5f0bd: Download complete
May 27 17:43:05.285425 etcd-wrapper[1838]: 5318d93a3a65: Pull complete
May 27 17:43:05.326118 etcd-wrapper[1838]: 307c1adadb60: Pull complete
May 27 17:43:05.553501 systemd[1]: var-lib-docker-overlay2-3e7a7424b4edab16541387c148a3dfc6d4af6a5d1bc00d873da7767556036c5f-merged.mount: Deactivated successfully.
May 27 17:43:05.652932 etcd-wrapper[1838]: fbb01d9e9dc9: Pull complete
May 27 17:43:05.755381 systemd[1]: var-lib-docker-overlay2-cb0d0adf8d1985dbe88820a535ab02c71548744370a9560c98af572d912a1d7f-merged.mount: Deactivated successfully.
May 27 17:43:06.088919 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd.
May 27 17:43:06.090284 systemd[1]: Started sshd@0-10.0.0.73:22-10.0.0.1:59990.service - OpenSSH per-connection server daemon (10.0.0.1:59990).
May 27 17:43:06.107633 etcd-wrapper[1838]: fbfea02ac3cf: Pull complete
May 27 17:43:06.171212 sshd[1921]: Accepted publickey for core from 10.0.0.1 port 59990 ssh2: RSA SHA256:Sdu3hc/K/GsFAoVLDVpDFh1tw++0J1r4WpeL8cs/qlY
May 27 17:43:06.173479 sshd-session[1921]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 27 17:43:06.184549 systemd[1]: Created slice user-500.slice - User Slice of UID 500.
May 27 17:43:06.187400 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500...
May 27 17:43:06.190518 systemd-logind[1472]: New session 1 of user core.
May 27 17:43:06.213963 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500.
May 27 17:43:06.216409 systemd[1]: Starting user@500.service - User Manager for UID 500...
May 27 17:43:06.230898 (systemd)[1930]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0)
May 27 17:43:06.233937 systemd-logind[1472]: New session c1 of user core.
May 27 17:43:06.324649 etcd-wrapper[1838]: 8c26e4bf18e2: Pull complete
May 27 17:43:06.415514 systemd[1930]: Queued start job for default target default.target.
May 27 17:43:06.439850 systemd[1930]: Created slice app.slice - User Application Slice.
May 27 17:43:06.439879 systemd[1930]: Reached target paths.target - Paths.
May 27 17:43:06.439923 systemd[1930]: Reached target timers.target - Timers.
May 27 17:43:06.441564 systemd[1930]: Starting dbus.socket - D-Bus User Message Bus Socket...
May 27 17:43:06.454463 systemd[1930]: Listening on dbus.socket - D-Bus User Message Bus Socket.
May 27 17:43:06.454592 systemd[1930]: Reached target sockets.target - Sockets.
May 27 17:43:06.454631 systemd[1930]: Reached target basic.target - Basic System.
May 27 17:43:06.454669 systemd[1930]: Reached target default.target - Main User Target.
May 27 17:43:06.454702 systemd[1930]: Startup finished in 209ms.
May 27 17:43:06.455357 systemd[1]: Started user@500.service - User Manager for UID 500.
May 27 17:43:06.464521 systemd[1]: Started session-1.scope - Session 1 of User core.
May 27 17:43:06.466546 etcd-wrapper[1838]: 1e59a65f8816: Pull complete
May 27 17:43:06.497126 etcd-wrapper[1838]: ffbd4ca5f0bd: Pull complete
May 27 17:43:06.507673 etcd-wrapper[1838]: Digest: sha256:d967d98a12dc220a1a290794711dba7eba04b8ce465e12b02383d1bfbb33e159
May 27 17:43:06.513001 etcd-wrapper[1838]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.16
May 27 17:43:06.523994 systemd[1]: Started sshd@1-10.0.0.73:22-10.0.0.1:59992.service - OpenSSH per-connection server daemon (10.0.0.1:59992).
May 27 17:43:06.546822 systemd[1]: var-lib-docker-overlay2-b5b11af1177762114a07c79bd4ccf201c403ea0d97b547ee66260a73be8b9e1f-merged.mount: Deactivated successfully.
May 27 17:43:06.549574 systemd[1]: var-lib-docker-overlay2-9fe7e242debf33b9f7c18c4424f3ae78d9f812aeca43c38d1f07356fa5d6adc4-merged.mount: Deactivated successfully.
May 27 17:43:06.577696 sshd[1950]: Accepted publickey for core from 10.0.0.1 port 59992 ssh2: RSA SHA256:Sdu3hc/K/GsFAoVLDVpDFh1tw++0J1r4WpeL8cs/qlY
May 27 17:43:06.580014 sshd-session[1950]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 27 17:43:06.585193 containerd[1548]: time="2025-05-27T17:43:06.585151584Z" level=info msg="connecting to shim b396186ecb836b8f82e0630126b333e296a9d7caf41e0afb4f928c27b4c746d7" address="unix:///run/containerd/s/d9f2a3eaad5c64ed194d187824fa1b161cb4cfe685b9a6a4c5627110623c0a99" namespace=moby protocol=ttrpc version=3
May 27 17:43:06.585755 systemd-logind[1472]: New session 2 of user core.
May 27 17:43:06.591441 systemd[1]: Started session-2.scope - Session 2 of User core.
May 27 17:43:06.619579 systemd[1]: Started docker-b396186ecb836b8f82e0630126b333e296a9d7caf41e0afb4f928c27b4c746d7.scope - libcontainer container b396186ecb836b8f82e0630126b333e296a9d7caf41e0afb4f928c27b4c746d7.
May 27 17:43:06.636675 systemd-resolved[1346]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address
May 27 17:43:06.644913 sshd[1981]: Connection closed by 10.0.0.1 port 59992
May 27 17:43:06.645589 sshd-session[1950]: pam_unix(sshd:session): session closed for user core
May 27 17:43:06.660785 systemd[1]: sshd@1-10.0.0.73:22-10.0.0.1:59992.service: Deactivated successfully.
May 27 17:43:06.663086 systemd[1]: session-2.scope: Deactivated successfully.
May 27 17:43:06.664169 systemd-logind[1472]: Session 2 logged out. Waiting for processes to exit.
May 27 17:43:06.668097 systemd[1]: Started sshd@2-10.0.0.73:22-10.0.0.1:60002.service - OpenSSH per-connection server daemon (10.0.0.1:60002).
May 27 17:43:06.669177 systemd-logind[1472]: Removed session 2.
May 27 17:43:06.700007 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:06.699624Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"}
May 27 17:43:06.700743 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:06.699807Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"5584fcede1904795a5322be231bd9024"}
May 27 17:43:06.700743 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:43:06.699831Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"}
May 27 17:43:06.700743 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:43:06.699838Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.16"}
May 27 17:43:06.700743 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:43:06.699844Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"}
May 27 17:43:06.700743 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:43:06.699848Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"}
May 27 17:43:06.700743 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:43:06.699903Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."}
May 27 17:43:06.700743 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:06.699922Z","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.73:2379"]}
May 27 17:43:06.700743 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:43:06.700159Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."}
May 27 17:43:06.700743 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:06.700173Z","caller":"embed/etcd.go:128","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]}
May 27 17:43:06.701044 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:06.700898Z","caller":"embed/etcd.go:136","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]}
May 27 17:43:06.701313 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:06.701039Z","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":"5584fcede1904795a5322be231bd9024","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.73:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"5584fcede1904795a5322be231bd9024=http://localhost:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":false,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"}
May 27 17:43:06.703509 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:06.703276Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.86057ms"}
May 27 17:43:06.709418 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:06.709180Z","caller":"etcdserver/raft.go:505","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"}
May 27 17:43:06.709624 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:06.709398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"}
May 27 17:43:06.709722 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:06.709446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"}
May 27 17:43:06.709722 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:06.709458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
May 27 17:43:06.709722 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:06.709464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"}
May 27 17:43:06.709722 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:06.709506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"}
May 27 17:43:06.714958 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:43:06.714740Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
May 27 17:43:06.716040 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:06.715861Z","caller":"mvcc/kvstore.go:423","msg":"kvstore restored","current-rev":1}
May 27 17:43:06.717062 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:06.716870Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"}
May 27 17:43:06.718238 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:06.717811Z","caller":"etcdserver/server.go:873","msg":"starting etcd server","local-member-id":"8e9e05c52164694d","local-server-version":"3.5.16","cluster-version":"to_be_decided"}
May 27 17:43:06.718461 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:06.718053Z","caller":"etcdserver/server.go:757","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8e9e05c52164694d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
May 27 17:43:06.718461 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:06.718172Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"}
May 27 17:43:06.718461 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:06.718219Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"}
May 27 17:43:06.718461 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:06.718226Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"}
May 27 17:43:06.718766 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:06.718593Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"}
May 27 17:43:06.719149 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:06.718928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"}
May 27 17:43:06.719149 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:06.719022Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","added-peer-id":"8e9e05c52164694d","added-peer-peer-urls":["http://localhost:2380"]}
May 27 17:43:06.721021 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:06.720830Z","caller":"embed/etcd.go:600","msg":"serving peer traffic","address":"127.0.0.1:2380"}
May 27 17:43:06.721021 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:06.720864Z","caller":"embed/etcd.go:572","msg":"cmux::serve","address":"127.0.0.1:2380"}
May 27 17:43:06.721115 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:06.720907Z","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.73:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]}
May 27 17:43:06.724292 sshd[1999]: Accepted publickey for core from 10.0.0.1 port 60002 ssh2: RSA SHA256:Sdu3hc/K/GsFAoVLDVpDFh1tw++0J1r4WpeL8cs/qlY
May 27 17:43:06.726021 sshd-session[1999]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 27 17:43:06.730853 systemd-logind[1472]: New session 3 of user core.
May 27 17:43:06.741616 systemd[1]: Started session-3.scope - Session 3 of User core.
May 27 17:43:06.793997 sshd[2013]: Connection closed by 10.0.0.1 port 60002
May 27 17:43:06.794369 sshd-session[1999]: pam_unix(sshd:session): session closed for user core
May 27 17:43:06.799317 systemd[1]: sshd@2-10.0.0.73:22-10.0.0.1:60002.service: Deactivated successfully.
May 27 17:43:06.801251 systemd[1]: session-3.scope: Deactivated successfully.
May 27 17:43:06.802122 systemd-logind[1472]: Session 3 logged out. Waiting for processes to exit.
May 27 17:43:06.803656 systemd-logind[1472]: Removed session 3.
May 27 17:43:07.410929 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:07.410523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"}
May 27 17:43:07.410929 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:07.410583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"}
May 27 17:43:07.410929 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:07.410601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"}
May 27 17:43:07.410929 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:07.410616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"}
May 27 17:43:07.410929 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:07.410623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"}
May 27 17:43:07.410929 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:07.410633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"}
May 27 17:43:07.410929 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:07.410641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"}
May 27 17:43:07.411706 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:07.411416Z","caller":"etcdserver/server.go:2651","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
May 27 17:43:07.412408 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:07.412045Z","caller":"etcdserver/server.go:2140","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:5584fcede1904795a5322be231bd9024 ClientURLs:[http://10.0.0.73:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"}
May 27 17:43:07.412408 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:07.412059Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
May 27 17:43:07.412408 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:07.412232Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
May 27 17:43:07.412994 systemd[1]: Started etcd-member.service - etcd (System Application Container).
May 27 17:43:07.413123 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:07.412410Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"}
May 27 17:43:07.413123 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:07.412515Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
May 27 17:43:07.413123 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:07.412545Z","caller":"etcdserver/server.go:2675","msg":"cluster version is updated","cluster-version":"3.5"}
May 27 17:43:07.413123 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:07.412581Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
May 27 17:43:07.413123 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:07.412721Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"}
May 27 17:43:07.413332 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:43:07.413131Z","caller":"embed/serve.go:187","msg":"serving client traffic insecurely; this is strongly discouraged!","traffic":"grpc+http","address":"[::]:2379"}
May 27 17:43:07.413349 systemd[1]: Reached target multi-user.target - Multi-User System.
May 27 17:43:07.413592 systemd[1]: Startup finished in 3.934s (kernel) + 4.386s (initrd) + 10.944s (userspace) = 19.265s.
May 27 17:43:16.818494 systemd[1]: Started sshd@3-10.0.0.73:22-10.0.0.1:45076.service - OpenSSH per-connection server daemon (10.0.0.1:45076).
May 27 17:43:16.865048 sshd[2021]: Accepted publickey for core from 10.0.0.1 port 45076 ssh2: RSA SHA256:Sdu3hc/K/GsFAoVLDVpDFh1tw++0J1r4WpeL8cs/qlY
May 27 17:43:16.866512 sshd-session[2021]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 27 17:43:16.871117 systemd-logind[1472]: New session 4 of user core.
May 27 17:43:16.880442 systemd[1]: Started session-4.scope - Session 4 of User core.
May 27 17:43:16.934176 sshd[2023]: Connection closed by 10.0.0.1 port 45076
May 27 17:43:16.934502 sshd-session[2021]: pam_unix(sshd:session): session closed for user core
May 27 17:43:16.944926 systemd[1]: sshd@3-10.0.0.73:22-10.0.0.1:45076.service: Deactivated successfully.
May 27 17:43:16.946838 systemd[1]: session-4.scope: Deactivated successfully.
May 27 17:43:16.947540 systemd-logind[1472]: Session 4 logged out. Waiting for processes to exit.
May 27 17:43:16.950603 systemd[1]: Started sshd@4-10.0.0.73:22-10.0.0.1:45078.service - OpenSSH per-connection server daemon (10.0.0.1:45078).
May 27 17:43:16.951147 systemd-logind[1472]: Removed session 4.
May 27 17:43:17.003040 sshd[2029]: Accepted publickey for core from 10.0.0.1 port 45078 ssh2: RSA SHA256:Sdu3hc/K/GsFAoVLDVpDFh1tw++0J1r4WpeL8cs/qlY
May 27 17:43:17.004843 sshd-session[2029]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 27 17:43:17.010670 systemd-logind[1472]: New session 5 of user core.
May 27 17:43:17.026653 systemd[1]: Started session-5.scope - Session 5 of User core.
May 27 17:43:17.078970 sshd[2031]: Connection closed by 10.0.0.1 port 45078
May 27 17:43:17.079210 sshd-session[2029]: pam_unix(sshd:session): session closed for user core
May 27 17:43:17.093887 systemd[1]: sshd@4-10.0.0.73:22-10.0.0.1:45078.service: Deactivated successfully.
May 27 17:43:17.096099 systemd[1]: session-5.scope: Deactivated successfully.
May 27 17:43:17.096919 systemd-logind[1472]: Session 5 logged out. Waiting for processes to exit.
May 27 17:43:17.101250 systemd[1]: Started sshd@5-10.0.0.73:22-10.0.0.1:45094.service - OpenSSH per-connection server daemon (10.0.0.1:45094).
May 27 17:43:17.102042 systemd-logind[1472]: Removed session 5.
May 27 17:43:17.163456 sshd[2037]: Accepted publickey for core from 10.0.0.1 port 45094 ssh2: RSA SHA256:Sdu3hc/K/GsFAoVLDVpDFh1tw++0J1r4WpeL8cs/qlY
May 27 17:43:17.164815 sshd-session[2037]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 27 17:43:17.169779 systemd-logind[1472]: New session 6 of user core.
May 27 17:43:17.185589 systemd[1]: Started session-6.scope - Session 6 of User core.
May 27 17:43:17.240522 sshd[2040]: Connection closed by 10.0.0.1 port 45094
May 27 17:43:17.240922 sshd-session[2037]: pam_unix(sshd:session): session closed for user core
May 27 17:43:17.259124 systemd[1]: sshd@5-10.0.0.73:22-10.0.0.1:45094.service: Deactivated successfully.
May 27 17:43:17.261064 systemd[1]: session-6.scope: Deactivated successfully.
May 27 17:43:17.261948 systemd-logind[1472]: Session 6 logged out. Waiting for processes to exit.
May 27 17:43:17.264732 systemd[1]: Started sshd@6-10.0.0.73:22-10.0.0.1:45108.service - OpenSSH per-connection server daemon (10.0.0.1:45108).
May 27 17:43:17.265487 systemd-logind[1472]: Removed session 6.
May 27 17:43:17.310542 sshd[2046]: Accepted publickey for core from 10.0.0.1 port 45108 ssh2: RSA SHA256:Sdu3hc/K/GsFAoVLDVpDFh1tw++0J1r4WpeL8cs/qlY
May 27 17:43:17.311729 sshd-session[2046]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 27 17:43:17.316156 systemd-logind[1472]: New session 7 of user core.
May 27 17:43:17.329439 systemd[1]: Started session-7.scope - Session 7 of User core.
May 27 17:43:17.387504 sudo[2049]:     core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1
May 27 17:43:17.387822 sudo[2049]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500)
May 27 17:43:17.412132 sudo[2049]: pam_unix(sudo:session): session closed for user root
May 27 17:43:17.414297 sshd[2048]: Connection closed by 10.0.0.1 port 45108
May 27 17:43:17.414650 sshd-session[2046]: pam_unix(sshd:session): session closed for user core
May 27 17:43:17.432201 systemd[1]: sshd@6-10.0.0.73:22-10.0.0.1:45108.service: Deactivated successfully.
May 27 17:43:17.434542 systemd[1]: session-7.scope: Deactivated successfully.
May 27 17:43:17.435388 systemd-logind[1472]: Session 7 logged out. Waiting for processes to exit.
May 27 17:43:17.439024 systemd[1]: Started sshd@7-10.0.0.73:22-10.0.0.1:45114.service - OpenSSH per-connection server daemon (10.0.0.1:45114).
May 27 17:43:17.439697 systemd-logind[1472]: Removed session 7.
May 27 17:43:17.498912 sshd[2055]: Accepted publickey for core from 10.0.0.1 port 45114 ssh2: RSA SHA256:Sdu3hc/K/GsFAoVLDVpDFh1tw++0J1r4WpeL8cs/qlY
May 27 17:43:17.500586 sshd-session[2055]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 27 17:43:17.505025 systemd-logind[1472]: New session 8 of user core.
May 27 17:43:17.514454 systemd[1]: Started session-8.scope - Session 8 of User core.
May 27 17:43:17.571885 sudo[2059]:     core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules
May 27 17:43:17.572278 sudo[2059]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500)
May 27 17:43:17.581393 sudo[2059]: pam_unix(sudo:session): session closed for user root
May 27 17:43:17.590123 sudo[2058]:     core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules
May 27 17:43:17.590539 sudo[2058]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500)
May 27 17:43:17.602832 systemd[1]: Starting audit-rules.service - Load Audit Rules...
May 27 17:43:17.659812 augenrules[2081]: No rules
May 27 17:43:17.662090 systemd[1]: audit-rules.service: Deactivated successfully.
May 27 17:43:17.662492 systemd[1]: Finished audit-rules.service - Load Audit Rules.
May 27 17:43:17.663927 sudo[2058]: pam_unix(sudo:session): session closed for user root
May 27 17:43:17.665976 sshd[2057]: Connection closed by 10.0.0.1 port 45114
May 27 17:43:17.666453 sshd-session[2055]: pam_unix(sshd:session): session closed for user core
May 27 17:43:17.679395 systemd[1]: sshd@7-10.0.0.73:22-10.0.0.1:45114.service: Deactivated successfully.
May 27 17:43:17.681267 systemd[1]: session-8.scope: Deactivated successfully.
May 27 17:43:17.682092 systemd-logind[1472]: Session 8 logged out. Waiting for processes to exit.
May 27 17:43:17.685118 systemd[1]: Started sshd@8-10.0.0.73:22-10.0.0.1:45116.service - OpenSSH per-connection server daemon (10.0.0.1:45116).
May 27 17:43:17.685667 systemd-logind[1472]: Removed session 8.
May 27 17:43:17.740458 sshd[2090]: Accepted publickey for core from 10.0.0.1 port 45116 ssh2: RSA SHA256:Sdu3hc/K/GsFAoVLDVpDFh1tw++0J1r4WpeL8cs/qlY
May 27 17:43:17.742101 sshd-session[2090]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 27 17:43:17.746871 systemd-logind[1472]: New session 9 of user core.
May 27 17:43:17.762533 systemd[1]: Started session-9.scope - Session 9 of User core.
May 27 17:43:17.814119 sshd[2092]: Connection closed by 10.0.0.1 port 45116
May 27 17:43:17.814492 sshd-session[2090]: pam_unix(sshd:session): session closed for user core
May 27 17:43:17.831572 systemd[1]: sshd@8-10.0.0.73:22-10.0.0.1:45116.service: Deactivated successfully.
May 27 17:43:17.833792 systemd[1]: session-9.scope: Deactivated successfully.
May 27 17:43:17.834658 systemd-logind[1472]: Session 9 logged out. Waiting for processes to exit.
May 27 17:43:17.837787 systemd[1]: Started sshd@9-10.0.0.73:22-10.0.0.1:45122.service - OpenSSH per-connection server daemon (10.0.0.1:45122).
May 27 17:43:17.838345 systemd-logind[1472]: Removed session 9.
May 27 17:43:17.895980 sshd[2100]: Accepted publickey for core from 10.0.0.1 port 45122 ssh2: RSA SHA256:Sdu3hc/K/GsFAoVLDVpDFh1tw++0J1r4WpeL8cs/qlY
May 27 17:43:17.897368 sshd-session[2100]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 27 17:43:17.901963 systemd-logind[1472]: New session 10 of user core.
May 27 17:43:17.918420 systemd[1]: Started session-10.scope - Session 10 of User core.
May 27 17:43:18.039796 sshd[2102]: Connection closed by 10.0.0.1 port 45122
May 27 17:43:18.040136 sshd-session[2100]: pam_unix(sshd:session): session closed for user core
May 27 17:43:18.045614 systemd[1]: sshd@9-10.0.0.73:22-10.0.0.1:45122.service: Deactivated successfully.
May 27 17:43:18.047496 systemd[1]: session-10.scope: Deactivated successfully.
May 27 17:43:18.048235 systemd-logind[1472]: Session 10 logged out. Waiting for processes to exit.
May 27 17:43:18.049391 systemd-logind[1472]: Removed session 10.
May 27 17:43:43.151987 update_engine[1478]: I20250527 17:43:43.151873  1478 update_attempter.cc:509] Updating boot flags...
May 27 17:44:03.138818 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:44:03.138152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.98593ms","expected-duration":"100ms","prefix":"","request":"header:<ID:7587887054365840774 > txn:<compare:<target:MOD key:\"/registry/minions/localhost\" mod_revision:0 > success:<request_put:<key:\"/registry/minions/localhost\" value_size:2857 >> failure:<>>","response":"size:14"}
May 27 17:44:03.139464 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:03.138351Z","caller":"traceutil/trace.go:171","msg":"trace[1239276106] linearizableReadLoop","detail":"{readStateIndex:16; appliedIndex:14; }","duration":"139.365826ms","start":"2025-05-27T17:44:02.998967Z","end":"2025-05-27T17:44:03.138333Z","steps":["trace[1239276106] 'read index received'  (duration: 37.809704ms)","trace[1239276106] 'applied index is now lower than readState.Index'  (duration: 101.555591ms)"],"step_count":2}
May 27 17:44:03.139464 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:03.138419Z","caller":"traceutil/trace.go:171","msg":"trace[1784159811] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"219.365869ms","start":"2025-05-27T17:44:02.919046Z","end":"2025-05-27T17:44:03.138412Z","steps":["trace[1784159811] 'process raft request'  (duration: 117.745145ms)","trace[1784159811] 'compare'  (duration: 100.887284ms)"],"step_count":2}
May 27 17:44:03.139464 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:44:03.138462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.013189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/localhost\" limit:1 ","response":"range_response_count:0 size:4"}
May 27 17:44:03.139464 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:03.138485Z","caller":"traceutil/trace.go:171","msg":"trace[498795920] range","detail":"{range_begin:/registry/csinodes/localhost; range_end:; response_count:0; response_revision:12; }","duration":"223.050709ms","start":"2025-05-27T17:44:02.915427Z","end":"2025-05-27T17:44:03.138478Z","steps":["trace[498795920] 'agreement among raft nodes before linearized reading'  (duration: 223.002419ms)"],"step_count":1}
May 27 17:44:03.139625 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:03.138392Z","caller":"traceutil/trace.go:171","msg":"trace[1234542879] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"218.747075ms","start":"2025-05-27T17:44:02.919635Z","end":"2025-05-27T17:44:03.138382Z","steps":["trace[1234542879] 'process raft request'  (duration: 218.652828ms)"],"step_count":1}
May 27 17:44:03.139625 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:44:03.138507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.560752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"}
May 27 17:44:03.139625 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:03.138521Z","caller":"traceutil/trace.go:171","msg":"trace[571734914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12; }","duration":"228.588446ms","start":"2025-05-27T17:44:02.909927Z","end":"2025-05-27T17:44:03.138516Z","steps":["trace[571734914] 'agreement among raft nodes before linearized reading'  (duration: 228.566294ms)"],"step_count":1}
May 27 17:44:03.139625 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:44:03.138628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.210191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" limit:1 ","response":"range_response_count:1 size:350"}
May 27 17:44:03.139625 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:03.138695Z","caller":"traceutil/trace.go:171","msg":"trace[1771286755] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:12; }","duration":"180.309187ms","start":"2025-05-27T17:44:02.958378Z","end":"2025-05-27T17:44:03.138687Z","steps":["trace[1771286755] 'agreement among raft nodes before linearized reading'  (duration: 180.042075ms)"],"step_count":1}
May 27 17:44:03.172828 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:03.172502Z","caller":"traceutil/trace.go:171","msg":"trace[1796607725] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"170.461543ms","start":"2025-05-27T17:44:03.002024Z","end":"2025-05-27T17:44:03.172485Z","steps":["trace[1796607725] 'process raft request'  (duration: 170.383806ms)"],"step_count":1}
May 27 17:44:03.172828 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:03.172564Z","caller":"traceutil/trace.go:171","msg":"trace[1858309469] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"170.49196ms","start":"2025-05-27T17:44:03.002067Z","end":"2025-05-27T17:44:03.172559Z","steps":["trace[1858309469] 'process raft request'  (duration: 170.379859ms)"],"step_count":1}
May 27 17:44:03.172828 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:03.172533Z","caller":"traceutil/trace.go:171","msg":"trace[999624464] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"170.578774ms","start":"2025-05-27T17:44:03.001944Z","end":"2025-05-27T17:44:03.172523Z","steps":["trace[999624464] 'process raft request'  (duration: 170.416649ms)"],"step_count":1}
May 27 17:44:03.173013 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:03.172628Z","caller":"traceutil/trace.go:171","msg":"trace[528669994] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"170.275302ms","start":"2025-05-27T17:44:03.002344Z","end":"2025-05-27T17:44:03.172619Z","steps":["trace[528669994] 'process raft request'  (duration: 170.227352ms)"],"step_count":1}
May 27 17:44:03.173013 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:03.172633Z","caller":"traceutil/trace.go:171","msg":"trace[1221447158] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"170.583431ms","start":"2025-05-27T17:44:03.002043Z","end":"2025-05-27T17:44:03.172626Z","steps":["trace[1221447158] 'process raft request'  (duration: 170.485247ms)"],"step_count":1}
May 27 17:44:03.173013 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:03.172679Z","caller":"traceutil/trace.go:171","msg":"trace[1680382550] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"170.327561ms","start":"2025-05-27T17:44:03.002338Z","end":"2025-05-27T17:44:03.172665Z","steps":["trace[1680382550] 'process raft request'  (duration: 170.214929ms)"],"step_count":1}
May 27 17:44:03.173013 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:44:03.172699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.105544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" limit:1 ","response":"range_response_count:0 size:4"}
May 27 17:44:03.173013 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:03.172845Z","caller":"traceutil/trace.go:171","msg":"trace[1023149737] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:18; }","duration":"171.285182ms","start":"2025-05-27T17:44:03.001551Z","end":"2025-05-27T17:44:03.172836Z","steps":["trace[1023149737] 'agreement among raft nodes before linearized reading'  (duration: 171.12994ms)"],"step_count":1}
May 27 17:44:14.143044 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:14.142637Z","caller":"traceutil/trace.go:171","msg":"trace[268002573] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"143.542284ms","start":"2025-05-27T17:44:13.999075Z","end":"2025-05-27T17:44:14.142617Z","steps":["trace[268002573] 'process raft request'  (duration: 143.454619ms)"],"step_count":1}
May 27 17:44:15.094297 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:15.093598Z","caller":"traceutil/trace.go:171","msg":"trace[889747592] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"179.798222ms","start":"2025-05-27T17:44:14.913777Z","end":"2025-05-27T17:44:15.093576Z","steps":["trace[889747592] 'process raft request'  (duration: 179.740063ms)"],"step_count":1}
May 27 17:44:15.094297 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:15.093687Z","caller":"traceutil/trace.go:171","msg":"trace[487130138] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"180.249139ms","start":"2025-05-27T17:44:14.913390Z","end":"2025-05-27T17:44:15.093639Z","steps":["trace[487130138] 'process raft request'  (duration: 118.376297ms)","trace[487130138] 'compare'  (duration: 61.600591ms)"],"step_count":2}
May 27 17:44:15.094297 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:15.093799Z","caller":"traceutil/trace.go:171","msg":"trace[2140697498] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"167.301084ms","start":"2025-05-27T17:44:14.926489Z","end":"2025-05-27T17:44:15.093790Z","steps":["trace[2140697498] 'process raft request'  (duration: 167.170819ms)"],"step_count":1}
May 27 17:44:15.094297 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:15.093774Z","caller":"traceutil/trace.go:171","msg":"trace[1766969192] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"145.08827ms","start":"2025-05-27T17:44:14.948664Z","end":"2025-05-27T17:44:15.093752Z","steps":["trace[1766969192] 'process raft request'  (duration: 145.05661ms)"],"step_count":1}
May 27 17:44:16.098988 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:16.098574Z","caller":"traceutil/trace.go:171","msg":"trace[80939380] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:524; }","duration":"137.495897ms","start":"2025-05-27T17:44:15.961058Z","end":"2025-05-27T17:44:16.098554Z","steps":["trace[80939380] 'read index received'  (duration: 137.36412ms)","trace[80939380] 'applied index is now lower than readState.Index'  (duration: 131.016µs)"],"step_count":2}
May 27 17:44:16.098988 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:16.098616Z","caller":"traceutil/trace.go:171","msg":"trace[868467969] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"144.43131ms","start":"2025-05-27T17:44:15.954156Z","end":"2025-05-27T17:44:16.098588Z","steps":["trace[868467969] 'process raft request'  (duration: 144.279185ms)"],"step_count":1}
May 27 17:44:16.099500 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:44:16.099004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.924352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/installations.operator.tigera.io\" limit:1 ","response":"range_response_count:1 size:630504"}
May 27 17:44:16.099500 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:16.099053Z","caller":"traceutil/trace.go:171","msg":"trace[1219538217] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/installations.operator.tigera.io; range_end:; response_count:1; response_revision:510; }","duration":"138.006135ms","start":"2025-05-27T17:44:15.961038Z","end":"2025-05-27T17:44:16.099044Z","steps":["trace[1219538217] 'agreement among raft nodes before linearized reading'  (duration: 137.589913ms)"],"step_count":1}
May 27 17:44:19.221071 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:19.220559Z","caller":"traceutil/trace.go:171","msg":"trace[1316879461] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"185.175603ms","start":"2025-05-27T17:44:19.035331Z","end":"2025-05-27T17:44:19.220507Z","steps":["trace[1316879461] 'process raft request'  (duration: 105.896569ms)","trace[1316879461] 'compare'  (duration: 79.046678ms)"],"step_count":2}
May 27 17:44:20.025123 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.024507Z","caller":"traceutil/trace.go:171","msg":"trace[1168815740] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"153.600928ms","start":"2025-05-27T17:44:19.870881Z","end":"2025-05-27T17:44:20.024482Z","steps":["trace[1168815740] 'process raft request'  (duration: 135.129836ms)","trace[1168815740] 'compare'  (duration: 18.314759ms)"],"step_count":2}
May 27 17:44:20.025123 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:44:20.024595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.601229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/calico-system/goldmane\" limit:1 ","response":"range_response_count:1 size:771"}
May 27 17:44:20.025123 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.024634Z","caller":"traceutil/trace.go:171","msg":"trace[1422095692] range","detail":"{range_begin:/registry/services/specs/calico-system/goldmane; range_end:; response_count:1; response_revision:688; }","duration":"153.663595ms","start":"2025-05-27T17:44:19.870960Z","end":"2025-05-27T17:44:20.024623Z","steps":["trace[1422095692] 'agreement among raft nodes before linearized reading'  (duration: 153.541506ms)"],"step_count":1}
May 27 17:44:20.025123 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:44:20.024637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.008273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/calico-system/tigera-ca-bundle\" limit:1 ","response":"range_response_count:1 size:228569"}
May 27 17:44:20.025123 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.024685Z","caller":"traceutil/trace.go:171","msg":"trace[796506476] range","detail":"{range_begin:/registry/configmaps/calico-system/tigera-ca-bundle; range_end:; response_count:1; response_revision:688; }","duration":"118.066603ms","start":"2025-05-27T17:44:19.906608Z","end":"2025-05-27T17:44:20.024675Z","steps":["trace[796506476] 'agreement among raft nodes before linearized reading'  (duration: 117.881506ms)"],"step_count":1}
May 27 17:44:20.025754 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:44:20.024740Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.370526ms","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"}
May 27 17:44:20.025754 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.024777Z","caller":"traceutil/trace.go:171","msg":"trace[415951942] range","detail":"{range_begin:/registry/secrets/tigera-operator/goldmane-key-pair; range_end:; response_count:1; response_revision:688; }","duration":"142.433044ms","start":"2025-05-27T17:44:19.882332Z","end":"2025-05-27T17:44:20.024765Z","steps":["trace[415951942] 'agreement among raft nodes before linearized reading'  (duration: 142.323829ms)"],"step_count":1}
May 27 17:44:20.213164 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.212722Z","caller":"traceutil/trace.go:171","msg":"trace[303444568] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"123.618053ms","start":"2025-05-27T17:44:20.089077Z","end":"2025-05-27T17:44:20.212695Z","steps":["trace[303444568] 'process raft request'  (duration: 102.061209ms)","trace[303444568] 'compare'  (duration: 21.464951ms)"],"step_count":2}
May 27 17:44:20.213164 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:44:20.212800Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.331103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/calico-system/goldmane\" limit:1 ","response":"range_response_count:1 size:597"}
May 27 17:44:20.213164 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:44:20.212830Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.704133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/operator.tigera.io/installations/default\" limit:1 ","response":"range_response_count:1 size:3463"}
May 27 17:44:20.213164 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.212851Z","caller":"traceutil/trace.go:171","msg":"trace[1196781951] range","detail":"{range_begin:/registry/networkpolicies/calico-system/goldmane; range_end:; response_count:1; response_revision:691; }","duration":"123.393411ms","start":"2025-05-27T17:44:20.089444Z","end":"2025-05-27T17:44:20.212837Z","steps":["trace[1196781951] 'agreement among raft nodes before linearized reading'  (duration: 123.252516ms)"],"step_count":1}
May 27 17:44:20.213164 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.212861Z","caller":"traceutil/trace.go:171","msg":"trace[1996195292] range","detail":"{range_begin:/registry/operator.tigera.io/installations/default; range_end:; response_count:1; response_revision:691; }","duration":"120.758716ms","start":"2025-05-27T17:44:20.092095Z","end":"2025-05-27T17:44:20.212854Z","steps":["trace[1996195292] 'agreement among raft nodes before linearized reading'  (duration: 120.606519ms)"],"step_count":1}
May 27 17:44:20.213474 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:44:20.212904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.935882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/calico-system/node-certs\" limit:1 ","response":"range_response_count:1 size:4584"}
May 27 17:44:20.213474 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.212932Z","caller":"traceutil/trace.go:171","msg":"trace[1844318917] range","detail":"{range_begin:/registry/secrets/calico-system/node-certs; range_end:; response_count:1; response_revision:691; }","duration":"105.982108ms","start":"2025-05-27T17:44:20.106940Z","end":"2025-05-27T17:44:20.212923Z","steps":["trace[1844318917] 'agreement among raft nodes before linearized reading'  (duration: 105.864888ms)"],"step_count":1}
May 27 17:44:20.234102 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.233450Z","caller":"traceutil/trace.go:171","msg":"trace[1860611812] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"114.02778ms","start":"2025-05-27T17:44:20.119410Z","end":"2025-05-27T17:44:20.233438Z","steps":["trace[1860611812] 'process raft request'  (duration: 113.936198ms)"],"step_count":1}
May 27 17:44:20.234102 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:44:20.233639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.219114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/calico-apiserver/allow-apiserver\" limit:1 ","response":"range_response_count:1 size:601"}
May 27 17:44:20.234102 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.233687Z","caller":"traceutil/trace.go:171","msg":"trace[2124524604] range","detail":"{range_begin:/registry/networkpolicies/calico-apiserver/allow-apiserver; range_end:; response_count:1; response_revision:692; }","duration":"102.294907ms","start":"2025-05-27T17:44:20.131385Z","end":"2025-05-27T17:44:20.233679Z","steps":["trace[2124524604] 'agreement among raft nodes before linearized reading'  (duration: 102.176705ms)"],"step_count":1}
May 27 17:44:20.234102 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:44:20.233700Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.789929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/calico-system/whisker\" limit:1 ","response":"range_response_count:1 size:739"}
May 27 17:44:20.234102 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.233767Z","caller":"traceutil/trace.go:171","msg":"trace[532354607] range","detail":"{range_begin:/registry/networkpolicies/calico-system/whisker; range_end:; response_count:1; response_revision:692; }","duration":"115.888504ms","start":"2025-05-27T17:44:20.117866Z","end":"2025-05-27T17:44:20.233754Z","steps":["trace[532354607] 'agreement among raft nodes before linearized reading'  (duration: 115.707954ms)"],"step_count":1}
May 27 17:44:20.400282 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.399542Z","caller":"traceutil/trace.go:171","msg":"trace[1450650120] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"163.521274ms","start":"2025-05-27T17:44:20.236000Z","end":"2025-05-27T17:44:20.399522Z","steps":["trace[1450650120] 'process raft request'  (duration: 163.492601ms)"],"step_count":1}
May 27 17:44:20.400282 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.399601Z","caller":"traceutil/trace.go:171","msg":"trace[1122588325] linearizableReadLoop","detail":"{readStateIndex:709; appliedIndex:708; }","duration":"166.151061ms","start":"2025-05-27T17:44:20.233423Z","end":"2025-05-27T17:44:20.399574Z","steps":["trace[1122588325] 'read index received'  (duration: 154.165394ms)","trace[1122588325] 'applied index is now lower than readState.Index'  (duration: 11.984184ms)"],"step_count":2}
May 27 17:44:20.400282 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.399567Z","caller":"traceutil/trace.go:171","msg":"trace[819714577] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"182.139662ms","start":"2025-05-27T17:44:20.217415Z","end":"2025-05-27T17:44:20.399555Z","steps":["trace[819714577] 'process raft request'  (duration: 170.181065ms)","trace[819714577] 'compare'  (duration: 11.803757ms)"],"step_count":2}
May 27 17:44:20.400282 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:44:20.399776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.232619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/calico-system/calico-typha\" limit:1 ","response":"range_response_count:1 size:6670"}
May 27 17:44:20.400519 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.399797Z","caller":"traceutil/trace.go:171","msg":"trace[1278462804] range","detail":"{range_begin:/registry/deployments/calico-system/calico-typha; range_end:; response_count:1; response_revision:694; }","duration":"168.258978ms","start":"2025-05-27T17:44:20.231531Z","end":"2025-05-27T17:44:20.399790Z","steps":["trace[1278462804] 'agreement among raft nodes before linearized reading'  (duration: 168.13202ms)"],"step_count":1}
May 27 17:44:20.400519 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:44:20.399808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.095468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/calico-system/goldmane-ca-bundle\" limit:1 ","response":"range_response_count:1 size:1959"}
May 27 17:44:20.400519 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.399822Z","caller":"traceutil/trace.go:171","msg":"trace[1430075898] range","detail":"{range_begin:/registry/configmaps/calico-system/goldmane-ca-bundle; range_end:; response_count:1; response_revision:694; }","duration":"183.117539ms","start":"2025-05-27T17:44:20.216699Z","end":"2025-05-27T17:44:20.399816Z","steps":["trace[1430075898] 'agreement among raft nodes before linearized reading'  (duration: 182.991162ms)"],"step_count":1}
May 27 17:44:20.400519 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:44:20.399895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.31564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/calico-system/calico-node\" limit:1 ","response":"range_response_count:1 size:525"}
May 27 17:44:20.400519 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:44:20.399776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.123771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/calico-crds\" limit:1 ","response":"range_response_count:1 size:1255"}
May 27 17:44:20.400657 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.399914Z","caller":"traceutil/trace.go:171","msg":"trace[382089337] range","detail":"{range_begin:/registry/serviceaccounts/calico-system/calico-node; range_end:; response_count:1; response_revision:694; }","duration":"142.353131ms","start":"2025-05-27T17:44:20.257557Z","end":"2025-05-27T17:44:20.399910Z","steps":["trace[382089337] 'agreement among raft nodes before linearized reading'  (duration: 142.152505ms)"],"step_count":1}
May 27 17:44:20.400657 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.399921Z","caller":"traceutil/trace.go:171","msg":"trace[752986950] range","detail":"{range_begin:/registry/clusterroles/calico-crds; range_end:; response_count:1; response_revision:694; }","duration":"183.289692ms","start":"2025-05-27T17:44:20.216624Z","end":"2025-05-27T17:44:20.399914Z","steps":["trace[752986950] 'agreement among raft nodes before linearized reading'  (duration: 183.050263ms)"],"step_count":1}
May 27 17:44:20.400657 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:44:20.399953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.905369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/calico-node\" limit:1 ","response":"range_response_count:1 size:1903"}
May 27 17:44:20.400657 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.399964Z","caller":"traceutil/trace.go:171","msg":"trace[1825189166] range","detail":"{range_begin:/registry/clusterroles/calico-node; range_end:; response_count:1; response_revision:694; }","duration":"117.934394ms","start":"2025-05-27T17:44:20.282026Z","end":"2025-05-27T17:44:20.399960Z","steps":["trace[1825189166] 'agreement among raft nodes before linearized reading'  (duration: 117.903135ms)"],"step_count":1}
May 27 17:44:20.552103 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.551518Z","caller":"traceutil/trace.go:171","msg":"trace[1754618683] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"109.317629ms","start":"2025-05-27T17:44:20.442182Z","end":"2025-05-27T17:44:20.551499Z","steps":["trace[1754618683] 'process raft request'  (duration: 94.258952ms)","trace[1754618683] 'compare'  (duration: 14.913424ms)"],"step_count":2}
May 27 17:44:20.552103 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.551596Z","caller":"traceutil/trace.go:171","msg":"trace[1609376160] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"108.431256ms","start":"2025-05-27T17:44:20.443146Z","end":"2025-05-27T17:44:20.551577Z","steps":["trace[1609376160] 'process raft request'  (duration: 108.303856ms)"],"step_count":1}
May 27 17:44:20.552103 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.551633Z","caller":"traceutil/trace.go:171","msg":"trace[1352309225] range","detail":"{range_begin:/registry/daemonsets/calico-system/csi-node-driver; range_end:; response_count:1; response_revision:701; }","duration":"100.014156ms","start":"2025-05-27T17:44:20.451609Z","end":"2025-05-27T17:44:20.551623Z","steps":["trace[1352309225] 'agreement among raft nodes before linearized reading'  (duration: 99.917134ms)"],"step_count":1}
May 27 17:44:20.552103 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:44:20.551685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.222974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/calico-system/csi-node-driver-5skj9\" limit:1 ","response":"range_response_count:1 size:5324"}
May 27 17:44:20.552351 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.551716Z","caller":"traceutil/trace.go:171","msg":"trace[33901292] range","detail":"{range_begin:/registry/pods/calico-system/csi-node-driver-5skj9; range_end:; response_count:1; response_revision:701; }","duration":"108.305359ms","start":"2025-05-27T17:44:20.443402Z","end":"2025-05-27T17:44:20.551708Z","steps":["trace[33901292] 'agreement among raft nodes before linearized reading'  (duration: 108.154967ms)"],"step_count":1}
May 27 17:44:20.552351 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:44:20.551775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.089114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/calico-tier-getter\" limit:1 ","response":"range_response_count:1 size:515"}
May 27 17:44:20.552351 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.551803Z","caller":"traceutil/trace.go:171","msg":"trace[1356992895] range","detail":"{range_begin:/registry/clusterroles/calico-tier-getter; range_end:; response_count:1; response_revision:701; }","duration":"101.152343ms","start":"2025-05-27T17:44:20.450645Z","end":"2025-05-27T17:44:20.551798Z","steps":["trace[1356992895] 'agreement among raft nodes before linearized reading'  (duration: 100.90011ms)"],"step_count":1}
May 27 17:44:20.645627 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:44:20.645001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.412427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/calico-system/calico-kube-controllers-metrics\" limit:1 ","response":"range_response_count:0 size:5"}
May 27 17:44:20.645627 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.644976Z","caller":"traceutil/trace.go:171","msg":"trace[515889675] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"158.828806ms","start":"2025-05-27T17:44:20.486129Z","end":"2025-05-27T17:44:20.644957Z","steps":["trace[515889675] 'process raft request'  (duration: 158.668374ms)"],"step_count":1}
May 27 17:44:20.645627 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.645042Z","caller":"traceutil/trace.go:171","msg":"trace[571638254] range","detail":"{range_begin:/registry/services/specs/calico-system/calico-kube-controllers-metrics; range_end:; response_count:0; response_revision:702; }","duration":"161.472179ms","start":"2025-05-27T17:44:20.483560Z","end":"2025-05-27T17:44:20.645032Z","steps":["trace[571638254] 'agreement among raft nodes before linearized reading'  (duration: 161.415032ms)"],"step_count":1}
May 27 17:44:20.645627 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:44:20.645019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.696101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/calico-typha\" limit:1 ","response":"range_response_count:1 size:1668"}
May 27 17:44:20.645627 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:44:20.645001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.403688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/calico-typha\" limit:1 ","response":"range_response_count:1 size:612"}
May 27 17:44:20.645883 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.645091Z","caller":"traceutil/trace.go:171","msg":"trace[1373973221] range","detail":"{range_begin:/registry/clusterroles/calico-typha; range_end:; response_count:1; response_revision:702; }","duration":"136.805816ms","start":"2025-05-27T17:44:20.508274Z","end":"2025-05-27T17:44:20.645080Z","steps":["trace[1373973221] 'agreement among raft nodes before linearized reading'  (duration: 136.637571ms)"],"step_count":1}
May 27 17:44:20.645883 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:44:20.645106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.556589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/calico-system/goldmane-key-pair\" limit:1 ","response":"range_response_count:1 size:4711"}
May 27 17:44:20.645883 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.645118Z","caller":"traceutil/trace.go:171","msg":"trace[1729935064] range","detail":"{range_begin:/registry/secrets/calico-system/goldmane-key-pair; range_end:; response_count:1; response_revision:702; }","duration":"160.582358ms","start":"2025-05-27T17:44:20.484532Z","end":"2025-05-27T17:44:20.645114Z","steps":["trace[1729935064] 'agreement among raft nodes before linearized reading'  (duration: 160.542684ms)"],"step_count":1}
May 27 17:44:20.645883 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.645113Z","caller":"traceutil/trace.go:171","msg":"trace[1930824302] range","detail":"{range_begin:/registry/clusterrolebindings/calico-typha; range_end:; response_count:1; response_revision:702; }","duration":"110.550633ms","start":"2025-05-27T17:44:20.534556Z","end":"2025-05-27T17:44:20.645106Z","steps":["trace[1930824302] 'agreement among raft nodes before linearized reading'  (duration: 110.34627ms)"],"step_count":1}
May 27 17:44:20.645883 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:44:20.645173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.033692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/calico-system/calico-node\" limit:1 ","response":"range_response_count:1 size:11398"}
May 27 17:44:20.646029 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.645197Z","caller":"traceutil/trace.go:171","msg":"trace[1285480909] range","detail":"{range_begin:/registry/daemonsets/calico-system/calico-node; range_end:; response_count:1; response_revision:702; }","duration":"162.071112ms","start":"2025-05-27T17:44:20.483119Z","end":"2025-05-27T17:44:20.645190Z","steps":["trace[1285480909] 'agreement among raft nodes before linearized reading'  (duration: 161.906162ms)"],"step_count":1}
May 27 17:44:20.734808 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:44:20.734252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.117496ms","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"}
May 27 17:44:20.734808 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:44:20.734278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.236794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/calico-system/csi-node-driver\" limit:1 ","response":"range_response_count:1 size:4886"}
May 27 17:44:20.734808 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.734321Z","caller":"traceutil/trace.go:171","msg":"trace[752258453] range","detail":"{range_begin:/registry/rolebindings/calico-system/tigera-operator-secrets; range_end:; response_count:1; response_revision:703; }","duration":"177.176206ms","start":"2025-05-27T17:44:20.557116Z","end":"2025-05-27T17:44:20.734292Z","steps":["trace[752258453] 'agreement among raft nodes before linearized reading'  (duration: 177.078222ms)"],"step_count":1}
May 27 17:44:20.734808 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.734322Z","caller":"traceutil/trace.go:171","msg":"trace[2051541949] range","detail":"{range_begin:/registry/daemonsets/calico-system/csi-node-driver; range_end:; response_count:1; response_revision:703; }","duration":"179.283803ms","start":"2025-05-27T17:44:20.555031Z","end":"2025-05-27T17:44:20.734315Z","steps":["trace[2051541949] 'agreement among raft nodes before linearized reading'  (duration: 179.196719ms)"],"step_count":1}
May 27 17:44:20.734808 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:44:20.734249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.902979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/tigera-operator/calico-apiserver-certs\" limit:1 ","response":"range_response_count:1 size:4755"}
May 27 17:44:20.735066 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.734384Z","caller":"traceutil/trace.go:171","msg":"trace[1903070577] range","detail":"{range_begin:/registry/secrets/tigera-operator/calico-apiserver-certs; range_end:; response_count:1; response_revision:703; }","duration":"127.085291ms","start":"2025-05-27T17:44:20.607292Z","end":"2025-05-27T17:44:20.734377Z","steps":["trace[1903070577] 'agreement among raft nodes before linearized reading'  (duration: 126.867743ms)"],"step_count":1}
May 27 17:44:20.735066 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.734392Z","caller":"traceutil/trace.go:171","msg":"trace[1757830831] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"173.69954ms","start":"2025-05-27T17:44:20.560684Z","end":"2025-05-27T17:44:20.734383Z","steps":["trace[1757830831] 'process raft request'  (duration: 165.592051ms)"],"step_count":1}
May 27 17:44:20.735066 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:44:20.734410Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.156504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/calico-tier-getter\" limit:1 ","response":"range_response_count:1 size:647"}
May 27 17:44:20.735066 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:44:20.734249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.677564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/calico-system/active-operator\" limit:1 ","response":"range_response_count:1 size:518"}
May 27 17:44:20.735066 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:44:20.734434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.675517ms","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"}
May 27 17:44:20.735192 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.734432Z","caller":"traceutil/trace.go:171","msg":"trace[231046612] range","detail":"{range_begin:/registry/clusterrolebindings/calico-tier-getter; range_end:; response_count:1; response_revision:703; }","duration":"172.188985ms","start":"2025-05-27T17:44:20.562236Z","end":"2025-05-27T17:44:20.734425Z","steps":["trace[231046612] 'agreement among raft nodes before linearized reading'  (duration: 172.044083ms)"],"step_count":1}
May 27 17:44:20.735192 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.734444Z","caller":"traceutil/trace.go:171","msg":"trace[406734976] range","detail":"{range_begin:/registry/configmaps/calico-system/active-operator; range_end:; response_count:1; response_revision:703; }","duration":"151.909619ms","start":"2025-05-27T17:44:20.582529Z","end":"2025-05-27T17:44:20.734438Z","steps":["trace[406734976] 'agreement among raft nodes before linearized reading'  (duration: 151.639843ms)"],"step_count":1}
May 27 17:44:20.735192 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:20.734450Z","caller":"traceutil/trace.go:171","msg":"trace[1581668616] range","detail":"{range_begin:/registry/rolebindings/kube-system/calico-apiserver-auth-reader; range_end:; response_count:1; response_revision:703; }","duration":"100.707838ms","start":"2025-05-27T17:44:20.633736Z","end":"2025-05-27T17:44:20.734443Z","steps":["trace[1581668616] 'agreement among raft nodes before linearized reading'  (duration: 100.665529ms)"],"step_count":1}
May 27 17:44:30.775118 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:30.774597Z","caller":"traceutil/trace.go:171","msg":"trace[1461165430] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"105.884457ms","start":"2025-05-27T17:44:30.668691Z","end":"2025-05-27T17:44:30.774575Z","steps":["trace[1461165430] 'process raft request'  (duration: 74.020241ms)","trace[1461165430] 'compare'  (duration: 31.729524ms)"],"step_count":2}
May 27 17:44:45.452727 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:45.452390Z","caller":"traceutil/trace.go:171","msg":"trace[95129578] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"155.812838ms","start":"2025-05-27T17:44:45.296559Z","end":"2025-05-27T17:44:45.452372Z","steps":["trace[95129578] 'process raft request'  (duration: 134.065209ms)","trace[95129578] 'compare'  (duration: 21.630664ms)"],"step_count":2}
May 27 17:44:46.950546 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:46.950113Z","caller":"traceutil/trace.go:171","msg":"trace[2044911513] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"146.632675ms","start":"2025-05-27T17:44:46.803450Z","end":"2025-05-27T17:44:46.950083Z","steps":["trace[2044911513] 'process raft request'  (duration: 67.834588ms)","trace[2044911513] 'compare'  (duration: 78.546907ms)"],"step_count":2}
May 27 17:44:52.231128 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:52.230717Z","caller":"traceutil/trace.go:171","msg":"trace[1253505742] linearizableReadLoop","detail":"{readStateIndex:1159; appliedIndex:1158; }","duration":"102.373511ms","start":"2025-05-27T17:44:52.128323Z","end":"2025-05-27T17:44:52.230697Z","steps":["trace[1253505742] 'read index received'  (duration: 38.247253ms)","trace[1253505742] 'applied index is now lower than readState.Index'  (duration: 64.125717ms)"],"step_count":2}
May 27 17:44:52.231128 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:52.230752Z","caller":"traceutil/trace.go:171","msg":"trace[75951582] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"109.938802ms","start":"2025-05-27T17:44:52.120786Z","end":"2025-05-27T17:44:52.230725Z","steps":["trace[75951582] 'process raft request'  (duration: 45.783888ms)","trace[75951582] 'compare'  (duration: 64.026759ms)"],"step_count":2}
May 27 17:44:52.231128 etcd-wrapper[1838]: {"level":"warn","ts":"2025-05-27T17:44:52.230903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.547103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/calico-apiserver-webhook-reader\" limit:1 ","response":"range_response_count:1 size:663"}
May 27 17:44:52.231803 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:52.230938Z","caller":"traceutil/trace.go:171","msg":"trace[1768504818] range","detail":"{range_begin:/registry/clusterrolebindings/calico-apiserver-webhook-reader; range_end:; response_count:1; response_revision:1059; }","duration":"102.646173ms","start":"2025-05-27T17:44:52.128282Z","end":"2025-05-27T17:44:52.230928Z","steps":["trace[1768504818] 'agreement among raft nodes before linearized reading'  (duration: 102.486749ms)"],"step_count":1}
May 27 17:44:53.624429 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:53.623599Z","caller":"traceutil/trace.go:171","msg":"trace[133903723] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"119.109083ms","start":"2025-05-27T17:44:53.504460Z","end":"2025-05-27T17:44:53.623569Z","steps":["trace[133903723] 'process raft request'  (duration: 86.658011ms)","trace[133903723] 'compare'  (duration: 32.305124ms)"],"step_count":2}
May 27 17:44:53.624429 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:53.623764Z","caller":"traceutil/trace.go:171","msg":"trace[1879308086] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"119.181692ms","start":"2025-05-27T17:44:53.504561Z","end":"2025-05-27T17:44:53.623742Z","steps":["trace[1879308086] 'process raft request'  (duration: 118.953886ms)"],"step_count":1}
May 27 17:44:53.624429 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:53.623961Z","caller":"traceutil/trace.go:171","msg":"trace[1926918054] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"119.268248ms","start":"2025-05-27T17:44:53.504682Z","end":"2025-05-27T17:44:53.623950Z","steps":["trace[1926918054] 'process raft request'  (duration: 119.009214ms)"],"step_count":1}
May 27 17:44:53.624429 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:53.623977Z","caller":"traceutil/trace.go:171","msg":"trace[173395585] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"100.167145ms","start":"2025-05-27T17:44:53.523802Z","end":"2025-05-27T17:44:53.623969Z","steps":["trace[173395585] 'process raft request'  (duration: 100.146956ms)"],"step_count":1}
May 27 17:44:53.624429 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:44:53.624059Z","caller":"traceutil/trace.go:171","msg":"trace[748985852] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"111.096647ms","start":"2025-05-27T17:44:53.512935Z","end":"2025-05-27T17:44:53.624032Z","steps":["trace[748985852] 'process raft request'  (duration: 110.937444ms)"],"step_count":1}
May 27 17:45:01.149508 update_engine[1478]: I20250527 17:45:01.149436  1478 prefs.cc:52] certificate-report-to-send-update not present in /var/lib/update_engine/prefs
May 27 17:45:01.149508 update_engine[1478]: I20250527 17:45:01.149490  1478 prefs.cc:52] certificate-report-to-send-download not present in /var/lib/update_engine/prefs
May 27 17:45:01.150012 update_engine[1478]: I20250527 17:45:01.149708  1478 prefs.cc:52] aleph-version not present in /var/lib/update_engine/prefs
May 27 17:45:01.150479 update_engine[1478]: I20250527 17:45:01.150418  1478 omaha_request_params.cc:62] Current group set to alpha
May 27 17:45:01.150565 update_engine[1478]: I20250527 17:45:01.150544  1478 update_attempter.cc:499] Already updated boot flags. Skipping.
May 27 17:45:01.150565 update_engine[1478]: I20250527 17:45:01.150560  1478 update_attempter.cc:643] Scheduling an action processor start.
May 27 17:45:01.150607 update_engine[1478]: I20250527 17:45:01.150578  1478 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction
May 27 17:45:01.150635 update_engine[1478]: I20250527 17:45:01.150606  1478 prefs.cc:52] previous-version not present in /var/lib/update_engine/prefs
May 27 17:45:01.150686 update_engine[1478]: I20250527 17:45:01.150666  1478 omaha_request_action.cc:271] Posting an Omaha request to disabled
May 27 17:45:01.150686 update_engine[1478]: I20250527 17:45:01.150678  1478 omaha_request_action.cc:272] Request: <?xml version="1.0" encoding="UTF-8"?>
May 27 17:45:01.150686 update_engine[1478]: <request protocol="3.0" version="update_engine-0.4.10" updaterversion="update_engine-0.4.10" installsource="scheduler" ismachine="1">
May 27 17:45:01.150686 update_engine[1478]:     <os version="Chateau" platform="CoreOS" sp="4344.0.0_x86_64"></os>
May 27 17:45:01.150686 update_engine[1478]:     <app appid="{e96281a6-d1af-4bde-9a0a-97b76e56dc57}" version="4344.0.0" track="alpha" bootid="{ce2e9dfa-c64b-434d-b068-1ff21693c352}" oem="" oemversion="" alephversion="4344.0.0" machineid="5584fcede1904795a5322be231bd9024" machinealias="" lang="en-US" board="amd64-usr" hardware_class="" delta_okay="false" >
May 27 17:45:01.150686 update_engine[1478]:         <ping active="1"></ping>
May 27 17:45:01.150686 update_engine[1478]:         <updatecheck></updatecheck>
May 27 17:45:01.150686 update_engine[1478]:         <event eventtype="3" eventresult="2" previousversion="0.0.0.0"></event>
May 27 17:45:01.150686 update_engine[1478]:     </app>
May 27 17:45:01.150686 update_engine[1478]: </request>
May 27 17:45:01.150909 update_engine[1478]: I20250527 17:45:01.150687  1478 libcurl_http_fetcher.cc:47] Starting/Resuming transfer
May 27 17:45:01.150965 locksmithd[1511]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_CHECKING_FOR_UPDATE" NewVersion=0.0.0 NewSize=0
May 27 17:45:01.151959 update_engine[1478]: I20250527 17:45:01.151917  1478 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP
May 27 17:45:01.152285 update_engine[1478]: I20250527 17:45:01.152256  1478 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds.
May 27 17:45:01.216153 update_engine[1478]: E20250527 17:45:01.216063  1478 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled
May 27 17:45:01.216294 update_engine[1478]: I20250527 17:45:01.216192  1478 libcurl_http_fetcher.cc:283] No HTTP response, retry 1
May 27 17:45:11.156540 update_engine[1478]: I20250527 17:45:11.156440  1478 libcurl_http_fetcher.cc:47] Starting/Resuming transfer
May 27 17:45:11.157083 update_engine[1478]: I20250527 17:45:11.156772  1478 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP
May 27 17:45:11.157083 update_engine[1478]: I20250527 17:45:11.157048  1478 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds.
May 27 17:45:11.184761 update_engine[1478]: E20250527 17:45:11.184695  1478 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled
May 27 17:45:11.184861 update_engine[1478]: I20250527 17:45:11.184777  1478 libcurl_http_fetcher.cc:283] No HTTP response, retry 2
May 27 17:45:21.151593 update_engine[1478]: I20250527 17:45:21.151510  1478 libcurl_http_fetcher.cc:47] Starting/Resuming transfer
May 27 17:45:21.152091 update_engine[1478]: I20250527 17:45:21.151791  1478 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP
May 27 17:45:21.152091 update_engine[1478]: I20250527 17:45:21.152071  1478 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds.
May 27 17:45:21.181898 update_engine[1478]: E20250527 17:45:21.181816  1478 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled
May 27 17:45:21.181898 update_engine[1478]: I20250527 17:45:21.181905  1478 libcurl_http_fetcher.cc:283] No HTTP response, retry 3
May 27 17:45:22.071907 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:45:22.071490Z","caller":"traceutil/trace.go:171","msg":"trace[1133954158] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"109.615158ms","start":"2025-05-27T17:45:21.961856Z","end":"2025-05-27T17:45:22.071471Z","steps":["trace[1133954158] 'process raft request'  (duration: 109.575653ms)"],"step_count":1}
May 27 17:45:22.071907 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:45:22.071513Z","caller":"traceutil/trace.go:171","msg":"trace[491960738] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"115.26088ms","start":"2025-05-27T17:45:21.956239Z","end":"2025-05-27T17:45:22.071500Z","steps":["trace[491960738] 'process raft request'  (duration: 42.219747ms)","trace[491960738] 'compare'  (duration: 72.830675ms)"],"step_count":2}
May 27 17:45:31.154494 update_engine[1478]: I20250527 17:45:31.154395  1478 libcurl_http_fetcher.cc:47] Starting/Resuming transfer
May 27 17:45:31.155059 update_engine[1478]: I20250527 17:45:31.154737  1478 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP
May 27 17:45:31.155059 update_engine[1478]: I20250527 17:45:31.155014  1478 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds.
May 27 17:45:31.165582 update_engine[1478]: E20250527 17:45:31.165532  1478 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled
May 27 17:45:31.165659 update_engine[1478]: I20250527 17:45:31.165586  1478 libcurl_http_fetcher.cc:297] Transfer resulted in an error (0), 0 bytes downloaded
May 27 17:45:31.165659 update_engine[1478]: I20250527 17:45:31.165596  1478 omaha_request_action.cc:617] Omaha request response:
May 27 17:45:31.165725 update_engine[1478]: E20250527 17:45:31.165701  1478 omaha_request_action.cc:636] Omaha request network transfer failed.
May 27 17:45:31.165752 update_engine[1478]: I20250527 17:45:31.165724  1478 action_processor.cc:68] ActionProcessor::ActionComplete: OmahaRequestAction action failed. Aborting processing.
May 27 17:45:31.165752 update_engine[1478]: I20250527 17:45:31.165732  1478 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction
May 27 17:45:31.165752 update_engine[1478]: I20250527 17:45:31.165740  1478 update_attempter.cc:306] Processing Done.
May 27 17:45:31.165839 update_engine[1478]: E20250527 17:45:31.165755  1478 update_attempter.cc:619] Update failed.
May 27 17:45:31.165839 update_engine[1478]: I20250527 17:45:31.165763  1478 utils.cc:600] Converting error code 2000 to kActionCodeOmahaErrorInHTTPResponse
May 27 17:45:31.165839 update_engine[1478]: I20250527 17:45:31.165771  1478 payload_state.cc:97] Updating payload state for error code: 37 (kActionCodeOmahaErrorInHTTPResponse)
May 27 17:45:31.165839 update_engine[1478]: I20250527 17:45:31.165779  1478 payload_state.cc:103] Ignoring failures until we get a valid Omaha response.
May 27 17:45:31.165948 update_engine[1478]: I20250527 17:45:31.165869  1478 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction
May 27 17:45:31.165948 update_engine[1478]: I20250527 17:45:31.165894  1478 omaha_request_action.cc:271] Posting an Omaha request to disabled
May 27 17:45:31.165948 update_engine[1478]: I20250527 17:45:31.165902  1478 omaha_request_action.cc:272] Request: <?xml version="1.0" encoding="UTF-8"?>
May 27 17:45:31.165948 update_engine[1478]: <request protocol="3.0" version="update_engine-0.4.10" updaterversion="update_engine-0.4.10" installsource="scheduler" ismachine="1">
May 27 17:45:31.165948 update_engine[1478]:     <os version="Chateau" platform="CoreOS" sp="4344.0.0_x86_64"></os>
May 27 17:45:31.165948 update_engine[1478]:     <app appid="{e96281a6-d1af-4bde-9a0a-97b76e56dc57}" version="4344.0.0" track="alpha" bootid="{ce2e9dfa-c64b-434d-b068-1ff21693c352}" oem="" oemversion="" alephversion="4344.0.0" machineid="5584fcede1904795a5322be231bd9024" machinealias="" lang="en-US" board="amd64-usr" hardware_class="" delta_okay="false" >
May 27 17:45:31.165948 update_engine[1478]:         <event eventtype="3" eventresult="0" errorcode="268437456"></event>
May 27 17:45:31.165948 update_engine[1478]:     </app>
May 27 17:45:31.165948 update_engine[1478]: </request>
May 27 17:45:31.165948 update_engine[1478]: I20250527 17:45:31.165910  1478 libcurl_http_fetcher.cc:47] Starting/Resuming transfer
May 27 17:45:31.166207 update_engine[1478]: I20250527 17:45:31.166078  1478 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP
May 27 17:45:31.166317 locksmithd[1511]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_REPORTING_ERROR_EVENT" NewVersion=0.0.0 NewSize=0
May 27 17:45:31.166612 update_engine[1478]: I20250527 17:45:31.166285  1478 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds.
May 27 17:45:31.175227 update_engine[1478]: E20250527 17:45:31.175122  1478 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled
May 27 17:45:31.175398 update_engine[1478]: I20250527 17:45:31.175244  1478 libcurl_http_fetcher.cc:297] Transfer resulted in an error (0), 0 bytes downloaded
May 27 17:45:31.175398 update_engine[1478]: I20250527 17:45:31.175257  1478 omaha_request_action.cc:617] Omaha request response:
May 27 17:45:31.175398 update_engine[1478]: I20250527 17:45:31.175267  1478 action_processor.cc:65] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction
May 27 17:45:31.175398 update_engine[1478]: I20250527 17:45:31.175274  1478 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction
May 27 17:45:31.175398 update_engine[1478]: I20250527 17:45:31.175282  1478 update_attempter.cc:306] Processing Done.
May 27 17:45:31.175398 update_engine[1478]: I20250527 17:45:31.175290  1478 update_attempter.cc:310] Error event sent.
May 27 17:45:31.175398 update_engine[1478]: I20250527 17:45:31.175341  1478 update_check_scheduler.cc:74] Next update check in 47m16s
May 27 17:45:31.175893 locksmithd[1511]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_IDLE" NewVersion=0.0.0 NewSize=0
May 27 17:45:33.064183 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:45:33.063731Z","caller":"traceutil/trace.go:171","msg":"trace[1376744276] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"101.776538ms","start":"2025-05-27T17:45:32.961927Z","end":"2025-05-27T17:45:33.063704Z","steps":["trace[1376744276] 'process raft request'  (duration: 91.80287ms)"],"step_count":1}
May 27 17:45:35.344857 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:45:35.344410Z","caller":"traceutil/trace.go:171","msg":"trace[681464733] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"112.112929ms","start":"2025-05-27T17:45:35.232273Z","end":"2025-05-27T17:45:35.344386Z","steps":["trace[681464733] 'process raft request'  (duration: 99.280989ms)","trace[681464733] 'compare'  (duration: 12.709308ms)"],"step_count":2}
May 27 17:45:42.489931 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:45:42.489543Z","caller":"traceutil/trace.go:171","msg":"trace[1500509095] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"132.77625ms","start":"2025-05-27T17:45:42.356746Z","end":"2025-05-27T17:45:42.489523Z","steps":["trace[1500509095] 'process raft request'  (duration: 100.370325ms)","trace[1500509095] 'compare'  (duration: 32.310385ms)"],"step_count":2}
May 27 17:45:44.142317 etcd-wrapper[1838]: {"level":"info","ts":"2025-05-27T17:45:44.141972Z","caller":"traceutil/trace.go:171","msg":"trace[1894141589] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"135.554767ms","start":"2025-05-27T17:45:44.006395Z","end":"2025-05-27T17:45:44.141949Z","steps":["trace[1894141589] 'process raft request'  (duration: 75.346782ms)","trace[1894141589] 'compare'  (duration: 60.034177ms)"],"step_count":2}