Dec 12 18:33:57.897308 kernel: Linux version 6.12.61-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.0 p8) 14.3.0, GNU ld (Gentoo 2.44 p4) 2.44.0) #1 SMP PREEMPT_DYNAMIC Fri Dec 12 15:21:28 -00 2025
Dec 12 18:33:57.897329 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=a214a2d85e162c493e8b13db2df50a43e1005a0e4854a1ae089a14f442a30022
Dec 12 18:33:57.897340 kernel: BIOS-provided physical RAM map:
Dec 12 18:33:57.897346 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
Dec 12 18:33:57.897353 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
Dec 12 18:33:57.897359 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
Dec 12 18:33:57.897366 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000009cfdbfff] usable
Dec 12 18:33:57.897373 kernel: BIOS-e820: [mem 0x000000009cfdc000-0x000000009cffffff] reserved
Dec 12 18:33:57.897379 kernel: BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved
Dec 12 18:33:57.897385 kernel: BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved
Dec 12 18:33:57.897392 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
Dec 12 18:33:57.897400 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
Dec 12 18:33:57.897406 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved
Dec 12 18:33:57.897413 kernel: NX (Execute Disable) protection: active
Dec 12 18:33:57.897421 kernel: APIC: Static calls initialized
Dec 12 18:33:57.897428 kernel: SMBIOS 2.8 present.
Dec 12 18:33:57.897437 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.16.2-debian-1.16.2-1 04/01/2014
Dec 12 18:33:57.897444 kernel: DMI: Memory slots populated: 1/1
Dec 12 18:33:57.897450 kernel: Hypervisor detected: KVM
Dec 12 18:33:57.897457 kernel: last_pfn = 0x9cfdc max_arch_pfn = 0x400000000
Dec 12 18:33:57.897464 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00
Dec 12 18:33:57.897471 kernel: kvm-clock: using sched offset of 3870286239 cycles
Dec 12 18:33:57.897478 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
Dec 12 18:33:57.897485 kernel: tsc: Detected 2794.748 MHz processor
Dec 12 18:33:57.897493 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
Dec 12 18:33:57.897507 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable
Dec 12 18:33:57.897516 kernel: last_pfn = 0x9cfdc max_arch_pfn = 0x400000000
Dec 12 18:33:57.897523 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs
Dec 12 18:33:57.897530 kernel: x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT  
Dec 12 18:33:57.897538 kernel: Using GB pages for direct mapping
Dec 12 18:33:57.897545 kernel: ACPI: Early table checksum verification disabled
Dec 12 18:33:57.897552 kernel: ACPI: RSDP 0x00000000000F59D0 000014 (v00 BOCHS )
Dec 12 18:33:57.897559 kernel: ACPI: RSDT 0x000000009CFE241A 000038 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
Dec 12 18:33:57.897566 kernel: ACPI: FACP 0x000000009CFE21FA 0000F4 (v03 BOCHS  BXPC     00000001 BXPC 00000001)
Dec 12 18:33:57.897573 kernel: ACPI: DSDT 0x000000009CFE0040 0021BA (v01 BOCHS  BXPC     00000001 BXPC 00000001)
Dec 12 18:33:57.897582 kernel: ACPI: FACS 0x000000009CFE0000 000040
Dec 12 18:33:57.898230 kernel: ACPI: APIC 0x000000009CFE22EE 000090 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
Dec 12 18:33:57.898243 kernel: ACPI: HPET 0x000000009CFE237E 000038 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
Dec 12 18:33:57.898250 kernel: ACPI: MCFG 0x000000009CFE23B6 00003C (v01 BOCHS  BXPC     00000001 BXPC 00000001)
Dec 12 18:33:57.898257 kernel: ACPI: WAET 0x000000009CFE23F2 000028 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
Dec 12 18:33:57.898269 kernel: ACPI: Reserving FACP table memory at [mem 0x9cfe21fa-0x9cfe22ed]
Dec 12 18:33:57.898276 kernel: ACPI: Reserving DSDT table memory at [mem 0x9cfe0040-0x9cfe21f9]
Dec 12 18:33:57.898285 kernel: ACPI: Reserving FACS table memory at [mem 0x9cfe0000-0x9cfe003f]
Dec 12 18:33:57.898292 kernel: ACPI: Reserving APIC table memory at [mem 0x9cfe22ee-0x9cfe237d]
Dec 12 18:33:57.898299 kernel: ACPI: Reserving HPET table memory at [mem 0x9cfe237e-0x9cfe23b5]
Dec 12 18:33:57.898306 kernel: ACPI: Reserving MCFG table memory at [mem 0x9cfe23b6-0x9cfe23f1]
Dec 12 18:33:57.898314 kernel: ACPI: Reserving WAET table memory at [mem 0x9cfe23f2-0x9cfe2419]
Dec 12 18:33:57.898321 kernel: No NUMA configuration found
Dec 12 18:33:57.898328 kernel: Faking a node at [mem 0x0000000000000000-0x000000009cfdbfff]
Dec 12 18:33:57.898337 kernel: NODE_DATA(0) allocated [mem 0x9cfd4dc0-0x9cfdbfff]
Dec 12 18:33:57.898344 kernel: Zone ranges:
Dec 12 18:33:57.898352 kernel:   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
Dec 12 18:33:57.898359 kernel:   DMA32    [mem 0x0000000001000000-0x000000009cfdbfff]
Dec 12 18:33:57.898366 kernel:   Normal   empty
Dec 12 18:33:57.898373 kernel:   Device   empty
Dec 12 18:33:57.898380 kernel: Movable zone start for each node
Dec 12 18:33:57.898387 kernel: Early memory node ranges
Dec 12 18:33:57.898394 kernel:   node   0: [mem 0x0000000000001000-0x000000000009efff]
Dec 12 18:33:57.898401 kernel:   node   0: [mem 0x0000000000100000-0x000000009cfdbfff]
Dec 12 18:33:57.898410 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009cfdbfff]
Dec 12 18:33:57.898417 kernel: On node 0, zone DMA: 1 pages in unavailable ranges
Dec 12 18:33:57.898425 kernel: On node 0, zone DMA: 97 pages in unavailable ranges
Dec 12 18:33:57.898432 kernel: On node 0, zone DMA32: 12324 pages in unavailable ranges
Dec 12 18:33:57.898439 kernel: ACPI: PM-Timer IO Port: 0x608
Dec 12 18:33:57.898446 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
Dec 12 18:33:57.898453 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
Dec 12 18:33:57.898460 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
Dec 12 18:33:57.898468 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
Dec 12 18:33:57.898486 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
Dec 12 18:33:57.898501 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
Dec 12 18:33:57.898508 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
Dec 12 18:33:57.898516 kernel: ACPI: Using ACPI (MADT) for SMP configuration information
Dec 12 18:33:57.898523 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000
Dec 12 18:33:57.898530 kernel: TSC deadline timer available
Dec 12 18:33:57.898538 kernel: CPU topo: Max. logical packages:   1
Dec 12 18:33:57.898545 kernel: CPU topo: Max. logical dies:       1
Dec 12 18:33:57.898552 kernel: CPU topo: Max. dies per package:   1
Dec 12 18:33:57.898561 kernel: CPU topo: Max. threads per core:   1
Dec 12 18:33:57.898568 kernel: CPU topo: Num. cores per package:     4
Dec 12 18:33:57.898575 kernel: CPU topo: Num. threads per package:   4
Dec 12 18:33:57.898582 kernel: CPU topo: Allowing 4 present CPUs plus 0 hotplug CPUs
Dec 12 18:33:57.898590 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()
Dec 12 18:33:57.898597 kernel: kvm-guest: KVM setup pv remote TLB flush
Dec 12 18:33:57.898604 kernel: kvm-guest: setup PV sched yield
Dec 12 18:33:57.898611 kernel: [mem 0xc0000000-0xfed1bfff] available for PCI devices
Dec 12 18:33:57.898618 kernel: Booting paravirtualized kernel on KVM
Dec 12 18:33:57.898625 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
Dec 12 18:33:57.898635 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1
Dec 12 18:33:57.898642 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u524288
Dec 12 18:33:57.898649 kernel: pcpu-alloc: s207832 r8192 d29736 u524288 alloc=1*2097152
Dec 12 18:33:57.898656 kernel: pcpu-alloc: [0] 0 1 2 3 
Dec 12 18:33:57.898663 kernel: kvm-guest: PV spinlocks enabled
Dec 12 18:33:57.898671 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear)
Dec 12 18:33:57.898679 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=a214a2d85e162c493e8b13db2df50a43e1005a0e4854a1ae089a14f442a30022
Dec 12 18:33:57.898687 kernel: random: crng init done
Dec 12 18:33:57.898696 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
Dec 12 18:33:57.898703 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
Dec 12 18:33:57.898710 kernel: Fallback order for Node 0: 0 
Dec 12 18:33:57.898717 kernel: Built 1 zonelists, mobility grouping on.  Total pages: 642938
Dec 12 18:33:57.898724 kernel: Policy zone: DMA32
Dec 12 18:33:57.898732 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off
Dec 12 18:33:57.898739 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
Dec 12 18:33:57.898746 kernel: ftrace: allocating 40103 entries in 157 pages
Dec 12 18:33:57.898753 kernel: ftrace: allocated 157 pages with 5 groups
Dec 12 18:33:57.898762 kernel: Dynamic Preempt: voluntary
Dec 12 18:33:57.898769 kernel: rcu: Preemptible hierarchical RCU implementation.
Dec 12 18:33:57.898778 kernel: rcu:         RCU event tracing is enabled.
Dec 12 18:33:57.898787 kernel: rcu:         RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4.
Dec 12 18:33:57.898795 kernel:         Trampoline variant of Tasks RCU enabled.
Dec 12 18:33:57.898804 kernel:         Rude variant of Tasks RCU enabled.
Dec 12 18:33:57.898811 kernel:         Tracing variant of Tasks RCU enabled.
Dec 12 18:33:57.898818 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
Dec 12 18:33:57.898825 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
Dec 12 18:33:57.898833 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4.
Dec 12 18:33:57.898842 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4.
Dec 12 18:33:57.898849 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4.
Dec 12 18:33:57.898856 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16
Dec 12 18:33:57.898864 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention.
Dec 12 18:33:57.898878 kernel: Console: colour VGA+ 80x25
Dec 12 18:33:57.898887 kernel: printk: legacy console [ttyS0] enabled
Dec 12 18:33:57.898894 kernel: ACPI: Core revision 20240827
Dec 12 18:33:57.898902 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
Dec 12 18:33:57.898909 kernel: APIC: Switch to symmetric I/O mode setup
Dec 12 18:33:57.898916 kernel: x2apic enabled
Dec 12 18:33:57.898924 kernel: APIC: Switched APIC routing to: physical x2apic
Dec 12 18:33:57.898934 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask()
Dec 12 18:33:57.898941 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself()
Dec 12 18:33:57.898949 kernel: kvm-guest: setup PV IPIs
Dec 12 18:33:57.898956 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
Dec 12 18:33:57.898964 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x2848df6a9de, max_idle_ns: 440795280912 ns
Dec 12 18:33:57.898973 kernel: Calibrating delay loop (skipped) preset value.. 5589.49 BogoMIPS (lpj=2794748)
Dec 12 18:33:57.898981 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated
Dec 12 18:33:57.898988 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127
Dec 12 18:33:57.898996 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0
Dec 12 18:33:57.899003 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
Dec 12 18:33:57.899011 kernel: Spectre V2 : Mitigation: Retpolines
Dec 12 18:33:57.899018 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT
Dec 12 18:33:57.899026 kernel: Spectre V2 : Enabling Speculation Barrier for firmware calls
Dec 12 18:33:57.899033 kernel: active return thunk: retbleed_return_thunk
Dec 12 18:33:57.899042 kernel: RETBleed: Mitigation: untrained return thunk
Dec 12 18:33:57.899050 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier
Dec 12 18:33:57.899057 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl
Dec 12 18:33:57.899065 kernel: Speculative Return Stack Overflow: IBPB-extending microcode not applied!
Dec 12 18:33:57.899073 kernel: Speculative Return Stack Overflow: WARNING: See https://kernel.org/doc/html/latest/admin-guide/hw-vuln/srso.html for mitigation options.
Dec 12 18:33:57.899080 kernel: active return thunk: srso_return_thunk
Dec 12 18:33:57.899088 kernel: Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode
Dec 12 18:33:57.899095 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
Dec 12 18:33:57.899105 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
Dec 12 18:33:57.899112 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
Dec 12 18:33:57.899120 kernel: x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256
Dec 12 18:33:57.899127 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format.
Dec 12 18:33:57.899135 kernel: Freeing SMP alternatives memory: 32K
Dec 12 18:33:57.899142 kernel: pid_max: default: 32768 minimum: 301
Dec 12 18:33:57.899149 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima
Dec 12 18:33:57.899157 kernel: landlock: Up and running.
Dec 12 18:33:57.899164 kernel: SELinux:  Initializing.
Dec 12 18:33:57.899174 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
Dec 12 18:33:57.899181 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
Dec 12 18:33:57.899189 kernel: smpboot: CPU0: AMD EPYC 7402P 24-Core Processor (family: 0x17, model: 0x31, stepping: 0x0)
Dec 12 18:33:57.899196 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver.
Dec 12 18:33:57.899811 kernel: ... version:                0
Dec 12 18:33:57.899820 kernel: ... bit width:              48
Dec 12 18:33:57.899828 kernel: ... generic registers:      6
Dec 12 18:33:57.899836 kernel: ... value mask:             0000ffffffffffff
Dec 12 18:33:57.899843 kernel: ... max period:             00007fffffffffff
Dec 12 18:33:57.899854 kernel: ... fixed-purpose events:   0
Dec 12 18:33:57.899861 kernel: ... event mask:             000000000000003f
Dec 12 18:33:57.899869 kernel: signal: max sigframe size: 1776
Dec 12 18:33:57.899876 kernel: rcu: Hierarchical SRCU implementation.
Dec 12 18:33:57.899884 kernel: rcu:         Max phase no-delay instances is 400.
Dec 12 18:33:57.899892 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level
Dec 12 18:33:57.899900 kernel: smp: Bringing up secondary CPUs ...
Dec 12 18:33:57.899907 kernel: smpboot: x86: Booting SMP configuration:
Dec 12 18:33:57.899914 kernel: .... node  #0, CPUs:      #1 #2 #3
Dec 12 18:33:57.899924 kernel: smp: Brought up 1 node, 4 CPUs
Dec 12 18:33:57.899932 kernel: smpboot: Total of 4 processors activated (22357.98 BogoMIPS)
Dec 12 18:33:57.899940 kernel: Memory: 2420720K/2571752K available (14336K kernel code, 2444K rwdata, 26064K rodata, 46188K init, 2572K bss, 145096K reserved, 0K cma-reserved)
Dec 12 18:33:57.899947 kernel: devtmpfs: initialized
Dec 12 18:33:57.899955 kernel: x86/mm: Memory block size: 128MB
Dec 12 18:33:57.899963 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
Dec 12 18:33:57.899970 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
Dec 12 18:33:57.899978 kernel: pinctrl core: initialized pinctrl subsystem
Dec 12 18:33:57.899985 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family
Dec 12 18:33:57.899995 kernel: audit: initializing netlink subsys (disabled)
Dec 12 18:33:57.900002 kernel: audit: type=2000 audit(1765564434.562:1): state=initialized audit_enabled=0 res=1
Dec 12 18:33:57.900010 kernel: thermal_sys: Registered thermal governor 'step_wise'
Dec 12 18:33:57.900017 kernel: thermal_sys: Registered thermal governor 'user_space'
Dec 12 18:33:57.900025 kernel: cpuidle: using governor menu
Dec 12 18:33:57.900032 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
Dec 12 18:33:57.900040 kernel: dca service started, version 1.12.1
Dec 12 18:33:57.900047 kernel: PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]
Dec 12 18:33:57.900055 kernel: PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry
Dec 12 18:33:57.900065 kernel: PCI: Using configuration type 1 for base access
Dec 12 18:33:57.900073 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.
Dec 12 18:33:57.900080 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages
Dec 12 18:33:57.900088 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page
Dec 12 18:33:57.900095 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
Dec 12 18:33:57.900103 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page
Dec 12 18:33:57.900110 kernel: ACPI: Added _OSI(Module Device)
Dec 12 18:33:57.900117 kernel: ACPI: Added _OSI(Processor Device)
Dec 12 18:33:57.900125 kernel: ACPI: Added _OSI(Processor Aggregator Device)
Dec 12 18:33:57.900134 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded
Dec 12 18:33:57.900142 kernel: ACPI: Interpreter enabled
Dec 12 18:33:57.900149 kernel: ACPI: PM: (supports S0 S3 S5)
Dec 12 18:33:57.900157 kernel: ACPI: Using IOAPIC for interrupt routing
Dec 12 18:33:57.900164 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
Dec 12 18:33:57.900172 kernel: PCI: Using E820 reservations for host bridge windows
Dec 12 18:33:57.900179 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F
Dec 12 18:33:57.900186 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
Dec 12 18:33:57.900367 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]
Dec 12 18:33:57.900491 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]
Dec 12 18:33:57.900618 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]
Dec 12 18:33:57.900628 kernel: PCI host bridge to bus 0000:00
Dec 12 18:33:57.900745 kernel: pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
Dec 12 18:33:57.900862 kernel: pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
Dec 12 18:33:57.900967 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
Dec 12 18:33:57.901079 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xafffffff window]
Dec 12 18:33:57.901183 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]
Dec 12 18:33:57.901306 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]
Dec 12 18:33:57.901459 kernel: pci_bus 0000:00: root bus resource [bus 00-ff]
Dec 12 18:33:57.901610 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint
Dec 12 18:33:57.901748 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint
Dec 12 18:33:57.903400 kernel: pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]
Dec 12 18:33:57.903532 kernel: pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]
Dec 12 18:33:57.903648 kernel: pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]
Dec 12 18:33:57.903763 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
Dec 12 18:33:57.903888 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint
Dec 12 18:33:57.904003 kernel: pci 0000:00:02.0: BAR 0 [io  0xc0c0-0xc0df]
Dec 12 18:33:57.904118 kernel: pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]
Dec 12 18:33:57.904255 kernel: pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]
Dec 12 18:33:57.904379 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint
Dec 12 18:33:57.904502 kernel: pci 0000:00:03.0: BAR 0 [io  0xc000-0xc07f]
Dec 12 18:33:57.904620 kernel: pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]
Dec 12 18:33:57.904737 kernel: pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]
Dec 12 18:33:57.904861 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint
Dec 12 18:33:57.904976 kernel: pci 0000:00:04.0: BAR 0 [io  0xc0e0-0xc0ff]
Dec 12 18:33:57.905095 kernel: pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]
Dec 12 18:33:57.905231 kernel: pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]
Dec 12 18:33:57.905349 kernel: pci 0000:00:04.0: ROM [mem 0xfeb80000-0xfebbffff pref]
Dec 12 18:33:57.905472 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint
Dec 12 18:33:57.905597 kernel: pci 0000:00:1f.0: quirk: [io  0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO
Dec 12 18:33:57.905718 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint
Dec 12 18:33:57.905833 kernel: pci 0000:00:1f.2: BAR 4 [io  0xc100-0xc11f]
Dec 12 18:33:57.905950 kernel: pci 0000:00:1f.2: BAR 5 [mem 0xfebd4000-0xfebd4fff]
Dec 12 18:33:57.906071 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint
Dec 12 18:33:57.906186 kernel: pci 0000:00:1f.3: BAR 4 [io  0x0700-0x073f]
Dec 12 18:33:57.906196 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10
Dec 12 18:33:57.906222 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10
Dec 12 18:33:57.906230 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11
Dec 12 18:33:57.906237 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11
Dec 12 18:33:57.906248 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10
Dec 12 18:33:57.906256 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10
Dec 12 18:33:57.906263 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11
Dec 12 18:33:57.906271 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11
Dec 12 18:33:57.906278 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16
Dec 12 18:33:57.906286 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17
Dec 12 18:33:57.906293 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18
Dec 12 18:33:57.906300 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19
Dec 12 18:33:57.906308 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20
Dec 12 18:33:57.906317 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21
Dec 12 18:33:57.906324 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22
Dec 12 18:33:57.906332 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23
Dec 12 18:33:57.906339 kernel: iommu: Default domain type: Translated
Dec 12 18:33:57.906347 kernel: iommu: DMA domain TLB invalidation policy: lazy mode
Dec 12 18:33:57.906354 kernel: PCI: Using ACPI for IRQ routing
Dec 12 18:33:57.906362 kernel: PCI: pci_cache_line_size set to 64 bytes
Dec 12 18:33:57.906369 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
Dec 12 18:33:57.906376 kernel: e820: reserve RAM buffer [mem 0x9cfdc000-0x9fffffff]
Dec 12 18:33:57.906504 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device
Dec 12 18:33:57.906620 kernel: pci 0000:00:01.0: vgaarb: bridge control possible
Dec 12 18:33:57.906734 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
Dec 12 18:33:57.906744 kernel: vgaarb: loaded
Dec 12 18:33:57.906751 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
Dec 12 18:33:57.906759 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter
Dec 12 18:33:57.906767 kernel: clocksource: Switched to clocksource kvm-clock
Dec 12 18:33:57.906774 kernel: VFS: Disk quotas dquot_6.6.0
Dec 12 18:33:57.906782 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
Dec 12 18:33:57.906793 kernel: pnp: PnP ACPI init
Dec 12 18:33:57.906922 kernel: system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved
Dec 12 18:33:57.906933 kernel: pnp: PnP ACPI: found 6 devices
Dec 12 18:33:57.906941 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
Dec 12 18:33:57.906949 kernel: NET: Registered PF_INET protocol family
Dec 12 18:33:57.906956 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)
Dec 12 18:33:57.906964 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)
Dec 12 18:33:57.906972 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
Dec 12 18:33:57.906982 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)
Dec 12 18:33:57.906990 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear)
Dec 12 18:33:57.906997 kernel: TCP: Hash tables configured (established 32768 bind 32768)
Dec 12 18:33:57.907005 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)
Dec 12 18:33:57.907012 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)
Dec 12 18:33:57.907020 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family
Dec 12 18:33:57.907028 kernel: NET: Registered PF_XDP protocol family
Dec 12 18:33:57.907134 kernel: pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
Dec 12 18:33:57.907279 kernel: pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
Dec 12 18:33:57.907391 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
Dec 12 18:33:57.907503 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xafffffff window]
Dec 12 18:33:57.907612 kernel: pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]
Dec 12 18:33:57.907716 kernel: pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]
Dec 12 18:33:57.907725 kernel: PCI: CLS 0 bytes, default 64
Dec 12 18:33:57.907733 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x2848df6a9de, max_idle_ns: 440795280912 ns
Dec 12 18:33:57.907741 kernel: Initialise system trusted keyrings
Dec 12 18:33:57.907749 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0
Dec 12 18:33:57.907760 kernel: Key type asymmetric registered
Dec 12 18:33:57.907767 kernel: Asymmetric key parser 'x509' registered
Dec 12 18:33:57.907774 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
Dec 12 18:33:57.907782 kernel: io scheduler mq-deadline registered
Dec 12 18:33:57.907790 kernel: io scheduler kyber registered
Dec 12 18:33:57.907797 kernel: io scheduler bfq registered
Dec 12 18:33:57.907808 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00
Dec 12 18:33:57.907816 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22
Dec 12 18:33:57.907824 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23
Dec 12 18:33:57.907833 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20
Dec 12 18:33:57.907841 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
Dec 12 18:33:57.907848 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
Dec 12 18:33:57.907856 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
Dec 12 18:33:57.907864 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1
Dec 12 18:33:57.907871 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12
Dec 12 18:33:57.907992 kernel: rtc_cmos 00:04: RTC can wake from S4
Dec 12 18:33:57.908003 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
Dec 12 18:33:57.908113 kernel: rtc_cmos 00:04: registered as rtc0
Dec 12 18:33:57.908240 kernel: rtc_cmos 00:04: setting system clock to 2025-12-12T18:33:57 UTC (1765564437)
Dec 12 18:33:57.908349 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs
Dec 12 18:33:57.908360 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled
Dec 12 18:33:57.908368 kernel: NET: Registered PF_INET6 protocol family
Dec 12 18:33:57.908376 kernel: Segment Routing with IPv6
Dec 12 18:33:57.908383 kernel: In-situ OAM (IOAM) with IPv6
Dec 12 18:33:57.908391 kernel: NET: Registered PF_PACKET protocol family
Dec 12 18:33:57.908399 kernel: Key type dns_resolver registered
Dec 12 18:33:57.908410 kernel: IPI shorthand broadcast: enabled
Dec 12 18:33:57.908417 kernel: sched_clock: Marking stable (2927003070, 324422210)->(3306563036, -55137756)
Dec 12 18:33:57.908425 kernel: registered taskstats version 1
Dec 12 18:33:57.908432 kernel: Loading compiled-in X.509 certificates
Dec 12 18:33:57.908439 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.61-flatcar: 0d0c78e6590cb40d27f1cef749ef9f2f3425f38d'
Dec 12 18:33:57.908447 kernel: Demotion targets for Node 0: null
Dec 12 18:33:57.908454 kernel: Key type .fscrypt registered
Dec 12 18:33:57.908472 kernel: Key type fscrypt-provisioning registered
Dec 12 18:33:57.908480 kernel: ima: No TPM chip found, activating TPM-bypass!
Dec 12 18:33:57.908489 kernel: ima: Allocated hash algorithm: sha1
Dec 12 18:33:57.908504 kernel: ima: No architecture policies found
Dec 12 18:33:57.908512 kernel: clk: Disabling unused clocks
Dec 12 18:33:57.908519 kernel: Warning: unable to open an initial console.
Dec 12 18:33:57.908527 kernel: Freeing unused kernel image (initmem) memory: 46188K
Dec 12 18:33:57.908535 kernel: Write protecting the kernel read-only data: 40960k
Dec 12 18:33:57.908542 kernel: Freeing unused kernel image (rodata/data gap) memory: 560K
Dec 12 18:33:57.908550 kernel: Run /init as init process
Dec 12 18:33:57.908557 kernel:   with arguments:
Dec 12 18:33:57.908567 kernel:     /init
Dec 12 18:33:57.908574 kernel:   with environment:
Dec 12 18:33:57.908581 kernel:     HOME=/
Dec 12 18:33:57.908588 kernel:     TERM=linux
Dec 12 18:33:57.908597 systemd[1]: Successfully made /usr/ read-only.
Dec 12 18:33:57.908608 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)
Dec 12 18:33:57.908628 systemd[1]: Detected virtualization kvm.
Dec 12 18:33:57.908636 systemd[1]: Detected architecture x86-64.
Dec 12 18:33:57.908644 systemd[1]: Running in initrd.
Dec 12 18:33:57.908652 systemd[1]: No hostname configured, using default hostname.
Dec 12 18:33:57.908660 systemd[1]: Hostname set to <localhost>.
Dec 12 18:33:57.908668 systemd[1]: Initializing machine ID from VM UUID.
Dec 12 18:33:57.908676 systemd[1]: Queued start job for default target initrd.target.
Dec 12 18:33:57.908685 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
Dec 12 18:33:57.908695 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
Dec 12 18:33:57.908704 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM...
Dec 12 18:33:57.908712 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM...
Dec 12 18:33:57.908721 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT...
Dec 12 18:33:57.908729 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A...
Dec 12 18:33:57.908739 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132...
Dec 12 18:33:57.908749 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr...
Dec 12 18:33:57.908757 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
Dec 12 18:33:57.908765 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes.
Dec 12 18:33:57.908773 systemd[1]: Reached target paths.target - Path Units.
Dec 12 18:33:57.908781 systemd[1]: Reached target slices.target - Slice Units.
Dec 12 18:33:57.908789 systemd[1]: Reached target swap.target - Swaps.
Dec 12 18:33:57.908798 systemd[1]: Reached target timers.target - Timer Units.
Dec 12 18:33:57.908806 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket.
Dec 12 18:33:57.908816 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket.
Dec 12 18:33:57.908826 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log).
Dec 12 18:33:57.908834 systemd[1]: Listening on systemd-journald.socket - Journal Sockets.
Dec 12 18:33:57.908842 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket.
Dec 12 18:33:57.908850 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket.
Dec 12 18:33:57.908858 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket.
Dec 12 18:33:57.908866 systemd[1]: Reached target sockets.target - Socket Units.
Dec 12 18:33:57.908875 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup...
Dec 12 18:33:57.908885 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes...
Dec 12 18:33:57.908893 systemd[1]: Finished network-cleanup.service - Network Cleanup.
Dec 12 18:33:57.908902 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).
Dec 12 18:33:57.908910 systemd[1]: Starting systemd-fsck-usr.service...
Dec 12 18:33:57.908918 systemd[1]: Starting systemd-journald.service - Journal Service...
Dec 12 18:33:57.908926 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules...
Dec 12 18:33:57.908937 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
Dec 12 18:33:57.908948 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup.
Dec 12 18:33:57.908958 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes.
Dec 12 18:33:57.908968 systemd[1]: Finished systemd-fsck-usr.service.
Dec 12 18:33:57.908998 systemd-journald[201]: Collecting audit messages is disabled.
Dec 12 18:33:57.909020 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully...
Dec 12 18:33:57.909028 systemd-journald[201]: Journal started
Dec 12 18:33:57.909050 systemd-journald[201]: Runtime Journal (/run/log/journal/c35380178e3c4992bd53837c62d29057) is 6M, max 48.3M, 42.2M free.
Dec 12 18:33:57.897307 systemd-modules-load[204]: Inserted module 'overlay'
Dec 12 18:33:57.984573 systemd[1]: Started systemd-journald.service - Journal Service.
Dec 12 18:33:57.984598 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
Dec 12 18:33:57.984611 kernel: Bridge firewalling registered
Dec 12 18:33:57.928664 systemd-modules-load[204]: Inserted module 'br_netfilter'
Dec 12 18:33:57.984011 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules.
Dec 12 18:33:57.986727 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup.
Dec 12 18:33:57.990066 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully.
Dec 12 18:33:57.997761 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters...
Dec 12 18:33:57.999761 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables...
Dec 12 18:33:58.012912 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev...
Dec 12 18:33:58.021316 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories...
Dec 12 18:33:58.037332 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
Dec 12 18:33:58.041903 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters.
Dec 12 18:33:58.044441 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables.
Dec 12 18:33:58.048087 systemd-tmpfiles[226]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring.
Dec 12 18:33:58.048219 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook...
Dec 12 18:33:58.061342 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories.
Dec 12 18:33:58.065097 systemd[1]: Starting systemd-resolved.service - Network Name Resolution...
Dec 12 18:33:58.077295 dracut-cmdline[241]: 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=a214a2d85e162c493e8b13db2df50a43e1005a0e4854a1ae089a14f442a30022
Dec 12 18:33:58.104556 systemd-resolved[247]: Positive Trust Anchors:
Dec 12 18:33:58.104575 systemd-resolved[247]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
Dec 12 18:33:58.104610 systemd-resolved[247]: 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
Dec 12 18:33:58.107517 systemd-resolved[247]: Defaulting to hostname 'linux'.
Dec 12 18:33:58.119264 systemd[1]: Started systemd-resolved.service - Network Name Resolution.
Dec 12 18:33:58.122772 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups.
Dec 12 18:33:58.196244 kernel: SCSI subsystem initialized
Dec 12 18:33:58.205231 kernel: Loading iSCSI transport class v2.0-870.
Dec 12 18:33:58.216238 kernel: iscsi: registered transport (tcp)
Dec 12 18:33:58.242666 kernel: iscsi: registered transport (qla4xxx)
Dec 12 18:33:58.242699 kernel: QLogic iSCSI HBA Driver
Dec 12 18:33:58.265224 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line...
Dec 12 18:33:58.290810 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line.
Dec 12 18:33:58.293525 systemd[1]: Reached target network-pre.target - Preparation for Network.
Dec 12 18:33:58.357076 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook.
Dec 12 18:33:58.359636 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook...
Dec 12 18:33:58.415237 kernel: raid6: avx2x4   gen() 29093 MB/s
Dec 12 18:33:58.432230 kernel: raid6: avx2x2   gen() 30499 MB/s
Dec 12 18:33:58.449965 kernel: raid6: avx2x1   gen() 25283 MB/s
Dec 12 18:33:58.449985 kernel: raid6: using algorithm avx2x2 gen() 30499 MB/s
Dec 12 18:33:58.468021 kernel: raid6: .... xor() 19509 MB/s, rmw enabled
Dec 12 18:33:58.468053 kernel: raid6: using avx2x2 recovery algorithm
Dec 12 18:33:58.524236 kernel: xor: automatically using best checksumming function   avx       
Dec 12 18:33:58.689255 kernel: Btrfs loaded, zoned=no, fsverity=no
Dec 12 18:33:58.698454 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook.
Dec 12 18:33:58.700974 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files...
Dec 12 18:33:58.749045 systemd-udevd[455]: Using default interface naming scheme 'v255'.
Dec 12 18:33:58.756688 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files.
Dec 12 18:33:58.759604 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook...
Dec 12 18:33:58.792421 dracut-pre-trigger[465]: rd.md=0: removing MD RAID activation
Dec 12 18:33:58.821349 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook.
Dec 12 18:33:58.825167 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices...
Dec 12 18:33:58.901174 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices.
Dec 12 18:33:58.903495 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook...
Dec 12 18:33:58.950081 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues
Dec 12 18:33:58.955876 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB)
Dec 12 18:33:58.959218 kernel:  vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9
Dec 12 18:33:58.959239 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input2
Dec 12 18:33:58.978396 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
Dec 12 18:33:58.983066 kernel: cryptd: max_cpu_qlen set to 1000
Dec 12 18:33:58.983090 kernel: libata version 3.00 loaded.
Dec 12 18:33:58.978611 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup.
Dec 12 18:33:58.986606 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup...
Dec 12 18:33:58.993484 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
Dec 12 18:33:58.996647 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully.
Dec 12 18:33:59.015730 kernel: AES CTR mode by8 optimization enabled
Dec 12 18:33:59.029939 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM.
Dec 12 18:33:59.037225 kernel: ahci 0000:00:1f.2: version 3.0
Dec 12 18:33:59.037460 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16
Dec 12 18:33:59.038233 kernel: ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode
Dec 12 18:33:59.038419 kernel: ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)
Dec 12 18:33:59.038599 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only 
Dec 12 18:33:59.044224 kernel: scsi host0: ahci
Dec 12 18:33:59.046221 kernel: scsi host1: ahci
Dec 12 18:33:59.046392 kernel: scsi host2: ahci
Dec 12 18:33:59.050295 kernel: scsi host3: ahci
Dec 12 18:33:59.053769 kernel: scsi host4: ahci
Dec 12 18:33:59.053939 kernel: scsi host5: ahci
Dec 12 18:33:59.054095 kernel: ata1: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4100 irq 34 lpm-pol 1
Dec 12 18:33:59.054120 kernel: ata2: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4180 irq 34 lpm-pol 1
Dec 12 18:33:59.054130 kernel: ata3: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4200 irq 34 lpm-pol 1
Dec 12 18:33:59.054140 kernel: ata4: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4280 irq 34 lpm-pol 1
Dec 12 18:33:59.054150 kernel: ata5: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4300 irq 34 lpm-pol 1
Dec 12 18:33:59.054161 kernel: ata6: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4380 irq 34 lpm-pol 1
Dec 12 18:33:59.069905 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM.
Dec 12 18:33:59.129502 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT.
Dec 12 18:33:59.131742 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup.
Dec 12 18:33:59.142578 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132.
Dec 12 18:33:59.144576 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A.
Dec 12 18:33:59.150695 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary...
Dec 12 18:33:59.206229 kernel:  vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9
Dec 12 18:33:59.214235 kernel:  vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9
Dec 12 18:33:59.359245 kernel: ata4: SATA link down (SStatus 0 SControl 300)
Dec 12 18:33:59.359327 kernel: ata6: SATA link down (SStatus 0 SControl 300)
Dec 12 18:33:59.367270 kernel: ata5: SATA link down (SStatus 0 SControl 300)
Dec 12 18:33:59.369238 kernel: ata2: SATA link down (SStatus 0 SControl 300)
Dec 12 18:33:59.369259 kernel: ata1: SATA link down (SStatus 0 SControl 300)
Dec 12 18:33:59.370233 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Dec 12 18:33:59.372175 kernel: ata3.00: LPM support broken, forcing max_power
Dec 12 18:33:59.372192 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
Dec 12 18:33:59.373234 kernel: ata3.00: applying bridge limits
Dec 12 18:33:59.375186 kernel: ata3.00: LPM support broken, forcing max_power
Dec 12 18:33:59.375222 kernel: ata3.00: configured for UDMA/100
Dec 12 18:33:59.378234 kernel: scsi 2:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM     2.5+ PQ: 0 ANSI: 5
Dec 12 18:33:59.430918 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
Dec 12 18:33:59.431166 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20
Dec 12 18:33:59.451232 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0
Dec 12 18:33:59.907777 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook.
Dec 12 18:33:59.909118 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems.
Dec 12 18:33:59.912070 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes.
Dec 12 18:33:59.916055 systemd[1]: Reached target remote-fs.target - Remote File Systems.
Dec 12 18:33:59.920635 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook...
Dec 12 18:33:59.943829 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook.
Dec 12 18:34:00.223247 kernel:  vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9
Dec 12 18:34:00.223846 disk-uuid[618]: The operation has completed successfully.
Dec 12 18:34:00.251533 systemd[1]: disk-uuid.service: Deactivated successfully.
Dec 12 18:34:00.251665 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary.
Dec 12 18:34:00.289634 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr...
Dec 12 18:34:00.321919 sh[647]: Success
Dec 12 18:34:00.342245 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.
Dec 12 18:34:00.342299 kernel: device-mapper: uevent: version 1.0.3
Dec 12 18:34:00.344246 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev
Dec 12 18:34:00.353287 kernel: device-mapper: verity: sha256 using shash "sha256-ni"
Dec 12 18:34:00.382170 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr.
Dec 12 18:34:00.388461 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr...
Dec 12 18:34:00.413633 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr.
Dec 12 18:34:00.423547 kernel: BTRFS: device fsid a6ae7f96-a076-4d3c-81ed-46dd341492f8 devid 1 transid 37 /dev/mapper/usr (253:0) scanned by mount (659)
Dec 12 18:34:00.423568 kernel: BTRFS info (device dm-0): first mount of filesystem a6ae7f96-a076-4d3c-81ed-46dd341492f8
Dec 12 18:34:00.423578 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm
Dec 12 18:34:00.429335 kernel: BTRFS info (device dm-0): disabling log replay at mount time
Dec 12 18:34:00.429360 kernel: BTRFS info (device dm-0): enabling free space tree
Dec 12 18:34:00.430965 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr.
Dec 12 18:34:00.434320 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System.
Dec 12 18:34:00.436349 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met.
Dec 12 18:34:00.438237 systemd[1]: Starting ignition-setup.service - Ignition (setup)...
Dec 12 18:34:00.439840 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline...
Dec 12 18:34:00.481418 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (692)
Dec 12 18:34:00.481482 kernel: BTRFS info (device vda6): first mount of filesystem 7e9ead35-f0ec-40e8-bc31-5061934f865a
Dec 12 18:34:00.483262 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm
Dec 12 18:34:00.489287 kernel: BTRFS info (device vda6): turning on async discard
Dec 12 18:34:00.489336 kernel: BTRFS info (device vda6): enabling free space tree
Dec 12 18:34:00.496247 kernel: BTRFS info (device vda6): last unmount of filesystem 7e9ead35-f0ec-40e8-bc31-5061934f865a
Dec 12 18:34:00.497450 systemd[1]: Finished ignition-setup.service - Ignition (setup).
Dec 12 18:34:00.502796 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)...
Dec 12 18:34:00.590313 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline.
Dec 12 18:34:00.597097 systemd[1]: Starting systemd-networkd.service - Network Configuration...
Dec 12 18:34:00.601603 ignition[737]: Ignition 2.22.0
Dec 12 18:34:00.601616 ignition[737]: Stage: fetch-offline
Dec 12 18:34:00.601645 ignition[737]: no configs at "/usr/lib/ignition/base.d"
Dec 12 18:34:00.601654 ignition[737]: no config dir at "/usr/lib/ignition/base.platform.d/qemu"
Dec 12 18:34:00.601739 ignition[737]: parsed url from cmdline: ""
Dec 12 18:34:00.601743 ignition[737]: no config URL provided
Dec 12 18:34:00.601748 ignition[737]: reading system config file "/usr/lib/ignition/user.ign"
Dec 12 18:34:00.601757 ignition[737]: no config at "/usr/lib/ignition/user.ign"
Dec 12 18:34:00.601777 ignition[737]: op(1): [started]  loading QEMU firmware config module
Dec 12 18:34:00.601783 ignition[737]: op(1): executing: "modprobe" "qemu_fw_cfg"
Dec 12 18:34:00.608909 ignition[737]: op(1): [finished] loading QEMU firmware config module
Dec 12 18:34:00.609556 ignition[737]: parsing config with SHA512: 05e5d891cce1a23fb9d4510aa137f93809781bf92cf7e3208083d5e58708813d2f83c79700e71970e035eff71ad2a2eff3b24e832b2e9bf9608d6bcd464c8daf
Dec 12 18:34:00.616632 unknown[737]: fetched base config from "system"
Dec 12 18:34:00.616644 unknown[737]: fetched user config from "qemu"
Dec 12 18:34:00.616815 ignition[737]: fetch-offline: fetch-offline passed
Dec 12 18:34:00.616882 ignition[737]: Ignition finished successfully
Dec 12 18:34:00.619896 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline).
Dec 12 18:34:00.662563 systemd-networkd[832]: lo: Link UP
Dec 12 18:34:00.662572 systemd-networkd[832]: lo: Gained carrier
Dec 12 18:34:00.664004 systemd-networkd[832]: Enumeration completed
Dec 12 18:34:00.664129 systemd[1]: Started systemd-networkd.service - Network Configuration.
Dec 12 18:34:00.664380 systemd-networkd[832]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
Dec 12 18:34:00.664384 systemd-networkd[832]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
Dec 12 18:34:00.665453 systemd-networkd[832]: eth0: Link UP
Dec 12 18:34:00.665578 systemd-networkd[832]: eth0: Gained carrier
Dec 12 18:34:00.665587 systemd-networkd[832]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
Dec 12 18:34:00.667193 systemd[1]: Reached target network.target - Network.
Dec 12 18:34:00.668021 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json).
Dec 12 18:34:00.668860 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)...
Dec 12 18:34:00.702263 systemd-networkd[832]: eth0: DHCPv4 address 10.0.0.42/16, gateway 10.0.0.1 acquired from 10.0.0.1
Dec 12 18:34:00.724132 ignition[841]: Ignition 2.22.0
Dec 12 18:34:00.724144 ignition[841]: Stage: kargs
Dec 12 18:34:00.724275 ignition[841]: no configs at "/usr/lib/ignition/base.d"
Dec 12 18:34:00.724284 ignition[841]: no config dir at "/usr/lib/ignition/base.platform.d/qemu"
Dec 12 18:34:00.724734 ignition[841]: kargs: kargs passed
Dec 12 18:34:00.724773 ignition[841]: Ignition finished successfully
Dec 12 18:34:00.731034 systemd[1]: Finished ignition-kargs.service - Ignition (kargs).
Dec 12 18:34:00.733141 systemd[1]: Starting ignition-disks.service - Ignition (disks)...
Dec 12 18:34:00.762006 ignition[850]: Ignition 2.22.0
Dec 12 18:34:00.762020 ignition[850]: Stage: disks
Dec 12 18:34:00.762188 ignition[850]: no configs at "/usr/lib/ignition/base.d"
Dec 12 18:34:00.767075 systemd[1]: Finished ignition-disks.service - Ignition (disks).
Dec 12 18:34:00.762217 ignition[850]: no config dir at "/usr/lib/ignition/base.platform.d/qemu"
Dec 12 18:34:00.768383 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device.
Dec 12 18:34:00.762907 ignition[850]: disks: disks passed
Dec 12 18:34:00.771231 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems.
Dec 12 18:34:00.762955 ignition[850]: Ignition finished successfully
Dec 12 18:34:00.774774 systemd[1]: Reached target local-fs.target - Local File Systems.
Dec 12 18:34:00.779808 systemd[1]: Reached target sysinit.target - System Initialization.
Dec 12 18:34:00.782547 systemd[1]: Reached target basic.target - Basic System.
Dec 12 18:34:00.787651 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT...
Dec 12 18:34:00.818681 systemd-fsck[860]: ROOT: clean, 15/553520 files, 52789/553472 blocks
Dec 12 18:34:00.826759 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT.
Dec 12 18:34:00.828604 systemd[1]: Mounting sysroot.mount - /sysroot...
Dec 12 18:34:00.947240 kernel: EXT4-fs (vda9): mounted filesystem e48ca59c-1206-4abd-b121-5e9b35e49852 r/w with ordered data mode. Quota mode: none.
Dec 12 18:34:00.947872 systemd[1]: Mounted sysroot.mount - /sysroot.
Dec 12 18:34:00.950701 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System.
Dec 12 18:34:00.955047 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem...
Dec 12 18:34:00.956752 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr...
Dec 12 18:34:00.959041 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met.
Dec 12 18:34:00.959093 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot).
Dec 12 18:34:00.959121 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup.
Dec 12 18:34:00.977945 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr.
Dec 12 18:34:00.979911 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup...
Dec 12 18:34:00.991241 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (868)
Dec 12 18:34:00.995298 kernel: BTRFS info (device vda6): first mount of filesystem 7e9ead35-f0ec-40e8-bc31-5061934f865a
Dec 12 18:34:00.995360 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm
Dec 12 18:34:00.999504 kernel: BTRFS info (device vda6): turning on async discard
Dec 12 18:34:00.999551 kernel: BTRFS info (device vda6): enabling free space tree
Dec 12 18:34:01.002020 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem.
Dec 12 18:34:01.021887 initrd-setup-root[892]: cut: /sysroot/etc/passwd: No such file or directory
Dec 12 18:34:01.028049 initrd-setup-root[899]: cut: /sysroot/etc/group: No such file or directory
Dec 12 18:34:01.033429 initrd-setup-root[906]: cut: /sysroot/etc/shadow: No such file or directory
Dec 12 18:34:01.040287 initrd-setup-root[913]: cut: /sysroot/etc/gshadow: No such file or directory
Dec 12 18:34:01.119810 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup.
Dec 12 18:34:01.124499 systemd[1]: Starting ignition-mount.service - Ignition (mount)...
Dec 12 18:34:01.128227 systemd[1]: Starting sysroot-boot.service - /sysroot/boot...
Dec 12 18:34:01.148239 kernel: BTRFS info (device vda6): last unmount of filesystem 7e9ead35-f0ec-40e8-bc31-5061934f865a
Dec 12 18:34:01.160510 systemd[1]: Finished sysroot-boot.service - /sysroot/boot.
Dec 12 18:34:01.181573 ignition[982]: INFO     : Ignition 2.22.0
Dec 12 18:34:01.181573 ignition[982]: INFO     : Stage: mount
Dec 12 18:34:01.184055 ignition[982]: INFO     : no configs at "/usr/lib/ignition/base.d"
Dec 12 18:34:01.184055 ignition[982]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/qemu"
Dec 12 18:34:01.184055 ignition[982]: INFO     : mount: mount passed
Dec 12 18:34:01.184055 ignition[982]: INFO     : Ignition finished successfully
Dec 12 18:34:01.192356 systemd[1]: Finished ignition-mount.service - Ignition (mount).
Dec 12 18:34:01.195743 systemd[1]: Starting ignition-files.service - Ignition (files)...
Dec 12 18:34:01.418646 systemd[1]: sysroot-oem.mount: Deactivated successfully.
Dec 12 18:34:01.420398 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem...
Dec 12 18:34:01.445236 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (994)
Dec 12 18:34:01.448441 kernel: BTRFS info (device vda6): first mount of filesystem 7e9ead35-f0ec-40e8-bc31-5061934f865a
Dec 12 18:34:01.448468 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm
Dec 12 18:34:01.452302 kernel: BTRFS info (device vda6): turning on async discard
Dec 12 18:34:01.452327 kernel: BTRFS info (device vda6): enabling free space tree
Dec 12 18:34:01.454071 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem.
Dec 12 18:34:01.491261 ignition[1011]: INFO     : Ignition 2.22.0
Dec 12 18:34:01.491261 ignition[1011]: INFO     : Stage: files
Dec 12 18:34:01.493899 ignition[1011]: INFO     : no configs at "/usr/lib/ignition/base.d"
Dec 12 18:34:01.493899 ignition[1011]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/qemu"
Dec 12 18:34:01.497455 ignition[1011]: DEBUG    : files: compiled without relabeling support, skipping
Dec 12 18:34:01.499430 ignition[1011]: INFO     : files: ensureUsers: op(1): [started]  creating or modifying user "core"
Dec 12 18:34:01.499430 ignition[1011]: DEBUG    : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core"
Dec 12 18:34:01.505437 ignition[1011]: INFO     : files: ensureUsers: op(1): [finished] creating or modifying user "core"
Dec 12 18:34:01.507741 ignition[1011]: INFO     : files: ensureUsers: op(2): [started]  adding ssh keys to user "core"
Dec 12 18:34:01.509880 ignition[1011]: INFO     : files: ensureUsers: op(2): [finished] adding ssh keys to user "core"
Dec 12 18:34:01.508163 unknown[1011]: wrote ssh authorized keys file for user: core
Dec 12 18:34:01.516114 ignition[1011]: INFO     : files: createFilesystemsFiles: createFiles: op(3): [started]  writing file "/sysroot/etc/flatcar/update.conf"
Dec 12 18:34:01.519387 ignition[1011]: INFO     : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf"
Dec 12 18:34:01.519387 ignition[1011]: INFO     : files: op(4): [started]  processing unit "etcd-member.service"
Dec 12 18:34:01.519387 ignition[1011]: 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"
Dec 12 18:34:01.529089 ignition[1011]: 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"
Dec 12 18:34:01.529089 ignition[1011]: INFO     : files: op(4): [finished] processing unit "etcd-member.service"
Dec 12 18:34:01.529089 ignition[1011]: INFO     : files: op(6): [started]  processing unit "coreos-metadata.service"
Dec 12 18:34:01.529089 ignition[1011]: INFO     : files: op(6): op(7): [started]  writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service"
Dec 12 18:34:01.529089 ignition[1011]: INFO     : files: op(6): op(7): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service"
Dec 12 18:34:01.529089 ignition[1011]: INFO     : files: op(6): [finished] processing unit "coreos-metadata.service"
Dec 12 18:34:01.529089 ignition[1011]: INFO     : files: op(8): [started]  setting preset to disabled for "coreos-metadata.service"
Dec 12 18:34:01.553811 ignition[1011]: INFO     : files: op(8): op(9): [started]  removing enablement symlink(s) for "coreos-metadata.service"
Dec 12 18:34:01.557547 ignition[1011]: INFO     : files: op(8): op(9): [finished] removing enablement symlink(s) for "coreos-metadata.service"
Dec 12 18:34:01.560356 ignition[1011]: INFO     : files: op(8): [finished] setting preset to disabled for "coreos-metadata.service"
Dec 12 18:34:01.560356 ignition[1011]: INFO     : files: op(a): [started]  setting preset to enabled for "etcd-member.service"
Dec 12 18:34:01.560356 ignition[1011]: INFO     : files: op(a): [finished] setting preset to enabled for "etcd-member.service"
Dec 12 18:34:01.560356 ignition[1011]: INFO     : files: createResultFile: createFiles: op(b): [started]  writing file "/sysroot/etc/.ignition-result.json"
Dec 12 18:34:01.560356 ignition[1011]: INFO     : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json"
Dec 12 18:34:01.560356 ignition[1011]: INFO     : files: files passed
Dec 12 18:34:01.560356 ignition[1011]: INFO     : Ignition finished successfully
Dec 12 18:34:01.566268 systemd[1]: Finished ignition-files.service - Ignition (files).
Dec 12 18:34:01.568776 systemd[1]: Starting ignition-quench.service - Ignition (record completion)...
Dec 12 18:34:01.577000 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion...
Dec 12 18:34:01.588802 systemd[1]: ignition-quench.service: Deactivated successfully.
Dec 12 18:34:01.588922 systemd[1]: Finished ignition-quench.service - Ignition (record completion).
Dec 12 18:34:01.593859 initrd-setup-root-after-ignition[1040]: grep: /sysroot/oem/oem-release: No such file or directory
Dec 12 18:34:01.598288 initrd-setup-root-after-ignition[1042]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory
Dec 12 18:34:01.598288 initrd-setup-root-after-ignition[1042]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory
Dec 12 18:34:01.603597 initrd-setup-root-after-ignition[1046]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory
Dec 12 18:34:01.607223 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion.
Dec 12 18:34:01.611352 systemd[1]: Reached target ignition-complete.target - Ignition Complete.
Dec 12 18:34:01.612669 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root...
Dec 12 18:34:01.650497 systemd[1]: initrd-parse-etc.service: Deactivated successfully.
Dec 12 18:34:01.650659 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root.
Dec 12 18:34:01.651731 systemd[1]: Reached target initrd-fs.target - Initrd File Systems.
Dec 12 18:34:01.656763 systemd[1]: Reached target initrd.target - Initrd Default Target.
Dec 12 18:34:01.659640 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met.
Dec 12 18:34:01.660466 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook...
Dec 12 18:34:01.705850 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook.
Dec 12 18:34:01.707922 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons...
Dec 12 18:34:01.731951 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups.
Dec 12 18:34:01.735566 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes.
Dec 12 18:34:01.737593 systemd[1]: Stopped target timers.target - Timer Units.
Dec 12 18:34:01.740744 systemd[1]: dracut-pre-pivot.service: Deactivated successfully.
Dec 12 18:34:01.740852 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook.
Dec 12 18:34:01.745360 systemd[1]: Stopped target initrd.target - Initrd Default Target.
Dec 12 18:34:01.748733 systemd[1]: Stopped target basic.target - Basic System.
Dec 12 18:34:01.751601 systemd[1]: Stopped target ignition-complete.target - Ignition Complete.
Dec 12 18:34:01.754637 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup.
Dec 12 18:34:01.757993 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device.
Dec 12 18:34:01.758851 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System.
Dec 12 18:34:01.763727 systemd[1]: Stopped target remote-fs.target - Remote File Systems.
Dec 12 18:34:01.767174 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems.
Dec 12 18:34:01.770249 systemd[1]: Stopped target sysinit.target - System Initialization.
Dec 12 18:34:01.774022 systemd[1]: Stopped target local-fs.target - Local File Systems.
Dec 12 18:34:01.777237 systemd[1]: Stopped target swap.target - Swaps.
Dec 12 18:34:01.780285 systemd[1]: dracut-pre-mount.service: Deactivated successfully.
Dec 12 18:34:01.780449 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook.
Dec 12 18:34:01.785172 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes.
Dec 12 18:34:01.786030 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
Dec 12 18:34:01.790745 systemd[1]: clevis-luks-askpass.path: Deactivated successfully.
Dec 12 18:34:01.790852 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
Dec 12 18:34:01.794081 systemd[1]: dracut-initqueue.service: Deactivated successfully.
Dec 12 18:34:01.794184 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook.
Dec 12 18:34:01.800487 systemd[1]: ignition-fetch-offline.service: Deactivated successfully.
Dec 12 18:34:01.800598 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline).
Dec 12 18:34:01.803809 systemd[1]: Stopped target paths.target - Path Units.
Dec 12 18:34:01.806621 systemd[1]: systemd-ask-password-console.path: Deactivated successfully.
Dec 12 18:34:01.811560 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
Dec 12 18:34:01.812138 systemd[1]: Stopped target slices.target - Slice Units.
Dec 12 18:34:01.817695 systemd[1]: Stopped target sockets.target - Socket Units.
Dec 12 18:34:01.820741 systemd[1]: iscsid.socket: Deactivated successfully.
Dec 12 18:34:01.820833 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket.
Dec 12 18:34:01.823343 systemd[1]: iscsiuio.socket: Deactivated successfully.
Dec 12 18:34:01.823449 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket.
Dec 12 18:34:01.826098 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully.
Dec 12 18:34:01.826227 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion.
Dec 12 18:34:01.829105 systemd[1]: ignition-files.service: Deactivated successfully.
Dec 12 18:34:01.829249 systemd[1]: Stopped ignition-files.service - Ignition (files).
Dec 12 18:34:01.834695 systemd[1]: Stopping ignition-mount.service - Ignition (mount)...
Dec 12 18:34:01.837713 systemd[1]: kmod-static-nodes.service: Deactivated successfully.
Dec 12 18:34:01.837840 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes.
Dec 12 18:34:01.846219 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot...
Dec 12 18:34:01.850975 systemd[1]: systemd-udev-trigger.service: Deactivated successfully.
Dec 12 18:34:01.854361 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices.
Dec 12 18:34:01.858509 systemd[1]: dracut-pre-trigger.service: Deactivated successfully.
Dec 12 18:34:01.860153 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook.
Dec 12 18:34:01.868991 systemd[1]: initrd-cleanup.service: Deactivated successfully.
Dec 12 18:34:01.869129 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons.
Dec 12 18:34:01.879312 ignition[1066]: INFO     : Ignition 2.22.0
Dec 12 18:34:01.879312 ignition[1066]: INFO     : Stage: umount
Dec 12 18:34:01.882171 ignition[1066]: INFO     : no configs at "/usr/lib/ignition/base.d"
Dec 12 18:34:01.882171 ignition[1066]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/qemu"
Dec 12 18:34:01.882171 ignition[1066]: INFO     : umount: umount passed
Dec 12 18:34:01.882171 ignition[1066]: INFO     : Ignition finished successfully
Dec 12 18:34:01.883684 systemd[1]: sysroot-boot.mount: Deactivated successfully.
Dec 12 18:34:01.884724 systemd[1]: ignition-mount.service: Deactivated successfully.
Dec 12 18:34:01.884858 systemd[1]: Stopped ignition-mount.service - Ignition (mount).
Dec 12 18:34:01.888120 systemd[1]: Stopped target network.target - Network.
Dec 12 18:34:01.890244 systemd[1]: ignition-disks.service: Deactivated successfully.
Dec 12 18:34:01.890300 systemd[1]: Stopped ignition-disks.service - Ignition (disks).
Dec 12 18:34:01.893259 systemd[1]: ignition-kargs.service: Deactivated successfully.
Dec 12 18:34:01.893314 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs).
Dec 12 18:34:01.896195 systemd[1]: ignition-setup.service: Deactivated successfully.
Dec 12 18:34:01.896270 systemd[1]: Stopped ignition-setup.service - Ignition (setup).
Dec 12 18:34:01.899250 systemd[1]: ignition-setup-pre.service: Deactivated successfully.
Dec 12 18:34:01.899297 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup.
Dec 12 18:34:01.902488 systemd[1]: Stopping systemd-networkd.service - Network Configuration...
Dec 12 18:34:01.903030 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution...
Dec 12 18:34:01.911581 systemd[1]: systemd-resolved.service: Deactivated successfully.
Dec 12 18:34:01.911711 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution.
Dec 12 18:34:01.918951 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully.
Dec 12 18:34:01.919250 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.
Dec 12 18:34:01.919296 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories.
Dec 12 18:34:01.925092 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully.
Dec 12 18:34:01.925400 systemd[1]: systemd-networkd.service: Deactivated successfully.
Dec 12 18:34:01.925512 systemd[1]: Stopped systemd-networkd.service - Network Configuration.
Dec 12 18:34:01.929864 systemd[1]: sysroot-boot.service: Deactivated successfully.
Dec 12 18:34:01.929962 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot.
Dec 12 18:34:01.931265 systemd[1]: Stopped target network-pre.target - Preparation for Network.
Dec 12 18:34:01.933658 systemd[1]: systemd-networkd.socket: Deactivated successfully.
Dec 12 18:34:01.933702 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket.
Dec 12 18:34:01.936815 systemd[1]: initrd-setup-root.service: Deactivated successfully.
Dec 12 18:34:01.936870 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup.
Dec 12 18:34:01.945787 systemd[1]: Stopping network-cleanup.service - Network Cleanup...
Dec 12 18:34:01.949349 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully.
Dec 12 18:34:01.949431 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline.
Dec 12 18:34:01.950646 systemd[1]: systemd-sysctl.service: Deactivated successfully.
Dec 12 18:34:01.950723 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables.
Dec 12 18:34:01.957744 systemd[1]: systemd-modules-load.service: Deactivated successfully.
Dec 12 18:34:01.957791 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules.
Dec 12 18:34:01.960920 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files...
Dec 12 18:34:01.983147 systemd[1]: network-cleanup.service: Deactivated successfully.
Dec 12 18:34:01.983296 systemd[1]: Stopped network-cleanup.service - Network Cleanup.
Dec 12 18:34:02.005988 systemd[1]: systemd-udevd.service: Deactivated successfully.
Dec 12 18:34:02.006173 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files.
Dec 12 18:34:02.007310 systemd[1]: systemd-udevd-control.socket: Deactivated successfully.
Dec 12 18:34:02.007352 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket.
Dec 12 18:34:02.012241 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully.
Dec 12 18:34:02.012279 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket.
Dec 12 18:34:02.015562 systemd[1]: dracut-pre-udev.service: Deactivated successfully.
Dec 12 18:34:02.015610 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook.
Dec 12 18:34:02.021809 systemd[1]: dracut-cmdline.service: Deactivated successfully.
Dec 12 18:34:02.021857 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook.
Dec 12 18:34:02.025407 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully.
Dec 12 18:34:02.025456 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters.
Dec 12 18:34:02.033543 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database...
Dec 12 18:34:02.034153 systemd[1]: systemd-network-generator.service: Deactivated successfully.
Dec 12 18:34:02.034230 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line.
Dec 12 18:34:02.041147 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.
Dec 12 18:34:02.041194 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
Dec 12 18:34:02.044625 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
Dec 12 18:34:02.044670 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup.
Dec 12 18:34:02.060865 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.
Dec 12 18:34:02.060989 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database.
Dec 12 18:34:02.062047 systemd[1]: Reached target initrd-switch-root.target - Switch Root.
Dec 12 18:34:02.070002 systemd[1]: Starting initrd-switch-root.service - Switch Root...
Dec 12 18:34:02.089842 systemd[1]: Switching root.
Dec 12 18:34:02.131131 systemd-journald[201]: Journal stopped
Dec 12 18:34:03.180225 systemd-journald[201]: Received SIGTERM from PID 1 (systemd).
Dec 12 18:34:03.180287 kernel: SELinux:  policy capability network_peer_controls=1
Dec 12 18:34:03.180301 kernel: SELinux:  policy capability open_perms=1
Dec 12 18:34:03.180312 kernel: SELinux:  policy capability extended_socket_class=1
Dec 12 18:34:03.180324 kernel: SELinux:  policy capability always_check_network=0
Dec 12 18:34:03.180337 kernel: SELinux:  policy capability cgroup_seclabel=1
Dec 12 18:34:03.180349 kernel: SELinux:  policy capability nnp_nosuid_transition=1
Dec 12 18:34:03.180373 kernel: SELinux:  policy capability genfs_seclabel_symlinks=0
Dec 12 18:34:03.180384 kernel: SELinux:  policy capability ioctl_skip_cloexec=0
Dec 12 18:34:03.180395 kernel: SELinux:  policy capability userspace_initial_context=0
Dec 12 18:34:03.180417 kernel: audit: type=1403 audit(1765564442.242:2): auid=4294967295 ses=4294967295 lsm=selinux res=1
Dec 12 18:34:03.180429 systemd[1]: Successfully loaded SELinux policy in 65.563ms.
Dec 12 18:34:03.180454 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 9.673ms.
Dec 12 18:34:03.180467 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)
Dec 12 18:34:03.180480 systemd[1]: Detected virtualization kvm.
Dec 12 18:34:03.180497 systemd[1]: Detected architecture x86-64.
Dec 12 18:34:03.180511 systemd[1]: Detected first boot.
Dec 12 18:34:03.180523 systemd[1]: Initializing machine ID from VM UUID.
Dec 12 18:34:03.180535 zram_generator::config[1112]: No configuration found.
Dec 12 18:34:03.180548 kernel: Guest personality initialized and is inactive
Dec 12 18:34:03.180560 kernel: VMCI host device registered (name=vmci, major=10, minor=258)
Dec 12 18:34:03.180570 kernel: Initialized host personality
Dec 12 18:34:03.180583 kernel: NET: Registered PF_VSOCK protocol family
Dec 12 18:34:03.180595 systemd[1]: Populated /etc with preset unit settings.
Dec 12 18:34:03.180608 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully.
Dec 12 18:34:03.180622 systemd[1]: initrd-switch-root.service: Deactivated successfully.
Dec 12 18:34:03.180636 systemd[1]: Stopped initrd-switch-root.service - Switch Root.
Dec 12 18:34:03.180648 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.
Dec 12 18:34:03.180660 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config.
Dec 12 18:34:03.180672 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run.
Dec 12 18:34:03.180686 systemd[1]: Created slice system-getty.slice - Slice /system/getty.
Dec 12 18:34:03.180697 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe.
Dec 12 18:34:03.180710 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty.
Dec 12 18:34:03.180722 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit.
Dec 12 18:34:03.180734 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck.
Dec 12 18:34:03.180746 systemd[1]: Created slice user.slice - User and Session Slice.
Dec 12 18:34:03.180766 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
Dec 12 18:34:03.180781 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
Dec 12 18:34:03.180796 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch.
Dec 12 18:34:03.180814 systemd[1]: Set up automount boot.automount - Boot partition Automount Point.
Dec 12 18:34:03.180829 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point.
Dec 12 18:34:03.180844 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM...
Dec 12 18:34:03.180859 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0...
Dec 12 18:34:03.180875 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
Dec 12 18:34:03.180890 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes.
Dec 12 18:34:03.180905 systemd[1]: Stopped target initrd-switch-root.target - Switch Root.
Dec 12 18:34:03.180923 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems.
Dec 12 18:34:03.180937 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System.
Dec 12 18:34:03.180953 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes.
Dec 12 18:34:03.180968 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes.
Dec 12 18:34:03.180983 systemd[1]: Reached target remote-fs.target - Remote File Systems.
Dec 12 18:34:03.180995 systemd[1]: Reached target slices.target - Slice Units.
Dec 12 18:34:03.181007 systemd[1]: Reached target swap.target - Swaps.
Dec 12 18:34:03.181018 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes.
Dec 12 18:34:03.181030 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket.
Dec 12 18:34:03.181044 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption.
Dec 12 18:34:03.181061 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket.
Dec 12 18:34:03.181074 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket.
Dec 12 18:34:03.181085 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket.
Dec 12 18:34:03.181097 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket.
Dec 12 18:34:03.181109 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System...
Dec 12 18:34:03.181121 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System...
Dec 12 18:34:03.181133 systemd[1]: Mounting media.mount - External Media Directory...
Dec 12 18:34:03.181145 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen).
Dec 12 18:34:03.181160 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System...
Dec 12 18:34:03.181172 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System...
Dec 12 18:34:03.181184 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp...
Dec 12 18:34:03.181197 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).
Dec 12 18:34:03.182163 systemd[1]: Reached target machines.target - Containers.
Dec 12 18:34:03.182177 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files...
Dec 12 18:34:03.182189 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
Dec 12 18:34:03.182216 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes...
Dec 12 18:34:03.182228 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs...
Dec 12 18:34:03.182244 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
Dec 12 18:34:03.182256 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm...
Dec 12 18:34:03.182268 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
Dec 12 18:34:03.182280 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse...
Dec 12 18:34:03.182291 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
Dec 12 18:34:03.182303 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf).
Dec 12 18:34:03.182315 systemd[1]: systemd-fsck-root.service: Deactivated successfully.
Dec 12 18:34:03.182327 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device.
Dec 12 18:34:03.182341 systemd[1]: systemd-fsck-usr.service: Deactivated successfully.
Dec 12 18:34:03.182363 systemd[1]: Stopped systemd-fsck-usr.service.
Dec 12 18:34:03.182376 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).
Dec 12 18:34:03.182387 systemd[1]: Starting systemd-journald.service - Journal Service...
Dec 12 18:34:03.182401 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules...
Dec 12 18:34:03.182413 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line...
Dec 12 18:34:03.182426 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems...
Dec 12 18:34:03.182460 systemd-journald[1176]: Collecting audit messages is disabled.
Dec 12 18:34:03.182486 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials...
Dec 12 18:34:03.182499 systemd-journald[1176]: Journal started
Dec 12 18:34:03.182521 systemd-journald[1176]: Runtime Journal (/run/log/journal/c35380178e3c4992bd53837c62d29057) is 6M, max 48.3M, 42.2M free.
Dec 12 18:34:03.188419 kernel: loop: module loaded
Dec 12 18:34:03.188474 kernel: fuse: init (API version 7.41)
Dec 12 18:34:02.825171 systemd[1]: Queued start job for default target multi-user.target.
Dec 12 18:34:02.844416 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6.
Dec 12 18:34:02.844961 systemd[1]: systemd-journald.service: Deactivated successfully.
Dec 12 18:34:03.192307 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices...
Dec 12 18:34:03.195598 systemd[1]: verity-setup.service: Deactivated successfully.
Dec 12 18:34:03.195651 systemd[1]: Stopped verity-setup.service.
Dec 12 18:34:03.202241 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen).
Dec 12 18:34:03.207870 systemd[1]: Started systemd-journald.service - Journal Service.
Dec 12 18:34:03.209702 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System.
Dec 12 18:34:03.211678 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System.
Dec 12 18:34:03.213920 systemd[1]: Mounted media.mount - External Media Directory.
Dec 12 18:34:03.215708 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System.
Dec 12 18:34:03.223852 kernel: ACPI: bus type drm_connector registered
Dec 12 18:34:03.218019 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System.
Dec 12 18:34:03.221110 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp.
Dec 12 18:34:03.223513 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes.
Dec 12 18:34:03.226004 systemd[1]: modprobe@configfs.service: Deactivated successfully.
Dec 12 18:34:03.226258 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs.
Dec 12 18:34:03.229159 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Dec 12 18:34:03.229601 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
Dec 12 18:34:03.232053 systemd[1]: modprobe@drm.service: Deactivated successfully.
Dec 12 18:34:03.232369 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm.
Dec 12 18:34:03.238631 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Dec 12 18:34:03.238894 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
Dec 12 18:34:03.241279 systemd[1]: modprobe@fuse.service: Deactivated successfully.
Dec 12 18:34:03.241531 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse.
Dec 12 18:34:03.243638 systemd[1]: modprobe@loop.service: Deactivated successfully.
Dec 12 18:34:03.243857 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
Dec 12 18:34:03.246061 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules.
Dec 12 18:34:03.248285 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line.
Dec 12 18:34:03.250717 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems.
Dec 12 18:34:03.253149 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials.
Dec 12 18:34:03.267098 systemd[1]: Reached target network-pre.target - Preparation for Network.
Dec 12 18:34:03.270514 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System...
Dec 12 18:34:03.276332 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System...
Dec 12 18:34:03.278457 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).
Dec 12 18:34:03.278514 systemd[1]: Reached target local-fs.target - Local File Systems.
Dec 12 18:34:03.281277 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management.
Dec 12 18:34:03.287960 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache...
Dec 12 18:34:03.290028 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
Dec 12 18:34:03.291542 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database...
Dec 12 18:34:03.294680 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage...
Dec 12 18:34:03.298357 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
Dec 12 18:34:03.301799 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed...
Dec 12 18:34:03.303851 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met.
Dec 12 18:34:03.309310 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables...
Dec 12 18:34:03.320317 systemd-journald[1176]: Time spent on flushing to /var/log/journal/c35380178e3c4992bd53837c62d29057 is 29.274ms for 947 entries.
Dec 12 18:34:03.320317 systemd-journald[1176]: System Journal (/var/log/journal/c35380178e3c4992bd53837c62d29057) is 8M, max 195.6M, 187.6M free.
Dec 12 18:34:03.380502 systemd-journald[1176]: Received client request to flush runtime journal.
Dec 12 18:34:03.380579 kernel: loop0: detected capacity change from 0 to 110984
Dec 12 18:34:03.315923 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/...
Dec 12 18:34:03.321968 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System.
Dec 12 18:34:03.327576 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System.
Dec 12 18:34:03.333703 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices.
Dec 12 18:34:03.342678 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed.
Dec 12 18:34:03.348361 systemd[1]: Reached target first-boot-complete.target - First Boot Complete.
Dec 12 18:34:03.355035 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk...
Dec 12 18:34:03.361753 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files.
Dec 12 18:34:03.364962 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables.
Dec 12 18:34:03.371590 systemd[1]: Starting systemd-sysusers.service - Create System Users...
Dec 12 18:34:03.382362 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage.
Dec 12 18:34:03.395523 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk.
Dec 12 18:34:03.396474 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher
Dec 12 18:34:03.413258 kernel: loop1: detected capacity change from 0 to 128560
Dec 12 18:34:03.423338 systemd[1]: Finished systemd-sysusers.service - Create System Users.
Dec 12 18:34:03.427172 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev...
Dec 12 18:34:03.446261 kernel: loop2: detected capacity change from 0 to 110984
Dec 12 18:34:03.464120 systemd-tmpfiles[1250]: ACLs are not supported, ignoring.
Dec 12 18:34:03.464139 systemd-tmpfiles[1250]: ACLs are not supported, ignoring.
Dec 12 18:34:03.465250 kernel: loop3: detected capacity change from 0 to 128560
Dec 12 18:34:03.472901 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
Dec 12 18:34:03.482000 (sd-merge)[1251]: Using extensions 'containerd-flatcar', 'docker-flatcar'.
Dec 12 18:34:03.482778 (sd-merge)[1251]: Merged extensions into '/usr'.
Dec 12 18:34:03.486795 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/.
Dec 12 18:34:03.491273 systemd[1]: Starting ensure-sysext.service...
Dec 12 18:34:03.493617 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories...
Dec 12 18:34:03.518281 systemd[1]: Reload requested from client PID 1255 ('systemctl') (unit ensure-sysext.service)...
Dec 12 18:34:03.518304 systemd[1]: Reloading...
Dec 12 18:34:03.526660 systemd-tmpfiles[1256]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring.
Dec 12 18:34:03.527157 systemd-tmpfiles[1256]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring.
Dec 12 18:34:03.527595 systemd-tmpfiles[1256]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring.
Dec 12 18:34:03.527954 systemd-tmpfiles[1256]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring.
Dec 12 18:34:03.529164 systemd-tmpfiles[1256]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring.
Dec 12 18:34:03.529602 systemd-tmpfiles[1256]: ACLs are not supported, ignoring.
Dec 12 18:34:03.529693 systemd-tmpfiles[1256]: ACLs are not supported, ignoring.
Dec 12 18:34:03.535824 systemd-tmpfiles[1256]: Detected autofs mount point /boot during canonicalization of boot.
Dec 12 18:34:03.535912 systemd-tmpfiles[1256]: Skipping /boot
Dec 12 18:34:03.548144 systemd-tmpfiles[1256]: Detected autofs mount point /boot during canonicalization of boot.
Dec 12 18:34:03.548288 systemd-tmpfiles[1256]: Skipping /boot
Dec 12 18:34:03.595057 zram_generator::config[1280]: No configuration found.
Dec 12 18:34:03.721228 ldconfig[1211]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start.
Dec 12 18:34:03.790674 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
Dec 12 18:34:03.790810 systemd[1]: Reloading finished in 272 ms.
Dec 12 18:34:03.826531 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache.
Dec 12 18:34:03.852013 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories.
Dec 12 18:34:03.863197 systemd[1]: Starting audit-rules.service - Load Audit Rules...
Dec 12 18:34:03.905635 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs...
Dec 12 18:34:03.910619 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog...
Dec 12 18:34:03.918649 systemd[1]: Starting systemd-resolved.service - Network Name Resolution...
Dec 12 18:34:03.922281 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP...
Dec 12 18:34:03.928391 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen).
Dec 12 18:34:03.929020 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
Dec 12 18:34:03.933156 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
Dec 12 18:34:03.937484 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
Dec 12 18:34:03.941716 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
Dec 12 18:34:03.943541 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
Dec 12 18:34:03.943693 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).
Dec 12 18:34:03.943828 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen).
Dec 12 18:34:03.945569 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database.
Dec 12 18:34:03.954778 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog.
Dec 12 18:34:03.957932 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Dec 12 18:34:03.958186 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
Dec 12 18:34:03.960788 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Dec 12 18:34:03.961089 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
Dec 12 18:34:03.963555 systemd[1]: modprobe@loop.service: Deactivated successfully.
Dec 12 18:34:03.963925 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
Dec 12 18:34:03.966426 augenrules[1351]: No rules
Dec 12 18:34:03.967182 systemd[1]: audit-rules.service: Deactivated successfully.
Dec 12 18:34:03.967468 systemd[1]: Finished audit-rules.service - Load Audit Rules.
Dec 12 18:34:03.976693 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP.
Dec 12 18:34:03.982602 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen).
Dec 12 18:34:03.984166 systemd[1]: Starting audit-rules.service - Load Audit Rules...
Dec 12 18:34:03.985796 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
Dec 12 18:34:03.987083 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
Dec 12 18:34:03.988989 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm...
Dec 12 18:34:04.001872 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
Dec 12 18:34:04.005182 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
Dec 12 18:34:04.007440 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
Dec 12 18:34:04.007586 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).
Dec 12 18:34:04.010437 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files...
Dec 12 18:34:04.013921 systemd[1]: Starting systemd-update-done.service - Update is Completed...
Dec 12 18:34:04.019509 systemd[1]: Starting systemd-userdbd.service - User Database Manager...
Dec 12 18:34:04.020250 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen).
Dec 12 18:34:04.023576 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs.
Dec 12 18:34:04.026390 augenrules[1362]: /sbin/augenrules: No change
Dec 12 18:34:04.026813 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Dec 12 18:34:04.027090 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
Dec 12 18:34:04.029848 systemd[1]: modprobe@drm.service: Deactivated successfully.
Dec 12 18:34:04.030111 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm.
Dec 12 18:34:04.032468 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Dec 12 18:34:04.032806 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
Dec 12 18:34:04.035444 systemd[1]: modprobe@loop.service: Deactivated successfully.
Dec 12 18:34:04.035725 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
Dec 12 18:34:04.037991 systemd[1]: Finished systemd-update-done.service - Update is Completed.
Dec 12 18:34:04.040077 augenrules[1386]: No rules
Dec 12 18:34:04.043121 systemd[1]: audit-rules.service: Deactivated successfully.
Dec 12 18:34:04.043550 systemd[1]: Finished audit-rules.service - Load Audit Rules.
Dec 12 18:34:04.045853 systemd[1]: Finished ensure-sysext.service.
Dec 12 18:34:04.052068 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
Dec 12 18:34:04.052232 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met.
Dec 12 18:34:04.054227 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization...
Dec 12 18:34:04.056211 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).
Dec 12 18:34:04.061839 systemd-udevd[1370]: Using default interface naming scheme 'v255'.
Dec 12 18:34:04.078704 systemd[1]: Started systemd-userdbd.service - User Database Manager.
Dec 12 18:34:04.083611 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files.
Dec 12 18:34:04.094423 systemd[1]: Starting systemd-networkd.service - Network Configuration...
Dec 12 18:34:04.160021 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped.
Dec 12 18:34:04.207225 kernel: mousedev: PS/2 mouse device common for all mice
Dec 12 18:34:04.216245 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3
Dec 12 18:34:04.219802 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM.
Dec 12 18:34:04.222391 kernel: ACPI: button: Power Button [PWRF]
Dec 12 18:34:04.225459 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM...
Dec 12 18:34:04.258238 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt
Dec 12 18:34:04.258557 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD
Dec 12 18:34:04.267277 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM.
Dec 12 18:34:04.304118 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization.
Dec 12 18:34:04.306411 systemd[1]: Reached target time-set.target - System Time Set.
Dec 12 18:34:04.314837 systemd-networkd[1410]: lo: Link UP
Dec 12 18:34:04.314848 systemd-networkd[1410]: lo: Gained carrier
Dec 12 18:34:04.318116 systemd-networkd[1410]: Enumeration completed
Dec 12 18:34:04.318242 systemd[1]: Started systemd-networkd.service - Network Configuration.
Dec 12 18:34:04.318568 systemd-networkd[1410]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
Dec 12 18:34:04.318573 systemd-networkd[1410]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
Dec 12 18:34:04.320507 systemd-networkd[1410]: eth0: Link UP
Dec 12 18:34:04.320703 systemd-networkd[1410]: eth0: Gained carrier
Dec 12 18:34:04.320728 systemd-networkd[1410]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
Dec 12 18:34:04.324307 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd...
Dec 12 18:34:04.328942 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured...
Dec 12 18:34:04.346168 systemd-networkd[1410]: eth0: DHCPv4 address 10.0.0.42/16, gateway 10.0.0.1 acquired from 10.0.0.1
Dec 12 18:34:04.346886 systemd-timesyncd[1399]: Network configuration changed, trying to establish connection.
Dec 12 18:34:04.348830 systemd-timesyncd[1399]: Contacted time server 10.0.0.1:123 (10.0.0.1).
Dec 12 18:34:04.348912 systemd-timesyncd[1399]: Initial clock synchronization to Fri 2025-12-12 18:34:04.581815 UTC.
Dec 12 18:34:04.354084 systemd-resolved[1332]: Positive Trust Anchors:
Dec 12 18:34:04.354102 systemd-resolved[1332]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
Dec 12 18:34:04.354144 systemd-resolved[1332]: 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
Dec 12 18:34:04.364428 systemd-resolved[1332]: Defaulting to hostname 'linux'.
Dec 12 18:34:04.367563 systemd[1]: Started systemd-resolved.service - Network Name Resolution.
Dec 12 18:34:04.371656 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd.
Dec 12 18:34:04.373933 systemd[1]: Reached target network.target - Network.
Dec 12 18:34:04.375519 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups.
Dec 12 18:34:04.379390 systemd[1]: Reached target sysinit.target - System Initialization.
Dec 12 18:34:04.381451 systemd[1]: Started motdgen.path - Watch for update engine configuration changes.
Dec 12 18:34:04.383700 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data.
Dec 12 18:34:04.386146 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer.
Dec 12 18:34:04.388596 systemd[1]: Started logrotate.timer - Daily rotation of log files.
Dec 12 18:34:04.390994 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information..
Dec 12 18:34:04.393723 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories.
Dec 12 18:34:04.396196 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate).
Dec 12 18:34:04.396337 systemd[1]: Reached target paths.target - Path Units.
Dec 12 18:34:04.398381 systemd[1]: Reached target timers.target - Timer Units.
Dec 12 18:34:04.401596 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket.
Dec 12 18:34:04.405756 systemd[1]: Starting docker.socket - Docker Socket for the API...
Dec 12 18:34:04.410417 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).
Dec 12 18:34:04.412981 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK).
Dec 12 18:34:04.415450 systemd[1]: Reached target ssh-access.target - SSH Access Available.
Dec 12 18:34:04.430138 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket.
Dec 12 18:34:04.432579 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket.
Dec 12 18:34:04.435950 systemd[1]: Listening on docker.socket - Docker Socket for the API.
Dec 12 18:34:04.449391 systemd[1]: Reached target sockets.target - Socket Units.
Dec 12 18:34:04.451289 systemd[1]: Reached target basic.target - Basic System.
Dec 12 18:34:04.453410 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met.
Dec 12 18:34:04.453444 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met.
Dec 12 18:34:04.454726 systemd[1]: Starting containerd.service - containerd container runtime...
Dec 12 18:34:04.459554 systemd[1]: Starting dbus.service - D-Bus System Message Bus...
Dec 12 18:34:04.462438 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown...
Dec 12 18:34:04.481990 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit...
Dec 12 18:34:04.488244 systemd[1]: Starting extend-filesystems.service - Extend Filesystems...
Dec 12 18:34:04.490230 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).
Dec 12 18:34:04.494340 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh...
Dec 12 18:34:04.501025 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd...
Dec 12 18:34:04.507457 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline...
Dec 12 18:34:04.512309 google_oslogin_nss_cache[1475]: oslogin_cache_refresh[1475]: Refreshing passwd entry cache
Dec 12 18:34:04.510599 oslogin_cache_refresh[1475]: Refreshing passwd entry cache
Dec 12 18:34:04.513434 jq[1473]: false
Dec 12 18:34:04.515812 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys...
Dec 12 18:34:04.519601 extend-filesystems[1474]: Found /dev/vda6
Dec 12 18:34:04.524539 google_oslogin_nss_cache[1475]: oslogin_cache_refresh[1475]: Failure getting users, quitting
Dec 12 18:34:04.524539 google_oslogin_nss_cache[1475]: oslogin_cache_refresh[1475]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak.
Dec 12 18:34:04.524539 google_oslogin_nss_cache[1475]: oslogin_cache_refresh[1475]: Refreshing group entry cache
Dec 12 18:34:04.520960 oslogin_cache_refresh[1475]: Failure getting users, quitting
Dec 12 18:34:04.520981 oslogin_cache_refresh[1475]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak.
Dec 12 18:34:04.521044 oslogin_cache_refresh[1475]: Refreshing group entry cache
Dec 12 18:34:04.525066 extend-filesystems[1474]: Found /dev/vda9
Dec 12 18:34:04.526582 systemd[1]: Starting systemd-logind.service - User Login Management...
Dec 12 18:34:04.527578 google_oslogin_nss_cache[1475]: oslogin_cache_refresh[1475]: Failure getting groups, quitting
Dec 12 18:34:04.527572 oslogin_cache_refresh[1475]: Failure getting groups, quitting
Dec 12 18:34:04.527657 google_oslogin_nss_cache[1475]: oslogin_cache_refresh[1475]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak.
Dec 12 18:34:04.527587 oslogin_cache_refresh[1475]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak.
Dec 12 18:34:04.530239 kernel: kvm_amd: TSC scaling supported
Dec 12 18:34:04.530292 kernel: kvm_amd: Nested Virtualization enabled
Dec 12 18:34:04.530330 kernel: kvm_amd: Nested Paging enabled
Dec 12 18:34:04.530352 kernel: kvm_amd: LBR virtualization supported
Dec 12 18:34:04.531227 kernel: kvm_amd: Virtual VMLOAD VMSAVE supported
Dec 12 18:34:04.531283 kernel: kvm_amd: Virtual GIF supported
Dec 12 18:34:04.532102 extend-filesystems[1474]: Checking size of /dev/vda9
Dec 12 18:34:04.543863 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
Dec 12 18:34:04.547863 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0).
Dec 12 18:34:04.549705 extend-filesystems[1474]: Old size kept for /dev/vda9
Dec 12 18:34:04.551152 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details.
Dec 12 18:34:04.552024 systemd[1]: Starting update-engine.service - Update Engine...
Dec 12 18:34:04.557352 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition...
Dec 12 18:34:04.563028 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown.
Dec 12 18:34:04.566016 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'.
Dec 12 18:34:04.566370 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped.
Dec 12 18:34:04.566719 systemd[1]: extend-filesystems.service: Deactivated successfully.
Dec 12 18:34:04.566947 systemd[1]: Finished extend-filesystems.service - Extend Filesystems.
Dec 12 18:34:04.569887 systemd[1]: google-oslogin-cache.service: Deactivated successfully.
Dec 12 18:34:04.570165 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh.
Dec 12 18:34:04.573583 systemd[1]: motdgen.service: Deactivated successfully.
Dec 12 18:34:04.574926 jq[1498]: true
Dec 12 18:34:04.573847 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd.
Dec 12 18:34:04.578737 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully.
Dec 12 18:34:04.579286 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline.
Dec 12 18:34:04.624623 update_engine[1497]: I20251212 18:34:04.623651  1497 main.cc:92] Flatcar Update Engine starting
Dec 12 18:34:04.620710 (ntainerd)[1505]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR
Dec 12 18:34:04.637966 jq[1503]: true
Dec 12 18:34:04.654220 dbus-daemon[1471]: [system] SELinux support is enabled
Dec 12 18:34:04.656599 systemd[1]: Started dbus.service - D-Bus System Message Bus.
Dec 12 18:34:04.660372 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).
Dec 12 18:34:04.660405 systemd[1]: Reached target system-config.target - Load system-provided cloud configs.
Dec 12 18:34:04.661086 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).
Dec 12 18:34:04.661103 systemd[1]: Reached target user-config.target - Load user-provided cloud configs.
Dec 12 18:34:04.662355 systemd-logind[1485]: Watching system buttons on /dev/input/event2 (Power Button)
Dec 12 18:34:04.662648 systemd-logind[1485]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)
Dec 12 18:34:04.662979 systemd-logind[1485]: New seat seat0.
Dec 12 18:34:04.663899 systemd[1]: Started systemd-logind.service - User Login Management.
Dec 12 18:34:04.668342 systemd[1]: Started update-engine.service - Update Engine.
Dec 12 18:34:04.669134 update_engine[1497]: I20251212 18:34:04.668990  1497 update_check_scheduler.cc:74] Next update check in 10m59s
Dec 12 18:34:04.672669 systemd[1]: Started locksmithd.service - Cluster reboot manager.
Dec 12 18:34:04.708359 kernel: EDAC MC: Ver: 3.0.0
Dec 12 18:34:04.733173 bash[1534]: Updated "/home/core/.ssh/authorized_keys"
Dec 12 18:34:04.738545 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition.
Dec 12 18:34:04.745286 locksmithd[1521]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot"
Dec 12 18:34:04.782901 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met.
Dec 12 18:34:04.848652 containerd[1505]: time="2025-12-12T18:34:04Z" 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
Dec 12 18:34:04.849300 containerd[1505]: time="2025-12-12T18:34:04.849269694Z" level=info msg="starting containerd" revision=4ac6c20c7bbf8177f29e46bbdc658fec02ffb8ad version=v2.0.7
Dec 12 18:34:04.854290 sshd_keygen[1500]: ssh-keygen: generating new host keys: RSA ECDSA ED25519
Dec 12 18:34:04.860347 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup.
Dec 12 18:34:04.861536 containerd[1505]: time="2025-12-12T18:34:04.861496133Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="9.448µs"
Dec 12 18:34:04.861621 containerd[1505]: time="2025-12-12T18:34:04.861605358Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1
Dec 12 18:34:04.861676 containerd[1505]: time="2025-12-12T18:34:04.861661934Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1
Dec 12 18:34:04.861909 containerd[1505]: time="2025-12-12T18:34:04.861892266Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1
Dec 12 18:34:04.861971 containerd[1505]: time="2025-12-12T18:34:04.861958470Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1
Dec 12 18:34:04.862046 containerd[1505]: time="2025-12-12T18:34:04.862032729Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1
Dec 12 18:34:04.862164 containerd[1505]: time="2025-12-12T18:34:04.862147655Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1
Dec 12 18:34:04.862230 containerd[1505]: time="2025-12-12T18:34:04.862199071Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1
Dec 12 18:34:04.862600 containerd[1505]: time="2025-12-12T18:34:04.862577310Z" 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
Dec 12 18:34:04.862668 containerd[1505]: time="2025-12-12T18:34:04.862652742Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1
Dec 12 18:34:04.862733 containerd[1505]: time="2025-12-12T18:34:04.862716892Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1
Dec 12 18:34:04.862809 containerd[1505]: time="2025-12-12T18:34:04.862791412Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1
Dec 12 18:34:04.863011 containerd[1505]: time="2025-12-12T18:34:04.862990886Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1
Dec 12 18:34:04.863305 containerd[1505]: time="2025-12-12T18:34:04.863287442Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1
Dec 12 18:34:04.863389 containerd[1505]: time="2025-12-12T18:34:04.863375227Z" 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
Dec 12 18:34:04.863444 containerd[1505]: time="2025-12-12T18:34:04.863432374Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1
Dec 12 18:34:04.863512 containerd[1505]: time="2025-12-12T18:34:04.863500592Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1
Dec 12 18:34:04.863741 containerd[1505]: time="2025-12-12T18:34:04.863727608Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1
Dec 12 18:34:04.863839 containerd[1505]: time="2025-12-12T18:34:04.863826313Z" level=info msg="metadata content store policy set" policy=shared
Dec 12 18:34:04.873434 containerd[1505]: time="2025-12-12T18:34:04.873384478Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1
Dec 12 18:34:04.873434 containerd[1505]: time="2025-12-12T18:34:04.873435353Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1
Dec 12 18:34:04.873517 containerd[1505]: time="2025-12-12T18:34:04.873451694Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1
Dec 12 18:34:04.873517 containerd[1505]: time="2025-12-12T18:34:04.873467634Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1
Dec 12 18:34:04.873517 containerd[1505]: time="2025-12-12T18:34:04.873488924Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1
Dec 12 18:34:04.873517 containerd[1505]: time="2025-12-12T18:34:04.873503441Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1
Dec 12 18:34:04.875229 containerd[1505]: time="2025-12-12T18:34:04.873746807Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1
Dec 12 18:34:04.875229 containerd[1505]: time="2025-12-12T18:34:04.873778717Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1
Dec 12 18:34:04.875229 containerd[1505]: time="2025-12-12T18:34:04.873800478Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1
Dec 12 18:34:04.875229 containerd[1505]: time="2025-12-12T18:34:04.873823481Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1
Dec 12 18:34:04.875229 containerd[1505]: time="2025-12-12T18:34:04.874325723Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1
Dec 12 18:34:04.875229 containerd[1505]: time="2025-12-12T18:34:04.874370577Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2
Dec 12 18:34:04.875229 containerd[1505]: time="2025-12-12T18:34:04.874534735Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1
Dec 12 18:34:04.875229 containerd[1505]: time="2025-12-12T18:34:04.874556616Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1
Dec 12 18:34:04.875229 containerd[1505]: time="2025-12-12T18:34:04.874570021Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1
Dec 12 18:34:04.875229 containerd[1505]: time="2025-12-12T18:34:04.874579349Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1
Dec 12 18:34:04.875229 containerd[1505]: time="2025-12-12T18:34:04.874588886Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1
Dec 12 18:34:04.875229 containerd[1505]: time="2025-12-12T18:34:04.874598124Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1
Dec 12 18:34:04.875229 containerd[1505]: time="2025-12-12T18:34:04.874613843Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1
Dec 12 18:34:04.875229 containerd[1505]: time="2025-12-12T18:34:04.874623020Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1
Dec 12 18:34:04.875229 containerd[1505]: time="2025-12-12T18:34:04.874632989Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1
Dec 12 18:34:04.875625 containerd[1505]: time="2025-12-12T18:34:04.874646384Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1
Dec 12 18:34:04.875625 containerd[1505]: time="2025-12-12T18:34:04.874655892Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1
Dec 12 18:34:04.875625 containerd[1505]: time="2025-12-12T18:34:04.874696839Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\""
Dec 12 18:34:04.875625 containerd[1505]: time="2025-12-12T18:34:04.874707369Z" level=info msg="Start snapshots syncer"
Dec 12 18:34:04.875625 containerd[1505]: time="2025-12-12T18:34:04.874727256Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1
Dec 12 18:34:04.875753 containerd[1505]: time="2025-12-12T18:34:04.874953751Z" 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\"}"
Dec 12 18:34:04.875753 containerd[1505]: time="2025-12-12T18:34:04.875000308Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1
Dec 12 18:34:04.875880 containerd[1505]: time="2025-12-12T18:34:04.875032408Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1
Dec 12 18:34:04.875880 containerd[1505]: time="2025-12-12T18:34:04.875130793Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1
Dec 12 18:34:04.875880 containerd[1505]: time="2025-12-12T18:34:04.875151812Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1
Dec 12 18:34:04.875880 containerd[1505]: time="2025-12-12T18:34:04.875161250Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1
Dec 12 18:34:04.875880 containerd[1505]: time="2025-12-12T18:34:04.875170387Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1
Dec 12 18:34:04.875880 containerd[1505]: time="2025-12-12T18:34:04.875181708Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1
Dec 12 18:34:04.875880 containerd[1505]: time="2025-12-12T18:34:04.875190956Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1
Dec 12 18:34:04.875880 containerd[1505]: time="2025-12-12T18:34:04.875200333Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1
Dec 12 18:34:04.875880 containerd[1505]: time="2025-12-12T18:34:04.875242542Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1
Dec 12 18:34:04.875880 containerd[1505]: time="2025-12-12T18:34:04.875252381Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1
Dec 12 18:34:04.875880 containerd[1505]: time="2025-12-12T18:34:04.875262169Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1
Dec 12 18:34:04.875880 containerd[1505]: time="2025-12-12T18:34:04.875292416Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1
Dec 12 18:34:04.875880 containerd[1505]: time="2025-12-12T18:34:04.875304539Z" 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
Dec 12 18:34:04.875880 containerd[1505]: time="2025-12-12T18:34:04.875322432Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1
Dec 12 18:34:04.876169 containerd[1505]: time="2025-12-12T18:34:04.875331599Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1
Dec 12 18:34:04.876169 containerd[1505]: time="2025-12-12T18:34:04.875339604Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1
Dec 12 18:34:04.876169 containerd[1505]: time="2025-12-12T18:34:04.875348391Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1
Dec 12 18:34:04.876169 containerd[1505]: time="2025-12-12T18:34:04.875363770Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1
Dec 12 18:34:04.876169 containerd[1505]: time="2025-12-12T18:34:04.875401961Z" level=info msg="runtime interface created"
Dec 12 18:34:04.876169 containerd[1505]: time="2025-12-12T18:34:04.875407742Z" level=info msg="created NRI interface"
Dec 12 18:34:04.876169 containerd[1505]: time="2025-12-12T18:34:04.875430334Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1
Dec 12 18:34:04.876169 containerd[1505]: time="2025-12-12T18:34:04.875440824Z" level=info msg="Connect containerd service"
Dec 12 18:34:04.876169 containerd[1505]: time="2025-12-12T18:34:04.875460852Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this"
Dec 12 18:34:04.876399 containerd[1505]: time="2025-12-12T18:34:04.876318400Z" 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"
Dec 12 18:34:04.881387 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys.
Dec 12 18:34:04.885045 systemd[1]: Starting issuegen.service - Generate /run/issue...
Dec 12 18:34:04.904735 systemd[1]: issuegen.service: Deactivated successfully.
Dec 12 18:34:04.905054 systemd[1]: Finished issuegen.service - Generate /run/issue.
Dec 12 18:34:04.908857 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions...
Dec 12 18:34:04.938798 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions.
Dec 12 18:34:04.943522 systemd[1]: Started getty@tty1.service - Getty on tty1.
Dec 12 18:34:04.947017 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0.
Dec 12 18:34:04.949406 systemd[1]: Reached target getty.target - Login Prompts.
Dec 12 18:34:04.961089 containerd[1505]: time="2025-12-12T18:34:04.961028782Z" level=info msg="Start subscribing containerd event"
Dec 12 18:34:04.961264 containerd[1505]: time="2025-12-12T18:34:04.961099725Z" level=info msg="Start recovering state"
Dec 12 18:34:04.961264 containerd[1505]: time="2025-12-12T18:34:04.961217686Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc
Dec 12 18:34:04.961334 containerd[1505]: time="2025-12-12T18:34:04.961232033Z" level=info msg="Start event monitor"
Dec 12 18:34:04.961334 containerd[1505]: time="2025-12-12T18:34:04.961285373Z" level=info msg="Start cni network conf syncer for default"
Dec 12 18:34:04.961334 containerd[1505]: time="2025-12-12T18:34:04.961294190Z" level=info msg="Start streaming server"
Dec 12 18:34:04.961334 containerd[1505]: time="2025-12-12T18:34:04.961298027Z" level=info msg=serving... address=/run/containerd/containerd.sock
Dec 12 18:34:04.961334 containerd[1505]: time="2025-12-12T18:34:04.961323024Z" level=info msg="Registered namespace \"k8s.io\" with NRI"
Dec 12 18:34:04.961334 containerd[1505]: time="2025-12-12T18:34:04.961332802Z" level=info msg="runtime interface starting up..."
Dec 12 18:34:04.961481 containerd[1505]: time="2025-12-12T18:34:04.961342601Z" level=info msg="starting plugins..."
Dec 12 18:34:04.961481 containerd[1505]: time="2025-12-12T18:34:04.961358651Z" level=info msg="Synchronizing NRI (plugin) with current runtime state"
Dec 12 18:34:04.961528 containerd[1505]: time="2025-12-12T18:34:04.961511918Z" level=info msg="containerd successfully booted in 0.113369s"
Dec 12 18:34:04.961623 systemd[1]: Started containerd.service - containerd container runtime.
Dec 12 18:34:05.975861 systemd-networkd[1410]: eth0: Gained IPv6LL
Dec 12 18:34:05.983130 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured.
Dec 12 18:34:05.988610 systemd[1]: Reached target network-online.target - Network is Online.
Dec 12 18:34:05.994669 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent...
Dec 12 18:34:06.000338 systemd[1]: Starting nvidia.service - NVIDIA Configure Service...
Dec 12 18:34:06.078788 systemd[1]: Finished nvidia.service - NVIDIA Configure Service.
Dec 12 18:34:06.084047 systemd[1]: coreos-metadata.service: Deactivated successfully.
Dec 12 18:34:06.084441 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent.
Dec 12 18:34:06.097417 systemd[1]: Starting etcd-member.service - etcd (System Application Container)...
Dec 12 18:34:06.102151 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met.
Dec 12 18:34:06.122014 (-wrapper)[1596]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS
Dec 12 18:34:06.410263 systemd[1]: Starting docker.service - Docker Application Container Engine...
Dec 12 18:34:06.435571 (dockerd)[1607]: 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
Dec 12 18:34:06.832105 dockerd[1607]: time="2025-12-12T18:34:06.831968698Z" level=info msg="Starting up"
Dec 12 18:34:06.832889 dockerd[1607]: time="2025-12-12T18:34:06.832864800Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider"
Dec 12 18:34:06.851416 dockerd[1607]: time="2025-12-12T18:34:06.851346605Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s
Dec 12 18:34:06.933351 dockerd[1607]: time="2025-12-12T18:34:06.933274064Z" level=info msg="Loading containers: start."
Dec 12 18:34:06.950500 kernel: Initializing XFRM netlink socket
Dec 12 18:34:07.307587 systemd-networkd[1410]: docker0: Link UP
Dec 12 18:34:07.315766 dockerd[1607]: time="2025-12-12T18:34:07.315677376Z" level=info msg="Loading containers: done."
Dec 12 18:34:07.340629 dockerd[1607]: time="2025-12-12T18:34:07.340118450Z" 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
Dec 12 18:34:07.340629 dockerd[1607]: time="2025-12-12T18:34:07.340256515Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4
Dec 12 18:34:07.340629 dockerd[1607]: time="2025-12-12T18:34:07.340373747Z" level=info msg="Initializing buildkit"
Dec 12 18:34:07.392469 dockerd[1607]: time="2025-12-12T18:34:07.392411303Z" level=info msg="Completed buildkit initialization"
Dec 12 18:34:07.400252 dockerd[1607]: time="2025-12-12T18:34:07.398648060Z" level=info msg="Daemon has completed initialization"
Dec 12 18:34:07.400252 dockerd[1607]: time="2025-12-12T18:34:07.399095728Z" level=info msg="API listen on /run/docker.sock"
Dec 12 18:34:07.400392 systemd[1]: Started docker.service - Docker Application Container Engine.
Dec 12 18:34:07.401303 etcd-wrapper[1600]: Error response from daemon: No such container: etcd-member
Dec 12 18:34:07.422134 etcd-wrapper[1808]: Error response from daemon: No such container: etcd-member
Dec 12 18:34:07.455546 etcd-wrapper[1831]: Unable to find image 'quay.io/coreos/etcd:v3.5.22' locally
Dec 12 18:34:09.514471 etcd-wrapper[1831]: v3.5.22: Pulling from coreos/etcd
Dec 12 18:34:09.980885 etcd-wrapper[1831]: f5083dc2076d: Pulling fs layer
Dec 12 18:34:09.981302 etcd-wrapper[1831]: 2ae710cd8bfe: Pulling fs layer
Dec 12 18:34:09.981375 etcd-wrapper[1831]: d462aa345367: Pulling fs layer
Dec 12 18:34:09.981375 etcd-wrapper[1831]: 0f8b424aa0b9: Pulling fs layer
Dec 12 18:34:09.981375 etcd-wrapper[1831]: d557676654e5: Pulling fs layer
Dec 12 18:34:09.981375 etcd-wrapper[1831]: c8022d07192e: Pulling fs layer
Dec 12 18:34:09.981375 etcd-wrapper[1831]: d858cbc252ad: Pulling fs layer
Dec 12 18:34:09.981375 etcd-wrapper[1831]: 1069fc2daed1: Pulling fs layer
Dec 12 18:34:09.981375 etcd-wrapper[1831]: b40161cd83fc: Pulling fs layer
Dec 12 18:34:09.981575 etcd-wrapper[1831]: 5318d93a3a65: Pulling fs layer
Dec 12 18:34:09.981575 etcd-wrapper[1831]: 307c1adadb60: Pulling fs layer
Dec 12 18:34:09.981575 etcd-wrapper[1831]: 60a82e1b19c6: Pulling fs layer
Dec 12 18:34:09.981575 etcd-wrapper[1831]: 6f77055ebb6e: Pulling fs layer
Dec 12 18:34:09.981575 etcd-wrapper[1831]: 67f98bbc9874: Pulling fs layer
Dec 12 18:34:09.981575 etcd-wrapper[1831]: b40161cd83fc: Waiting
Dec 12 18:34:09.981575 etcd-wrapper[1831]: 5318d93a3a65: Waiting
Dec 12 18:34:09.981575 etcd-wrapper[1831]: 307c1adadb60: Waiting
Dec 12 18:34:09.981848 etcd-wrapper[1831]: 60a82e1b19c6: Waiting
Dec 12 18:34:09.981848 etcd-wrapper[1831]: 6f77055ebb6e: Waiting
Dec 12 18:34:09.981848 etcd-wrapper[1831]: 67f98bbc9874: Waiting
Dec 12 18:34:09.981848 etcd-wrapper[1831]: 0f8b424aa0b9: Waiting
Dec 12 18:34:09.981848 etcd-wrapper[1831]: d557676654e5: Waiting
Dec 12 18:34:09.981848 etcd-wrapper[1831]: c8022d07192e: Waiting
Dec 12 18:34:09.981848 etcd-wrapper[1831]: d858cbc252ad: Waiting
Dec 12 18:34:09.981848 etcd-wrapper[1831]: 1069fc2daed1: Waiting
Dec 12 18:34:10.241373 etcd-wrapper[1831]: d462aa345367: Download complete
Dec 12 18:34:10.254038 etcd-wrapper[1831]: f5083dc2076d: Verifying Checksum
Dec 12 18:34:10.254038 etcd-wrapper[1831]: f5083dc2076d: Download complete
Dec 12 18:34:10.315133 etcd-wrapper[1831]: f5083dc2076d: Pull complete
Dec 12 18:34:10.335977 etcd-wrapper[1831]: 2ae710cd8bfe: Verifying Checksum
Dec 12 18:34:10.366080 systemd[1]: var-lib-docker-overlay2-e3bf784093cf9a04972596b919a838115ee2d280c3fee0b4d223edc276346ae1-merged.mount: Deactivated successfully.
Dec 12 18:34:10.413008 etcd-wrapper[1831]: 2ae710cd8bfe: Pull complete
Dec 12 18:34:10.488779 etcd-wrapper[1831]: d557676654e5: Download complete
Dec 12 18:34:10.585484 etcd-wrapper[1831]: c8022d07192e: Download complete
Dec 12 18:34:10.602559 etcd-wrapper[1831]: 0f8b424aa0b9: Verifying Checksum
Dec 12 18:34:10.602559 etcd-wrapper[1831]: 0f8b424aa0b9: Download complete
Dec 12 18:34:10.854004 etcd-wrapper[1831]: 1069fc2daed1: Download complete
Dec 12 18:34:10.857388 etcd-wrapper[1831]: d858cbc252ad: Verifying Checksum
Dec 12 18:34:10.857388 etcd-wrapper[1831]: d858cbc252ad: Download complete
Dec 12 18:34:10.860575 etcd-wrapper[1831]: b40161cd83fc: Verifying Checksum
Dec 12 18:34:10.860575 etcd-wrapper[1831]: b40161cd83fc: Download complete
Dec 12 18:34:11.012784 systemd[1]: var-lib-docker-overlay2-05da0d6af3c8bb461e9dffcbc756fcfccbd18ff645687a1d59e67d1c6fe0c921-merged.mount: Deactivated successfully.
Dec 12 18:34:11.054603 etcd-wrapper[1831]: d462aa345367: Pull complete
Dec 12 18:34:11.083716 systemd[1]: var-lib-docker-overlay2-f68895a8810ac8a7f1573f4ec66419d1a3b9a4f9cacc628b19682833aceead4d-merged.mount: Deactivated successfully.
Dec 12 18:34:11.114646 etcd-wrapper[1831]: 5318d93a3a65: Verifying Checksum
Dec 12 18:34:11.114646 etcd-wrapper[1831]: 5318d93a3a65: Download complete
Dec 12 18:34:11.116599 etcd-wrapper[1831]: 0f8b424aa0b9: Pull complete
Dec 12 18:34:11.155760 systemd[1]: var-lib-docker-overlay2-19f54d3e86bd6b57bd562507251e78d710cb2814324dab792184ad1e6f5b6494-merged.mount: Deactivated successfully.
Dec 12 18:34:11.178046 etcd-wrapper[1831]: 60a82e1b19c6: Verifying Checksum
Dec 12 18:34:11.178767 etcd-wrapper[1831]: 60a82e1b19c6: Download complete
Dec 12 18:34:11.223500 etcd-wrapper[1831]: d557676654e5: Pull complete
Dec 12 18:34:11.255489 etcd-wrapper[1831]: 307c1adadb60: Verifying Checksum
Dec 12 18:34:11.255489 etcd-wrapper[1831]: 307c1adadb60: Download complete
Dec 12 18:34:11.331135 etcd-wrapper[1831]: c8022d07192e: Pull complete
Dec 12 18:34:11.410606 etcd-wrapper[1831]: d858cbc252ad: Pull complete
Dec 12 18:34:11.506382 etcd-wrapper[1831]: 1069fc2daed1: Pull complete
Dec 12 18:34:11.521435 etcd-wrapper[1831]: 67f98bbc9874: Verifying Checksum
Dec 12 18:34:11.521435 etcd-wrapper[1831]: 67f98bbc9874: Download complete
Dec 12 18:34:11.601185 etcd-wrapper[1831]: 6f77055ebb6e: Verifying Checksum
Dec 12 18:34:11.601185 etcd-wrapper[1831]: 6f77055ebb6e: Download complete
Dec 12 18:34:11.653335 etcd-wrapper[1831]: b40161cd83fc: Pull complete
Dec 12 18:34:11.723639 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd.
Dec 12 18:34:11.729183 systemd[1]: Started sshd@0-10.0.0.42:22-10.0.0.1:40962.service - OpenSSH per-connection server daemon (10.0.0.1:40962).
Dec 12 18:34:11.745940 etcd-wrapper[1831]: 5318d93a3a65: Pull complete
Dec 12 18:34:11.820907 etcd-wrapper[1831]: 307c1adadb60: Pull complete
Dec 12 18:34:11.878036 sshd[1900]: Accepted publickey for core from 10.0.0.1 port 40962 ssh2: RSA SHA256:XP/ExdhtEcSQYMAbRPAas7ojuw0It2lorfeil1KG79k
Dec 12 18:34:11.883654 sshd-session[1900]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Dec 12 18:34:11.893589 systemd[1]: Created slice user-500.slice - User Slice of UID 500.
Dec 12 18:34:11.896709 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500...
Dec 12 18:34:11.910326 systemd-logind[1485]: New session 1 of user core.
Dec 12 18:34:11.937601 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500.
Dec 12 18:34:11.941254 systemd[1]: Starting user@500.service - User Manager for UID 500...
Dec 12 18:34:11.960208 (systemd)[1915]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0)
Dec 12 18:34:11.964641 systemd-logind[1485]: New session c1 of user core.
Dec 12 18:34:12.144351 systemd[1]: var-lib-docker-overlay2-d933d7e646c05e31a16a6f32f302794c485adc87735c902000d9e4563e5f39b7-merged.mount: Deactivated successfully.
Dec 12 18:34:12.408660 systemd[1915]: Queued start job for default target default.target.
Dec 12 18:34:12.430488 systemd[1915]: Created slice app.slice - User Application Slice.
Dec 12 18:34:12.430526 systemd[1915]: Reached target paths.target - Paths.
Dec 12 18:34:12.430596 systemd[1915]: Reached target timers.target - Timers.
Dec 12 18:34:12.433335 systemd[1915]: Starting dbus.socket - D-Bus User Message Bus Socket...
Dec 12 18:34:12.450949 systemd[1915]: Listening on dbus.socket - D-Bus User Message Bus Socket.
Dec 12 18:34:12.451136 systemd[1915]: Reached target sockets.target - Sockets.
Dec 12 18:34:12.451202 systemd[1915]: Reached target basic.target - Basic System.
Dec 12 18:34:12.451287 systemd[1915]: Reached target default.target - Main User Target.
Dec 12 18:34:12.451332 systemd[1915]: Startup finished in 471ms.
Dec 12 18:34:12.451924 systemd[1]: Started user@500.service - User Manager for UID 500.
Dec 12 18:34:12.452072 etcd-wrapper[1831]: 60a82e1b19c6: Pull complete
Dec 12 18:34:12.463689 systemd[1]: Started session-1.scope - Session 1 of User core.
Dec 12 18:34:12.544041 systemd[1]: Started sshd@1-10.0.0.42:22-10.0.0.1:40978.service - OpenSSH per-connection server daemon (10.0.0.1:40978).
Dec 12 18:34:12.622440 sshd[1931]: Accepted publickey for core from 10.0.0.1 port 40978 ssh2: RSA SHA256:XP/ExdhtEcSQYMAbRPAas7ojuw0It2lorfeil1KG79k
Dec 12 18:34:12.624913 sshd-session[1931]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Dec 12 18:34:12.633038 systemd-logind[1485]: New session 2 of user core.
Dec 12 18:34:12.643549 systemd[1]: Started session-2.scope - Session 2 of User core.
Dec 12 18:34:12.684639 systemd[1]: var-lib-docker-overlay2-1ef920540875122875e12c130f855637fea22f1a711ba82a09cae71b85caa587-merged.mount: Deactivated successfully.
Dec 12 18:34:12.707927 sshd[1935]: Connection closed by 10.0.0.1 port 40978
Dec 12 18:34:12.708394 sshd-session[1931]: pam_unix(sshd:session): session closed for user core
Dec 12 18:34:12.724918 systemd[1]: sshd@1-10.0.0.42:22-10.0.0.1:40978.service: Deactivated successfully.
Dec 12 18:34:12.728137 systemd[1]: session-2.scope: Deactivated successfully.
Dec 12 18:34:12.730436 systemd-logind[1485]: Session 2 logged out. Waiting for processes to exit.
Dec 12 18:34:12.734642 systemd[1]: Started sshd@2-10.0.0.42:22-10.0.0.1:40984.service - OpenSSH per-connection server daemon (10.0.0.1:40984).
Dec 12 18:34:12.735639 systemd-logind[1485]: Removed session 2.
Dec 12 18:34:12.799078 sshd[1941]: Accepted publickey for core from 10.0.0.1 port 40984 ssh2: RSA SHA256:XP/ExdhtEcSQYMAbRPAas7ojuw0It2lorfeil1KG79k
Dec 12 18:34:12.801446 sshd-session[1941]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Dec 12 18:34:12.815343 systemd-logind[1485]: New session 3 of user core.
Dec 12 18:34:12.823667 systemd[1]: Started session-3.scope - Session 3 of User core.
Dec 12 18:34:12.831162 etcd-wrapper[1831]: 6f77055ebb6e: Pull complete
Dec 12 18:34:12.894062 sshd[1944]: Connection closed by 10.0.0.1 port 40984
Dec 12 18:34:12.894477 sshd-session[1941]: pam_unix(sshd:session): session closed for user core
Dec 12 18:34:12.900290 systemd-logind[1485]: Session 3 logged out. Waiting for processes to exit.
Dec 12 18:34:12.900633 systemd[1]: sshd@2-10.0.0.42:22-10.0.0.1:40984.service: Deactivated successfully.
Dec 12 18:34:12.902957 systemd[1]: session-3.scope: Deactivated successfully.
Dec 12 18:34:12.905868 systemd-logind[1485]: Removed session 3.
Dec 12 18:34:13.027357 systemd[1]: var-lib-docker-overlay2-87e4c6a9a42725795402f18ed196e7f026c13e9cd7d14f0c5739628cd3b8852a-merged.mount: Deactivated successfully.
Dec 12 18:34:13.127700 etcd-wrapper[1831]: 67f98bbc9874: Pull complete
Dec 12 18:34:13.166358 etcd-wrapper[1831]: Digest: sha256:51efcfc4f3a863c072ebe745942f790878c2a268e713a6d140c4f2593271adc6
Dec 12 18:34:13.180571 etcd-wrapper[1831]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.22
Dec 12 18:34:13.205980 systemd[1]: var-lib-docker-overlay2-272f95c6e3ddd14caeaa8a73cd379c72073a0e5d7087840ec69889fa59e9db8a\x2dinit-merged.mount: Deactivated successfully.
Dec 12 18:34:13.257829 systemd[1]: var-lib-docker-overlay2-272f95c6e3ddd14caeaa8a73cd379c72073a0e5d7087840ec69889fa59e9db8a-merged.mount: Deactivated successfully.
Dec 12 18:34:13.362081 containerd[1505]: time="2025-12-12T18:34:13.361651131Z" level=info msg="connecting to shim 9d57f765dc3855ee6cca9c7ce8dee98e282db510613e38df0288d788249e6515" address="unix:///run/containerd/s/d1e5bd32e57c71f1ead2b5930e666689744ca2a0154d342574ce268802f092ef" namespace=moby protocol=ttrpc version=3
Dec 12 18:34:13.417573 systemd[1]: Started docker-9d57f765dc3855ee6cca9c7ce8dee98e282db510613e38df0288d788249e6515.scope - libcontainer container 9d57f765dc3855ee6cca9c7ce8dee98e282db510613e38df0288d788249e6515.
Dec 12 18:34:13.453904 systemd-resolved[1332]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address
Dec 12 18:34:13.593477 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.591464Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"}
Dec 12 18:34:13.593477 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.591723Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"c35380178e3c4992bd53837c62d29057"}
Dec 12 18:34:13.593477 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:34:13.591755Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"}
Dec 12 18:34:13.593477 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:34:13.591765Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.22"}
Dec 12 18:34:13.593477 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:34:13.591770Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"}
Dec 12 18:34:13.593477 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:34:13.591775Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"}
Dec 12 18:34:13.593477 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:34:13.591806Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."}
Dec 12 18:34:13.593477 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.591912Z","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.42:2379"]}
Dec 12 18:34:13.593477 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:34:13.592014Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."}
Dec 12 18:34:13.593477 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.592034Z","caller":"embed/etcd.go:140","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]}
Dec 12 18:34:13.593477 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.592624Z","caller":"embed/etcd.go:148","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]}
Dec 12 18:34:13.594082 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.592783Z","caller":"embed/etcd.go:323","msg":"starting an etcd server","etcd-version":"3.5.22","git-sha":"7862c85","go-version":"go1.23.11","go-os":"linux","go-arch":"amd64","max-cpu-set":4,"max-cpu-available":4,"member-initialized":false,"name":"c35380178e3c4992bd53837c62d29057","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.42:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"c35380178e3c4992bd53837c62d29057=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"}
Dec 12 18:34:13.599746 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.599354Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.080055ms"}
Dec 12 18:34:13.612011 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.608586Z","caller":"etcdserver/raft.go:507","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"}
Dec 12 18:34:13.612011 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.608800Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"}
Dec 12 18:34:13.612011 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.608844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"}
Dec 12 18:34:13.612011 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.608855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
Dec 12 18:34:13.612011 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.608870Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"}
Dec 12 18:34:13.612011 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.608909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"}
Dec 12 18:34:13.621785 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:34:13.621290Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
Dec 12 18:34:13.626245 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.625275Z","caller":"mvcc/kvstore.go:425","msg":"kvstore restored","current-rev":1}
Dec 12 18:34:13.626245 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.625338Z","caller":"etcdserver/server.go:628","msg":"restore consistentIndex","index":0}
Dec 12 18:34:13.627504 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.627245Z","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"}
Dec 12 18:34:13.634139 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.630955Z","caller":"etcdserver/server.go:875","msg":"starting etcd server","local-member-id":"8e9e05c52164694d","local-server-version":"3.5.22","cluster-version":"to_be_decided"}
Dec 12 18:34:13.639979 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.634470Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"}
Dec 12 18:34:13.639979 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.634686Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"}
Dec 12 18:34:13.639979 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.634574Z","caller":"etcdserver/server.go:759","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8e9e05c52164694d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
Dec 12 18:34:13.639979 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.634729Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"}
Dec 12 18:34:13.639979 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.634737Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"}
Dec 12 18:34:13.641942 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.637833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"}
Dec 12 18:34:13.641942 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.640089Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","added-peer-id":"8e9e05c52164694d","added-peer-peer-urls":["http://localhost:2380"],"added-peer-is-learner":false}
Dec 12 18:34:13.641942 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.639791Z","caller":"embed/etcd.go:633","msg":"serving peer traffic","address":"127.0.0.1:2380"}
Dec 12 18:34:13.641942 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.640380Z","caller":"embed/etcd.go:603","msg":"cmux::serve","address":"127.0.0.1:2380"}
Dec 12 18:34:13.641942 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.639833Z","caller":"embed/etcd.go:292","msg":"now serving peer/client/metrics","local-member-id":"8e9e05c52164694d","initial-advertise-peer-urls":["http://localhost:2380"],"listen-peer-urls":["http://localhost:2380"],"advertise-client-urls":["http://10.0.0.42:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]}
Dec 12 18:34:13.811456 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.810145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"}
Dec 12 18:34:13.811456 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.810984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"}
Dec 12 18:34:13.811456 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.811285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"}
Dec 12 18:34:13.811827 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.811359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"}
Dec 12 18:34:13.811827 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.811416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"}
Dec 12 18:34:13.811827 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.811431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"}
Dec 12 18:34:13.811827 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.811441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"}
Dec 12 18:34:13.814857 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.812900Z","caller":"etcdserver/server.go:2144","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:c35380178e3c4992bd53837c62d29057 ClientURLs:[http://10.0.0.42:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"}
Dec 12 18:34:13.814857 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.812954Z","caller":"embed/serve.go:124","msg":"ready to serve client requests"}
Dec 12 18:34:13.814857 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.813126Z","caller":"etcdserver/server.go:2697","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
Dec 12 18:34:13.814857 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.814310Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
Dec 12 18:34:13.814857 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.814586Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
Dec 12 18:34:13.814919 systemd[1]: Started etcd-member.service - etcd (System Application Container).
Dec 12 18:34:13.815687 systemd[1]: Reached target multi-user.target - Multi-User System.
Dec 12 18:34:13.817599 systemd[1]: Startup finished in 2.988s (kernel) + 4.602s (initrd) + 11.637s (userspace) = 19.229s.
Dec 12 18:34:13.819923 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.816128Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"}
Dec 12 18:34:13.819923 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.817191Z","caller":"embed/serve.go:210","msg":"serving client traffic insecurely; this is strongly discouraged!","traffic":"grpc+http","address":"[::]:2379"}
Dec 12 18:34:13.819923 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.819062Z","caller":"membership/cluster.go:587","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"}
Dec 12 18:34:13.820111 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.819775Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
Dec 12 18:34:13.820111 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:34:13.819867Z","caller":"etcdserver/server.go:2721","msg":"cluster version is updated","cluster-version":"3.5"}
Dec 12 18:34:22.971442 systemd[1]: Started sshd@3-10.0.0.42:22-10.0.0.1:54258.service - OpenSSH per-connection server daemon (10.0.0.1:54258).
Dec 12 18:34:23.033719 sshd[2012]: Accepted publickey for core from 10.0.0.1 port 54258 ssh2: RSA SHA256:XP/ExdhtEcSQYMAbRPAas7ojuw0It2lorfeil1KG79k
Dec 12 18:34:23.035679 sshd-session[2012]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Dec 12 18:34:23.041386 systemd-logind[1485]: New session 4 of user core.
Dec 12 18:34:23.051542 systemd[1]: Started session-4.scope - Session 4 of User core.
Dec 12 18:34:23.107770 sshd[2015]: Connection closed by 10.0.0.1 port 54258
Dec 12 18:34:23.108229 sshd-session[2012]: pam_unix(sshd:session): session closed for user core
Dec 12 18:34:23.122267 systemd[1]: sshd@3-10.0.0.42:22-10.0.0.1:54258.service: Deactivated successfully.
Dec 12 18:34:23.124381 systemd[1]: session-4.scope: Deactivated successfully.
Dec 12 18:34:23.125290 systemd-logind[1485]: Session 4 logged out. Waiting for processes to exit.
Dec 12 18:34:23.128312 systemd[1]: Started sshd@4-10.0.0.42:22-10.0.0.1:54270.service - OpenSSH per-connection server daemon (10.0.0.1:54270).
Dec 12 18:34:23.129177 systemd-logind[1485]: Removed session 4.
Dec 12 18:34:23.186241 sshd[2021]: Accepted publickey for core from 10.0.0.1 port 54270 ssh2: RSA SHA256:XP/ExdhtEcSQYMAbRPAas7ojuw0It2lorfeil1KG79k
Dec 12 18:34:23.188255 sshd-session[2021]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Dec 12 18:34:23.194241 systemd-logind[1485]: New session 5 of user core.
Dec 12 18:34:23.204753 systemd[1]: Started session-5.scope - Session 5 of User core.
Dec 12 18:34:23.257861 sshd[2024]: Connection closed by 10.0.0.1 port 54270
Dec 12 18:34:23.259054 sshd-session[2021]: pam_unix(sshd:session): session closed for user core
Dec 12 18:34:23.273443 systemd[1]: sshd@4-10.0.0.42:22-10.0.0.1:54270.service: Deactivated successfully.
Dec 12 18:34:23.275994 systemd[1]: session-5.scope: Deactivated successfully.
Dec 12 18:34:23.276951 systemd-logind[1485]: Session 5 logged out. Waiting for processes to exit.
Dec 12 18:34:23.280796 systemd[1]: Started sshd@5-10.0.0.42:22-10.0.0.1:54284.service - OpenSSH per-connection server daemon (10.0.0.1:54284).
Dec 12 18:34:23.281610 systemd-logind[1485]: Removed session 5.
Dec 12 18:34:23.346147 sshd[2030]: Accepted publickey for core from 10.0.0.1 port 54284 ssh2: RSA SHA256:XP/ExdhtEcSQYMAbRPAas7ojuw0It2lorfeil1KG79k
Dec 12 18:34:23.348128 sshd-session[2030]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Dec 12 18:34:23.353756 systemd-logind[1485]: New session 6 of user core.
Dec 12 18:34:23.370534 systemd[1]: Started session-6.scope - Session 6 of User core.
Dec 12 18:34:23.429784 sshd[2033]: Connection closed by 10.0.0.1 port 54284
Dec 12 18:34:23.430187 sshd-session[2030]: pam_unix(sshd:session): session closed for user core
Dec 12 18:34:23.450331 systemd[1]: sshd@5-10.0.0.42:22-10.0.0.1:54284.service: Deactivated successfully.
Dec 12 18:34:23.452661 systemd[1]: session-6.scope: Deactivated successfully.
Dec 12 18:34:23.453665 systemd-logind[1485]: Session 6 logged out. Waiting for processes to exit.
Dec 12 18:34:23.457291 systemd[1]: Started sshd@6-10.0.0.42:22-10.0.0.1:54296.service - OpenSSH per-connection server daemon (10.0.0.1:54296).
Dec 12 18:34:23.458116 systemd-logind[1485]: Removed session 6.
Dec 12 18:34:23.526044 sshd[2039]: Accepted publickey for core from 10.0.0.1 port 54296 ssh2: RSA SHA256:XP/ExdhtEcSQYMAbRPAas7ojuw0It2lorfeil1KG79k
Dec 12 18:34:23.527879 sshd-session[2039]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Dec 12 18:34:23.533588 systemd-logind[1485]: New session 7 of user core.
Dec 12 18:34:23.547495 systemd[1]: Started session-7.scope - Session 7 of User core.
Dec 12 18:34:23.612145 sudo[2043]:     core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1
Dec 12 18:34:23.612561 sudo[2043]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500)
Dec 12 18:34:23.635166 sudo[2043]: pam_unix(sudo:session): session closed for user root
Dec 12 18:34:23.636904 sshd[2042]: Connection closed by 10.0.0.1 port 54296
Dec 12 18:34:23.637392 sshd-session[2039]: pam_unix(sshd:session): session closed for user core
Dec 12 18:34:23.650793 systemd[1]: sshd@6-10.0.0.42:22-10.0.0.1:54296.service: Deactivated successfully.
Dec 12 18:34:23.652810 systemd[1]: session-7.scope: Deactivated successfully.
Dec 12 18:34:23.653687 systemd-logind[1485]: Session 7 logged out. Waiting for processes to exit.
Dec 12 18:34:23.656667 systemd[1]: Started sshd@7-10.0.0.42:22-10.0.0.1:54304.service - OpenSSH per-connection server daemon (10.0.0.1:54304).
Dec 12 18:34:23.657446 systemd-logind[1485]: Removed session 7.
Dec 12 18:34:23.715404 sshd[2049]: Accepted publickey for core from 10.0.0.1 port 54304 ssh2: RSA SHA256:XP/ExdhtEcSQYMAbRPAas7ojuw0It2lorfeil1KG79k
Dec 12 18:34:23.717351 sshd-session[2049]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Dec 12 18:34:23.723487 systemd-logind[1485]: New session 8 of user core.
Dec 12 18:34:23.735450 systemd[1]: Started session-8.scope - Session 8 of User core.
Dec 12 18:34:23.791698 sudo[2054]:     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
Dec 12 18:34:23.792043 sudo[2054]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500)
Dec 12 18:34:23.802121 sudo[2054]: pam_unix(sudo:session): session closed for user root
Dec 12 18:34:23.810830 sudo[2053]:     core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules
Dec 12 18:34:23.811317 sudo[2053]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500)
Dec 12 18:34:23.822380 systemd[1]: Starting audit-rules.service - Load Audit Rules...
Dec 12 18:34:23.877584 augenrules[2076]: No rules
Dec 12 18:34:23.879814 systemd[1]: audit-rules.service: Deactivated successfully.
Dec 12 18:34:23.880192 systemd[1]: Finished audit-rules.service - Load Audit Rules.
Dec 12 18:34:23.881783 sudo[2053]: pam_unix(sudo:session): session closed for user root
Dec 12 18:34:23.883617 sshd[2052]: Connection closed by 10.0.0.1 port 54304
Dec 12 18:34:23.883952 sshd-session[2049]: pam_unix(sshd:session): session closed for user core
Dec 12 18:34:23.893826 systemd[1]: sshd@7-10.0.0.42:22-10.0.0.1:54304.service: Deactivated successfully.
Dec 12 18:34:23.896011 systemd[1]: session-8.scope: Deactivated successfully.
Dec 12 18:34:23.897171 systemd-logind[1485]: Session 8 logged out. Waiting for processes to exit.
Dec 12 18:34:23.900356 systemd[1]: Started sshd@8-10.0.0.42:22-10.0.0.1:54314.service - OpenSSH per-connection server daemon (10.0.0.1:54314).
Dec 12 18:34:23.901141 systemd-logind[1485]: Removed session 8.
Dec 12 18:34:23.962693 sshd[2085]: Accepted publickey for core from 10.0.0.1 port 54314 ssh2: RSA SHA256:XP/ExdhtEcSQYMAbRPAas7ojuw0It2lorfeil1KG79k
Dec 12 18:34:23.964573 sshd-session[2085]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Dec 12 18:34:23.970734 systemd-logind[1485]: New session 9 of user core.
Dec 12 18:34:23.989503 systemd[1]: Started session-9.scope - Session 9 of User core.
Dec 12 18:34:24.043070 sshd[2088]: Connection closed by 10.0.0.1 port 54314
Dec 12 18:34:24.043786 sshd-session[2085]: pam_unix(sshd:session): session closed for user core
Dec 12 18:34:24.058386 systemd[1]: sshd@8-10.0.0.42:22-10.0.0.1:54314.service: Deactivated successfully.
Dec 12 18:34:24.060713 systemd[1]: session-9.scope: Deactivated successfully.
Dec 12 18:34:24.061709 systemd-logind[1485]: Session 9 logged out. Waiting for processes to exit.
Dec 12 18:34:24.065073 systemd[1]: Started sshd@9-10.0.0.42:22-10.0.0.1:54316.service - OpenSSH per-connection server daemon (10.0.0.1:54316).
Dec 12 18:34:24.066110 systemd-logind[1485]: Removed session 9.
Dec 12 18:34:24.136984 sshd[2096]: Accepted publickey for core from 10.0.0.1 port 54316 ssh2: RSA SHA256:XP/ExdhtEcSQYMAbRPAas7ojuw0It2lorfeil1KG79k
Dec 12 18:34:24.138861 sshd-session[2096]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Dec 12 18:34:24.143659 systemd-logind[1485]: New session 10 of user core.
Dec 12 18:34:24.158314 systemd[1]: Started session-10.scope - Session 10 of User core.
Dec 12 18:34:24.267808 sshd[2099]: Connection closed by 10.0.0.1 port 54316
Dec 12 18:34:24.268069 sshd-session[2096]: pam_unix(sshd:session): session closed for user core
Dec 12 18:34:24.274056 systemd[1]: sshd@9-10.0.0.42:22-10.0.0.1:54316.service: Deactivated successfully.
Dec 12 18:34:24.276601 systemd[1]: session-10.scope: Deactivated successfully.
Dec 12 18:34:24.277578 systemd-logind[1485]: Session 10 logged out. Waiting for processes to exit.
Dec 12 18:34:24.279596 systemd-logind[1485]: Removed session 10.
Dec 12 18:34:49.597413 update_engine[1497]: I20251212 18:34:49.597290  1497 update_attempter.cc:509] Updating boot flags...
Dec 12 18:35:20.688615 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:20.688159Z","caller":"traceutil/trace.go:171","msg":"trace[1288866501] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"114.15294ms","start":"2025-12-12T18:35:20.573976Z","end":"2025-12-12T18:35:20.688129Z","steps":["trace[1288866501] 'process raft request'  (duration: 113.316837ms)"],"step_count":1}
Dec 12 18:35:20.987987 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:20.987535Z","caller":"traceutil/trace.go:171","msg":"trace[712274151] linearizableReadLoop","detail":"{readStateIndex:292; appliedIndex:291; }","duration":"294.426871ms","start":"2025-12-12T18:35:20.693087Z","end":"2025-12-12T18:35:20.987514Z","steps":["trace[712274151] 'read index received'  (duration: 294.051824ms)","trace[712274151] 'applied index is now lower than readState.Index'  (duration: 374.356µs)"],"step_count":2}
Dec 12 18:35:20.988108 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:20.987673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.569471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" limit:1 ","response":"range_response_count:1 size:216"}
Dec 12 18:35:20.988108 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:20.987666Z","caller":"traceutil/trace.go:171","msg":"trace[253752275] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"296.565275ms","start":"2025-12-12T18:35:20.691074Z","end":"2025-12-12T18:35:20.987639Z","steps":["trace[253752275] 'process raft request'  (duration: 296.1249ms)"],"step_count":1}
Dec 12 18:35:20.988108 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:20.987710Z","caller":"traceutil/trace.go:171","msg":"trace[847834413] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:281; }","duration":"294.617867ms","start":"2025-12-12T18:35:20.693082Z","end":"2025-12-12T18:35:20.987699Z","steps":["trace[847834413] 'agreement among raft nodes before linearized reading'  (duration: 294.5276ms)"],"step_count":1}
Dec 12 18:35:25.250007 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:25.249500Z","caller":"traceutil/trace.go:171","msg":"trace[271272435] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"116.257544ms","start":"2025-12-12T18:35:25.133224Z","end":"2025-12-12T18:35:25.249482Z","steps":["trace[271272435] 'process raft request'  (duration: 116.157408ms)"],"step_count":1}
Dec 12 18:35:25.383704 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:25.383301Z","caller":"traceutil/trace.go:171","msg":"trace[1692933572] linearizableReadLoop","detail":"{readStateIndex:322; appliedIndex:320; }","duration":"208.139278ms","start":"2025-12-12T18:35:25.175141Z","end":"2025-12-12T18:35:25.383281Z","steps":["trace[1692933572] 'read index received'  (duration: 74.281015ms)","trace[1692933572] 'applied index is now lower than readState.Index'  (duration: 133.857612ms)"],"step_count":2}
Dec 12 18:35:25.383704 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:25.383342Z","caller":"traceutil/trace.go:171","msg":"trace[303283522] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"221.643138ms","start":"2025-12-12T18:35:25.161671Z","end":"2025-12-12T18:35:25.383315Z","steps":["trace[303283522] 'process raft request'  (duration: 163.497743ms)","trace[303283522] 'compare'  (duration: 57.97904ms)"],"step_count":2}
Dec 12 18:35:25.384109 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:25.383530Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.366133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-cidrs-controller\" limit:1 ","response":"range_response_count:1 size:214"}
Dec 12 18:35:25.384109 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:25.383601Z","caller":"traceutil/trace.go:171","msg":"trace[965777456] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-cidrs-controller; range_end:; response_count:1; response_revision:311; }","duration":"208.450478ms","start":"2025-12-12T18:35:25.175137Z","end":"2025-12-12T18:35:25.383588Z","steps":["trace[965777456] 'agreement among raft nodes before linearized reading'  (duration: 208.240225ms)"],"step_count":1}
Dec 12 18:35:25.384109 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:25.383603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.611599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" limit:1 ","response":"range_response_count:1 size:207"}
Dec 12 18:35:25.384109 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:25.383626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.695465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" limit:1 ","response":"range_response_count:1 size:197"}
Dec 12 18:35:25.384109 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:25.383650Z","caller":"traceutil/trace.go:171","msg":"trace[1207190533] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:311; }","duration":"108.669883ms","start":"2025-12-12T18:35:25.274972Z","end":"2025-12-12T18:35:25.383642Z","steps":["trace[1207190533] 'agreement among raft nodes before linearized reading'  (duration: 108.594716ms)"],"step_count":1}
Dec 12 18:35:25.384334 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:25.383660Z","caller":"traceutil/trace.go:171","msg":"trace[836839156] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:311; }","duration":"158.738911ms","start":"2025-12-12T18:35:25.224910Z","end":"2025-12-12T18:35:25.383649Z","steps":["trace[836839156] 'agreement among raft nodes before linearized reading'  (duration: 158.48328ms)"],"step_count":1}
Dec 12 18:35:25.622609 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:25.620927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.504567ms","expected-duration":"100ms","prefix":"","request":"header:<ID:7587891456712567863 > txn:<compare:<target:MOD key:\"/registry/minions/localhost\" mod_revision:310 > success:<request_put:<key:\"/registry/minions/localhost\" value_size:3953 >> failure:<request_range:<key:\"/registry/minions/localhost\" > >>","response":"size:16"}
Dec 12 18:35:25.622609 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:25.621002Z","caller":"traceutil/trace.go:171","msg":"trace[1024844134] linearizableReadLoop","detail":"{readStateIndex:325; appliedIndex:324; }","duration":"120.301329ms","start":"2025-12-12T18:35:25.500690Z","end":"2025-12-12T18:35:25.620991Z","steps":["trace[1024844134] 'read index received'  (duration: 13.368414ms)","trace[1024844134] 'applied index is now lower than readState.Index'  (duration: 106.932033ms)"],"step_count":2}
Dec 12 18:35:25.622609 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:25.621078Z","caller":"traceutil/trace.go:171","msg":"trace[1812847206] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"140.588737ms","start":"2025-12-12T18:35:25.480483Z","end":"2025-12-12T18:35:25.621071Z","steps":["trace[1812847206] 'process raft request'  (duration: 33.578621ms)","trace[1812847206] 'compare'  (duration: 106.408778ms)"],"step_count":2}
Dec 12 18:35:25.622609 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:25.621156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.462755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-localhost\" limit:1 ","response":"range_response_count:1 size:4953"}
Dec 12 18:35:25.622889 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:25.621179Z","caller":"traceutil/trace.go:171","msg":"trace[1009435046] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-localhost; range_end:; response_count:1; response_revision:314; }","duration":"120.486892ms","start":"2025-12-12T18:35:25.500687Z","end":"2025-12-12T18:35:25.621174Z","steps":["trace[1009435046] 'agreement among raft nodes before linearized reading'  (duration: 120.4451ms)"],"step_count":1}
Dec 12 18:35:25.903567 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:25.902744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.486747ms","expected-duration":"100ms","prefix":"","request":"header:<ID:7587891456712567870 > txn:<compare:<target:MOD key:\"/registry/pods/kube-system/kube-scheduler-localhost\" mod_revision:315 > success:<request_put:<key:\"/registry/pods/kube-system/kube-scheduler-localhost\" value_size:4687 >> failure:<request_range:<key:\"/registry/pods/kube-system/kube-scheduler-localhost\" > >>","response":"size:16"}
Dec 12 18:35:25.903567 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:25.902878Z","caller":"traceutil/trace.go:171","msg":"trace[683798893] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"209.452218ms","start":"2025-12-12T18:35:25.693388Z","end":"2025-12-12T18:35:25.902840Z","steps":["trace[683798893] 'process raft request'  (duration: 105.846177ms)","trace[683798893] 'compare'  (duration: 103.068339ms)"],"step_count":2}
Dec 12 18:35:25.903567 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:25.902921Z","caller":"traceutil/trace.go:171","msg":"trace[485837799] linearizableReadLoop","detail":"{readStateIndex:327; appliedIndex:326; }","duration":"178.004047ms","start":"2025-12-12T18:35:25.724903Z","end":"2025-12-12T18:35:25.902907Z","steps":["trace[485837799] 'read index received'  (duration: 74.235146ms)","trace[485837799] 'applied index is now lower than readState.Index'  (duration: 103.767899ms)"],"step_count":2}
Dec 12 18:35:25.903567 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:25.903022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.098933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" limit:1 ","response":"range_response_count:1 size:209"}
Dec 12 18:35:25.904023 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:25.903041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.984997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" limit:1 ","response":"range_response_count:1 size:207"}
Dec 12 18:35:25.904023 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:25.903050Z","caller":"traceutil/trace.go:171","msg":"trace[2043994155] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:317; }","duration":"178.149031ms","start":"2025-12-12T18:35:25.724893Z","end":"2025-12-12T18:35:25.903042Z","steps":["trace[2043994155] 'agreement among raft nodes before linearized reading'  (duration: 178.085557ms)"],"step_count":1}
Dec 12 18:35:25.904023 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:25.903068Z","caller":"traceutil/trace.go:171","msg":"trace[985884291] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:317; }","duration":"128.008433ms","start":"2025-12-12T18:35:25.775047Z","end":"2025-12-12T18:35:25.903056Z","steps":["trace[985884291] 'agreement among raft nodes before linearized reading'  (duration: 127.940961ms)"],"step_count":1}
Dec 12 18:35:26.415527 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:26.415198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.860834ms","expected-duration":"100ms","prefix":"","request":"header:<ID:7587891456712567883 > txn:<compare:<target:MOD key:\"/registry/configmaps/kube-node-lease/kube-root-ca.crt\" mod_revision:0 > success:<request_put:<key:\"/registry/configmaps/kube-node-lease/kube-root-ca.crt\" value_size:1678 >> failure:<>>","response":"size:16"}
Dec 12 18:35:26.415527 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:26.415317Z","caller":"traceutil/trace.go:171","msg":"trace[1838165310] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"422.348618ms","start":"2025-12-12T18:35:25.992954Z","end":"2025-12-12T18:35:26.415303Z","steps":["trace[1838165310] 'process raft request'  (duration: 20.329715ms)","trace[1838165310] 'compare'  (duration: 401.72124ms)"],"step_count":2}
Dec 12 18:35:26.415527 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:26.415370Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-12-12T18:35:25.992941Z","time spent":"422.404888ms","remote":"10.0.0.44:54548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1739,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/configmaps/kube-node-lease/kube-root-ca.crt\" mod_revision:0 > success:<request_put:<key:\"/registry/configmaps/kube-node-lease/kube-root-ca.crt\" value_size:1678 >> failure:<>"}
Dec 12 18:35:26.684016 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:26.683067Z","caller":"traceutil/trace.go:171","msg":"trace[1119970565] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"688.642777ms","start":"2025-12-12T18:35:25.994400Z","end":"2025-12-12T18:35:26.683043Z","steps":["trace[1119970565] 'process raft request'  (duration: 591.99807ms)","trace[1119970565] 'compare'  (duration: 96.423905ms)"],"step_count":2}
Dec 12 18:35:26.684016 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:26.683152Z","caller":"traceutil/trace.go:171","msg":"trace[784446497] linearizableReadLoop","detail":"{readStateIndex:340; appliedIndex:335; }","duration":"686.175547ms","start":"2025-12-12T18:35:25.996967Z","end":"2025-12-12T18:35:26.683143Z","steps":["trace[784446497] 'read index received'  (duration: 16.307702ms)","trace[784446497] 'applied index is now lower than readState.Index'  (duration: 669.867404ms)"],"step_count":2}
Dec 12 18:35:26.684016 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:26.683174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-12-12T18:35:25.994392Z","time spent":"688.735438ms","remote":"10.0.0.44:54710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13713,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/cilium-4kw72\" mod_revision:0 > success:<request_put:<key:\"/registry/pods/kube-system/cilium-4kw72\" value_size:13666 >> failure:<>"}
Dec 12 18:35:26.684016 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:26.683191Z","caller":"traceutil/trace.go:171","msg":"trace[429467205] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"686.420879ms","start":"2025-12-12T18:35:25.996766Z","end":"2025-12-12T18:35:26.683187Z","steps":["trace[429467205] 'process raft request'  (duration: 686.305292ms)"],"step_count":1}
Dec 12 18:35:26.684301 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:26.683236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-12-12T18:35:25.996760Z","time spent":"686.459863ms","remote":"10.0.0.44:54864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1045,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/endpointslices/kube-system/kube-dns-lhqr6\" mod_revision:0 > success:<request_put:<key:\"/registry/endpointslices/kube-system/kube-dns-lhqr6\" value_size:986 >> failure:<>"}
Dec 12 18:35:26.684301 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:26.683246Z","caller":"traceutil/trace.go:171","msg":"trace[1742524060] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"686.316423ms","start":"2025-12-12T18:35:25.996918Z","end":"2025-12-12T18:35:26.683235Z","steps":["trace[1742524060] 'process raft request'  (duration: 686.171099ms)"],"step_count":1}
Dec 12 18:35:26.684301 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:26.683270Z","caller":"traceutil/trace.go:171","msg":"trace[1008914220] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"685.49729ms","start":"2025-12-12T18:35:25.997768Z","end":"2025-12-12T18:35:26.683266Z","steps":["trace[1008914220] 'process raft request'  (duration: 685.354139ms)"],"step_count":1}
Dec 12 18:35:26.684301 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:26.683251Z","caller":"traceutil/trace.go:171","msg":"trace[1455800434] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"687.470511ms","start":"2025-12-12T18:35:25.995760Z","end":"2025-12-12T18:35:26.683230Z","steps":["trace[1455800434] 'process raft request'  (duration: 687.232005ms)"],"step_count":1}
Dec 12 18:35:26.684495 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:26.683291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-12-12T18:35:25.997763Z","time spent":"685.515415ms","remote":"10.0.0.44:55056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3669,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/clusterroles/edit\" mod_revision:323 > success:<request_put:<key:\"/registry/clusterroles/edit\" value_size:3634 >> failure:<request_range:<key:\"/registry/clusterroles/edit\" > >"}
Dec 12 18:35:26.684495 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:26.683297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-12-12T18:35:25.996911Z","time spent":"686.354699ms","remote":"10.0.0.44:55056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2372,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/clusterroles/admin\" mod_revision:321 > success:<request_put:<key:\"/registry/clusterroles/admin\" value_size:2336 >> failure:<request_range:<key:\"/registry/clusterroles/admin\" > >"}
Dec 12 18:35:26.684495 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:26.683338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.369507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking\" limit:1 ","response":"range_response_count:1 size:370"}
Dec 12 18:35:26.684495 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:26.683347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-12-12T18:35:25.995753Z","time spent":"687.55119ms","remote":"10.0.0.44:54710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3359,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/kube-proxy-mmvwq\" mod_revision:0 > success:<request_put:<key:\"/registry/pods/kube-system/kube-proxy-mmvwq\" value_size:3308 >> failure:<>"}
Dec 12 18:35:26.684680 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:26.683399Z","caller":"traceutil/trace.go:171","msg":"trace[2013657965] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking; range_end:; response_count:1; response_revision:330; }","duration":"686.384496ms","start":"2025-12-12T18:35:25.996965Z","end":"2025-12-12T18:35:26.683349Z","steps":["trace[2013657965] 'agreement among raft nodes before linearized reading'  (duration: 686.346301ms)"],"step_count":1}
Dec 12 18:35:26.684680 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:26.683418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-12-12T18:35:25.996957Z","time spent":"686.455225ms","remote":"10.0.0.44:54548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":1,"response size":394,"request content":"key:\"/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking\" limit:1 "}
Dec 12 18:35:26.727020 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:26.726634Z","caller":"traceutil/trace.go:171","msg":"trace[1198006671] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"637.84391ms","start":"2025-12-12T18:35:26.088777Z","end":"2025-12-12T18:35:26.726621Z","steps":["trace[1198006671] 'process raft request'  (duration: 637.75703ms)"],"step_count":1}
Dec 12 18:35:26.727329 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:26.726720Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-12-12T18:35:26.088761Z","time spent":"637.914307ms","remote":"10.0.0.44:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:304 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
Dec 12 18:35:26.727329 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:26.726746Z","caller":"traceutil/trace.go:171","msg":"trace[622236396] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"528.473005ms","start":"2025-12-12T18:35:26.198264Z","end":"2025-12-12T18:35:26.726737Z","steps":["trace[622236396] 'process raft request'  (duration: 528.334865ms)"],"step_count":1}
Dec 12 18:35:26.727329 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:26.726737Z","caller":"traceutil/trace.go:171","msg":"trace[1703017200] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"309.027882ms","start":"2025-12-12T18:35:26.417700Z","end":"2025-12-12T18:35:26.726728Z","steps":["trace[1703017200] 'process raft request'  (duration: 308.961372ms)"],"step_count":1}
Dec 12 18:35:26.727329 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:26.726789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-12-12T18:35:26.417685Z","time spent":"309.085636ms","remote":"10.0.0.44:54548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/configmaps/kube-public/kube-root-ca.crt\" mod_revision:0 > success:<request_put:<key:\"/registry/configmaps/kube-public/kube-root-ca.crt\" value_size:1674 >> failure:<>"}
Dec 12 18:35:26.727524 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:26.726806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-12-12T18:35:26.198247Z","time spent":"528.521199ms","remote":"10.0.0.44:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/apiserver-l2yubf4cvcl5c7a3wrubrwqdve\" mod_revision:64 > success:<request_put:<key:\"/registry/leases/kube-system/apiserver-l2yubf4cvcl5c7a3wrubrwqdve\" value_size:600 >> failure:<request_range:<key:\"/registry/leases/kube-system/apiserver-l2yubf4cvcl5c7a3wrubrwqdve\" > >"}
Dec 12 18:35:26.727524 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:26.726834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.509619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" limit:1 ","response":"range_response_count:1 size:203"}
Dec 12 18:35:26.727524 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:26.726857Z","caller":"traceutil/trace.go:171","msg":"trace[792279864] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:333; }","duration":"602.532845ms","start":"2025-12-12T18:35:26.124317Z","end":"2025-12-12T18:35:26.726850Z","steps":["trace[792279864] 'agreement among raft nodes before linearized reading'  (duration: 602.485272ms)"],"step_count":1}
Dec 12 18:35:26.727524 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:26.726862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.882928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" limit:1 ","response":"range_response_count:1 size:218"}
Dec 12 18:35:26.727632 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:26.726878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-12-12T18:35:26.124303Z","time spent":"602.568135ms","remote":"10.0.0.44:54754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":227,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" limit:1 "}
Dec 12 18:35:26.727632 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:26.726879Z","caller":"traceutil/trace.go:171","msg":"trace[365074702] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:333; }","duration":"651.89962ms","start":"2025-12-12T18:35:26.074973Z","end":"2025-12-12T18:35:26.726873Z","steps":["trace[365074702] 'agreement among raft nodes before linearized reading'  (duration: 651.870323ms)"],"step_count":1}
Dec 12 18:35:26.727632 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:26.726897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-12-12T18:35:26.074959Z","time spent":"651.932665ms","remote":"10.0.0.44:54754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":1,"response size":242,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" limit:1 "}
Dec 12 18:35:26.727632 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:26.726959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.724301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" limit:1 ","response":"range_response_count:1 size:207"}
Dec 12 18:35:26.727723 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:26.726990Z","caller":"traceutil/trace.go:171","msg":"trace[980619860] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:333; }","duration":"701.743199ms","start":"2025-12-12T18:35:26.025229Z","end":"2025-12-12T18:35:26.726973Z","steps":["trace[980619860] 'agreement among raft nodes before linearized reading'  (duration: 701.467751ms)"],"step_count":1}
Dec 12 18:35:26.727723 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:26.727014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-12-12T18:35:26.025191Z","time spent":"701.816863ms","remote":"10.0.0.44:54754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" limit:1 "}
Dec 12 18:35:26.987315 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:26.986787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.213074ms","expected-duration":"100ms","prefix":"","request":"header:<ID:7587891456712567900 > txn:<compare:<target:MOD key:\"/registry/configmaps/kube-system/kube-root-ca.crt\" mod_revision:0 > success:<request_put:<key:\"/registry/configmaps/kube-system/kube-root-ca.crt\" value_size:1674 >> failure:<>>","response":"size:16"}
Dec 12 18:35:26.987448 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:26.987052Z","caller":"traceutil/trace.go:171","msg":"trace[2023875357] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"256.818809ms","start":"2025-12-12T18:35:26.730217Z","end":"2025-12-12T18:35:26.987036Z","steps":["trace[2023875357] 'process raft request'  (duration: 153.304455ms)","trace[2023875357] 'compare'  (duration: 103.098961ms)"],"step_count":2}
Dec 12 18:35:26.987448 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:26.987095Z","caller":"traceutil/trace.go:171","msg":"trace[1274424017] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"255.083976ms","start":"2025-12-12T18:35:26.732003Z","end":"2025-12-12T18:35:26.987087Z","steps":["trace[1274424017] 'process raft request'  (duration: 255.059668ms)"],"step_count":1}
Dec 12 18:35:26.987448 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:26.987150Z","caller":"traceutil/trace.go:171","msg":"trace[942277582] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"256.104602ms","start":"2025-12-12T18:35:26.731039Z","end":"2025-12-12T18:35:26.987143Z","steps":["trace[942277582] 'process raft request'  (duration: 255.972063ms)"],"step_count":1}
Dec 12 18:35:26.987448 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:26.987151Z","caller":"traceutil/trace.go:171","msg":"trace[982582279] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"256.867394ms","start":"2025-12-12T18:35:26.730274Z","end":"2025-12-12T18:35:26.987142Z","steps":["trace[982582279] 'process raft request'  (duration: 256.676291ms)"],"step_count":1}
Dec 12 18:35:26.987579 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:26.987240Z","caller":"traceutil/trace.go:171","msg":"trace[1760027366] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"255.696213ms","start":"2025-12-12T18:35:26.731534Z","end":"2025-12-12T18:35:26.987230Z","steps":["trace[1760027366] 'process raft request'  (duration: 255.498225ms)"],"step_count":1}
Dec 12 18:35:27.024811 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:27.024387Z","caller":"traceutil/trace.go:171","msg":"trace[1734883606] linearizableReadLoop","detail":"{readStateIndex:357; appliedIndex:350; }","duration":"247.772498ms","start":"2025-12-12T18:35:26.776597Z","end":"2025-12-12T18:35:27.024369Z","steps":["trace[1734883606] 'read index received'  (duration: 106.915083ms)","trace[1734883606] 'applied index is now lower than readState.Index'  (duration: 140.856603ms)"],"step_count":2}
Dec 12 18:35:27.024811 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:27.024454Z","caller":"traceutil/trace.go:171","msg":"trace[860785743] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"249.986718ms","start":"2025-12-12T18:35:26.774450Z","end":"2025-12-12T18:35:27.024436Z","steps":["trace[860785743] 'process raft request'  (duration: 249.812588ms)"],"step_count":1}
Dec 12 18:35:27.024811 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:27.024481Z","caller":"traceutil/trace.go:171","msg":"trace[580758620] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"248.90929ms","start":"2025-12-12T18:35:26.775561Z","end":"2025-12-12T18:35:27.024470Z","steps":["trace[580758620] 'process raft request'  (duration: 248.785037ms)"],"step_count":1}
Dec 12 18:35:27.024811 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:27.024518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.904204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" limit:1 ","response":"range_response_count:1 size:2878"}
Dec 12 18:35:27.024811 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:27.024544Z","caller":"traceutil/trace.go:171","msg":"trace[295450886] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:346; }","duration":"247.944874ms","start":"2025-12-12T18:35:26.776593Z","end":"2025-12-12T18:35:27.024538Z","steps":["trace[295450886] 'agreement among raft nodes before linearized reading'  (duration: 247.862894ms)"],"step_count":1}
Dec 12 18:35:27.219768 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:27.219389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.041956ms","expected-duration":"100ms","prefix":"","request":"header:<ID:7587891456712567907 > txn:<compare:<target:MOD key:\"/registry/serviceaccounts/kube-node-lease/default\" mod_revision:0 > success:<request_put:<key:\"/registry/serviceaccounts/kube-node-lease/default\" value_size:116 >> failure:<>>","response":"size:16"}
Dec 12 18:35:27.220236 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:27.219593Z","caller":"traceutil/trace.go:171","msg":"trace[1075356607] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"192.491957ms","start":"2025-12-12T18:35:27.027092Z","end":"2025-12-12T18:35:27.219584Z","steps":["trace[1075356607] 'process raft request'  (duration: 192.468511ms)"],"step_count":1}
Dec 12 18:35:27.220236 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:27.219590Z","caller":"traceutil/trace.go:171","msg":"trace[522727835] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"227.717232ms","start":"2025-12-12T18:35:26.991846Z","end":"2025-12-12T18:35:27.219563Z","steps":["trace[522727835] 'process raft request'  (duration: 227.610864ms)"],"step_count":1}
Dec 12 18:35:27.220236 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:27.219670Z","caller":"traceutil/trace.go:171","msg":"trace[241122462] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"226.768026ms","start":"2025-12-12T18:35:26.992895Z","end":"2025-12-12T18:35:27.219663Z","steps":["trace[241122462] 'process raft request'  (duration: 226.637391ms)"],"step_count":1}
Dec 12 18:35:27.220236 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:27.219705Z","caller":"traceutil/trace.go:171","msg":"trace[429761126] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"228.515264ms","start":"2025-12-12T18:35:26.991182Z","end":"2025-12-12T18:35:27.219697Z","steps":["trace[429761126] 'process raft request'  (duration: 108.127772ms)","trace[429761126] 'compare'  (duration: 119.95184ms)"],"step_count":2}
Dec 12 18:35:27.220379 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:27.219710Z","caller":"traceutil/trace.go:171","msg":"trace[1411455223] linearizableReadLoop","detail":"{readStateIndex:361; appliedIndex:357; }","duration":"195.264827ms","start":"2025-12-12T18:35:27.024438Z","end":"2025-12-12T18:35:27.219703Z","steps":["trace[1411455223] 'read index received'  (duration: 74.87951ms)","trace[1411455223] 'applied index is now lower than readState.Index'  (duration: 120.383694ms)"],"step_count":2}
Dec 12 18:35:27.220379 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:27.219593Z","caller":"traceutil/trace.go:171","msg":"trace[357456630] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"226.945413ms","start":"2025-12-12T18:35:26.992634Z","end":"2025-12-12T18:35:27.219580Z","steps":["trace[357456630] 'process raft request'  (duration: 226.861088ms)"],"step_count":1}
Dec 12 18:35:27.220379 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:27.219800Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.190384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" limit:1 ","response":"range_response_count:1 size:185"}
Dec 12 18:35:27.220379 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:27.219820Z","caller":"traceutil/trace.go:171","msg":"trace[370326172] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:351; }","duration":"202.216676ms","start":"2025-12-12T18:35:27.017597Z","end":"2025-12-12T18:35:27.219813Z","steps":["trace[370326172] 'agreement among raft nodes before linearized reading'  (duration: 202.173451ms)"],"step_count":1}
Dec 12 18:35:27.220379 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:27.219830Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.970127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-mmvwq\" limit:1 ","response":"range_response_count:1 size:3423"}
Dec 12 18:35:27.220525 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:27.219847Z","caller":"traceutil/trace.go:171","msg":"trace[1604597457] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-mmvwq; range_end:; response_count:1; response_revision:351; }","duration":"344.986408ms","start":"2025-12-12T18:35:26.874855Z","end":"2025-12-12T18:35:27.219842Z","steps":["trace[1604597457] 'agreement among raft nodes before linearized reading'  (duration: 344.957812ms)"],"step_count":1}
Dec 12 18:35:27.220525 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:27.219871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.421503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" limit:1 ","response":"range_response_count:1 size:2878"}
Dec 12 18:35:27.220525 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:27.219867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-12-12T18:35:26.874848Z","time spent":"345.010686ms","remote":"10.0.0.44:54710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":3447,"request content":"key:\"/registry/pods/kube-system/kube-proxy-mmvwq\" limit:1 "}
Dec 12 18:35:27.220525 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:27.219891Z","caller":"traceutil/trace.go:171","msg":"trace[1568412412] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:351; }","duration":"193.441974ms","start":"2025-12-12T18:35:27.026444Z","end":"2025-12-12T18:35:27.219886Z","steps":["trace[1568412412] 'agreement among raft nodes before linearized reading'  (duration: 193.412968ms)"],"step_count":1}
Dec 12 18:35:27.220525 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:27.219824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.304698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" limit:1 ","response":"range_response_count:1 size:207"}
Dec 12 18:35:27.220689 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:27.219963Z","caller":"traceutil/trace.go:171","msg":"trace[237867088] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:351; }","duration":"229.452196ms","start":"2025-12-12T18:35:26.990503Z","end":"2025-12-12T18:35:27.219955Z","steps":["trace[237867088] 'agreement among raft nodes before linearized reading'  (duration: 229.272845ms)"],"step_count":1}
Dec 12 18:35:27.220689 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:27.219983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.258953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" limit:1 ","response":"range_response_count:1 size:177"}
Dec 12 18:35:27.220689 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:27.219800Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.368518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" limit:1 ","response":"range_response_count:1 size:12890"}
Dec 12 18:35:27.220689 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:27.220006Z","caller":"traceutil/trace.go:171","msg":"trace[340976342] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:351; }","duration":"439.580562ms","start":"2025-12-12T18:35:26.780421Z","end":"2025-12-12T18:35:27.220002Z","steps":["trace[340976342] 'agreement among raft nodes before linearized reading'  (duration: 439.353629ms)"],"step_count":1}
Dec 12 18:35:27.220689 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:27.220005Z","caller":"traceutil/trace.go:171","msg":"trace[1384332460] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:351; }","duration":"101.279333ms","start":"2025-12-12T18:35:27.118718Z","end":"2025-12-12T18:35:27.219997Z","steps":["trace[1384332460] 'agreement among raft nodes before linearized reading'  (duration: 101.246118ms)"],"step_count":1}
Dec 12 18:35:27.220843 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:27.220023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-12-12T18:35:26.780416Z","time spent":"439.600181ms","remote":"10.0.0.44:55254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":12914,"request content":"key:\"/registry/daemonsets/kube-system/cilium\" limit:1 "}
Dec 12 18:35:37.261534 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:37.261100Z","caller":"traceutil/trace.go:171","msg":"trace[1420991531] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"167.518642ms","start":"2025-12-12T18:35:37.093566Z","end":"2025-12-12T18:35:37.261084Z","steps":["trace[1420991531] 'process raft request'  (duration: 85.231669ms)","trace[1420991531] 'compare'  (duration: 82.132312ms)"],"step_count":2}
Dec 12 18:35:37.510067 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:37.509672Z","caller":"traceutil/trace.go:171","msg":"trace[1173458571] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"189.348203ms","start":"2025-12-12T18:35:37.320307Z","end":"2025-12-12T18:35:37.509656Z","steps":["trace[1173458571] 'process raft request'  (duration: 189.242096ms)"],"step_count":1}
Dec 12 18:35:38.195687 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:38.195411Z","caller":"traceutil/trace.go:171","msg":"trace[1621736573] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"152.884225ms","start":"2025-12-12T18:35:38.042511Z","end":"2025-12-12T18:35:38.195395Z","steps":["trace[1621736573] 'process raft request'  (duration: 152.792556ms)"],"step_count":1}
Dec 12 18:35:47.000997 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:47.000681Z","caller":"traceutil/trace.go:171","msg":"trace[1294758507] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"261.424058ms","start":"2025-12-12T18:35:46.739234Z","end":"2025-12-12T18:35:47.000658Z","steps":["trace[1294758507] 'process raft request'  (duration: 163.09374ms)","trace[1294758507] 'compare'  (duration: 98.203952ms)"],"step_count":2}
Dec 12 18:35:47.070028 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:47.069675Z","caller":"traceutil/trace.go:171","msg":"trace[1969832236] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"122.506723ms","start":"2025-12-12T18:35:46.947150Z","end":"2025-12-12T18:35:47.069657Z","steps":["trace[1969832236] 'process raft request'  (duration: 122.396046ms)"],"step_count":1}
Dec 12 18:35:47.286029 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:47.285420Z","caller":"traceutil/trace.go:171","msg":"trace[1744307731] linearizableReadLoop","detail":"{readStateIndex:497; appliedIndex:496; }","duration":"111.101449ms","start":"2025-12-12T18:35:47.174298Z","end":"2025-12-12T18:35:47.285400Z","steps":["trace[1744307731] 'read index received'  (duration: 17.862522ms)","trace[1744307731] 'applied index is now lower than readState.Index'  (duration: 93.238146ms)"],"step_count":2}
Dec 12 18:35:47.286272 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:47.285617Z","caller":"traceutil/trace.go:171","msg":"trace[1876815490] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"212.128852ms","start":"2025-12-12T18:35:47.073465Z","end":"2025-12-12T18:35:47.285594Z","steps":["trace[1876815490] 'process raft request'  (duration: 118.728823ms)","trace[1876815490] 'compare'  (duration: 93.110067ms)"],"step_count":2}
Dec 12 18:35:47.286272 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:47.285679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.364441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/10.0.0.44\" limit:1 ","response":"range_response_count:1 size:125"}
Dec 12 18:35:47.286272 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:47.285711Z","caller":"traceutil/trace.go:171","msg":"trace[1084064098] range","detail":"{range_begin:/registry/masterleases/10.0.0.44; range_end:; response_count:1; response_revision:480; }","duration":"111.409256ms","start":"2025-12-12T18:35:47.174292Z","end":"2025-12-12T18:35:47.285702Z","steps":["trace[1084064098] 'agreement among raft nodes before linearized reading'  (duration: 111.332472ms)"],"step_count":1}
Dec 12 18:35:49.261125 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:49.260817Z","caller":"traceutil/trace.go:171","msg":"trace[393079390] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"187.645844ms","start":"2025-12-12T18:35:49.073156Z","end":"2025-12-12T18:35:49.260802Z","steps":["trace[393079390] 'process raft request'  (duration: 185.829904ms)"],"step_count":1}
Dec 12 18:35:57.643050 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:57.642683Z","caller":"traceutil/trace.go:171","msg":"trace[2102319488] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"120.414793ms","start":"2025-12-12T18:35:57.522248Z","end":"2025-12-12T18:35:57.642663Z","steps":["trace[2102319488] 'read index received'  (duration: 54.709535ms)","trace[2102319488] 'applied index is now lower than readState.Index'  (duration: 65.704506ms)"],"step_count":2}
Dec 12 18:35:57.643050 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:57.642767Z","caller":"traceutil/trace.go:171","msg":"trace[1578501890] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"143.584679ms","start":"2025-12-12T18:35:57.499169Z","end":"2025-12-12T18:35:57.642754Z","steps":["trace[1578501890] 'process raft request'  (duration: 77.824088ms)","trace[1578501890] 'compare'  (duration: 65.570353ms)"],"step_count":2}
Dec 12 18:35:57.643050 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:35:57.642778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.513889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/10.0.0.50\" limit:1 ","response":"range_response_count:0 size:5"}
Dec 12 18:35:57.643050 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:35:57.642804Z","caller":"traceutil/trace.go:171","msg":"trace[307265477] range","detail":"{range_begin:/registry/leases/kube-node-lease/10.0.0.50; range_end:; response_count:0; response_revision:571; }","duration":"120.552522ms","start":"2025-12-12T18:35:57.522244Z","end":"2025-12-12T18:35:57.642796Z","steps":["trace[307265477] 'agreement among raft nodes before linearized reading'  (duration: 120.501045ms)"],"step_count":1}
Dec 12 18:36:18.638087 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:36:18.637728Z","caller":"traceutil/trace.go:171","msg":"trace[1555921722] linearizableReadLoop","detail":"{readStateIndex:691; appliedIndex:690; }","duration":"299.540235ms","start":"2025-12-12T18:36:18.338169Z","end":"2025-12-12T18:36:18.637709Z","steps":["trace[1555921722] 'read index received'  (duration: 299.424364ms)","trace[1555921722] 'applied index is now lower than readState.Index'  (duration: 115.511µs)"],"step_count":2}
Dec 12 18:36:18.638087 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:36:18.637805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.62664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases\" limit:1 ","response":"range_response_count:0 size:5"}
Dec 12 18:36:18.638087 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:36:18.637823Z","caller":"traceutil/trace.go:171","msg":"trace[652152631] range","detail":"{range_begin:/registry/leases; range_end:; response_count:0; response_revision:666; }","duration":"299.656918ms","start":"2025-12-12T18:36:18.338160Z","end":"2025-12-12T18:36:18.637817Z","steps":["trace[652152631] 'agreement among raft nodes before linearized reading'  (duration: 299.620889ms)"],"step_count":1}
Dec 12 18:36:18.639190 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:36:18.637803Z","caller":"traceutil/trace.go:171","msg":"trace[685568795] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"389.943172ms","start":"2025-12-12T18:36:18.247811Z","end":"2025-12-12T18:36:18.637754Z","steps":["trace[685568795] 'process raft request'  (duration: 389.80033ms)"],"step_count":1}
Dec 12 18:36:18.639190 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:36:18.637982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-12-12T18:36:18.247793Z","time spent":"390.075255ms","remote":"10.0.0.44:54710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18048,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/cilium-lkzq6\" mod_revision:636 > success:<request_put:<key:\"/registry/pods/kube-system/cilium-lkzq6\" value_size:17999 >> failure:<request_range:<key:\"/registry/pods/kube-system/cilium-lkzq6\" > >"}
Dec 12 18:36:18.926860 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:36:18.926462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.758168ms","expected-duration":"100ms","prefix":"","request":"header:<ID:7587891456712568501 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:660 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:414 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >>","response":"size:16"}
Dec 12 18:36:18.926860 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:36:18.926561Z","caller":"traceutil/trace.go:171","msg":"trace[1261541269] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"459.408081ms","start":"2025-12-12T18:36:18.467142Z","end":"2025-12-12T18:36:18.926550Z","steps":["trace[1261541269] 'process raft request'  (duration: 459.377072ms)"],"step_count":1}
Dec 12 18:36:18.927342 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:36:18.926594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-12-12T18:36:18.467127Z","time spent":"459.451614ms","remote":"10.0.0.44:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":674,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/apiserver-l2yubf4cvcl5c7a3wrubrwqdve\" mod_revision:638 > success:<request_put:<key:\"/registry/leases/kube-system/apiserver-l2yubf4cvcl5c7a3wrubrwqdve\" value_size:601 >> failure:<request_range:<key:\"/registry/leases/kube-system/apiserver-l2yubf4cvcl5c7a3wrubrwqdve\" > >"}
Dec 12 18:36:18.927342 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:36:18.926596Z","caller":"traceutil/trace.go:171","msg":"trace[1823051345] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"481.055392ms","start":"2025-12-12T18:36:18.445527Z","end":"2025-12-12T18:36:18.926583Z","steps":["trace[1823051345] 'process raft request'  (duration: 342.133952ms)","trace[1823051345] 'compare'  (duration: 138.681232ms)"],"step_count":2}
Dec 12 18:36:18.927342 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:36:18.926637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-12-12T18:36:18.445506Z","time spent":"481.111249ms","remote":"10.0.0.44:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":465,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:660 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:414 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
Dec 12 18:36:18.927588 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:36:18.926975Z","caller":"traceutil/trace.go:171","msg":"trace[98032766] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:691; }","duration":"188.511053ms","start":"2025-12-12T18:36:18.738454Z","end":"2025-12-12T18:36:18.926965Z","steps":["trace[98032766] 'read index received'  (duration: 49.287969ms)","trace[98032766] 'applied index is now lower than readState.Index'  (duration: 139.222483ms)"],"step_count":2}
Dec 12 18:36:18.927745 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:36:18.927328Z","caller":"traceutil/trace.go:171","msg":"trace[576321521] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"278.310282ms","start":"2025-12-12T18:36:18.649006Z","end":"2025-12-12T18:36:18.927316Z","steps":["trace[576321521] 'process raft request'  (duration: 277.879731ms)"],"step_count":1}
Dec 12 18:36:18.927745 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:36:18.927357Z","caller":"traceutil/trace.go:171","msg":"trace[2045860011] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"283.650585ms","start":"2025-12-12T18:36:18.643699Z","end":"2025-12-12T18:36:18.927350Z","steps":["trace[2045860011] 'process raft request'  (duration: 283.138059ms)"],"step_count":1}
Dec 12 18:36:18.927745 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:36:18.927543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.087863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 ","response":"range_response_count:1 size:493"}
Dec 12 18:36:18.927745 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:36:18.927566Z","caller":"traceutil/trace.go:171","msg":"trace[764262623] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:670; }","duration":"189.112099ms","start":"2025-12-12T18:36:18.738447Z","end":"2025-12-12T18:36:18.927559Z","steps":["trace[764262623] 'agreement among raft nodes before linearized reading'  (duration: 189.028751ms)"],"step_count":1}
Dec 12 18:36:19.672387 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:36:19.672076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.278488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses\" limit:1 ","response":"range_response_count:0 size:5"}
Dec 12 18:36:19.672387 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:36:19.672134Z","caller":"traceutil/trace.go:171","msg":"trace[1257950879] range","detail":"{range_begin:/registry/priorityclasses; range_end:; response_count:0; response_revision:672; }","duration":"105.343692ms","start":"2025-12-12T18:36:19.566778Z","end":"2025-12-12T18:36:19.672121Z","steps":["trace[1257950879] 'range keys from in-memory index tree'  (duration: 105.215417ms)"],"step_count":1}
Dec 12 18:36:29.681300 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:36:29.680917Z","caller":"traceutil/trace.go:171","msg":"trace[1251178679] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"101.618822ms","start":"2025-12-12T18:36:29.579283Z","end":"2025-12-12T18:36:29.680902Z","steps":["trace[1251178679] 'process raft request'  (duration: 101.530614ms)"],"step_count":1}
Dec 12 18:36:39.024900 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:36:39.024597Z","caller":"traceutil/trace.go:171","msg":"trace[1247700201] transaction","detail":"{read_only:false; number_of_response:1; response_revision:787; }","duration":"181.757964ms","start":"2025-12-12T18:36:38.842826Z","end":"2025-12-12T18:36:39.024584Z","steps":["trace[1247700201] 'process raft request'  (duration: 95.67808ms)","trace[1247700201] 'compare'  (duration: 85.989571ms)"],"step_count":2}
Dec 12 18:36:39.024900 etcd-wrapper[1831]: {"level":"warn","ts":"2025-12-12T18:36:39.024647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.233081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
Dec 12 18:36:39.024900 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:36:39.024669Z","caller":"traceutil/trace.go:171","msg":"trace[492569571] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:787; }","duration":"119.269471ms","start":"2025-12-12T18:36:38.905394Z","end":"2025-12-12T18:36:39.024663Z","steps":["trace[492569571] 'agreement among raft nodes before linearized reading'  (duration: 119.223312ms)"],"step_count":1}
Dec 12 18:36:39.024900 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:36:39.024580Z","caller":"traceutil/trace.go:171","msg":"trace[48067781] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"119.162427ms","start":"2025-12-12T18:36:38.905399Z","end":"2025-12-12T18:36:39.024561Z","steps":["trace[48067781] 'read index received'  (duration: 33.063766ms)","trace[48067781] 'applied index is now lower than readState.Index'  (duration: 86.097538ms)"],"step_count":2}
Dec 12 18:36:43.962986 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:36:43.962697Z","caller":"traceutil/trace.go:171","msg":"trace[542076077] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"148.100385ms","start":"2025-12-12T18:36:43.814579Z","end":"2025-12-12T18:36:43.962680Z","steps":["trace[542076077] 'process raft request'  (duration: 128.898767ms)","trace[542076077] 'compare'  (duration: 19.106225ms)"],"step_count":2}
Dec 12 18:36:48.640870 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:36:48.640552Z","caller":"traceutil/trace.go:171","msg":"trace[775733498] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"178.439929ms","start":"2025-12-12T18:36:48.462089Z","end":"2025-12-12T18:36:48.640529Z","steps":["trace[775733498] 'process raft request'  (duration: 178.265955ms)"],"step_count":1}
Dec 12 18:36:48.643807 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:36:48.643596Z","caller":"traceutil/trace.go:171","msg":"trace[69485127] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"181.270639ms","start":"2025-12-12T18:36:48.462309Z","end":"2025-12-12T18:36:48.643580Z","steps":["trace[69485127] 'process raft request'  (duration: 181.16643ms)"],"step_count":1}
Dec 12 18:36:48.644073 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:36:48.643793Z","caller":"traceutil/trace.go:171","msg":"trace[141277485] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"180.512816ms","start":"2025-12-12T18:36:48.463265Z","end":"2025-12-12T18:36:48.643778Z","steps":["trace[141277485] 'process raft request'  (duration: 180.279559ms)"],"step_count":1}
Dec 12 18:36:49.199121 etcd-wrapper[1831]: {"level":"info","ts":"2025-12-12T18:36:49.198865Z","caller":"traceutil/trace.go:171","msg":"trace[499989573] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"109.416353ms","start":"2025-12-12T18:36:49.089435Z","end":"2025-12-12T18:36:49.198852Z","steps":["trace[499989573] 'process raft request'  (duration: 109.332372ms)"],"step_count":1}