Nov 6 00:13:59.159145 kernel: Linux version 6.12.54-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 Wed Nov 5 22:12:28 -00 2025 Nov 6 00:13:59.159188 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=59ca0b9e28689480cec05e5a7a50ffb2fd81e743a9e2986eb3bceb3b87f6702e Nov 6 00:13:59.159202 kernel: BIOS-provided physical RAM map: Nov 6 00:13:59.159209 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Nov 6 00:13:59.159216 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Nov 6 00:13:59.159222 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Nov 6 00:13:59.159230 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000009cfdbfff] usable Nov 6 00:13:59.159237 kernel: BIOS-e820: [mem 0x000000009cfdc000-0x000000009cffffff] reserved Nov 6 00:13:59.159247 kernel: BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved Nov 6 00:13:59.159253 kernel: BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved Nov 6 00:13:59.159263 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Nov 6 00:13:59.159269 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Nov 6 00:13:59.159276 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Nov 6 00:13:59.159283 kernel: NX (Execute Disable) protection: active Nov 6 00:13:59.159291 kernel: APIC: Static calls initialized Nov 6 00:13:59.159298 kernel: SMBIOS 2.8 present. Nov 6 00:13:59.159311 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.16.2-debian-1.16.2-1 04/01/2014 Nov 6 00:13:59.159318 kernel: DMI: Memory slots populated: 1/1 Nov 6 00:13:59.159325 kernel: Hypervisor detected: KVM Nov 6 00:13:59.159333 kernel: last_pfn = 0x9cfdc max_arch_pfn = 0x400000000 Nov 6 00:13:59.159340 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Nov 6 00:13:59.159347 kernel: kvm-clock: using sched offset of 5309895859 cycles Nov 6 00:13:59.159355 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Nov 6 00:13:59.159363 kernel: tsc: Detected 2794.748 MHz processor Nov 6 00:13:59.159371 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Nov 6 00:13:59.159379 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Nov 6 00:13:59.159390 kernel: last_pfn = 0x9cfdc max_arch_pfn = 0x400000000 Nov 6 00:13:59.159397 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Nov 6 00:13:59.159405 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Nov 6 00:13:59.159413 kernel: Using GB pages for direct mapping Nov 6 00:13:59.159420 kernel: ACPI: Early table checksum verification disabled Nov 6 00:13:59.159428 kernel: ACPI: RSDP 0x00000000000F59D0 000014 (v00 BOCHS ) Nov 6 00:13:59.159436 kernel: ACPI: RSDT 0x000000009CFE241A 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 6 00:13:59.159446 kernel: ACPI: FACP 0x000000009CFE21FA 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) Nov 6 00:13:59.159453 kernel: ACPI: DSDT 0x000000009CFE0040 0021BA (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 6 00:13:59.159463 kernel: ACPI: FACS 0x000000009CFE0000 000040 Nov 6 00:13:59.159471 kernel: ACPI: APIC 0x000000009CFE22EE 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 6 00:13:59.159478 kernel: ACPI: HPET 0x000000009CFE237E 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 6 00:13:59.159486 kernel: ACPI: MCFG 0x000000009CFE23B6 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 6 00:13:59.159494 kernel: ACPI: WAET 0x000000009CFE23F2 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 6 00:13:59.159504 kernel: ACPI: Reserving FACP table memory at [mem 0x9cfe21fa-0x9cfe22ed] Nov 6 00:13:59.159514 kernel: ACPI: Reserving DSDT table memory at [mem 0x9cfe0040-0x9cfe21f9] Nov 6 00:13:59.159522 kernel: ACPI: Reserving FACS table memory at [mem 0x9cfe0000-0x9cfe003f] Nov 6 00:13:59.159530 kernel: ACPI: Reserving APIC table memory at [mem 0x9cfe22ee-0x9cfe237d] Nov 6 00:13:59.159538 kernel: ACPI: Reserving HPET table memory at [mem 0x9cfe237e-0x9cfe23b5] Nov 6 00:13:59.159553 kernel: ACPI: Reserving MCFG table memory at [mem 0x9cfe23b6-0x9cfe23f1] Nov 6 00:13:59.159561 kernel: ACPI: Reserving WAET table memory at [mem 0x9cfe23f2-0x9cfe2419] Nov 6 00:13:59.159568 kernel: No NUMA configuration found Nov 6 00:13:59.159576 kernel: Faking a node at [mem 0x0000000000000000-0x000000009cfdbfff] Nov 6 00:13:59.159586 kernel: NODE_DATA(0) allocated [mem 0x9cfd4dc0-0x9cfdbfff] Nov 6 00:13:59.159594 kernel: Zone ranges: Nov 6 00:13:59.159602 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Nov 6 00:13:59.159610 kernel: DMA32 [mem 0x0000000001000000-0x000000009cfdbfff] Nov 6 00:13:59.159618 kernel: Normal empty Nov 6 00:13:59.159625 kernel: Device empty Nov 6 00:13:59.159633 kernel: Movable zone start for each node Nov 6 00:13:59.159641 kernel: Early memory node ranges Nov 6 00:13:59.159648 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Nov 6 00:13:59.159656 kernel: node 0: [mem 0x0000000000100000-0x000000009cfdbfff] Nov 6 00:13:59.159666 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009cfdbfff] Nov 6 00:13:59.159674 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Nov 6 00:13:59.159684 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Nov 6 00:13:59.159692 kernel: On node 0, zone DMA32: 12324 pages in unavailable ranges Nov 6 00:13:59.159701 kernel: ACPI: PM-Timer IO Port: 0x608 Nov 6 00:13:59.159709 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Nov 6 00:13:59.159717 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Nov 6 00:13:59.159725 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Nov 6 00:13:59.159735 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Nov 6 00:13:59.159745 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Nov 6 00:13:59.159752 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Nov 6 00:13:59.159760 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Nov 6 00:13:59.159768 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Nov 6 00:13:59.159775 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Nov 6 00:13:59.159783 kernel: TSC deadline timer available Nov 6 00:13:59.159791 kernel: CPU topo: Max. logical packages: 1 Nov 6 00:13:59.159798 kernel: CPU topo: Max. logical dies: 1 Nov 6 00:13:59.159806 kernel: CPU topo: Max. dies per package: 1 Nov 6 00:13:59.159841 kernel: CPU topo: Max. threads per core: 1 Nov 6 00:13:59.159851 kernel: CPU topo: Num. cores per package: 4 Nov 6 00:13:59.159861 kernel: CPU topo: Num. threads per package: 4 Nov 6 00:13:59.159869 kernel: CPU topo: Allowing 4 present CPUs plus 0 hotplug CPUs Nov 6 00:13:59.159876 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Nov 6 00:13:59.159884 kernel: kvm-guest: KVM setup pv remote TLB flush Nov 6 00:13:59.159892 kernel: kvm-guest: setup PV sched yield Nov 6 00:13:59.159900 kernel: [mem 0xc0000000-0xfed1bfff] available for PCI devices Nov 6 00:13:59.159907 kernel: Booting paravirtualized kernel on KVM Nov 6 00:13:59.159918 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Nov 6 00:13:59.159926 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 Nov 6 00:13:59.159934 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u524288 Nov 6 00:13:59.159942 kernel: pcpu-alloc: s207832 r8192 d29736 u524288 alloc=1*2097152 Nov 6 00:13:59.159949 kernel: pcpu-alloc: [0] 0 1 2 3 Nov 6 00:13:59.159957 kernel: kvm-guest: PV spinlocks enabled Nov 6 00:13:59.159964 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Nov 6 00:13:59.159974 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=59ca0b9e28689480cec05e5a7a50ffb2fd81e743a9e2986eb3bceb3b87f6702e Nov 6 00:13:59.159984 kernel: random: crng init done Nov 6 00:13:59.159992 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 6 00:13:59.160000 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 6 00:13:59.160008 kernel: Fallback order for Node 0: 0 Nov 6 00:13:59.160015 kernel: Built 1 zonelists, mobility grouping on. Total pages: 642938 Nov 6 00:13:59.160023 kernel: Policy zone: DMA32 Nov 6 00:13:59.160031 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 6 00:13:59.160039 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Nov 6 00:13:59.160047 kernel: ftrace: allocating 40021 entries in 157 pages Nov 6 00:13:59.160057 kernel: ftrace: allocated 157 pages with 5 groups Nov 6 00:13:59.160064 kernel: Dynamic Preempt: voluntary Nov 6 00:13:59.160072 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 6 00:13:59.160081 kernel: rcu: RCU event tracing is enabled. Nov 6 00:13:59.160089 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Nov 6 00:13:59.160097 kernel: Trampoline variant of Tasks RCU enabled. Nov 6 00:13:59.160107 kernel: Rude variant of Tasks RCU enabled. Nov 6 00:13:59.160115 kernel: Tracing variant of Tasks RCU enabled. Nov 6 00:13:59.160123 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 6 00:13:59.160133 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Nov 6 00:13:59.160141 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Nov 6 00:13:59.160149 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Nov 6 00:13:59.160157 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Nov 6 00:13:59.160164 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 Nov 6 00:13:59.160172 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 6 00:13:59.160187 kernel: Console: colour VGA+ 80x25 Nov 6 00:13:59.160198 kernel: printk: legacy console [ttyS0] enabled Nov 6 00:13:59.160206 kernel: ACPI: Core revision 20240827 Nov 6 00:13:59.160214 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Nov 6 00:13:59.160222 kernel: APIC: Switch to symmetric I/O mode setup Nov 6 00:13:59.160230 kernel: x2apic enabled Nov 6 00:13:59.160240 kernel: APIC: Switched APIC routing to: physical x2apic Nov 6 00:13:59.160250 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() Nov 6 00:13:59.160259 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() Nov 6 00:13:59.160267 kernel: kvm-guest: setup PV IPIs Nov 6 00:13:59.160275 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Nov 6 00:13:59.160285 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x2848df6a9de, max_idle_ns: 440795280912 ns Nov 6 00:13:59.160293 kernel: Calibrating delay loop (skipped) preset value.. 5589.49 BogoMIPS (lpj=2794748) Nov 6 00:13:59.160301 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Nov 6 00:13:59.160309 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 Nov 6 00:13:59.160317 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 Nov 6 00:13:59.160325 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Nov 6 00:13:59.160333 kernel: Spectre V2 : Mitigation: Retpolines Nov 6 00:13:59.160341 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Nov 6 00:13:59.160350 kernel: Spectre V2 : Enabling Speculation Barrier for firmware calls Nov 6 00:13:59.160360 kernel: active return thunk: retbleed_return_thunk Nov 6 00:13:59.160368 kernel: RETBleed: Mitigation: untrained return thunk Nov 6 00:13:59.160376 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Nov 6 00:13:59.160384 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Nov 6 00:13:59.160392 kernel: Speculative Return Stack Overflow: IBPB-extending microcode not applied! Nov 6 00:13:59.160401 kernel: Speculative Return Stack Overflow: WARNING: See https://kernel.org/doc/html/latest/admin-guide/hw-vuln/srso.html for mitigation options. Nov 6 00:13:59.160409 kernel: active return thunk: srso_return_thunk Nov 6 00:13:59.160417 kernel: Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode Nov 6 00:13:59.160427 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Nov 6 00:13:59.160435 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Nov 6 00:13:59.160444 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Nov 6 00:13:59.160452 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Nov 6 00:13:59.160460 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format. Nov 6 00:13:59.160468 kernel: Freeing SMP alternatives memory: 32K Nov 6 00:13:59.160476 kernel: pid_max: default: 32768 minimum: 301 Nov 6 00:13:59.160485 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Nov 6 00:13:59.160493 kernel: landlock: Up and running. Nov 6 00:13:59.160503 kernel: SELinux: Initializing. Nov 6 00:13:59.160514 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 6 00:13:59.160522 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 6 00:13:59.160530 kernel: smpboot: CPU0: AMD EPYC 7402P 24-Core Processor (family: 0x17, model: 0x31, stepping: 0x0) Nov 6 00:13:59.160538 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver. Nov 6 00:13:59.160553 kernel: ... version: 0 Nov 6 00:13:59.160561 kernel: ... bit width: 48 Nov 6 00:13:59.160569 kernel: ... generic registers: 6 Nov 6 00:13:59.160576 kernel: ... value mask: 0000ffffffffffff Nov 6 00:13:59.160587 kernel: ... max period: 00007fffffffffff Nov 6 00:13:59.160595 kernel: ... fixed-purpose events: 0 Nov 6 00:13:59.160603 kernel: ... event mask: 000000000000003f Nov 6 00:13:59.160611 kernel: signal: max sigframe size: 1776 Nov 6 00:13:59.160619 kernel: rcu: Hierarchical SRCU implementation. Nov 6 00:13:59.160627 kernel: rcu: Max phase no-delay instances is 400. Nov 6 00:13:59.160635 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Nov 6 00:13:59.160643 kernel: smp: Bringing up secondary CPUs ... Nov 6 00:13:59.160651 kernel: smpboot: x86: Booting SMP configuration: Nov 6 00:13:59.160661 kernel: .... node #0, CPUs: #1 #2 #3 Nov 6 00:13:59.160669 kernel: smp: Brought up 1 node, 4 CPUs Nov 6 00:13:59.160677 kernel: smpboot: Total of 4 processors activated (22357.98 BogoMIPS) Nov 6 00:13:59.160686 kernel: Memory: 2422768K/2571752K available (14336K kernel code, 2436K rwdata, 26048K rodata, 45548K init, 1180K bss, 143048K reserved, 0K cma-reserved) Nov 6 00:13:59.160694 kernel: devtmpfs: initialized Nov 6 00:13:59.160702 kernel: x86/mm: Memory block size: 128MB Nov 6 00:13:59.160710 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 6 00:13:59.160718 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Nov 6 00:13:59.160726 kernel: pinctrl core: initialized pinctrl subsystem Nov 6 00:13:59.160739 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 6 00:13:59.160747 kernel: audit: initializing netlink subsys (disabled) Nov 6 00:13:59.160755 kernel: audit: type=2000 audit(1762388036.114:1): state=initialized audit_enabled=0 res=1 Nov 6 00:13:59.160763 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 6 00:13:59.160771 kernel: thermal_sys: Registered thermal governor 'user_space' Nov 6 00:13:59.160779 kernel: cpuidle: using governor menu Nov 6 00:13:59.160787 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 6 00:13:59.160795 kernel: dca service started, version 1.12.1 Nov 6 00:13:59.160803 kernel: PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff] Nov 6 00:13:59.160835 kernel: PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry Nov 6 00:13:59.160846 kernel: PCI: Using configuration type 1 for base access Nov 6 00:13:59.160855 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Nov 6 00:13:59.160863 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Nov 6 00:13:59.160871 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Nov 6 00:13:59.160878 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Nov 6 00:13:59.160886 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Nov 6 00:13:59.160894 kernel: ACPI: Added _OSI(Module Device) Nov 6 00:13:59.160902 kernel: ACPI: Added _OSI(Processor Device) Nov 6 00:13:59.160914 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 6 00:13:59.160922 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 6 00:13:59.160933 kernel: ACPI: Interpreter enabled Nov 6 00:13:59.160941 kernel: ACPI: PM: (supports S0 S3 S5) Nov 6 00:13:59.160949 kernel: ACPI: Using IOAPIC for interrupt routing Nov 6 00:13:59.160957 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Nov 6 00:13:59.160965 kernel: PCI: Using E820 reservations for host bridge windows Nov 6 00:13:59.160973 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F Nov 6 00:13:59.160981 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Nov 6 00:13:59.161237 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Nov 6 00:13:59.161377 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] Nov 6 00:13:59.161503 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] Nov 6 00:13:59.161513 kernel: PCI host bridge to bus 0000:00 Nov 6 00:13:59.161661 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Nov 6 00:13:59.161777 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Nov 6 00:13:59.161930 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Nov 6 00:13:59.162061 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xafffffff window] Nov 6 00:13:59.162176 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] Nov 6 00:13:59.162289 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] Nov 6 00:13:59.162402 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Nov 6 00:13:59.162599 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint Nov 6 00:13:59.162742 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint Nov 6 00:13:59.162900 kernel: pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref] Nov 6 00:13:59.163027 kernel: pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff] Nov 6 00:13:59.163151 kernel: pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref] Nov 6 00:13:59.163274 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Nov 6 00:13:59.163419 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Nov 6 00:13:59.163556 kernel: pci 0000:00:02.0: BAR 0 [io 0xc0c0-0xc0df] Nov 6 00:13:59.163688 kernel: pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff] Nov 6 00:13:59.163828 kernel: pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref] Nov 6 00:13:59.163985 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint Nov 6 00:13:59.164111 kernel: pci 0000:00:03.0: BAR 0 [io 0xc000-0xc07f] Nov 6 00:13:59.164235 kernel: pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff] Nov 6 00:13:59.164411 kernel: pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref] Nov 6 00:13:59.164699 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint Nov 6 00:13:59.164973 kernel: pci 0000:00:04.0: BAR 0 [io 0xc0e0-0xc0ff] Nov 6 00:13:59.165222 kernel: pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff] Nov 6 00:13:59.165460 kernel: pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref] Nov 6 00:13:59.165707 kernel: pci 0000:00:04.0: ROM [mem 0xfeb80000-0xfebbffff pref] Nov 6 00:13:59.165990 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint Nov 6 00:13:59.166237 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO Nov 6 00:13:59.166553 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint Nov 6 00:13:59.166804 kernel: pci 0000:00:1f.2: BAR 4 [io 0xc100-0xc11f] Nov 6 00:13:59.167086 kernel: pci 0000:00:1f.2: BAR 5 [mem 0xfebd4000-0xfebd4fff] Nov 6 00:13:59.167353 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint Nov 6 00:13:59.167599 kernel: pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f] Nov 6 00:13:59.167624 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Nov 6 00:13:59.167643 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Nov 6 00:13:59.167661 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Nov 6 00:13:59.167692 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Nov 6 00:13:59.167711 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 Nov 6 00:13:59.167730 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 Nov 6 00:13:59.167748 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 Nov 6 00:13:59.167764 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 Nov 6 00:13:59.167783 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 Nov 6 00:13:59.167801 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 Nov 6 00:13:59.167840 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 Nov 6 00:13:59.167860 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 Nov 6 00:13:59.167886 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 Nov 6 00:13:59.167904 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 Nov 6 00:13:59.167922 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 Nov 6 00:13:59.167942 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 Nov 6 00:13:59.167961 kernel: iommu: Default domain type: Translated Nov 6 00:13:59.167980 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Nov 6 00:13:59.167998 kernel: PCI: Using ACPI for IRQ routing Nov 6 00:13:59.168016 kernel: PCI: pci_cache_line_size set to 64 bytes Nov 6 00:13:59.168034 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Nov 6 00:13:59.168058 kernel: e820: reserve RAM buffer [mem 0x9cfdc000-0x9fffffff] Nov 6 00:13:59.168292 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device Nov 6 00:13:59.168525 kernel: pci 0000:00:01.0: vgaarb: bridge control possible Nov 6 00:13:59.168834 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Nov 6 00:13:59.168859 kernel: vgaarb: loaded Nov 6 00:13:59.168876 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Nov 6 00:13:59.168894 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Nov 6 00:13:59.168913 kernel: clocksource: Switched to clocksource kvm-clock Nov 6 00:13:59.168938 kernel: VFS: Disk quotas dquot_6.6.0 Nov 6 00:13:59.168985 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 6 00:13:59.169006 kernel: pnp: PnP ACPI init Nov 6 00:13:59.169413 kernel: system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved Nov 6 00:13:59.169454 kernel: pnp: PnP ACPI: found 6 devices Nov 6 00:13:59.169478 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Nov 6 00:13:59.169499 kernel: NET: Registered PF_INET protocol family Nov 6 00:13:59.169523 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Nov 6 00:13:59.169559 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Nov 6 00:13:59.169595 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 6 00:13:59.169619 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Nov 6 00:13:59.169643 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Nov 6 00:13:59.169659 kernel: TCP: Hash tables configured (established 32768 bind 32768) Nov 6 00:13:59.169670 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 6 00:13:59.169682 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 6 00:13:59.169693 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 6 00:13:59.169704 kernel: NET: Registered PF_XDP protocol family Nov 6 00:13:59.169887 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Nov 6 00:13:59.170045 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Nov 6 00:13:59.170189 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Nov 6 00:13:59.170379 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xafffffff window] Nov 6 00:13:59.170577 kernel: pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] Nov 6 00:13:59.170735 kernel: pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] Nov 6 00:13:59.170753 kernel: PCI: CLS 0 bytes, default 64 Nov 6 00:13:59.170765 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x2848df6a9de, max_idle_ns: 440795280912 ns Nov 6 00:13:59.170777 kernel: Initialise system trusted keyrings Nov 6 00:13:59.170794 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Nov 6 00:13:59.170805 kernel: Key type asymmetric registered Nov 6 00:13:59.170835 kernel: Asymmetric key parser 'x509' registered Nov 6 00:13:59.170846 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Nov 6 00:13:59.170857 kernel: io scheduler mq-deadline registered Nov 6 00:13:59.170868 kernel: io scheduler kyber registered Nov 6 00:13:59.170878 kernel: io scheduler bfq registered Nov 6 00:13:59.170889 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Nov 6 00:13:59.170901 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 Nov 6 00:13:59.170917 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 Nov 6 00:13:59.170929 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 Nov 6 00:13:59.170940 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 6 00:13:59.170951 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Nov 6 00:13:59.170963 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Nov 6 00:13:59.170974 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Nov 6 00:13:59.170984 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Nov 6 00:13:59.170995 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Nov 6 00:13:59.171190 kernel: rtc_cmos 00:04: RTC can wake from S4 Nov 6 00:13:59.171410 kernel: rtc_cmos 00:04: registered as rtc0 Nov 6 00:13:59.171588 kernel: rtc_cmos 00:04: setting system clock to 2025-11-06T00:13:58 UTC (1762388038) Nov 6 00:13:59.171756 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs Nov 6 00:13:59.171774 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled Nov 6 00:13:59.171786 kernel: NET: Registered PF_INET6 protocol family Nov 6 00:13:59.171797 kernel: Segment Routing with IPv6 Nov 6 00:13:59.171808 kernel: In-situ OAM (IOAM) with IPv6 Nov 6 00:13:59.171837 kernel: NET: Registered PF_PACKET protocol family Nov 6 00:13:59.171853 kernel: Key type dns_resolver registered Nov 6 00:13:59.171864 kernel: IPI shorthand broadcast: enabled Nov 6 00:13:59.171875 kernel: sched_clock: Marking stable (3255003789, 221912718)->(3539463968, -62547461) Nov 6 00:13:59.171886 kernel: registered taskstats version 1 Nov 6 00:13:59.171897 kernel: Loading compiled-in X.509 certificates Nov 6 00:13:59.171908 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.54-flatcar: f906521ec29cbf079ae365554bad8eb8ed6ecb31' Nov 6 00:13:59.171919 kernel: Demotion targets for Node 0: null Nov 6 00:13:59.171930 kernel: Key type .fscrypt registered Nov 6 00:13:59.171941 kernel: Key type fscrypt-provisioning registered Nov 6 00:13:59.171956 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 6 00:13:59.171967 kernel: ima: Allocated hash algorithm: sha1 Nov 6 00:13:59.171978 kernel: ima: No architecture policies found Nov 6 00:13:59.171989 kernel: clk: Disabling unused clocks Nov 6 00:13:59.172000 kernel: Warning: unable to open an initial console. Nov 6 00:13:59.172012 kernel: Freeing unused kernel image (initmem) memory: 45548K Nov 6 00:13:59.172022 kernel: Write protecting the kernel read-only data: 40960k Nov 6 00:13:59.172034 kernel: Freeing unused kernel image (rodata/data gap) memory: 576K Nov 6 00:13:59.172045 kernel: Run /init as init process Nov 6 00:13:59.172060 kernel: with arguments: Nov 6 00:13:59.172071 kernel: /init Nov 6 00:13:59.172081 kernel: with environment: Nov 6 00:13:59.172092 kernel: HOME=/ Nov 6 00:13:59.172103 kernel: TERM=linux Nov 6 00:13:59.172116 systemd[1]: Successfully made /usr/ read-only. Nov 6 00:13:59.172132 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) Nov 6 00:13:59.172164 systemd[1]: Detected virtualization kvm. Nov 6 00:13:59.172177 systemd[1]: Detected architecture x86-64. Nov 6 00:13:59.172188 systemd[1]: Running in initrd. Nov 6 00:13:59.172200 systemd[1]: No hostname configured, using default hostname. Nov 6 00:13:59.172212 systemd[1]: Hostname set to . Nov 6 00:13:59.172225 systemd[1]: Initializing machine ID from VM UUID. Nov 6 00:13:59.172237 systemd[1]: Queued start job for default target initrd.target. Nov 6 00:13:59.172253 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 6 00:13:59.172265 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 6 00:13:59.172279 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Nov 6 00:13:59.172291 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 6 00:13:59.172303 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Nov 6 00:13:59.172317 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Nov 6 00:13:59.172331 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Nov 6 00:13:59.172348 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Nov 6 00:13:59.172360 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 6 00:13:59.172372 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 6 00:13:59.172384 systemd[1]: Reached target paths.target - Path Units. Nov 6 00:13:59.172397 systemd[1]: Reached target slices.target - Slice Units. Nov 6 00:13:59.172409 systemd[1]: Reached target swap.target - Swaps. Nov 6 00:13:59.172421 systemd[1]: Reached target timers.target - Timer Units. Nov 6 00:13:59.172433 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Nov 6 00:13:59.172448 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 6 00:13:59.172460 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Nov 6 00:13:59.172473 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Nov 6 00:13:59.172485 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 6 00:13:59.172498 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 6 00:13:59.172510 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 6 00:13:59.172523 systemd[1]: Reached target sockets.target - Socket Units. Nov 6 00:13:59.172535 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Nov 6 00:13:59.172562 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 6 00:13:59.172574 systemd[1]: Finished network-cleanup.service - Network Cleanup. Nov 6 00:13:59.172587 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). Nov 6 00:13:59.172599 systemd[1]: Starting systemd-fsck-usr.service... Nov 6 00:13:59.172611 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 6 00:13:59.172623 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 6 00:13:59.172639 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 6 00:13:59.172651 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Nov 6 00:13:59.172664 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 6 00:13:59.172676 systemd[1]: Finished systemd-fsck-usr.service. Nov 6 00:13:59.172692 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 6 00:13:59.172830 systemd-journald[202]: Collecting audit messages is disabled. Nov 6 00:13:59.172863 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 6 00:13:59.172877 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Nov 6 00:13:59.172895 systemd-journald[202]: Journal started Nov 6 00:13:59.172921 systemd-journald[202]: Runtime Journal (/run/log/journal/cdb7f87e02464378a26108b27094489a) is 6M, max 48.3M, 42.2M free. Nov 6 00:13:59.172974 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 6 00:13:59.063778 systemd-modules-load[205]: Inserted module 'overlay' Nov 6 00:13:59.177947 systemd-modules-load[205]: Inserted module 'br_netfilter' Nov 6 00:13:59.181477 kernel: Bridge firewalling registered Nov 6 00:13:59.184478 systemd[1]: Started systemd-journald.service - Journal Service. Nov 6 00:13:59.192061 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 6 00:13:59.192476 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 6 00:13:59.196860 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 6 00:13:59.199915 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 6 00:13:59.205955 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 6 00:13:59.221260 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 6 00:13:59.222665 systemd-tmpfiles[224]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Nov 6 00:13:59.225423 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 6 00:13:59.228184 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 6 00:13:59.234083 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 6 00:13:59.238098 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Nov 6 00:13:59.241734 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 6 00:13:59.276650 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=59ca0b9e28689480cec05e5a7a50ffb2fd81e743a9e2986eb3bceb3b87f6702e Nov 6 00:13:59.295699 systemd-resolved[242]: Positive Trust Anchors: Nov 6 00:13:59.295717 systemd-resolved[242]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 6 00:13:59.295749 systemd-resolved[242]: 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 Nov 6 00:13:59.298648 systemd-resolved[242]: Defaulting to hostname 'linux'. Nov 6 00:13:59.309523 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 6 00:13:59.311450 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 6 00:13:59.524874 kernel: SCSI subsystem initialized Nov 6 00:13:59.537863 kernel: Loading iSCSI transport class v2.0-870. Nov 6 00:13:59.552886 kernel: iscsi: registered transport (tcp) Nov 6 00:13:59.579094 kernel: iscsi: registered transport (qla4xxx) Nov 6 00:13:59.579200 kernel: QLogic iSCSI HBA Driver Nov 6 00:13:59.606435 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 6 00:13:59.631784 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 6 00:13:59.714940 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 6 00:13:59.786283 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Nov 6 00:13:59.830081 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Nov 6 00:13:59.952863 kernel: raid6: avx2x4 gen() 28198 MB/s Nov 6 00:13:59.969863 kernel: raid6: avx2x2 gen() 29860 MB/s Nov 6 00:13:59.987740 kernel: raid6: avx2x1 gen() 25264 MB/s Nov 6 00:13:59.987806 kernel: raid6: using algorithm avx2x2 gen() 29860 MB/s Nov 6 00:14:00.056272 kernel: raid6: .... xor() 18955 MB/s, rmw enabled Nov 6 00:14:00.056360 kernel: raid6: using avx2x2 recovery algorithm Nov 6 00:14:00.078871 kernel: xor: automatically using best checksumming function avx Nov 6 00:14:00.261870 kernel: Btrfs loaded, zoned=no, fsverity=no Nov 6 00:14:00.272080 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Nov 6 00:14:00.276832 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 6 00:14:00.310442 systemd-udevd[452]: Using default interface naming scheme 'v255'. Nov 6 00:14:00.316931 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 6 00:14:00.322717 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Nov 6 00:14:00.366332 dracut-pre-trigger[463]: rd.md=0: removing MD RAID activation Nov 6 00:14:00.400525 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Nov 6 00:14:00.402586 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 6 00:14:00.544310 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 6 00:14:00.548421 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Nov 6 00:14:00.587852 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues Nov 6 00:14:00.601277 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Nov 6 00:14:00.604833 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 6 00:14:00.613838 kernel: cryptd: max_cpu_qlen set to 1000 Nov 6 00:14:00.617845 kernel: libata version 3.00 loaded. Nov 6 00:14:00.627053 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 6 00:14:00.632714 kernel: ahci 0000:00:1f.2: version 3.0 Nov 6 00:14:00.632995 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 Nov 6 00:14:00.627764 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 6 00:14:00.639161 kernel: ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode Nov 6 00:14:00.639336 kernel: ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f) Nov 6 00:14:00.639479 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only Nov 6 00:14:00.640324 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Nov 6 00:14:00.648493 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input2 Nov 6 00:14:00.644235 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 6 00:14:00.653206 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Nov 6 00:14:00.665879 kernel: AES CTR mode by8 optimization enabled Nov 6 00:14:00.675928 kernel: scsi host0: ahci Nov 6 00:14:00.686891 kernel: scsi host1: ahci Nov 6 00:14:00.690118 kernel: scsi host2: ahci Nov 6 00:14:00.693847 kernel: scsi host3: ahci Nov 6 00:14:00.694038 kernel: scsi host4: ahci Nov 6 00:14:00.695219 kernel: scsi host5: ahci Nov 6 00:14:00.696743 kernel: ata1: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4100 irq 34 lpm-pol 1 Nov 6 00:14:00.698192 kernel: ata2: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4180 irq 34 lpm-pol 1 Nov 6 00:14:00.698211 kernel: ata3: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4200 irq 34 lpm-pol 1 Nov 6 00:14:00.699661 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Nov 6 00:14:00.704077 kernel: ata4: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4280 irq 34 lpm-pol 1 Nov 6 00:14:00.704097 kernel: ata5: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4300 irq 34 lpm-pol 1 Nov 6 00:14:00.704108 kernel: ata6: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4380 irq 34 lpm-pol 1 Nov 6 00:14:00.790806 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Nov 6 00:14:00.795436 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 6 00:14:00.824505 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Nov 6 00:14:00.826914 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Nov 6 00:14:00.841014 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Nov 6 00:14:00.844410 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Nov 6 00:14:01.021892 kernel: ata4: SATA link down (SStatus 0 SControl 300) Nov 6 00:14:01.023864 kernel: ata2: SATA link down (SStatus 0 SControl 300) Nov 6 00:14:01.023895 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Nov 6 00:14:01.025839 kernel: ata3.00: LPM support broken, forcing max_power Nov 6 00:14:01.025869 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 Nov 6 00:14:01.026956 kernel: ata3.00: applying bridge limits Nov 6 00:14:01.029860 kernel: ata6: SATA link down (SStatus 0 SControl 300) Nov 6 00:14:01.029912 kernel: ata5: SATA link down (SStatus 0 SControl 300) Nov 6 00:14:01.030918 kernel: ata3.00: LPM support broken, forcing max_power Nov 6 00:14:01.033119 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 6 00:14:01.033145 kernel: ata3.00: configured for UDMA/100 Nov 6 00:14:01.034846 kernel: ata1: SATA link down (SStatus 0 SControl 300) Nov 6 00:14:01.038853 kernel: scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 Nov 6 00:14:01.077867 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 6 00:14:01.116210 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray Nov 6 00:14:01.116548 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Nov 6 00:14:01.131881 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 Nov 6 00:14:01.461431 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Nov 6 00:14:01.465534 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Nov 6 00:14:01.469804 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 6 00:14:01.473753 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 6 00:14:01.478042 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Nov 6 00:14:01.513984 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Nov 6 00:14:02.138842 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 6 00:14:02.139480 disk-uuid[614]: The operation has completed successfully. Nov 6 00:14:02.175973 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 6 00:14:02.176107 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Nov 6 00:14:02.231526 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Nov 6 00:14:02.256417 sh[643]: Success Nov 6 00:14:02.279687 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Nov 6 00:14:02.279758 kernel: device-mapper: uevent: version 1.0.3 Nov 6 00:14:02.281849 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Nov 6 00:14:02.292844 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Nov 6 00:14:02.328976 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Nov 6 00:14:02.334748 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Nov 6 00:14:02.352907 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Nov 6 00:14:02.361843 kernel: BTRFS: device fsid 85d805c5-984c-4a6a-aaeb-49fff3689175 devid 1 transid 38 /dev/mapper/usr (253:0) scanned by mount (655) Nov 6 00:14:02.361880 kernel: BTRFS info (device dm-0): first mount of filesystem 85d805c5-984c-4a6a-aaeb-49fff3689175 Nov 6 00:14:02.364796 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Nov 6 00:14:02.372393 kernel: BTRFS info (device dm-0): disabling log replay at mount time Nov 6 00:14:02.372416 kernel: BTRFS info (device dm-0): enabling free space tree Nov 6 00:14:02.373734 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Nov 6 00:14:02.376456 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Nov 6 00:14:02.376670 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Nov 6 00:14:02.377880 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Nov 6 00:14:02.391544 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Nov 6 00:14:02.412844 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (686) Nov 6 00:14:02.416018 kernel: BTRFS info (device vda6): first mount of filesystem ca2bb832-66d5-4dca-a6d2-cbf7440d9381 Nov 6 00:14:02.416056 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Nov 6 00:14:02.419786 kernel: BTRFS info (device vda6): turning on async discard Nov 6 00:14:02.419863 kernel: BTRFS info (device vda6): enabling free space tree Nov 6 00:14:02.425834 kernel: BTRFS info (device vda6): last unmount of filesystem ca2bb832-66d5-4dca-a6d2-cbf7440d9381 Nov 6 00:14:02.426652 systemd[1]: Finished ignition-setup.service - Ignition (setup). Nov 6 00:14:02.432051 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Nov 6 00:14:02.528902 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 6 00:14:02.536416 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 6 00:14:02.579802 ignition[737]: Ignition 2.22.0 Nov 6 00:14:02.580197 ignition[737]: Stage: fetch-offline Nov 6 00:14:02.580245 ignition[737]: no configs at "/usr/lib/ignition/base.d" Nov 6 00:14:02.580256 ignition[737]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 6 00:14:02.580340 ignition[737]: parsed url from cmdline: "" Nov 6 00:14:02.580344 ignition[737]: no config URL provided Nov 6 00:14:02.580349 ignition[737]: reading system config file "/usr/lib/ignition/user.ign" Nov 6 00:14:02.580358 ignition[737]: no config at "/usr/lib/ignition/user.ign" Nov 6 00:14:02.580382 ignition[737]: op(1): [started] loading QEMU firmware config module Nov 6 00:14:02.580386 ignition[737]: op(1): executing: "modprobe" "qemu_fw_cfg" Nov 6 00:14:02.593449 ignition[737]: op(1): [finished] loading QEMU firmware config module Nov 6 00:14:02.615756 ignition[737]: parsing config with SHA512: e48f01b87be6ac5dc55ba2f6b78d194d0d6f83a74fdcf18c3f8052961b0071b6c162198f4ca9861bdc99f1fca463f4c3fe9bc9746431d6c4fadfa0d9ed504014 Nov 6 00:14:02.623861 unknown[737]: fetched base config from "system" Nov 6 00:14:02.623876 unknown[737]: fetched user config from "qemu" Nov 6 00:14:02.624032 ignition[737]: fetch-offline: fetch-offline passed Nov 6 00:14:02.627711 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Nov 6 00:14:02.624108 ignition[737]: Ignition finished successfully Nov 6 00:14:02.637891 systemd-networkd[830]: lo: Link UP Nov 6 00:14:02.637900 systemd-networkd[830]: lo: Gained carrier Nov 6 00:14:02.639735 systemd-networkd[830]: Enumeration completed Nov 6 00:14:02.639870 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 6 00:14:02.640148 systemd-networkd[830]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 6 00:14:02.640152 systemd-networkd[830]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 6 00:14:02.641290 systemd-networkd[830]: eth0: Link UP Nov 6 00:14:02.641511 systemd-networkd[830]: eth0: Gained carrier Nov 6 00:14:02.641521 systemd-networkd[830]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 6 00:14:02.660632 systemd[1]: Reached target network.target - Network. Nov 6 00:14:02.660771 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Nov 6 00:14:02.661808 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Nov 6 00:14:02.685890 systemd-networkd[830]: eth0: DHCPv4 address 10.0.0.36/16, gateway 10.0.0.1 acquired from 10.0.0.1 Nov 6 00:14:02.730468 ignition[837]: Ignition 2.22.0 Nov 6 00:14:02.730497 ignition[837]: Stage: kargs Nov 6 00:14:02.730653 ignition[837]: no configs at "/usr/lib/ignition/base.d" Nov 6 00:14:02.730667 ignition[837]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 6 00:14:02.731284 ignition[837]: kargs: kargs passed Nov 6 00:14:02.731330 ignition[837]: Ignition finished successfully Nov 6 00:14:02.743981 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Nov 6 00:14:02.746940 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Nov 6 00:14:02.801875 ignition[846]: Ignition 2.22.0 Nov 6 00:14:02.801889 ignition[846]: Stage: disks Nov 6 00:14:02.802033 ignition[846]: no configs at "/usr/lib/ignition/base.d" Nov 6 00:14:02.802044 ignition[846]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 6 00:14:02.802496 ignition[846]: disks: disks passed Nov 6 00:14:02.807015 systemd[1]: Finished ignition-disks.service - Ignition (disks). Nov 6 00:14:02.802541 ignition[846]: Ignition finished successfully Nov 6 00:14:02.810188 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Nov 6 00:14:02.813549 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Nov 6 00:14:02.814253 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 6 00:14:02.819247 systemd[1]: Reached target sysinit.target - System Initialization. Nov 6 00:14:02.819809 systemd[1]: Reached target basic.target - Basic System. Nov 6 00:14:02.829470 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Nov 6 00:14:02.865354 systemd-fsck[856]: ROOT: clean, 15/553520 files, 52789/553472 blocks Nov 6 00:14:02.887188 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Nov 6 00:14:02.892139 systemd[1]: Mounting sysroot.mount - /sysroot... Nov 6 00:14:03.048855 kernel: EXT4-fs (vda9): mounted filesystem 25ee01aa-0270-4de7-b5da-d8936d968d16 r/w with ordered data mode. Quota mode: none. Nov 6 00:14:03.050253 systemd[1]: Mounted sysroot.mount - /sysroot. Nov 6 00:14:03.052749 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Nov 6 00:14:03.058767 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 6 00:14:03.062293 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Nov 6 00:14:03.064161 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Nov 6 00:14:03.064205 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Nov 6 00:14:03.064231 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Nov 6 00:14:03.081768 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Nov 6 00:14:03.090892 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (865) Nov 6 00:14:03.090928 kernel: BTRFS info (device vda6): first mount of filesystem ca2bb832-66d5-4dca-a6d2-cbf7440d9381 Nov 6 00:14:03.090944 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Nov 6 00:14:03.085343 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Nov 6 00:14:03.099682 kernel: BTRFS info (device vda6): turning on async discard Nov 6 00:14:03.099753 kernel: BTRFS info (device vda6): enabling free space tree Nov 6 00:14:03.102407 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 6 00:14:03.133607 initrd-setup-root[889]: cut: /sysroot/etc/passwd: No such file or directory Nov 6 00:14:03.138987 initrd-setup-root[896]: cut: /sysroot/etc/group: No such file or directory Nov 6 00:14:03.144185 initrd-setup-root[903]: cut: /sysroot/etc/shadow: No such file or directory Nov 6 00:14:03.148135 initrd-setup-root[910]: cut: /sysroot/etc/gshadow: No such file or directory Nov 6 00:14:03.247271 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Nov 6 00:14:03.248917 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Nov 6 00:14:03.254713 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Nov 6 00:14:03.271845 kernel: BTRFS info (device vda6): last unmount of filesystem ca2bb832-66d5-4dca-a6d2-cbf7440d9381 Nov 6 00:14:03.292008 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Nov 6 00:14:03.319797 ignition[979]: INFO : Ignition 2.22.0 Nov 6 00:14:03.319797 ignition[979]: INFO : Stage: mount Nov 6 00:14:03.322463 ignition[979]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 6 00:14:03.322463 ignition[979]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 6 00:14:03.326120 ignition[979]: INFO : mount: mount passed Nov 6 00:14:03.327424 ignition[979]: INFO : Ignition finished successfully Nov 6 00:14:03.331601 systemd[1]: Finished ignition-mount.service - Ignition (mount). Nov 6 00:14:03.335935 systemd[1]: Starting ignition-files.service - Ignition (files)... Nov 6 00:14:03.361598 systemd[1]: sysroot-oem.mount: Deactivated successfully. Nov 6 00:14:03.363408 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 6 00:14:03.400204 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (991) Nov 6 00:14:03.400242 kernel: BTRFS info (device vda6): first mount of filesystem ca2bb832-66d5-4dca-a6d2-cbf7440d9381 Nov 6 00:14:03.402095 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Nov 6 00:14:03.406202 kernel: BTRFS info (device vda6): turning on async discard Nov 6 00:14:03.406225 kernel: BTRFS info (device vda6): enabling free space tree Nov 6 00:14:03.407964 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 6 00:14:03.453344 ignition[1009]: INFO : Ignition 2.22.0 Nov 6 00:14:03.453344 ignition[1009]: INFO : Stage: files Nov 6 00:14:03.456524 ignition[1009]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 6 00:14:03.456524 ignition[1009]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 6 00:14:03.456524 ignition[1009]: DEBUG : files: compiled without relabeling support, skipping Nov 6 00:14:03.456524 ignition[1009]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Nov 6 00:14:03.456524 ignition[1009]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Nov 6 00:14:03.468770 ignition[1009]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Nov 6 00:14:03.468770 ignition[1009]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Nov 6 00:14:03.468770 ignition[1009]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Nov 6 00:14:03.468770 ignition[1009]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Nov 6 00:14:03.468770 ignition[1009]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Nov 6 00:14:03.468770 ignition[1009]: INFO : files: op(4): [started] processing unit "etcd-member.service" Nov 6 00:14:03.468770 ignition[1009]: 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" Nov 6 00:14:03.458157 unknown[1009]: wrote ssh authorized keys file for user: core Nov 6 00:14:03.493083 ignition[1009]: 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" Nov 6 00:14:03.493083 ignition[1009]: INFO : files: op(4): [finished] processing unit "etcd-member.service" Nov 6 00:14:03.493083 ignition[1009]: INFO : files: op(6): [started] processing unit "coreos-metadata.service" Nov 6 00:14:03.493083 ignition[1009]: INFO : files: op(6): op(7): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Nov 6 00:14:03.493083 ignition[1009]: INFO : files: op(6): op(7): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Nov 6 00:14:03.493083 ignition[1009]: INFO : files: op(6): [finished] processing unit "coreos-metadata.service" Nov 6 00:14:03.493083 ignition[1009]: INFO : files: op(8): [started] setting preset to disabled for "coreos-metadata.service" Nov 6 00:14:03.516894 ignition[1009]: INFO : files: op(8): op(9): [started] removing enablement symlink(s) for "coreos-metadata.service" Nov 6 00:14:03.516894 ignition[1009]: INFO : files: op(8): op(9): [finished] removing enablement symlink(s) for "coreos-metadata.service" Nov 6 00:14:03.516894 ignition[1009]: INFO : files: op(8): [finished] setting preset to disabled for "coreos-metadata.service" Nov 6 00:14:03.516894 ignition[1009]: INFO : files: op(a): [started] setting preset to enabled for "etcd-member.service" Nov 6 00:14:03.516894 ignition[1009]: INFO : files: op(a): [finished] setting preset to enabled for "etcd-member.service" Nov 6 00:14:03.516894 ignition[1009]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Nov 6 00:14:03.516894 ignition[1009]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Nov 6 00:14:03.516894 ignition[1009]: INFO : files: files passed Nov 6 00:14:03.516894 ignition[1009]: INFO : Ignition finished successfully Nov 6 00:14:03.506641 systemd[1]: Finished ignition-files.service - Ignition (files). Nov 6 00:14:03.509943 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Nov 6 00:14:03.513706 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Nov 6 00:14:03.527244 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 6 00:14:03.555082 initrd-setup-root-after-ignition[1037]: grep: /sysroot/oem/oem-release: No such file or directory Nov 6 00:14:03.527457 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Nov 6 00:14:03.559264 initrd-setup-root-after-ignition[1039]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 6 00:14:03.559264 initrd-setup-root-after-ignition[1039]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Nov 6 00:14:03.533520 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 6 00:14:03.566885 initrd-setup-root-after-ignition[1043]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 6 00:14:03.537477 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Nov 6 00:14:03.543347 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Nov 6 00:14:03.586418 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 6 00:14:03.586559 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Nov 6 00:14:03.590308 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Nov 6 00:14:03.594017 systemd[1]: Reached target initrd.target - Initrd Default Target. Nov 6 00:14:03.597280 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Nov 6 00:14:03.598160 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Nov 6 00:14:03.632639 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 6 00:14:03.634202 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Nov 6 00:14:03.663732 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Nov 6 00:14:03.664285 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 6 00:14:03.669560 systemd[1]: Stopped target timers.target - Timer Units. Nov 6 00:14:03.671454 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 6 00:14:03.671618 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 6 00:14:03.679651 systemd[1]: Stopped target initrd.target - Initrd Default Target. Nov 6 00:14:03.679840 systemd[1]: Stopped target basic.target - Basic System. Nov 6 00:14:03.684505 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Nov 6 00:14:03.685982 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Nov 6 00:14:03.693462 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Nov 6 00:14:03.695295 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Nov 6 00:14:03.695873 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Nov 6 00:14:03.703711 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Nov 6 00:14:03.705396 systemd[1]: Stopped target sysinit.target - System Initialization. Nov 6 00:14:03.705967 systemd[1]: Stopped target local-fs.target - Local File Systems. Nov 6 00:14:03.706547 systemd[1]: Stopped target swap.target - Swaps. Nov 6 00:14:03.716397 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 6 00:14:03.716574 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Nov 6 00:14:03.718619 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Nov 6 00:14:03.724956 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 6 00:14:03.725948 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Nov 6 00:14:03.726117 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 6 00:14:03.729723 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 6 00:14:03.729859 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Nov 6 00:14:03.733713 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Nov 6 00:14:03.733852 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Nov 6 00:14:03.741357 systemd[1]: Stopped target paths.target - Path Units. Nov 6 00:14:03.745506 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 6 00:14:03.745632 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 6 00:14:03.749188 systemd[1]: Stopped target slices.target - Slice Units. Nov 6 00:14:03.752995 systemd[1]: Stopped target sockets.target - Socket Units. Nov 6 00:14:03.755910 systemd[1]: iscsid.socket: Deactivated successfully. Nov 6 00:14:03.756023 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Nov 6 00:14:03.759195 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 6 00:14:03.759280 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 6 00:14:03.761664 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Nov 6 00:14:03.761787 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 6 00:14:03.764552 systemd[1]: ignition-files.service: Deactivated successfully. Nov 6 00:14:03.764658 systemd[1]: Stopped ignition-files.service - Ignition (files). Nov 6 00:14:03.772091 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Nov 6 00:14:03.774779 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 6 00:14:03.774917 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Nov 6 00:14:03.798101 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Nov 6 00:14:03.799911 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 6 00:14:03.800034 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Nov 6 00:14:03.805720 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 6 00:14:03.805951 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Nov 6 00:14:03.816452 ignition[1064]: INFO : Ignition 2.22.0 Nov 6 00:14:03.816452 ignition[1064]: INFO : Stage: umount Nov 6 00:14:03.819309 ignition[1064]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 6 00:14:03.819309 ignition[1064]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 6 00:14:03.819309 ignition[1064]: INFO : umount: umount passed Nov 6 00:14:03.819309 ignition[1064]: INFO : Ignition finished successfully Nov 6 00:14:03.819069 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 6 00:14:03.819258 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Nov 6 00:14:03.821925 systemd[1]: ignition-mount.service: Deactivated successfully. Nov 6 00:14:03.822118 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Nov 6 00:14:03.827379 systemd[1]: Stopped target network.target - Network. Nov 6 00:14:03.828311 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 6 00:14:03.828390 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Nov 6 00:14:03.832938 systemd[1]: ignition-kargs.service: Deactivated successfully. Nov 6 00:14:03.832990 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Nov 6 00:14:03.836151 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 6 00:14:03.836206 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Nov 6 00:14:03.839791 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Nov 6 00:14:03.839858 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Nov 6 00:14:03.843243 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Nov 6 00:14:03.844866 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Nov 6 00:14:03.851929 systemd[1]: sysroot-boot.mount: Deactivated successfully. Nov 6 00:14:03.852490 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 6 00:14:03.852606 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Nov 6 00:14:03.857784 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Nov 6 00:14:03.858112 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 6 00:14:03.858240 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Nov 6 00:14:03.862080 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 6 00:14:03.862206 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Nov 6 00:14:03.867926 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Nov 6 00:14:03.869561 systemd[1]: Stopped target network-pre.target - Preparation for Network. Nov 6 00:14:03.872100 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 6 00:14:03.872157 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Nov 6 00:14:03.874522 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 6 00:14:03.874618 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Nov 6 00:14:03.876278 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Nov 6 00:14:03.881183 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Nov 6 00:14:03.881248 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 6 00:14:03.884546 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 6 00:14:03.884611 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Nov 6 00:14:03.894831 systemd[1]: systemd-modules-load.service: Deactivated successfully. Nov 6 00:14:03.894892 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Nov 6 00:14:03.898327 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Nov 6 00:14:03.898406 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 6 00:14:03.905869 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 6 00:14:03.937528 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 6 00:14:03.937700 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Nov 6 00:14:03.941246 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 6 00:14:03.941462 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 6 00:14:03.946014 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 6 00:14:03.946102 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Nov 6 00:14:03.946616 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 6 00:14:03.946659 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Nov 6 00:14:03.950372 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 6 00:14:03.950451 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Nov 6 00:14:03.958730 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 6 00:14:03.958849 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Nov 6 00:14:03.963857 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 6 00:14:03.963952 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 6 00:14:03.970779 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Nov 6 00:14:03.974433 systemd[1]: systemd-network-generator.service: Deactivated successfully. Nov 6 00:14:03.974546 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Nov 6 00:14:03.978473 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 6 00:14:03.978539 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 6 00:14:03.984632 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 6 00:14:03.984726 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 6 00:14:04.003011 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 6 00:14:04.003198 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Nov 6 00:14:04.007242 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Nov 6 00:14:04.011619 systemd[1]: Starting initrd-switch-root.service - Switch Root... Nov 6 00:14:04.035173 systemd[1]: Switching root. Nov 6 00:14:04.069555 systemd-journald[202]: Journal stopped Nov 6 00:14:05.949272 systemd-journald[202]: Received SIGTERM from PID 1 (systemd). Nov 6 00:14:05.949341 kernel: SELinux: policy capability network_peer_controls=1 Nov 6 00:14:05.949356 kernel: SELinux: policy capability open_perms=1 Nov 6 00:14:05.949369 kernel: SELinux: policy capability extended_socket_class=1 Nov 6 00:14:05.949390 kernel: SELinux: policy capability always_check_network=0 Nov 6 00:14:05.949402 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 6 00:14:05.949421 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 6 00:14:05.949432 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 6 00:14:05.949444 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Nov 6 00:14:05.949463 kernel: SELinux: policy capability userspace_initial_context=0 Nov 6 00:14:05.949481 kernel: audit: type=1403 audit(1762388044.250:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 6 00:14:05.949511 systemd[1]: Successfully loaded SELinux policy in 108.074ms. Nov 6 00:14:05.949546 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 14.926ms. Nov 6 00:14:05.949563 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) Nov 6 00:14:05.949575 systemd[1]: Detected virtualization kvm. Nov 6 00:14:05.949588 systemd[1]: Detected architecture x86-64. Nov 6 00:14:05.949606 systemd[1]: Detected first boot. Nov 6 00:14:05.949618 systemd[1]: Initializing machine ID from VM UUID. Nov 6 00:14:05.949634 zram_generator::config[1109]: No configuration found. Nov 6 00:14:05.949647 kernel: Guest personality initialized and is inactive Nov 6 00:14:05.949660 kernel: VMCI host device registered (name=vmci, major=10, minor=125) Nov 6 00:14:05.949671 kernel: Initialized host personality Nov 6 00:14:05.949683 kernel: NET: Registered PF_VSOCK protocol family Nov 6 00:14:05.949695 systemd[1]: Populated /etc with preset unit settings. Nov 6 00:14:05.949710 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Nov 6 00:14:05.949723 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 6 00:14:05.949738 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Nov 6 00:14:05.949756 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 6 00:14:05.949769 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Nov 6 00:14:05.949781 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Nov 6 00:14:05.949793 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Nov 6 00:14:05.949808 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Nov 6 00:14:05.949835 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Nov 6 00:14:05.949848 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Nov 6 00:14:05.949861 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Nov 6 00:14:05.949873 systemd[1]: Created slice user.slice - User and Session Slice. Nov 6 00:14:05.949885 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 6 00:14:05.949898 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 6 00:14:05.949911 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Nov 6 00:14:05.949923 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Nov 6 00:14:05.949939 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Nov 6 00:14:05.949952 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 6 00:14:05.949964 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Nov 6 00:14:05.949977 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 6 00:14:05.949989 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 6 00:14:05.950003 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Nov 6 00:14:05.950015 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Nov 6 00:14:05.950029 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Nov 6 00:14:05.950044 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Nov 6 00:14:05.950058 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 6 00:14:05.950073 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 6 00:14:05.950089 systemd[1]: Reached target slices.target - Slice Units. Nov 6 00:14:05.950104 systemd[1]: Reached target swap.target - Swaps. Nov 6 00:14:05.950120 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Nov 6 00:14:05.950135 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Nov 6 00:14:05.950150 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Nov 6 00:14:05.950165 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 6 00:14:05.950180 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 6 00:14:05.950192 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 6 00:14:05.950208 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Nov 6 00:14:05.950224 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Nov 6 00:14:05.950240 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Nov 6 00:14:05.950252 systemd[1]: Mounting media.mount - External Media Directory... Nov 6 00:14:05.950267 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 6 00:14:05.950280 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Nov 6 00:14:05.950292 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Nov 6 00:14:05.950307 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Nov 6 00:14:05.950321 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). Nov 6 00:14:05.950333 systemd[1]: Reached target machines.target - Containers. Nov 6 00:14:05.950345 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Nov 6 00:14:05.950357 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 6 00:14:05.950369 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 6 00:14:05.950396 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Nov 6 00:14:05.950409 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 6 00:14:05.950423 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 6 00:14:05.950436 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 6 00:14:05.950449 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Nov 6 00:14:05.950461 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 6 00:14:05.950474 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Nov 6 00:14:05.950486 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 6 00:14:05.950498 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Nov 6 00:14:05.950511 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 6 00:14:05.950523 systemd[1]: Stopped systemd-fsck-usr.service. Nov 6 00:14:05.950539 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). Nov 6 00:14:05.950551 kernel: loop: module loaded Nov 6 00:14:05.950562 kernel: fuse: init (API version 7.41) Nov 6 00:14:05.950574 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 6 00:14:05.950588 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 6 00:14:05.950601 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 6 00:14:05.950614 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Nov 6 00:14:05.950627 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Nov 6 00:14:05.950641 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 6 00:14:05.950679 systemd-journald[1194]: Collecting audit messages is disabled. Nov 6 00:14:05.950702 systemd[1]: verity-setup.service: Deactivated successfully. Nov 6 00:14:05.950715 systemd-journald[1194]: Journal started Nov 6 00:14:05.950740 systemd-journald[1194]: Runtime Journal (/run/log/journal/cdb7f87e02464378a26108b27094489a) is 6M, max 48.3M, 42.2M free. Nov 6 00:14:05.499267 systemd[1]: Queued start job for default target multi-user.target. Nov 6 00:14:05.532583 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Nov 6 00:14:05.535459 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 6 00:14:05.951828 kernel: ACPI: bus type drm_connector registered Nov 6 00:14:05.952901 systemd[1]: Stopped verity-setup.service. Nov 6 00:14:05.956844 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 6 00:14:05.961841 systemd[1]: Started systemd-journald.service - Journal Service. Nov 6 00:14:05.964479 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Nov 6 00:14:05.966459 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Nov 6 00:14:05.968529 systemd[1]: Mounted media.mount - External Media Directory. Nov 6 00:14:05.970491 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Nov 6 00:14:05.972428 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Nov 6 00:14:05.974390 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Nov 6 00:14:05.976506 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Nov 6 00:14:05.978850 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 6 00:14:05.981210 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 6 00:14:05.981445 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Nov 6 00:14:05.983685 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 6 00:14:05.983931 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 6 00:14:05.986111 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 6 00:14:05.986333 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 6 00:14:05.988539 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 6 00:14:05.988748 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 6 00:14:05.991166 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 6 00:14:05.991388 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Nov 6 00:14:05.993673 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 6 00:14:05.993901 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 6 00:14:05.996363 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 6 00:14:05.999143 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 6 00:14:06.002025 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Nov 6 00:14:06.004744 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Nov 6 00:14:06.022202 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 6 00:14:06.026050 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Nov 6 00:14:06.029637 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Nov 6 00:14:06.031866 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Nov 6 00:14:06.031910 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 6 00:14:06.034974 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Nov 6 00:14:06.040885 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Nov 6 00:14:06.043990 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 6 00:14:06.045708 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Nov 6 00:14:06.051237 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Nov 6 00:14:06.053652 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 6 00:14:06.055393 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Nov 6 00:14:06.057580 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 6 00:14:06.059012 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 6 00:14:06.063395 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Nov 6 00:14:06.069174 systemd-journald[1194]: Time spent on flushing to /var/log/journal/cdb7f87e02464378a26108b27094489a is 26.798ms for 949 entries. Nov 6 00:14:06.069174 systemd-journald[1194]: System Journal (/var/log/journal/cdb7f87e02464378a26108b27094489a) is 8M, max 195.6M, 187.6M free. Nov 6 00:14:06.127168 systemd-journald[1194]: Received client request to flush runtime journal. Nov 6 00:14:06.127225 kernel: loop0: detected capacity change from 0 to 110984 Nov 6 00:14:06.069079 systemd[1]: Starting systemd-sysusers.service - Create System Users... Nov 6 00:14:06.076394 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 6 00:14:06.078936 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Nov 6 00:14:06.130988 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 6 00:14:06.081445 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Nov 6 00:14:06.088049 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Nov 6 00:14:06.092105 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Nov 6 00:14:06.097015 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Nov 6 00:14:06.108038 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 6 00:14:06.131608 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Nov 6 00:14:06.134938 systemd[1]: Finished systemd-sysusers.service - Create System Users. Nov 6 00:14:06.140732 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 6 00:14:06.164840 kernel: loop1: detected capacity change from 0 to 128016 Nov 6 00:14:06.175952 systemd-tmpfiles[1245]: ACLs are not supported, ignoring. Nov 6 00:14:06.175973 systemd-tmpfiles[1245]: ACLs are not supported, ignoring. Nov 6 00:14:06.181455 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 6 00:14:06.196832 kernel: loop2: detected capacity change from 0 to 110984 Nov 6 00:14:06.209855 kernel: loop3: detected capacity change from 0 to 128016 Nov 6 00:14:06.216440 (sd-merge)[1251]: Using extensions 'containerd-flatcar', 'docker-flatcar'. Nov 6 00:14:06.216996 (sd-merge)[1251]: Merged extensions into '/usr'. Nov 6 00:14:06.220794 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Nov 6 00:14:06.227061 systemd[1]: Starting ensure-sysext.service... Nov 6 00:14:06.229415 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 6 00:14:06.250479 systemd[1]: Reload requested from client PID 1253 ('systemctl') (unit ensure-sysext.service)... Nov 6 00:14:06.250494 systemd[1]: Reloading... Nov 6 00:14:06.253017 systemd-tmpfiles[1254]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Nov 6 00:14:06.253471 systemd-tmpfiles[1254]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Nov 6 00:14:06.253925 systemd-tmpfiles[1254]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Nov 6 00:14:06.254328 systemd-tmpfiles[1254]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Nov 6 00:14:06.255516 systemd-tmpfiles[1254]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Nov 6 00:14:06.255961 systemd-tmpfiles[1254]: ACLs are not supported, ignoring. Nov 6 00:14:06.256123 systemd-tmpfiles[1254]: ACLs are not supported, ignoring. Nov 6 00:14:06.301533 systemd-tmpfiles[1254]: Detected autofs mount point /boot during canonicalization of boot. Nov 6 00:14:06.301701 systemd-tmpfiles[1254]: Skipping /boot Nov 6 00:14:06.315388 systemd-tmpfiles[1254]: Detected autofs mount point /boot during canonicalization of boot. Nov 6 00:14:06.315466 systemd-tmpfiles[1254]: Skipping /boot Nov 6 00:14:06.400886 zram_generator::config[1285]: No configuration found. Nov 6 00:14:06.631416 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 6 00:14:06.631663 systemd[1]: Reloading finished in 380 ms. Nov 6 00:14:06.644195 ldconfig[1223]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 6 00:14:06.660030 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Nov 6 00:14:06.716832 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 6 00:14:06.728467 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 6 00:14:06.757723 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Nov 6 00:14:06.764055 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Nov 6 00:14:06.778219 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 6 00:14:06.820547 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Nov 6 00:14:06.827956 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 6 00:14:06.828381 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 6 00:14:06.830366 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 6 00:14:06.840607 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 6 00:14:06.846137 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 6 00:14:06.846464 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 6 00:14:06.846735 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). Nov 6 00:14:06.849086 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Nov 6 00:14:06.852191 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 6 00:14:06.853661 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 6 00:14:06.853938 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 6 00:14:06.857506 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 6 00:14:06.857735 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 6 00:14:06.860343 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 6 00:14:06.860583 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 6 00:14:06.870277 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 6 00:14:06.870638 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 6 00:14:06.873284 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 6 00:14:06.877314 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 6 00:14:06.886146 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 6 00:14:06.887989 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 6 00:14:06.888336 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). Nov 6 00:14:06.888634 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 6 00:14:06.891367 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Nov 6 00:14:06.894870 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 6 00:14:06.895365 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 6 00:14:06.898028 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 6 00:14:06.898287 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 6 00:14:06.901800 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 6 00:14:06.902232 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 6 00:14:06.910721 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Nov 6 00:14:06.923635 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 6 00:14:06.924009 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 6 00:14:06.925710 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 6 00:14:06.928611 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 6 00:14:06.932122 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 6 00:14:06.940624 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 6 00:14:06.942785 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 6 00:14:06.943935 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). Nov 6 00:14:06.944141 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 6 00:14:06.946214 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 6 00:14:06.946485 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 6 00:14:06.949362 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 6 00:14:06.949630 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 6 00:14:06.952899 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 6 00:14:06.953437 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 6 00:14:06.957880 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 6 00:14:06.958468 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 6 00:14:06.968894 systemd[1]: Finished ensure-sysext.service. Nov 6 00:14:06.981446 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 6 00:14:06.981521 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 6 00:14:06.984229 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Nov 6 00:14:07.102601 systemd[1]: Started systemd-userdbd.service - User Database Manager. Nov 6 00:14:07.105797 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Nov 6 00:14:07.138043 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Nov 6 00:14:07.153844 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). Nov 6 00:14:07.222193 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Nov 6 00:14:07.224525 systemd[1]: Reached target time-set.target - System Time Set. Nov 6 00:14:07.258529 systemd-resolved[1331]: Positive Trust Anchors: Nov 6 00:14:07.258551 systemd-resolved[1331]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 6 00:14:07.258581 systemd-resolved[1331]: 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 Nov 6 00:14:07.262611 systemd-resolved[1331]: Defaulting to hostname 'linux'. Nov 6 00:14:07.264236 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 6 00:14:07.266170 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 6 00:14:07.270271 augenrules[1380]: No rules Nov 6 00:14:07.272208 systemd[1]: audit-rules.service: Deactivated successfully. Nov 6 00:14:07.272550 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 6 00:14:07.371506 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Nov 6 00:14:07.375549 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 6 00:14:07.378669 systemd[1]: Starting systemd-update-done.service - Update is Completed... Nov 6 00:14:07.410911 systemd[1]: Finished systemd-update-done.service - Update is Completed. Nov 6 00:14:07.423382 systemd-udevd[1387]: Using default interface naming scheme 'v255'. Nov 6 00:14:07.443402 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 6 00:14:07.445648 systemd[1]: Reached target sysinit.target - System Initialization. Nov 6 00:14:07.447650 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Nov 6 00:14:07.449906 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Nov 6 00:14:07.452087 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Nov 6 00:14:07.454496 systemd[1]: Started logrotate.timer - Daily rotation of log files. Nov 6 00:14:07.456803 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Nov 6 00:14:07.458962 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Nov 6 00:14:07.461558 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Nov 6 00:14:07.461587 systemd[1]: Reached target paths.target - Path Units. Nov 6 00:14:07.463173 systemd[1]: Reached target timers.target - Timer Units. Nov 6 00:14:07.466520 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Nov 6 00:14:07.470590 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 6 00:14:07.478020 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Nov 6 00:14:07.497688 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Nov 6 00:14:07.500317 systemd[1]: Reached target ssh-access.target - SSH Access Available. Nov 6 00:14:07.513844 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Nov 6 00:14:07.515899 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Nov 6 00:14:07.520444 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 6 00:14:07.522762 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 6 00:14:07.531855 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Nov 6 00:14:07.532025 systemd[1]: Reached target sockets.target - Socket Units. Nov 6 00:14:07.533740 systemd[1]: Reached target basic.target - Basic System. Nov 6 00:14:07.535741 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Nov 6 00:14:07.535767 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Nov 6 00:14:07.539948 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Nov 6 00:14:07.543971 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Nov 6 00:14:07.548683 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Nov 6 00:14:07.556225 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Nov 6 00:14:07.558757 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). Nov 6 00:14:07.566170 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Nov 6 00:14:07.569071 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Nov 6 00:14:07.572993 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Nov 6 00:14:07.578084 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Nov 6 00:14:07.584130 systemd[1]: Starting systemd-logind.service - User Login Management... Nov 6 00:14:07.584782 jq[1428]: false Nov 6 00:14:07.586800 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Nov 6 00:14:07.590631 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 6 00:14:07.594103 systemd[1]: Starting update-engine.service - Update Engine... Nov 6 00:14:07.599965 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Nov 6 00:14:07.603075 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Nov 6 00:14:07.605546 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Nov 6 00:14:07.606115 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Nov 6 00:14:07.606506 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 6 00:14:07.606950 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Nov 6 00:14:07.616667 oslogin_cache_refresh[1430]: Refreshing passwd entry cache Nov 6 00:14:07.617322 extend-filesystems[1429]: Found /dev/vda6 Nov 6 00:14:07.620238 google_oslogin_nss_cache[1430]: oslogin_cache_refresh[1430]: Refreshing passwd entry cache Nov 6 00:14:07.616362 systemd[1]: motdgen.service: Deactivated successfully. Nov 6 00:14:07.620693 jq[1442]: true Nov 6 00:14:07.616625 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Nov 6 00:14:07.623357 google_oslogin_nss_cache[1430]: oslogin_cache_refresh[1430]: Failure getting users, quitting Nov 6 00:14:07.623357 google_oslogin_nss_cache[1430]: oslogin_cache_refresh[1430]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Nov 6 00:14:07.623357 google_oslogin_nss_cache[1430]: oslogin_cache_refresh[1430]: Refreshing group entry cache Nov 6 00:14:07.623357 google_oslogin_nss_cache[1430]: oslogin_cache_refresh[1430]: Failure getting groups, quitting Nov 6 00:14:07.623357 google_oslogin_nss_cache[1430]: oslogin_cache_refresh[1430]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Nov 6 00:14:07.622729 oslogin_cache_refresh[1430]: Failure getting users, quitting Nov 6 00:14:07.622748 oslogin_cache_refresh[1430]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Nov 6 00:14:07.622794 oslogin_cache_refresh[1430]: Refreshing group entry cache Nov 6 00:14:07.623278 oslogin_cache_refresh[1430]: Failure getting groups, quitting Nov 6 00:14:07.623289 oslogin_cache_refresh[1430]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Nov 6 00:14:07.625834 extend-filesystems[1429]: Found /dev/vda9 Nov 6 00:14:07.627551 extend-filesystems[1429]: Checking size of /dev/vda9 Nov 6 00:14:07.629085 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Nov 6 00:14:07.629381 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Nov 6 00:14:07.649480 extend-filesystems[1429]: Old size kept for /dev/vda9 Nov 6 00:14:07.663570 update_engine[1440]: I20251106 00:14:07.657218 1440 main.cc:92] Flatcar Update Engine starting Nov 6 00:14:07.659622 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 6 00:14:07.659938 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Nov 6 00:14:07.666057 jq[1456]: true Nov 6 00:14:07.680167 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Nov 6 00:14:07.726053 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Nov 6 00:14:07.744244 dbus-daemon[1421]: [system] SELinux support is enabled Nov 6 00:14:07.744433 systemd[1]: Started dbus.service - D-Bus System Message Bus. Nov 6 00:14:07.749938 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). Nov 6 00:14:07.749966 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Nov 6 00:14:07.752028 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). Nov 6 00:14:07.752042 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Nov 6 00:14:07.758972 systemd[1]: Started update-engine.service - Update Engine. Nov 6 00:14:07.759323 update_engine[1440]: I20251106 00:14:07.759274 1440 update_check_scheduler.cc:74] Next update check in 7m34s Nov 6 00:14:07.779965 systemd[1]: Started locksmithd.service - Cluster reboot manager. Nov 6 00:14:07.797834 kernel: mousedev: PS/2 mouse device common for all mice Nov 6 00:14:07.799258 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 6 00:14:07.810154 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Nov 6 00:14:07.820106 systemd-networkd[1419]: lo: Link UP Nov 6 00:14:07.820122 systemd-networkd[1419]: lo: Gained carrier Nov 6 00:14:07.822002 systemd-logind[1439]: New seat seat0. Nov 6 00:14:07.824071 systemd-networkd[1419]: Enumeration completed Nov 6 00:14:07.824501 systemd[1]: Started systemd-logind.service - User Login Management. Nov 6 00:14:07.847495 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 6 00:14:07.851801 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 Nov 6 00:14:07.850765 systemd[1]: Reached target network.target - Network. Nov 6 00:14:07.852895 systemd-networkd[1419]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 6 00:14:07.852906 systemd-networkd[1419]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 6 00:14:07.854763 systemd-networkd[1419]: eth0: Link UP Nov 6 00:14:07.856324 systemd-networkd[1419]: eth0: Gained carrier Nov 6 00:14:07.856354 systemd-networkd[1419]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 6 00:14:07.856803 systemd[1]: Starting containerd.service - containerd container runtime... Nov 6 00:14:07.858936 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Nov 6 00:14:07.861581 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Nov 6 00:14:07.868919 kernel: ACPI: button: Power Button [PWRF] Nov 6 00:14:07.868972 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Nov 6 00:14:07.871969 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Nov 6 00:14:07.965006 systemd-networkd[1419]: eth0: DHCPv4 address 10.0.0.36/16, gateway 10.0.0.1 acquired from 10.0.0.1 Nov 6 00:14:07.966168 systemd-timesyncd[1357]: Network configuration changed, trying to establish connection. Nov 6 00:14:08.963304 systemd-timesyncd[1357]: Contacted time server 10.0.0.1:123 (10.0.0.1). Nov 6 00:14:08.963379 systemd-timesyncd[1357]: Initial clock synchronization to Thu 2025-11-06 00:14:08.962820 UTC. Nov 6 00:14:08.965297 systemd-resolved[1331]: Clock change detected. Flushing caches. Nov 6 00:14:08.970323 systemd-logind[1439]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Nov 6 00:14:09.000224 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Nov 6 00:14:09.007560 locksmithd[1497]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 6 00:14:09.020963 sshd_keygen[1460]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Nov 6 00:14:09.055466 systemd-logind[1439]: Watching system buttons on /dev/input/event2 (Power Button) Nov 6 00:14:09.091692 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Nov 6 00:14:09.109753 (ntainerd)[1515]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Nov 6 00:14:09.133392 kernel: kvm_amd: TSC scaling supported Nov 6 00:14:09.133463 kernel: kvm_amd: Nested Virtualization enabled Nov 6 00:14:09.133478 kernel: kvm_amd: Nested Paging enabled Nov 6 00:14:09.133491 kernel: kvm_amd: LBR virtualization supported Nov 6 00:14:09.133504 kernel: kvm_amd: Virtual VMLOAD VMSAVE supported Nov 6 00:14:09.133536 kernel: kvm_amd: Virtual GIF supported Nov 6 00:14:09.133851 systemd[1]: Starting issuegen.service - Generate /run/issue... Nov 6 00:14:09.188804 systemd[1]: issuegen.service: Deactivated successfully. Nov 6 00:14:09.189171 systemd[1]: Finished issuegen.service - Generate /run/issue. Nov 6 00:14:09.204285 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Nov 6 00:14:09.226543 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 6 00:14:09.230684 bash[1493]: Updated "/home/core/.ssh/authorized_keys" Nov 6 00:14:09.234293 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Nov 6 00:14:09.240539 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Nov 6 00:14:09.245410 kernel: EDAC MC: Ver: 3.0.0 Nov 6 00:14:09.252745 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Nov 6 00:14:09.258915 systemd[1]: Started getty@tty1.service - Getty on tty1. Nov 6 00:14:09.262886 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Nov 6 00:14:09.264916 systemd[1]: Reached target getty.target - Login Prompts. Nov 6 00:14:09.371742 containerd[1515]: time="2025-11-06T00:14:09Z" 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 Nov 6 00:14:09.372444 containerd[1515]: time="2025-11-06T00:14:09.372410740Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Nov 6 00:14:09.380142 containerd[1515]: time="2025-11-06T00:14:09.380093948Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="9.949µs" Nov 6 00:14:09.380142 containerd[1515]: time="2025-11-06T00:14:09.380132881Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Nov 6 00:14:09.380198 containerd[1515]: time="2025-11-06T00:14:09.380151506Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Nov 6 00:14:09.380408 containerd[1515]: time="2025-11-06T00:14:09.380381848Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Nov 6 00:14:09.380408 containerd[1515]: time="2025-11-06T00:14:09.380405703Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Nov 6 00:14:09.380475 containerd[1515]: time="2025-11-06T00:14:09.380441440Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 6 00:14:09.380539 containerd[1515]: time="2025-11-06T00:14:09.380514177Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 6 00:14:09.380539 containerd[1515]: time="2025-11-06T00:14:09.380530688Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Nov 6 00:14:09.381461 containerd[1515]: time="2025-11-06T00:14:09.381016879Z" 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 Nov 6 00:14:09.381461 containerd[1515]: time="2025-11-06T00:14:09.381110866Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 6 00:14:09.381461 containerd[1515]: time="2025-11-06T00:14:09.381142956Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 6 00:14:09.381461 containerd[1515]: time="2025-11-06T00:14:09.381160248Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Nov 6 00:14:09.381461 containerd[1515]: time="2025-11-06T00:14:09.381296153Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Nov 6 00:14:09.381664 containerd[1515]: time="2025-11-06T00:14:09.381582710Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Nov 6 00:14:09.381664 containerd[1515]: time="2025-11-06T00:14:09.381622385Z" 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 Nov 6 00:14:09.381664 containerd[1515]: time="2025-11-06T00:14:09.381636241Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Nov 6 00:14:09.381726 containerd[1515]: time="2025-11-06T00:14:09.381669874Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Nov 6 00:14:09.382042 containerd[1515]: time="2025-11-06T00:14:09.381982711Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Nov 6 00:14:09.382206 containerd[1515]: time="2025-11-06T00:14:09.382185120Z" level=info msg="metadata content store policy set" policy=shared Nov 6 00:14:09.752181 containerd[1515]: time="2025-11-06T00:14:09.752111735Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Nov 6 00:14:09.752280 containerd[1515]: time="2025-11-06T00:14:09.752184471Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Nov 6 00:14:09.752280 containerd[1515]: time="2025-11-06T00:14:09.752206613Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Nov 6 00:14:09.752280 containerd[1515]: time="2025-11-06T00:14:09.752222452Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Nov 6 00:14:09.752280 containerd[1515]: time="2025-11-06T00:14:09.752238963Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Nov 6 00:14:09.752280 containerd[1515]: time="2025-11-06T00:14:09.752251867Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Nov 6 00:14:09.752280 containerd[1515]: time="2025-11-06T00:14:09.752275582Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Nov 6 00:14:09.752485 containerd[1515]: time="2025-11-06T00:14:09.752293876Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Nov 6 00:14:09.752485 containerd[1515]: time="2025-11-06T00:14:09.752311119Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Nov 6 00:14:09.752485 containerd[1515]: time="2025-11-06T00:14:09.752324684Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Nov 6 00:14:09.752485 containerd[1515]: time="2025-11-06T00:14:09.752337408Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Nov 6 00:14:09.752485 containerd[1515]: time="2025-11-06T00:14:09.752358347Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Nov 6 00:14:09.752618 containerd[1515]: time="2025-11-06T00:14:09.752577318Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Nov 6 00:14:09.752618 containerd[1515]: time="2025-11-06T00:14:09.752605821Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Nov 6 00:14:09.752673 containerd[1515]: time="2025-11-06T00:14:09.752625909Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Nov 6 00:14:09.752673 containerd[1515]: time="2025-11-06T00:14:09.752653020Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Nov 6 00:14:09.752673 containerd[1515]: time="2025-11-06T00:14:09.752669681Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Nov 6 00:14:09.752759 containerd[1515]: time="2025-11-06T00:14:09.752683517Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Nov 6 00:14:09.752759 containerd[1515]: time="2025-11-06T00:14:09.752698715Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Nov 6 00:14:09.752759 containerd[1515]: time="2025-11-06T00:14:09.752712151Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Nov 6 00:14:09.752759 containerd[1515]: time="2025-11-06T00:14:09.752726578Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Nov 6 00:14:09.752759 containerd[1515]: time="2025-11-06T00:14:09.752740744Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Nov 6 00:14:09.752759 containerd[1515]: time="2025-11-06T00:14:09.752754991Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Nov 6 00:14:09.752948 containerd[1515]: time="2025-11-06T00:14:09.752834650Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Nov 6 00:14:09.752948 containerd[1515]: time="2025-11-06T00:14:09.752853947Z" level=info msg="Start snapshots syncer" Nov 6 00:14:09.752948 containerd[1515]: time="2025-11-06T00:14:09.752905533Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Nov 6 00:14:09.753263 containerd[1515]: time="2025-11-06T00:14:09.753195918Z" 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\"}" Nov 6 00:14:09.753429 containerd[1515]: time="2025-11-06T00:14:09.753266771Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Nov 6 00:14:09.753429 containerd[1515]: time="2025-11-06T00:14:09.753388669Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Nov 6 00:14:09.753556 containerd[1515]: time="2025-11-06T00:14:09.753525766Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Nov 6 00:14:09.753597 containerd[1515]: time="2025-11-06T00:14:09.753556654Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Nov 6 00:14:09.753597 containerd[1515]: time="2025-11-06T00:14:09.753570991Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Nov 6 00:14:09.753597 containerd[1515]: time="2025-11-06T00:14:09.753585549Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Nov 6 00:14:09.753677 containerd[1515]: time="2025-11-06T00:14:09.753602851Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Nov 6 00:14:09.753677 containerd[1515]: time="2025-11-06T00:14:09.753617539Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Nov 6 00:14:09.753677 containerd[1515]: time="2025-11-06T00:14:09.753630783Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Nov 6 00:14:09.753677 containerd[1515]: time="2025-11-06T00:14:09.753657654Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Nov 6 00:14:09.753677 containerd[1515]: time="2025-11-06T00:14:09.753672011Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Nov 6 00:14:09.753817 containerd[1515]: time="2025-11-06T00:14:09.753685797Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Nov 6 00:14:09.753817 containerd[1515]: time="2025-11-06T00:14:09.753733316Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Nov 6 00:14:09.753817 containerd[1515]: time="2025-11-06T00:14:09.753752512Z" 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 Nov 6 00:14:09.753817 containerd[1515]: time="2025-11-06T00:14:09.753766548Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 6 00:14:09.753817 containerd[1515]: time="2025-11-06T00:14:09.753779312Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 6 00:14:09.753817 containerd[1515]: time="2025-11-06T00:14:09.753789892Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Nov 6 00:14:09.753817 containerd[1515]: time="2025-11-06T00:14:09.753802676Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Nov 6 00:14:09.753817 containerd[1515]: time="2025-11-06T00:14:09.753815620Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Nov 6 00:14:09.754037 containerd[1515]: time="2025-11-06T00:14:09.753838052Z" level=info msg="runtime interface created" Nov 6 00:14:09.754037 containerd[1515]: time="2025-11-06T00:14:09.753846077Z" level=info msg="created NRI interface" Nov 6 00:14:09.754037 containerd[1515]: time="2025-11-06T00:14:09.753856667Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Nov 6 00:14:09.754037 containerd[1515]: time="2025-11-06T00:14:09.753882826Z" level=info msg="Connect containerd service" Nov 6 00:14:09.754037 containerd[1515]: time="2025-11-06T00:14:09.753911239Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 6 00:14:09.754802 containerd[1515]: time="2025-11-06T00:14:09.754755783Z" 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" Nov 6 00:14:09.826429 containerd[1515]: time="2025-11-06T00:14:09.826358754Z" level=info msg="Start subscribing containerd event" Nov 6 00:14:09.826556 containerd[1515]: time="2025-11-06T00:14:09.826439886Z" level=info msg="Start recovering state" Nov 6 00:14:09.826556 containerd[1515]: time="2025-11-06T00:14:09.826548741Z" level=info msg="Start event monitor" Nov 6 00:14:09.826595 containerd[1515]: time="2025-11-06T00:14:09.826574258Z" level=info msg="Start cni network conf syncer for default" Nov 6 00:14:09.826595 containerd[1515]: time="2025-11-06T00:14:09.826585159Z" level=info msg="Start streaming server" Nov 6 00:14:09.826644 containerd[1515]: time="2025-11-06T00:14:09.826595649Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Nov 6 00:14:09.826644 containerd[1515]: time="2025-11-06T00:14:09.826604766Z" level=info msg="runtime interface starting up..." Nov 6 00:14:09.826644 containerd[1515]: time="2025-11-06T00:14:09.826619273Z" level=info msg="starting plugins..." Nov 6 00:14:09.826644 containerd[1515]: time="2025-11-06T00:14:09.826629211Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 6 00:14:09.826740 containerd[1515]: time="2025-11-06T00:14:09.826640893Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Nov 6 00:14:09.826740 containerd[1515]: time="2025-11-06T00:14:09.826708039Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 6 00:14:09.826851 containerd[1515]: time="2025-11-06T00:14:09.826829437Z" level=info msg="containerd successfully booted in 0.455659s" Nov 6 00:14:09.826948 systemd[1]: Started containerd.service - containerd container runtime. Nov 6 00:14:10.671561 systemd-networkd[1419]: eth0: Gained IPv6LL Nov 6 00:14:10.674565 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Nov 6 00:14:10.677217 systemd[1]: Reached target network-online.target - Network is Online. Nov 6 00:14:10.680470 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Nov 6 00:14:10.683183 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Nov 6 00:14:10.732616 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Nov 6 00:14:10.767923 systemd[1]: coreos-metadata.service: Deactivated successfully. Nov 6 00:14:10.768198 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Nov 6 00:14:10.771858 systemd[1]: Starting etcd-member.service - etcd (System Application Container)... Nov 6 00:14:10.774193 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Nov 6 00:14:10.777274 (-wrapper)[1579]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS Nov 6 00:14:11.016606 systemd[1]: Starting docker.service - Docker Application Container Engine... Nov 6 00:14:11.038780 (dockerd)[1591]: 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 Nov 6 00:14:11.716643 dockerd[1591]: time="2025-11-06T00:14:11.716543375Z" level=info msg="Starting up" Nov 6 00:14:11.717421 dockerd[1591]: time="2025-11-06T00:14:11.717401655Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Nov 6 00:14:11.746479 dockerd[1591]: time="2025-11-06T00:14:11.746427058Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Nov 6 00:14:12.412605 dockerd[1591]: time="2025-11-06T00:14:12.412540578Z" level=info msg="Loading containers: start." Nov 6 00:14:12.458410 kernel: Initializing XFRM netlink socket Nov 6 00:14:12.859401 systemd-networkd[1419]: docker0: Link UP Nov 6 00:14:12.868329 dockerd[1591]: time="2025-11-06T00:14:12.868236791Z" level=info msg="Loading containers: done." Nov 6 00:14:12.883841 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck2693339763-merged.mount: Deactivated successfully. Nov 6 00:14:12.885500 dockerd[1591]: time="2025-11-06T00:14:12.885447618Z" 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 Nov 6 00:14:12.885589 dockerd[1591]: time="2025-11-06T00:14:12.885561902Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Nov 6 00:14:12.885694 dockerd[1591]: time="2025-11-06T00:14:12.885677820Z" level=info msg="Initializing buildkit" Nov 6 00:14:12.924749 dockerd[1591]: time="2025-11-06T00:14:12.924666998Z" level=info msg="Completed buildkit initialization" Nov 6 00:14:12.933814 dockerd[1591]: time="2025-11-06T00:14:12.933727189Z" level=info msg="Daemon has completed initialization" Nov 6 00:14:12.933967 dockerd[1591]: time="2025-11-06T00:14:12.933878002Z" level=info msg="API listen on /run/docker.sock" Nov 6 00:14:12.934151 systemd[1]: Started docker.service - Docker Application Container Engine. Nov 6 00:14:12.941530 etcd-wrapper[1583]: Error response from daemon: No such container: etcd-member Nov 6 00:14:12.969251 etcd-wrapper[1793]: Error response from daemon: No such container: etcd-member Nov 6 00:14:13.043048 etcd-wrapper[1814]: Unable to find image 'quay.io/coreos/etcd:v3.5.22' locally Nov 6 00:14:13.960468 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Nov 6 00:14:13.964013 systemd[1]: Started sshd@0-10.0.0.36:22-10.0.0.1:40298.service - OpenSSH per-connection server daemon (10.0.0.1:40298). Nov 6 00:14:14.071427 sshd[1828]: Accepted publickey for core from 10.0.0.1 port 40298 ssh2: RSA SHA256:PmSYF5WO1c+PbjRA1Pm6yQw5/JNmNUR55sY7don0Q4E Nov 6 00:14:14.073632 sshd-session[1828]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:14:14.075071 etcd-wrapper[1814]: v3.5.22: Pulling from coreos/etcd Nov 6 00:14:14.082816 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Nov 6 00:14:14.085819 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Nov 6 00:14:14.094354 systemd-logind[1439]: New session 1 of user core. Nov 6 00:14:14.116771 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Nov 6 00:14:14.123257 systemd[1]: Starting user@500.service - User Manager for UID 500... Nov 6 00:14:14.148319 (systemd)[1833]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 6 00:14:14.151476 systemd-logind[1439]: New session c1 of user core. Nov 6 00:14:14.312265 systemd[1833]: Queued start job for default target default.target. Nov 6 00:14:14.322857 systemd[1833]: Created slice app.slice - User Application Slice. Nov 6 00:14:14.322885 systemd[1833]: Reached target paths.target - Paths. Nov 6 00:14:14.323031 systemd[1833]: Reached target timers.target - Timers. Nov 6 00:14:14.326544 systemd[1833]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 6 00:14:14.327545 etcd-wrapper[1814]: f5083dc2076d: Pulling fs layer Nov 6 00:14:14.327545 etcd-wrapper[1814]: 2ae710cd8bfe: Pulling fs layer Nov 6 00:14:14.327545 etcd-wrapper[1814]: d462aa345367: Pulling fs layer Nov 6 00:14:14.327545 etcd-wrapper[1814]: 0f8b424aa0b9: Pulling fs layer Nov 6 00:14:14.327545 etcd-wrapper[1814]: d557676654e5: Pulling fs layer Nov 6 00:14:14.327545 etcd-wrapper[1814]: c8022d07192e: Pulling fs layer Nov 6 00:14:14.327545 etcd-wrapper[1814]: d858cbc252ad: Pulling fs layer Nov 6 00:14:14.327545 etcd-wrapper[1814]: 1069fc2daed1: Pulling fs layer Nov 6 00:14:14.327545 etcd-wrapper[1814]: b40161cd83fc: Pulling fs layer Nov 6 00:14:14.327545 etcd-wrapper[1814]: 5318d93a3a65: Pulling fs layer Nov 6 00:14:14.327545 etcd-wrapper[1814]: 307c1adadb60: Pulling fs layer Nov 6 00:14:14.327545 etcd-wrapper[1814]: 60a82e1b19c6: Pulling fs layer Nov 6 00:14:14.327545 etcd-wrapper[1814]: 6f77055ebb6e: Pulling fs layer Nov 6 00:14:14.327545 etcd-wrapper[1814]: 67f98bbc9874: Pulling fs layer Nov 6 00:14:14.330406 etcd-wrapper[1814]: 1069fc2daed1: Waiting Nov 6 00:14:14.330761 etcd-wrapper[1814]: 307c1adadb60: Waiting Nov 6 00:14:14.330825 etcd-wrapper[1814]: 60a82e1b19c6: Waiting Nov 6 00:14:14.331028 etcd-wrapper[1814]: 6f77055ebb6e: Waiting Nov 6 00:14:14.337762 etcd-wrapper[1814]: b40161cd83fc: Waiting Nov 6 00:14:14.337762 etcd-wrapper[1814]: 5318d93a3a65: Waiting Nov 6 00:14:14.337762 etcd-wrapper[1814]: c8022d07192e: Waiting Nov 6 00:14:14.337762 etcd-wrapper[1814]: 67f98bbc9874: Waiting Nov 6 00:14:14.337762 etcd-wrapper[1814]: d557676654e5: Waiting Nov 6 00:14:14.337762 etcd-wrapper[1814]: d858cbc252ad: Waiting Nov 6 00:14:14.337762 etcd-wrapper[1814]: 0f8b424aa0b9: Waiting Nov 6 00:14:14.339178 systemd[1833]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 6 00:14:14.339317 systemd[1833]: Reached target sockets.target - Sockets. Nov 6 00:14:14.339358 systemd[1833]: Reached target basic.target - Basic System. Nov 6 00:14:14.339428 systemd[1833]: Reached target default.target - Main User Target. Nov 6 00:14:14.339465 systemd[1833]: Startup finished in 178ms. Nov 6 00:14:14.339596 systemd[1]: Started user@500.service - User Manager for UID 500. Nov 6 00:14:14.353632 systemd[1]: Started session-1.scope - Session 1 of User core. Nov 6 00:14:14.420944 systemd[1]: Started sshd@1-10.0.0.36:22-10.0.0.1:40310.service - OpenSSH per-connection server daemon (10.0.0.1:40310). Nov 6 00:14:14.475707 sshd[1849]: Accepted publickey for core from 10.0.0.1 port 40310 ssh2: RSA SHA256:PmSYF5WO1c+PbjRA1Pm6yQw5/JNmNUR55sY7don0Q4E Nov 6 00:14:14.477637 sshd-session[1849]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:14:14.483185 systemd-logind[1439]: New session 2 of user core. Nov 6 00:14:14.492666 systemd[1]: Started session-2.scope - Session 2 of User core. Nov 6 00:14:14.548021 sshd[1852]: Connection closed by 10.0.0.1 port 40310 Nov 6 00:14:14.548407 sshd-session[1849]: pam_unix(sshd:session): session closed for user core Nov 6 00:14:14.558619 systemd[1]: sshd@1-10.0.0.36:22-10.0.0.1:40310.service: Deactivated successfully. Nov 6 00:14:14.560630 systemd[1]: session-2.scope: Deactivated successfully. Nov 6 00:14:14.561711 systemd-logind[1439]: Session 2 logged out. Waiting for processes to exit. Nov 6 00:14:14.565129 systemd[1]: Started sshd@2-10.0.0.36:22-10.0.0.1:40324.service - OpenSSH per-connection server daemon (10.0.0.1:40324). Nov 6 00:14:14.566354 systemd-logind[1439]: Removed session 2. Nov 6 00:14:14.574890 etcd-wrapper[1814]: d462aa345367: Download complete Nov 6 00:14:14.583009 etcd-wrapper[1814]: 2ae710cd8bfe: Verifying Checksum Nov 6 00:14:14.583009 etcd-wrapper[1814]: 2ae710cd8bfe: Download complete Nov 6 00:14:14.589387 etcd-wrapper[1814]: f5083dc2076d: Verifying Checksum Nov 6 00:14:14.589387 etcd-wrapper[1814]: f5083dc2076d: Download complete Nov 6 00:14:14.625161 sshd[1858]: Accepted publickey for core from 10.0.0.1 port 40324 ssh2: RSA SHA256:PmSYF5WO1c+PbjRA1Pm6yQw5/JNmNUR55sY7don0Q4E Nov 6 00:14:14.627262 sshd-session[1858]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:14:14.628095 etcd-wrapper[1814]: f5083dc2076d: Pull complete Nov 6 00:14:14.632718 systemd-logind[1439]: New session 3 of user core. Nov 6 00:14:14.640510 systemd[1]: Started session-3.scope - Session 3 of User core. Nov 6 00:14:14.654065 systemd[1]: var-lib-docker-overlay2-9d80c4fd8a7646823dbc4d545e967fc2d6e8482b9fecbd04fe039d32ef8fa03a-merged.mount: Deactivated successfully. Nov 6 00:14:14.680219 etcd-wrapper[1814]: 2ae710cd8bfe: Pull complete Nov 6 00:14:14.699159 sshd[1870]: Connection closed by 10.0.0.1 port 40324 Nov 6 00:14:14.699619 sshd-session[1858]: pam_unix(sshd:session): session closed for user core Nov 6 00:14:14.705589 systemd-logind[1439]: Session 3 logged out. Waiting for processes to exit. Nov 6 00:14:14.706232 systemd[1]: sshd@2-10.0.0.36:22-10.0.0.1:40324.service: Deactivated successfully. Nov 6 00:14:14.709420 systemd[1]: session-3.scope: Deactivated successfully. Nov 6 00:14:14.711480 systemd-logind[1439]: Removed session 3. Nov 6 00:14:14.827083 etcd-wrapper[1814]: d557676654e5: Verifying Checksum Nov 6 00:14:14.827083 etcd-wrapper[1814]: d557676654e5: Download complete Nov 6 00:14:14.830446 etcd-wrapper[1814]: c8022d07192e: Verifying Checksum Nov 6 00:14:14.830446 etcd-wrapper[1814]: c8022d07192e: Download complete Nov 6 00:14:14.836176 etcd-wrapper[1814]: 0f8b424aa0b9: Verifying Checksum Nov 6 00:14:14.836176 etcd-wrapper[1814]: 0f8b424aa0b9: Download complete Nov 6 00:14:15.044489 etcd-wrapper[1814]: d858cbc252ad: Verifying Checksum Nov 6 00:14:15.044489 etcd-wrapper[1814]: d858cbc252ad: Download complete Nov 6 00:14:15.071508 etcd-wrapper[1814]: 1069fc2daed1: Verifying Checksum Nov 6 00:14:15.071508 etcd-wrapper[1814]: 1069fc2daed1: Download complete Nov 6 00:14:15.072169 etcd-wrapper[1814]: b40161cd83fc: Verifying Checksum Nov 6 00:14:15.072169 etcd-wrapper[1814]: b40161cd83fc: Download complete Nov 6 00:14:15.164395 systemd[1]: var-lib-docker-overlay2-8193c82cc40df7eff406d70a2c30ca86b0b68acc426bde06db651e89fba8d02e-merged.mount: Deactivated successfully. Nov 6 00:14:15.240267 etcd-wrapper[1814]: d462aa345367: Pull complete Nov 6 00:14:15.261317 systemd[1]: var-lib-docker-overlay2-9ab34fd071583c5f0300cc2a730dc95a3ce0d5c541225e0cfbe63c4135c6fb2a-merged.mount: Deactivated successfully. Nov 6 00:14:15.273478 etcd-wrapper[1814]: 0f8b424aa0b9: Pull complete Nov 6 00:14:15.292089 etcd-wrapper[1814]: 5318d93a3a65: Verifying Checksum Nov 6 00:14:15.292089 etcd-wrapper[1814]: 5318d93a3a65: Download complete Nov 6 00:14:15.299853 etcd-wrapper[1814]: 307c1adadb60: Verifying Checksum Nov 6 00:14:15.299853 etcd-wrapper[1814]: 307c1adadb60: Download complete Nov 6 00:14:15.308288 etcd-wrapper[1814]: d557676654e5: Pull complete Nov 6 00:14:15.350171 etcd-wrapper[1814]: c8022d07192e: Pull complete Nov 6 00:14:15.387197 etcd-wrapper[1814]: 60a82e1b19c6: Verifying Checksum Nov 6 00:14:15.387197 etcd-wrapper[1814]: 60a82e1b19c6: Download complete Nov 6 00:14:15.415202 etcd-wrapper[1814]: d858cbc252ad: Pull complete Nov 6 00:14:15.521947 etcd-wrapper[1814]: 1069fc2daed1: Pull complete Nov 6 00:14:15.672469 etcd-wrapper[1814]: 67f98bbc9874: Verifying Checksum Nov 6 00:14:15.672469 etcd-wrapper[1814]: 67f98bbc9874: Download complete Nov 6 00:14:15.683065 etcd-wrapper[1814]: 6f77055ebb6e: Verifying Checksum Nov 6 00:14:15.683065 etcd-wrapper[1814]: 6f77055ebb6e: Download complete Nov 6 00:14:16.115248 systemd[1]: var-lib-docker-overlay2-d47efc45a432778f4de257f7c8b1f0d95465d08d8e4261dbda423224cb461dcb-merged.mount: Deactivated successfully. Nov 6 00:14:17.309247 etcd-wrapper[1814]: b40161cd83fc: Pull complete Nov 6 00:14:17.561268 systemd[1]: var-lib-docker-overlay2-4efbf30473df24f7c9dd58c6a69614d343421d5fce323ea66930086ce5538d2f-merged.mount: Deactivated successfully. Nov 6 00:14:17.577086 etcd-wrapper[1814]: 5318d93a3a65: Pull complete Nov 6 00:14:17.663300 systemd[1]: var-lib-docker-overlay2-5c851b8488738de700f05c83d43b8f6bf0f5f5dcfcbc760541ae34ae1411f962-merged.mount: Deactivated successfully. Nov 6 00:14:17.689075 etcd-wrapper[1814]: 307c1adadb60: Pull complete Nov 6 00:14:18.039407 etcd-wrapper[1814]: 60a82e1b19c6: Pull complete Nov 6 00:14:18.285725 etcd-wrapper[1814]: 6f77055ebb6e: Pull complete Nov 6 00:14:18.557518 systemd[1]: var-lib-docker-overlay2-d8560d3d701aa704d75ea86d39613f1ee712b761807c04647f934bb97af74aff-merged.mount: Deactivated successfully. Nov 6 00:14:19.037234 etcd-wrapper[1814]: 67f98bbc9874: Pull complete Nov 6 00:14:19.350188 etcd-wrapper[1814]: Digest: sha256:51efcfc4f3a863c072ebe745942f790878c2a268e713a6d140c4f2593271adc6 Nov 6 00:14:19.354068 etcd-wrapper[1814]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.22 Nov 6 00:14:19.368822 systemd[1]: var-lib-docker-overlay2-033e6394c6dedf8c191eef01bccf9df2b4aa59bcbb457be5875a6c6000748247\x2dinit-merged.mount: Deactivated successfully. Nov 6 00:14:19.385283 systemd[1]: var-lib-docker-overlay2-033e6394c6dedf8c191eef01bccf9df2b4aa59bcbb457be5875a6c6000748247-merged.mount: Deactivated successfully. Nov 6 00:14:19.427604 containerd[1515]: time="2025-11-06T00:14:19.427532783Z" level=info msg="connecting to shim 1c575992422c84abbdae99746eb3f0d804f6af262fdb5c7b13abf758b34cace0" address="unix:///run/containerd/s/2434139bf02abfedb621d22a4fa6d106e5dd2fdc3c6ae29924e2ddcb142c61bf" namespace=moby protocol=ttrpc version=3 Nov 6 00:14:19.595604 systemd[1]: Started docker-1c575992422c84abbdae99746eb3f0d804f6af262fdb5c7b13abf758b34cace0.scope - libcontainer container 1c575992422c84abbdae99746eb3f0d804f6af262fdb5c7b13abf758b34cace0. Nov 6 00:14:19.616665 systemd-resolved[1331]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Nov 6 00:14:19.698197 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.697604Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} Nov 6 00:14:19.698197 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.697915Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"cdb7f87e02464378a26108b27094489a"} Nov 6 00:14:19.698197 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:14:19.697944Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} Nov 6 00:14:19.698197 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:14:19.697956Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.22"} Nov 6 00:14:19.698197 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:14:19.697961Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} Nov 6 00:14:19.698197 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:14:19.697965Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} Nov 6 00:14:19.698197 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:14:19.697995Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Nov 6 00:14:19.698197 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.698035Z","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.36:2379"]} Nov 6 00:14:19.698197 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:14:19.698114Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Nov 6 00:14:19.698554 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.698129Z","caller":"embed/etcd.go:140","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]} Nov 6 00:14:19.699825 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.699640Z","caller":"embed/etcd.go:148","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} Nov 6 00:14:19.699953 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.699813Z","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":"cdb7f87e02464378a26108b27094489a","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.36:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"cdb7f87e02464378a26108b27094489a=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"} Nov 6 00:14:19.702973 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.702741Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.566003ms"} Nov 6 00:14:19.706517 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.706344Z","caller":"etcdserver/raft.go:507","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"} Nov 6 00:14:19.706697 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.706434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"} Nov 6 00:14:19.706697 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.706490Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"} Nov 6 00:14:19.706697 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.706501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} Nov 6 00:14:19.706697 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.706518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"} Nov 6 00:14:19.706697 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.706558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Nov 6 00:14:19.711232 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:14:19.711024Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} Nov 6 00:14:19.713047 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.712809Z","caller":"mvcc/kvstore.go:425","msg":"kvstore restored","current-rev":1} Nov 6 00:14:19.713394 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.712851Z","caller":"etcdserver/server.go:628","msg":"restore consistentIndex","index":0} Nov 6 00:14:19.714323 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.714116Z","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"} Nov 6 00:14:19.715152 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.714994Z","caller":"etcdserver/server.go:875","msg":"starting etcd server","local-member-id":"8e9e05c52164694d","local-server-version":"3.5.22","cluster-version":"to_be_decided"} Nov 6 00:14:19.715414 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.715164Z","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"} Nov 6 00:14:19.715684 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.715272Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Nov 6 00:14:19.715684 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.715350Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} Nov 6 00:14:19.715684 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.715428Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} Nov 6 00:14:19.715684 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.715437Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} Nov 6 00:14:19.716180 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.716027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Nov 6 00:14:19.716300 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.716110Z","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} Nov 6 00:14:19.717195 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.717002Z","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.36:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} Nov 6 00:14:19.717195 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.716950Z","caller":"embed/etcd.go:633","msg":"serving peer traffic","address":"127.0.0.1:2380"} Nov 6 00:14:19.717195 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.717037Z","caller":"embed/etcd.go:603","msg":"cmux::serve","address":"127.0.0.1:2380"} Nov 6 00:14:19.807730 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.807349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"} Nov 6 00:14:19.807730 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.807418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"} Nov 6 00:14:19.807730 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.807434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"} Nov 6 00:14:19.807730 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.807464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"} Nov 6 00:14:19.807730 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.807500Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"} Nov 6 00:14:19.807730 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.807509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"} Nov 6 00:14:19.807730 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.807518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"} Nov 6 00:14:19.834517 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.833921Z","caller":"etcdserver/server.go:2144","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:cdb7f87e02464378a26108b27094489a ClientURLs:[http://10.0.0.36:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"} Nov 6 00:14:19.834517 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.833973Z","caller":"etcdserver/server.go:2697","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} Nov 6 00:14:19.834517 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.833987Z","caller":"embed/serve.go:124","msg":"ready to serve client requests"} Nov 6 00:14:19.834517 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.834274Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} Nov 6 00:14:19.834901 systemd[1]: Started etcd-member.service - etcd (System Application Container). Nov 6 00:14:19.835409 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.834441Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} Nov 6 00:14:19.835409 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.834836Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Nov 6 00:14:19.835631 systemd[1]: Reached target multi-user.target - Multi-User System. Nov 6 00:14:19.835854 systemd[1]: Startup finished in 3.336s (kernel) + 5.547s (initrd) + 14.692s (userspace) = 23.576s. Nov 6 00:14:19.836138 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.835324Z","caller":"embed/serve.go:210","msg":"serving client traffic insecurely; this is strongly discouraged!","traffic":"grpc+http","address":"[::]:2379"} Nov 6 00:14:19.836138 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.835959Z","caller":"membership/cluster.go:587","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"} Nov 6 00:14:19.836547 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.836419Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} Nov 6 00:14:19.836671 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:14:19.836511Z","caller":"etcdserver/server.go:2721","msg":"cluster version is updated","cluster-version":"3.5"} Nov 6 00:14:24.724620 systemd[1]: Started sshd@3-10.0.0.36:22-10.0.0.1:40872.service - OpenSSH per-connection server daemon (10.0.0.1:40872). Nov 6 00:14:24.773590 sshd[1993]: Accepted publickey for core from 10.0.0.1 port 40872 ssh2: RSA SHA256:PmSYF5WO1c+PbjRA1Pm6yQw5/JNmNUR55sY7don0Q4E Nov 6 00:14:24.775461 sshd-session[1993]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:14:24.780193 systemd-logind[1439]: New session 4 of user core. Nov 6 00:14:24.796534 systemd[1]: Started session-4.scope - Session 4 of User core. Nov 6 00:14:24.850678 sshd[1996]: Connection closed by 10.0.0.1 port 40872 Nov 6 00:14:24.851091 sshd-session[1993]: pam_unix(sshd:session): session closed for user core Nov 6 00:14:24.860193 systemd[1]: sshd@3-10.0.0.36:22-10.0.0.1:40872.service: Deactivated successfully. Nov 6 00:14:24.862066 systemd[1]: session-4.scope: Deactivated successfully. Nov 6 00:14:24.862772 systemd-logind[1439]: Session 4 logged out. Waiting for processes to exit. Nov 6 00:14:24.865359 systemd[1]: Started sshd@4-10.0.0.36:22-10.0.0.1:40882.service - OpenSSH per-connection server daemon (10.0.0.1:40882). Nov 6 00:14:24.866089 systemd-logind[1439]: Removed session 4. Nov 6 00:14:24.930093 sshd[2002]: Accepted publickey for core from 10.0.0.1 port 40882 ssh2: RSA SHA256:PmSYF5WO1c+PbjRA1Pm6yQw5/JNmNUR55sY7don0Q4E Nov 6 00:14:24.931738 sshd-session[2002]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:14:24.936827 systemd-logind[1439]: New session 5 of user core. Nov 6 00:14:24.954546 systemd[1]: Started session-5.scope - Session 5 of User core. Nov 6 00:14:25.004646 sshd[2005]: Connection closed by 10.0.0.1 port 40882 Nov 6 00:14:25.004845 sshd-session[2002]: pam_unix(sshd:session): session closed for user core Nov 6 00:14:25.018953 systemd[1]: sshd@4-10.0.0.36:22-10.0.0.1:40882.service: Deactivated successfully. Nov 6 00:14:25.020800 systemd[1]: session-5.scope: Deactivated successfully. Nov 6 00:14:25.021616 systemd-logind[1439]: Session 5 logged out. Waiting for processes to exit. Nov 6 00:14:25.025331 systemd[1]: Started sshd@5-10.0.0.36:22-10.0.0.1:40892.service - OpenSSH per-connection server daemon (10.0.0.1:40892). Nov 6 00:14:25.026263 systemd-logind[1439]: Removed session 5. Nov 6 00:14:25.091459 sshd[2011]: Accepted publickey for core from 10.0.0.1 port 40892 ssh2: RSA SHA256:PmSYF5WO1c+PbjRA1Pm6yQw5/JNmNUR55sY7don0Q4E Nov 6 00:14:25.093358 sshd-session[2011]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:14:25.098256 systemd-logind[1439]: New session 6 of user core. Nov 6 00:14:25.109573 systemd[1]: Started session-6.scope - Session 6 of User core. Nov 6 00:14:25.165151 sshd[2014]: Connection closed by 10.0.0.1 port 40892 Nov 6 00:14:25.165701 sshd-session[2011]: pam_unix(sshd:session): session closed for user core Nov 6 00:14:25.177678 systemd[1]: sshd@5-10.0.0.36:22-10.0.0.1:40892.service: Deactivated successfully. Nov 6 00:14:25.180278 systemd[1]: session-6.scope: Deactivated successfully. Nov 6 00:14:25.181171 systemd-logind[1439]: Session 6 logged out. Waiting for processes to exit. Nov 6 00:14:25.185286 systemd[1]: Started sshd@6-10.0.0.36:22-10.0.0.1:40898.service - OpenSSH per-connection server daemon (10.0.0.1:40898). Nov 6 00:14:25.186285 systemd-logind[1439]: Removed session 6. Nov 6 00:14:25.257595 sshd[2020]: Accepted publickey for core from 10.0.0.1 port 40898 ssh2: RSA SHA256:PmSYF5WO1c+PbjRA1Pm6yQw5/JNmNUR55sY7don0Q4E Nov 6 00:14:25.260015 sshd-session[2020]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:14:25.265016 systemd-logind[1439]: New session 7 of user core. Nov 6 00:14:25.274614 systemd[1]: Started session-7.scope - Session 7 of User core. Nov 6 00:14:25.337855 sudo[2024]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Nov 6 00:14:25.338244 sudo[2024]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 6 00:14:25.360765 sudo[2024]: pam_unix(sudo:session): session closed for user root Nov 6 00:14:25.363145 sshd[2023]: Connection closed by 10.0.0.1 port 40898 Nov 6 00:14:25.363729 sshd-session[2020]: pam_unix(sshd:session): session closed for user core Nov 6 00:14:25.380643 systemd[1]: sshd@6-10.0.0.36:22-10.0.0.1:40898.service: Deactivated successfully. Nov 6 00:14:25.382578 systemd[1]: session-7.scope: Deactivated successfully. Nov 6 00:14:25.383342 systemd-logind[1439]: Session 7 logged out. Waiting for processes to exit. Nov 6 00:14:25.386258 systemd[1]: Started sshd@7-10.0.0.36:22-10.0.0.1:40902.service - OpenSSH per-connection server daemon (10.0.0.1:40902). Nov 6 00:14:25.387104 systemd-logind[1439]: Removed session 7. Nov 6 00:14:25.456115 sshd[2030]: Accepted publickey for core from 10.0.0.1 port 40902 ssh2: RSA SHA256:PmSYF5WO1c+PbjRA1Pm6yQw5/JNmNUR55sY7don0Q4E Nov 6 00:14:25.458013 sshd-session[2030]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:14:25.463301 systemd-logind[1439]: New session 8 of user core. Nov 6 00:14:25.472517 systemd[1]: Started session-8.scope - Session 8 of User core. Nov 6 00:14:25.529151 sudo[2035]: 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 Nov 6 00:14:25.529492 sudo[2035]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 6 00:14:25.536252 sudo[2035]: pam_unix(sudo:session): session closed for user root Nov 6 00:14:25.543296 sudo[2034]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Nov 6 00:14:25.543648 sudo[2034]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 6 00:14:25.554611 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 6 00:14:25.613266 augenrules[2057]: No rules Nov 6 00:14:25.615164 systemd[1]: audit-rules.service: Deactivated successfully. Nov 6 00:14:25.615491 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 6 00:14:25.616639 sudo[2034]: pam_unix(sudo:session): session closed for user root Nov 6 00:14:25.618398 sshd[2033]: Connection closed by 10.0.0.1 port 40902 Nov 6 00:14:25.618757 sshd-session[2030]: pam_unix(sshd:session): session closed for user core Nov 6 00:14:25.638545 systemd[1]: sshd@7-10.0.0.36:22-10.0.0.1:40902.service: Deactivated successfully. Nov 6 00:14:25.641417 systemd[1]: session-8.scope: Deactivated successfully. Nov 6 00:14:25.642352 systemd-logind[1439]: Session 8 logged out. Waiting for processes to exit. Nov 6 00:14:25.646641 systemd[1]: Started sshd@8-10.0.0.36:22-10.0.0.1:40914.service - OpenSSH per-connection server daemon (10.0.0.1:40914). Nov 6 00:14:25.647302 systemd-logind[1439]: Removed session 8. Nov 6 00:14:25.715598 sshd[2066]: Accepted publickey for core from 10.0.0.1 port 40914 ssh2: RSA SHA256:PmSYF5WO1c+PbjRA1Pm6yQw5/JNmNUR55sY7don0Q4E Nov 6 00:14:25.717396 sshd-session[2066]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:14:25.722792 systemd-logind[1439]: New session 9 of user core. Nov 6 00:14:25.733585 systemd[1]: Started session-9.scope - Session 9 of User core. Nov 6 00:14:25.784992 sshd[2069]: Connection closed by 10.0.0.1 port 40914 Nov 6 00:14:25.785169 sshd-session[2066]: pam_unix(sshd:session): session closed for user core Nov 6 00:14:25.803613 systemd[1]: sshd@8-10.0.0.36:22-10.0.0.1:40914.service: Deactivated successfully. Nov 6 00:14:25.805812 systemd[1]: session-9.scope: Deactivated successfully. Nov 6 00:14:25.806696 systemd-logind[1439]: Session 9 logged out. Waiting for processes to exit. Nov 6 00:14:25.809841 systemd[1]: Started sshd@9-10.0.0.36:22-10.0.0.1:40928.service - OpenSSH per-connection server daemon (10.0.0.1:40928). Nov 6 00:14:25.810842 systemd-logind[1439]: Removed session 9. Nov 6 00:14:25.877152 sshd[2077]: Accepted publickey for core from 10.0.0.1 port 40928 ssh2: RSA SHA256:PmSYF5WO1c+PbjRA1Pm6yQw5/JNmNUR55sY7don0Q4E Nov 6 00:14:25.878735 sshd-session[2077]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:14:25.884171 systemd-logind[1439]: New session 10 of user core. Nov 6 00:14:25.899636 systemd[1]: Started session-10.scope - Session 10 of User core. Nov 6 00:14:26.032091 sshd[2080]: Connection closed by 10.0.0.1 port 40928 Nov 6 00:14:26.032540 sshd-session[2077]: pam_unix(sshd:session): session closed for user core Nov 6 00:14:26.038548 systemd[1]: sshd@9-10.0.0.36:22-10.0.0.1:40928.service: Deactivated successfully. Nov 6 00:14:26.041284 systemd[1]: session-10.scope: Deactivated successfully. Nov 6 00:14:26.042316 systemd-logind[1439]: Session 10 logged out. Waiting for processes to exit. Nov 6 00:14:26.044247 systemd-logind[1439]: Removed session 10. Nov 6 00:14:54.284774 update_engine[1440]: I20251106 00:14:54.284647 1440 update_attempter.cc:509] Updating boot flags... Nov 6 00:15:31.785360 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:15:31.784803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.563873ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Nov 6 00:15:31.785360 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:15:31.785041Z","caller":"traceutil/trace.go:171","msg":"trace[962168951] transaction","detail":"{read_only:false; response_revision:186; number_of_response:1; }","duration":"273.898377ms","start":"2025-11-06T00:15:31.511126Z","end":"2025-11-06T00:15:31.785024Z","steps":["trace[962168951] 'compare' (duration: 264.427316ms)"],"step_count":1} Nov 6 00:15:31.985574 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:15:31.985082Z","caller":"traceutil/trace.go:171","msg":"trace[1440346077] transaction","detail":"{read_only:false; response_revision:188; number_of_response:1; }","duration":"147.681511ms","start":"2025-11-06T00:15:31.837360Z","end":"2025-11-06T00:15:31.985042Z","steps":["trace[1440346077] 'process raft request' (duration: 55.925842ms)","trace[1440346077] 'compare' (duration: 91.524565ms)"],"step_count":2} Nov 6 00:15:34.433758 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:15:34.433191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.193281ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Nov 6 00:15:34.434641 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:15:34.433393Z","caller":"traceutil/trace.go:171","msg":"trace[414685214] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"139.147783ms","start":"2025-11-06T00:15:34.294202Z","end":"2025-11-06T00:15:34.433349Z","steps":["trace[414685214] 'process raft request' (duration: 139.096427ms)"],"step_count":1} Nov 6 00:15:34.434641 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:15:34.433357Z","caller":"traceutil/trace.go:171","msg":"trace[985624430] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"139.619489ms","start":"2025-11-06T00:15:34.293704Z","end":"2025-11-06T00:15:34.433323Z","steps":["trace[985624430] 'process raft request' (duration: 38.049821ms)","trace[985624430] 'compare' (duration: 101.072063ms)"],"step_count":2} Nov 6 00:15:34.434641 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:15:34.433446Z","caller":"traceutil/trace.go:171","msg":"trace[346760222] linearizableReadLoop","detail":"{readStateIndex:280; appliedIndex:279; }","duration":"139.500966ms","start":"2025-11-06T00:15:34.293911Z","end":"2025-11-06T00:15:34.433412Z","steps":["trace[346760222] 'read index received' (duration: 37.870735ms)","trace[346760222] 'applied index is now lower than readState.Index' (duration: 101.628177ms)"],"step_count":2} Nov 6 00:15:34.434641 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:15:34.433608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.631852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Nov 6 00:15:34.434641 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:15:34.433646Z","caller":"traceutil/trace.go:171","msg":"trace[1543565038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:274; }","duration":"139.73158ms","start":"2025-11-06T00:15:34.293906Z","end":"2025-11-06T00:15:34.433637Z","steps":["trace[1543565038] 'agreement among raft nodes before linearized reading' (duration: 139.607186ms)"],"step_count":1} Nov 6 00:15:40.541087 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:15:40.540566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.429484ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Nov 6 00:15:40.541087 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:15:40.540658Z","caller":"traceutil/trace.go:171","msg":"trace[1895099923] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"193.485401ms","start":"2025-11-06T00:15:40.347160Z","end":"2025-11-06T00:15:40.540645Z","steps":["trace[1895099923] 'process raft request' (duration: 35.920954ms)","trace[1895099923] 'compare' (duration: 157.256789ms)"],"step_count":2} Nov 6 00:15:40.558495 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:15:40.557927Z","caller":"traceutil/trace.go:171","msg":"trace[1620744095] linearizableReadLoop","detail":"{readStateIndex:405; appliedIndex:404; }","duration":"167.029437ms","start":"2025-11-06T00:15:40.390868Z","end":"2025-11-06T00:15:40.557897Z","steps":["trace[1620744095] 'read index received' (duration: 166.882913ms)","trace[1620744095] 'applied index is now lower than readState.Index' (duration: 145.673µs)"],"step_count":2} Nov 6 00:15:40.558495 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:15:40.558003Z","caller":"traceutil/trace.go:171","msg":"trace[1059708423] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"209.63684ms","start":"2025-11-06T00:15:40.348320Z","end":"2025-11-06T00:15:40.557957Z","steps":["trace[1059708423] 'process raft request' (duration: 209.463174ms)"],"step_count":1} Nov 6 00:15:40.558495 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:15:40.558173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.302721ms","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"} Nov 6 00:15:40.558495 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:15:40.558255Z","caller":"traceutil/trace.go:171","msg":"trace[1329109350] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:396; }","duration":"167.398471ms","start":"2025-11-06T00:15:40.390844Z","end":"2025-11-06T00:15:40.558243Z","steps":["trace[1329109350] 'agreement among raft nodes before linearized reading' (duration: 167.247778ms)"],"step_count":1} Nov 6 00:15:40.592656 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:15:40.591993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.169768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/legacy-service-account-token-cleaner\" limit:1 ","response":"range_response_count:1 size:238"} Nov 6 00:15:40.592656 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:15:40.592135Z","caller":"traceutil/trace.go:171","msg":"trace[1715914566] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"105.912633ms","start":"2025-11-06T00:15:40.486198Z","end":"2025-11-06T00:15:40.592111Z","steps":["trace[1715914566] 'process raft request' (duration: 105.520046ms)"],"step_count":1} Nov 6 00:15:40.592656 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:15:40.592238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.568101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" limit:1 ","response":"range_response_count:1 size:207"} Nov 6 00:15:40.592656 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:15:40.592161Z","caller":"traceutil/trace.go:171","msg":"trace[1422019461] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/legacy-service-account-token-cleaner; range_end:; response_count:1; response_revision:398; }","duration":"151.352019ms","start":"2025-11-06T00:15:40.440790Z","end":"2025-11-06T00:15:40.592142Z","steps":["trace[1422019461] 'agreement among raft nodes before linearized reading' (duration: 151.134712ms)"],"step_count":1} Nov 6 00:15:40.592975 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:15:40.592264Z","caller":"traceutil/trace.go:171","msg":"trace[781491275] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:398; }","duration":"101.59428ms","start":"2025-11-06T00:15:40.490661Z","end":"2025-11-06T00:15:40.592255Z","steps":["trace[781491275] 'agreement among raft nodes before linearized reading' (duration: 101.521634ms)"],"step_count":1} Nov 6 00:15:40.769485 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:15:40.768811Z","caller":"traceutil/trace.go:171","msg":"trace[1419013747] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"171.268963ms","start":"2025-11-06T00:15:40.597524Z","end":"2025-11-06T00:15:40.768793Z","steps":["trace[1419013747] 'process raft request' (duration: 115.591736ms)","trace[1419013747] 'compare' (duration: 55.546522ms)"],"step_count":2} Nov 6 00:15:40.769485 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:15:40.768990Z","caller":"traceutil/trace.go:171","msg":"trace[1996579311] linearizableReadLoop","detail":"{readStateIndex:413; appliedIndex:409; }","duration":"149.205691ms","start":"2025-11-06T00:15:40.619776Z","end":"2025-11-06T00:15:40.768982Z","steps":["trace[1996579311] 'read index received' (duration: 93.417035ms)","trace[1996579311] 'applied index is now lower than readState.Index' (duration: 55.788075ms)"],"step_count":2} Nov 6 00:15:40.769485 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:15:40.769003Z","caller":"traceutil/trace.go:171","msg":"trace[1109068123] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"170.785226ms","start":"2025-11-06T00:15:40.598206Z","end":"2025-11-06T00:15:40.768991Z","steps":["trace[1109068123] 'process raft request' (duration: 170.712349ms)"],"step_count":1} Nov 6 00:15:40.769485 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:15:40.769076Z","caller":"traceutil/trace.go:171","msg":"trace[413083675] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"154.924813ms","start":"2025-11-06T00:15:40.614140Z","end":"2025-11-06T00:15:40.769065Z","steps":["trace[413083675] 'process raft request' (duration: 154.812122ms)"],"step_count":1} Nov 6 00:15:40.769772 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:15:40.769144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.609535ms","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"} Nov 6 00:15:40.769772 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:15:40.769184Z","caller":"traceutil/trace.go:171","msg":"trace[1541000151] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking; range_end:; response_count:1; response_revision:404; }","duration":"170.662926ms","start":"2025-11-06T00:15:40.598511Z","end":"2025-11-06T00:15:40.769174Z","steps":["trace[1541000151] 'agreement among raft nodes before linearized reading' (duration: 170.583367ms)"],"step_count":1} Nov 6 00:15:40.769772 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:15:40.769089Z","caller":"traceutil/trace.go:171","msg":"trace[441460974] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"171.24059ms","start":"2025-11-06T00:15:40.597841Z","end":"2025-11-06T00:15:40.769082Z","steps":["trace[441460974] 'process raft request' (duration: 170.919097ms)"],"step_count":1} Nov 6 00:15:40.780114 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:15:40.779246Z","caller":"traceutil/trace.go:171","msg":"trace[1395288494] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"154.959698ms","start":"2025-11-06T00:15:40.624264Z","end":"2025-11-06T00:15:40.779224Z","steps":["trace[1395288494] 'process raft request' (duration: 154.81147ms)"],"step_count":1} Nov 6 00:15:40.780114 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:15:40.779348Z","caller":"traceutil/trace.go:171","msg":"trace[238821255] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"152.996956ms","start":"2025-11-06T00:15:40.626334Z","end":"2025-11-06T00:15:40.779331Z","steps":["trace[238821255] 'process raft request' (duration: 152.883644ms)"],"step_count":1} Nov 6 00:15:40.780114 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:15:40.779490Z","caller":"traceutil/trace.go:171","msg":"trace[335053969] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"153.201951ms","start":"2025-11-06T00:15:40.626280Z","end":"2025-11-06T00:15:40.779482Z","steps":["trace[335053969] 'process raft request' (duration: 152.886719ms)"],"step_count":1} Nov 6 00:15:40.780114 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:15:40.779488Z","caller":"traceutil/trace.go:171","msg":"trace[745205764] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"153.133723ms","start":"2025-11-06T00:15:40.626344Z","end":"2025-11-06T00:15:40.779477Z","steps":["trace[745205764] 'process raft request' (duration: 152.904392ms)"],"step_count":1} Nov 6 00:15:40.780114 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:15:40.779632Z","caller":"traceutil/trace.go:171","msg":"trace[1010891960] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"151.322695ms","start":"2025-11-06T00:15:40.628303Z","end":"2025-11-06T00:15:40.779626Z","steps":["trace[1010891960] 'process raft request' (duration: 151.130584ms)"],"step_count":1} Nov 6 00:15:53.982085 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:15:53.981686Z","caller":"traceutil/trace.go:171","msg":"trace[1881047241] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"231.600698ms","start":"2025-11-06T00:15:53.750069Z","end":"2025-11-06T00:15:53.981670Z","steps":["trace[1881047241] 'process raft request' (duration: 231.479967ms)"],"step_count":1} Nov 6 00:15:53.990063 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:15:53.989104Z","caller":"traceutil/trace.go:171","msg":"trace[287194749] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"174.087326ms","start":"2025-11-06T00:15:53.814999Z","end":"2025-11-06T00:15:53.989086Z","steps":["trace[287194749] 'process raft request' (duration: 173.993615ms)"],"step_count":1} Nov 6 00:15:58.959536 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:15:58.959037Z","caller":"traceutil/trace.go:171","msg":"trace[1025787758] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"138.840771ms","start":"2025-11-06T00:15:58.820186Z","end":"2025-11-06T00:15:58.959027Z","steps":["trace[1025787758] 'process raft request' (duration: 138.779463ms)"],"step_count":1} Nov 6 00:15:58.959536 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:15:58.959034Z","caller":"traceutil/trace.go:171","msg":"trace[729596262] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"153.489465ms","start":"2025-11-06T00:15:58.805519Z","end":"2025-11-06T00:15:58.959009Z","steps":["trace[729596262] 'process raft request' (duration: 68.58368ms)","trace[729596262] 'compare' (duration: 84.736801ms)"],"step_count":2} Nov 6 00:15:59.856403 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:15:59.855305Z","caller":"traceutil/trace.go:171","msg":"trace[850106283] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"112.134778ms","start":"2025-11-06T00:15:59.743153Z","end":"2025-11-06T00:15:59.855288Z","steps":["trace[850106283] 'process raft request' (duration: 110.07656ms)"],"step_count":1} Nov 6 00:16:00.028583 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:00.028102Z","caller":"traceutil/trace.go:171","msg":"trace[318829448] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:527; }","duration":"107.852838ms","start":"2025-11-06T00:15:59.920223Z","end":"2025-11-06T00:16:00.028075Z","steps":["trace[318829448] 'read index received' (duration: 62.493615ms)","trace[318829448] 'applied index is now lower than readState.Index' (duration: 45.358311ms)"],"step_count":2} Nov 6 00:16:00.029180 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:16:00.028232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.982576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-nnwpn.187542ad4c4ff635\" limit:1 ","response":"range_response_count:1 size:788"} Nov 6 00:16:00.029180 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:00.028263Z","caller":"traceutil/trace.go:171","msg":"trace[1216551017] range","detail":"{range_begin:/registry/events/kube-system/cilium-nnwpn.187542ad4c4ff635; range_end:; response_count:1; response_revision:516; }","duration":"108.03724ms","start":"2025-11-06T00:15:59.920217Z","end":"2025-11-06T00:16:00.028254Z","steps":["trace[1216551017] 'agreement among raft nodes before linearized reading' (duration: 107.956185ms)"],"step_count":1} Nov 6 00:16:00.029180 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:00.028246Z","caller":"traceutil/trace.go:171","msg":"trace[1806293507] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"153.65249ms","start":"2025-11-06T00:15:59.874559Z","end":"2025-11-06T00:16:00.028211Z","steps":["trace[1806293507] 'process raft request' (duration: 153.411449ms)"],"step_count":1} Nov 6 00:16:00.029180 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:00.028348Z","caller":"traceutil/trace.go:171","msg":"trace[176054919] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"127.696456ms","start":"2025-11-06T00:15:59.900629Z","end":"2025-11-06T00:16:00.028326Z","steps":["trace[176054919] 'process raft request' (duration: 127.410499ms)"],"step_count":1} Nov 6 00:16:00.029180 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:00.028443Z","caller":"traceutil/trace.go:171","msg":"trace[67460846] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"164.165345ms","start":"2025-11-06T00:15:59.864266Z","end":"2025-11-06T00:16:00.028431Z","steps":["trace[67460846] 'process raft request' (duration: 118.267212ms)","trace[67460846] 'compare' (duration: 45.308656ms)"],"step_count":2} Nov 6 00:16:00.583757 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:00.583220Z","caller":"traceutil/trace.go:171","msg":"trace[1788626589] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:537; }","duration":"151.002967ms","start":"2025-11-06T00:16:00.432194Z","end":"2025-11-06T00:16:00.583197Z","steps":["trace[1788626589] 'read index received' (duration: 70.428095ms)","trace[1788626589] 'applied index is now lower than readState.Index' (duration: 80.57405ms)"],"step_count":2} Nov 6 00:16:00.583757 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:00.583466Z","caller":"traceutil/trace.go:171","msg":"trace[753331770] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"151.272342ms","start":"2025-11-06T00:16:00.432175Z","end":"2025-11-06T00:16:00.583448Z","steps":["trace[753331770] 'process raft request' (duration: 70.399971ms)","trace[753331770] 'compare' (duration: 80.463809ms)"],"step_count":2} Nov 6 00:16:00.584014 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:16:00.583628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.397171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" limit:1 ","response":"range_response_count:0 size:5"} Nov 6 00:16:00.584014 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:00.583667Z","caller":"traceutil/trace.go:171","msg":"trace[834575219] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:524; }","duration":"151.467926ms","start":"2025-11-06T00:16:00.432188Z","end":"2025-11-06T00:16:00.583656Z","steps":["trace[834575219] 'agreement among raft nodes before linearized reading' (duration: 151.367013ms)"],"step_count":1} Nov 6 00:16:00.584982 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:00.584721Z","caller":"traceutil/trace.go:171","msg":"trace[437613205] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"152.37956ms","start":"2025-11-06T00:16:00.432332Z","end":"2025-11-06T00:16:00.584712Z","steps":["trace[437613205] 'process raft request' (duration: 152.306911ms)"],"step_count":1} Nov 6 00:16:00.585257 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:00.584775Z","caller":"traceutil/trace.go:171","msg":"trace[1727202319] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"147.576494ms","start":"2025-11-06T00:16:00.437192Z","end":"2025-11-06T00:16:00.584768Z","steps":["trace[1727202319] 'process raft request' (duration: 147.506109ms)"],"step_count":1} Nov 6 00:16:00.585257 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:00.584807Z","caller":"traceutil/trace.go:171","msg":"trace[153852616] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"152.565966ms","start":"2025-11-06T00:16:00.432234Z","end":"2025-11-06T00:16:00.584800Z","steps":["trace[153852616] 'process raft request' (duration: 152.359972ms)"],"step_count":1} Nov 6 00:16:00.585257 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:16:00.584749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.474442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" limit:1 ","response":"range_response_count:0 size:5"} Nov 6 00:16:00.585257 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:00.584827Z","caller":"traceutil/trace.go:171","msg":"trace[1845390387] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:528; }","duration":"152.554895ms","start":"2025-11-06T00:16:00.432266Z","end":"2025-11-06T00:16:00.584821Z","steps":["trace[1845390387] 'agreement among raft nodes before linearized reading' (duration: 152.457478ms)"],"step_count":1} Nov 6 00:16:00.585257 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:00.584836Z","caller":"traceutil/trace.go:171","msg":"trace[848116047] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"152.31688ms","start":"2025-11-06T00:16:00.432508Z","end":"2025-11-06T00:16:00.584825Z","steps":["trace[848116047] 'process raft request' (duration: 152.147986ms)"],"step_count":1} Nov 6 00:16:00.585517 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:16:00.584924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.383863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" limit:1 ","response":"range_response_count:1 size:179"} Nov 6 00:16:00.585517 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:00.584958Z","caller":"traceutil/trace.go:171","msg":"trace[244780877] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:528; }","duration":"150.422086ms","start":"2025-11-06T00:16:00.434528Z","end":"2025-11-06T00:16:00.584950Z","steps":["trace[244780877] 'agreement among raft nodes before linearized reading' (duration: 150.30367ms)"],"step_count":1} Nov 6 00:16:00.789177 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:00.788695Z","caller":"traceutil/trace.go:171","msg":"trace[113113098] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:547; }","duration":"146.953364ms","start":"2025-11-06T00:16:00.641716Z","end":"2025-11-06T00:16:00.788669Z","steps":["trace[113113098] 'read index received' (duration: 52.779745ms)","trace[113113098] 'applied index is now lower than readState.Index' (duration: 94.173048ms)"],"step_count":2} Nov 6 00:16:00.789177 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:00.788732Z","caller":"traceutil/trace.go:171","msg":"trace[417271229] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"149.625615ms","start":"2025-11-06T00:16:00.639089Z","end":"2025-11-06T00:16:00.788715Z","steps":["trace[417271229] 'process raft request' (duration: 55.362756ms)","trace[417271229] 'compare' (duration: 94.0961ms)"],"step_count":2} Nov 6 00:16:00.789177 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:16:00.788891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.154468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v2.cilium.io\" limit:1 ","response":"range_response_count:1 size:947"} Nov 6 00:16:00.789177 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:00.788957Z","caller":"traceutil/trace.go:171","msg":"trace[256958579] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v2.cilium.io; range_end:; response_count:1; response_revision:533; }","duration":"147.237035ms","start":"2025-11-06T00:16:00.641712Z","end":"2025-11-06T00:16:00.788949Z","steps":["trace[256958579] 'agreement among raft nodes before linearized reading' (duration: 147.050649ms)"],"step_count":1} Nov 6 00:16:00.798000 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:00.797571Z","caller":"traceutil/trace.go:171","msg":"trace[1776672580] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"150.197818ms","start":"2025-11-06T00:16:00.647350Z","end":"2025-11-06T00:16:00.797548Z","steps":["trace[1776672580] 'process raft request' (duration: 150.157049ms)"],"step_count":1} Nov 6 00:16:00.799739 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:00.797961Z","caller":"traceutil/trace.go:171","msg":"trace[1830295625] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"153.695236ms","start":"2025-11-06T00:16:00.643888Z","end":"2025-11-06T00:16:00.797583Z","steps":["trace[1830295625] 'process raft request' (duration: 153.420521ms)"],"step_count":1} Nov 6 00:16:00.954287 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:00.953743Z","caller":"traceutil/trace.go:171","msg":"trace[2018921231] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"112.860375ms","start":"2025-11-06T00:16:00.840856Z","end":"2025-11-06T00:16:00.953717Z","steps":["trace[2018921231] 'process raft request' (duration: 49.693694ms)","trace[2018921231] 'compare' (duration: 63.000133ms)"],"step_count":2} Nov 6 00:16:00.954287 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:00.953787Z","caller":"traceutil/trace.go:171","msg":"trace[1058209111] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:552; }","duration":"105.060439ms","start":"2025-11-06T00:16:00.848718Z","end":"2025-11-06T00:16:00.953778Z","steps":["trace[1058209111] 'read index received' (duration: 5.146743ms)","trace[1058209111] 'applied index is now lower than readState.Index' (duration: 99.913265ms)"],"step_count":2} Nov 6 00:16:00.954287 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:16:00.953867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.137135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-operator-6f9c7c5859-j4mlg.187542ad4c82d980\" limit:1 ","response":"range_response_count:1 size:848"} Nov 6 00:16:00.954287 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:00.953896Z","caller":"traceutil/trace.go:171","msg":"trace[85527295] range","detail":"{range_begin:/registry/events/kube-system/cilium-operator-6f9c7c5859-j4mlg.187542ad4c82d980; range_end:; response_count:1; response_revision:539; }","duration":"105.17688ms","start":"2025-11-06T00:16:00.848710Z","end":"2025-11-06T00:16:00.953886Z","steps":["trace[85527295] 'agreement among raft nodes before linearized reading' (duration: 105.107397ms)"],"step_count":1} Nov 6 00:16:01.130410 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:01.129950Z","caller":"traceutil/trace.go:171","msg":"trace[1614630895] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"110.528187ms","start":"2025-11-06T00:16:01.019399Z","end":"2025-11-06T00:16:01.129928Z","steps":["trace[1614630895] 'process raft request' (duration: 42.991415ms)","trace[1614630895] 'compare' (duration: 67.430739ms)"],"step_count":2} Nov 6 00:16:01.133541 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:01.133183Z","caller":"traceutil/trace.go:171","msg":"trace[1896361327] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"109.916879ms","start":"2025-11-06T00:16:01.023251Z","end":"2025-11-06T00:16:01.133168Z","steps":["trace[1896361327] 'process raft request' (duration: 109.720594ms)"],"step_count":1} Nov 6 00:16:01.140571 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:01.140252Z","caller":"traceutil/trace.go:171","msg":"trace[790768907] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"108.152857ms","start":"2025-11-06T00:16:01.032066Z","end":"2025-11-06T00:16:01.140219Z","steps":["trace[790768907] 'process raft request' (duration: 101.014553ms)"],"step_count":1} Nov 6 00:16:01.610819 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:01.609452Z","caller":"traceutil/trace.go:171","msg":"trace[547369178] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"132.960924ms","start":"2025-11-06T00:16:01.476462Z","end":"2025-11-06T00:16:01.609423Z","steps":["trace[547369178] 'process raft request' (duration: 102.308084ms)","trace[547369178] 'compare' (duration: 29.438328ms)"],"step_count":2} Nov 6 00:16:03.853541 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:03.853133Z","caller":"traceutil/trace.go:171","msg":"trace[631451454] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"181.217238ms","start":"2025-11-06T00:16:03.671895Z","end":"2025-11-06T00:16:03.853112Z","steps":["trace[631451454] 'process raft request' (duration: 145.9802ms)","trace[631451454] 'compare' (duration: 35.151645ms)"],"step_count":2} Nov 6 00:16:07.300843 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:07.300360Z","caller":"traceutil/trace.go:171","msg":"trace[1299921062] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"101.747853ms","start":"2025-11-06T00:16:07.198580Z","end":"2025-11-06T00:16:07.300328Z","steps":["trace[1299921062] 'process raft request' (duration: 85.465556ms)","trace[1299921062] 'compare' (duration: 16.086855ms)"],"step_count":2} Nov 6 00:16:23.122995 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:23.121936Z","caller":"traceutil/trace.go:171","msg":"trace[947656647] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:670; }","duration":"110.678102ms","start":"2025-11-06T00:16:23.011220Z","end":"2025-11-06T00:16:23.121898Z","steps":["trace[947656647] 'read index received' (duration: 44.106882ms)","trace[947656647] 'applied index is now lower than readState.Index' (duration: 66.570449ms)"],"step_count":2} Nov 6 00:16:23.122995 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:23.122155Z","caller":"traceutil/trace.go:171","msg":"trace[134403132] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"111.052111ms","start":"2025-11-06T00:16:23.011089Z","end":"2025-11-06T00:16:23.122142Z","steps":["trace[134403132] 'process raft request' (duration: 83.059759ms)","trace[134403132] 'compare' (duration: 27.649953ms)"],"step_count":2} Nov 6 00:16:23.122995 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:16:23.122250Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.002798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/10.0.0.49\" limit:1 ","response":"range_response_count:0 size:5"} Nov 6 00:16:23.122995 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:23.122273Z","caller":"traceutil/trace.go:171","msg":"trace[145714470] range","detail":"{range_begin:/registry/minions/10.0.0.49; range_end:; response_count:0; response_revision:651; }","duration":"111.052473ms","start":"2025-11-06T00:16:23.011215Z","end":"2025-11-06T00:16:23.122268Z","steps":["trace[145714470] 'agreement among raft nodes before linearized reading' (duration: 110.990124ms)"],"step_count":1} Nov 6 00:16:23.418524 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:23.418051Z","caller":"traceutil/trace.go:171","msg":"trace[1790464512] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"103.421532ms","start":"2025-11-06T00:16:23.314604Z","end":"2025-11-06T00:16:23.418025Z","steps":["trace[1790464512] 'process raft request' (duration: 103.318356ms)"],"step_count":1} Nov 6 00:16:23.761108 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:23.756656Z","caller":"traceutil/trace.go:171","msg":"trace[1210353653] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"121.968444ms","start":"2025-11-06T00:16:23.634667Z","end":"2025-11-06T00:16:23.756636Z","steps":["trace[1210353653] 'process raft request' (duration: 120.81194ms)"],"step_count":1} Nov 6 00:16:23.761108 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:23.756767Z","caller":"traceutil/trace.go:171","msg":"trace[1246370025] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"116.302899ms","start":"2025-11-06T00:16:23.640457Z","end":"2025-11-06T00:16:23.756760Z","steps":["trace[1246370025] 'process raft request' (duration: 115.774707ms)"],"step_count":1} Nov 6 00:16:23.761108 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:23.756803Z","caller":"traceutil/trace.go:171","msg":"trace[550440956] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"113.230994ms","start":"2025-11-06T00:16:23.643567Z","end":"2025-11-06T00:16:23.756798Z","steps":["trace[550440956] 'process raft request' (duration: 112.694978ms)"],"step_count":1} Nov 6 00:16:23.761108 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:16:23.756937Z","caller":"traceutil/trace.go:171","msg":"trace[1305455746] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"104.191252ms","start":"2025-11-06T00:16:23.652738Z","end":"2025-11-06T00:16:23.756929Z","steps":["trace[1305455746] 'process raft request' (duration: 103.550897ms)"],"step_count":1} Nov 6 00:17:09.432210 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:17:09.431185Z","caller":"traceutil/trace.go:171","msg":"trace[242230713] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"104.013281ms","start":"2025-11-06T00:17:09.327126Z","end":"2025-11-06T00:17:09.431140Z","steps":["trace[242230713] 'process raft request' (duration: 103.889227ms)"],"step_count":1} Nov 6 00:17:40.522722 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:17:40.521660Z","caller":"traceutil/trace.go:171","msg":"trace[93457185] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"113.55005ms","start":"2025-11-06T00:17:40.408088Z","end":"2025-11-06T00:17:40.521638Z","steps":["trace[93457185] 'process raft request' (duration: 113.494365ms)"],"step_count":1} Nov 6 00:17:40.522722 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:17:40.521984Z","caller":"traceutil/trace.go:171","msg":"trace[1172470451] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"215.403949ms","start":"2025-11-06T00:17:40.306570Z","end":"2025-11-06T00:17:40.521973Z","steps":["trace[1172470451] 'process raft request' (duration: 164.118615ms)","trace[1172470451] 'compare' (duration: 50.782428ms)"],"step_count":2} Nov 6 00:17:47.794865 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:17:47.789717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.752855ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} Nov 6 00:17:49.123275 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:17:49.119608Z","caller":"etcdserver/v3_server.go:920","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7587890643555730065,"retry-timeout":"500ms"} Nov 6 00:17:49.624955 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:17:49.623657Z","caller":"etcdserver/v3_server.go:920","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7587890643555730065,"retry-timeout":"500ms"} Nov 6 00:17:49.669064 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:17:49.668029Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.499765636s","expected-duration":"1s"} Nov 6 00:17:49.669613 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:17:49.668728Z","caller":"traceutil/trace.go:171","msg":"trace[828488046] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"1.500590739s","start":"2025-11-06T00:17:48.168104Z","end":"2025-11-06T00:17:49.668694Z","steps":["trace[828488046] 'process raft request' (duration: 1.500255568s)"],"step_count":1} Nov 6 00:17:49.669613 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:17:49.668963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-11-06T00:17:48.168076Z","time spent":"1.500734359s","remote":"10.0.0.42:39768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Nov 6 00:17:49.790035 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:17:49.787286Z","caller":"traceutil/trace.go:171","msg":"trace[1977549578] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:995; }","duration":"1.168654937s","start":"2025-11-06T00:17:48.618606Z","end":"2025-11-06T00:17:49.787261Z","steps":["trace[1977549578] 'read index received' (duration: 1.04979077s)","trace[1977549578] 'applied index is now lower than readState.Index' (duration: 118.863376ms)"],"step_count":2} Nov 6 00:17:49.790035 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:17:49.787628Z","caller":"traceutil/trace.go:171","msg":"trace[1453634743] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"1.503666033s","start":"2025-11-06T00:17:48.283951Z","end":"2025-11-06T00:17:49.787617Z","steps":["trace[1453634743] 'process raft request' (duration: 1.421839816s)","trace[1453634743] 'compare' (duration: 81.215548ms)"],"step_count":2} Nov 6 00:17:49.790035 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:17:49.787732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-11-06T00:17:48.283920Z","time spent":"1.503734191s","remote":"10.0.0.42:39768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Nov 6 00:17:49.790035 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:17:49.787799Z","caller":"traceutil/trace.go:171","msg":"trace[1594699741] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"1.154985686s","start":"2025-11-06T00:17:48.632806Z","end":"2025-11-06T00:17:49.787791Z","steps":["trace[1594699741] 'process raft request' (duration: 1.154381648s)"],"step_count":1} Nov 6 00:17:49.790560 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:17:49.787837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-11-06T00:17:48.632774Z","time spent":"1.155042312s","remote":"10.0.0.42:39768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":525,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Nov 6 00:17:49.790560 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:17:49.787870Z","caller":"traceutil/trace.go:171","msg":"trace[392319851] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"1.143543627s","start":"2025-11-06T00:17:48.644320Z","end":"2025-11-06T00:17:49.787864Z","steps":["trace[392319851] 'process raft request' (duration: 1.142909935s)"],"step_count":1} Nov 6 00:17:49.790560 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:17:49.787912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-11-06T00:17:48.644294Z","time spent":"1.143590325s","remote":"10.0.0.42:39768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Nov 6 00:17:49.790560 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:17:49.787953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.169347603s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Nov 6 00:17:49.790761 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:17:49.787974Z","caller":"traceutil/trace.go:171","msg":"trace[181231301] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:958; }","duration":"1.169368031s","start":"2025-11-06T00:17:48.618600Z","end":"2025-11-06T00:17:49.787968Z","steps":["trace[181231301] 'agreement among raft nodes before linearized reading' (duration: 1.169338686s)"],"step_count":1} Nov 6 00:17:49.790761 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:17:49.788001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-11-06T00:17:48.618565Z","time spent":"1.169428815s","remote":"10.0.0.42:39260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} Nov 6 00:17:49.790761 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:17:49.788057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.882994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets\" limit:1 ","response":"range_response_count:0 size:5"} Nov 6 00:17:49.790761 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:17:49.788085Z","caller":"traceutil/trace.go:171","msg":"trace[2068901278] range","detail":"{range_begin:/registry/statefulsets; range_end:; response_count:0; response_revision:958; }","duration":"776.914123ms","start":"2025-11-06T00:17:49.011160Z","end":"2025-11-06T00:17:49.788075Z","steps":["trace[2068901278] 'agreement among raft nodes before linearized reading' (duration: 776.875589ms)"],"step_count":1} Nov 6 00:17:49.790761 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:17:49.788108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-11-06T00:17:49.011082Z","time spent":"777.017357ms","remote":"10.0.0.42:40158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":26,"response count":0,"response size":29,"request content":"key:\"/registry/statefulsets\" limit:1 "} Nov 6 00:17:49.790946 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:17:49.788158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.610051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Nov 6 00:17:49.790946 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:17:49.788175Z","caller":"traceutil/trace.go:171","msg":"trace[1776558095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:958; }","duration":"523.626712ms","start":"2025-11-06T00:17:49.264543Z","end":"2025-11-06T00:17:49.788170Z","steps":["trace[1776558095] 'agreement among raft nodes before linearized reading' (duration: 523.602837ms)"],"step_count":1} Nov 6 00:17:49.790946 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:17:49.788192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-11-06T00:17:49.264518Z","time spent":"523.66867ms","remote":"10.0.0.42:39284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} Nov 6 00:18:10.252640 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:18:10.251923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.081359ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Nov 6 00:18:10.252640 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:18:10.252101Z","caller":"traceutil/trace.go:171","msg":"trace[923541487] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"402.153422ms","start":"2025-11-06T00:18:09.849928Z","end":"2025-11-06T00:18:10.252082Z","steps":["trace[923541487] 'process raft request' (duration: 114.81485ms)","trace[923541487] 'compare' (duration: 286.960357ms)"],"step_count":2} Nov 6 00:18:10.252640 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:18:10.252171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-11-06T00:18:09.849902Z","time spent":"402.236561ms","remote":"10.0.0.42:39650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18060,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Nov 6 00:18:10.283132 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:18:10.282493Z","caller":"traceutil/trace.go:171","msg":"trace[1927057202] linearizableReadLoop","detail":"{readStateIndex:1086; appliedIndex:1084; }","duration":"323.740943ms","start":"2025-11-06T00:18:09.958725Z","end":"2025-11-06T00:18:10.282466Z","steps":["trace[1927057202] 'read index received' (duration: 6.028821ms)","trace[1927057202] 'applied index is now lower than readState.Index' (duration: 317.71147ms)"],"step_count":2} Nov 6 00:18:10.283132 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:18:10.282580Z","caller":"traceutil/trace.go:171","msg":"trace[1192965554] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"425.75659ms","start":"2025-11-06T00:18:09.856816Z","end":"2025-11-06T00:18:10.282573Z","steps":["trace[1192965554] 'process raft request' (duration: 425.498267ms)"],"step_count":1} Nov 6 00:18:10.283132 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:18:10.282623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.891302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/clustermesh-apiserver\" limit:1 ","response":"range_response_count:0 size:5"} Nov 6 00:18:10.283132 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:18:10.282655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-11-06T00:18:09.856776Z","time spent":"425.822127ms","remote":"10.0.0.42:39650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18060,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Nov 6 00:18:10.283605 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:18:10.282673Z","caller":"traceutil/trace.go:171","msg":"trace[1948394603] range","detail":"{range_begin:/registry/services/specs/kube-system/clustermesh-apiserver; range_end:; response_count:0; response_revision:1040; }","duration":"323.948731ms","start":"2025-11-06T00:18:09.958710Z","end":"2025-11-06T00:18:10.282659Z","steps":["trace[1948394603] 'agreement among raft nodes before linearized reading' (duration: 323.872666ms)"],"step_count":1} Nov 6 00:18:10.283605 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:18:10.282701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-11-06T00:18:09.958690Z","time spent":"324.003204ms","remote":"10.0.0.42:39656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/kube-system/clustermesh-apiserver\" limit:1 "} Nov 6 00:18:10.298247 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:18:10.297653Z","caller":"traceutil/trace.go:171","msg":"trace[911645939] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"194.922632ms","start":"2025-11-06T00:18:10.102710Z","end":"2025-11-06T00:18:10.297633Z","steps":["trace[911645939] 'process raft request' (duration: 194.858929ms)"],"step_count":1} Nov 6 00:18:10.298247 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:18:10.297663Z","caller":"traceutil/trace.go:171","msg":"trace[1567916156] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"328.4386ms","start":"2025-11-06T00:18:09.969200Z","end":"2025-11-06T00:18:10.297638Z","steps":["trace[1567916156] 'process raft request' (duration: 328.231494ms)"],"step_count":1} Nov 6 00:18:10.298247 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:18:10.297742Z","caller":"traceutil/trace.go:171","msg":"trace[1552559619] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"328.228718ms","start":"2025-11-06T00:18:09.969505Z","end":"2025-11-06T00:18:10.297734Z","steps":["trace[1552559619] 'process raft request' (duration: 328.037622ms)"],"step_count":1} Nov 6 00:18:10.298247 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:18:10.297808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-11-06T00:18:09.969176Z","time spent":"328.56967ms","remote":"10.0.0.42:39768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Nov 6 00:18:10.298585 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:18:10.297808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-11-06T00:18:09.969497Z","time spent":"328.265307ms","remote":"10.0.0.42:39768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Nov 6 00:18:10.298585 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:18:10.297885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.52751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets\" limit:1 ","response":"range_response_count:0 size:5"} Nov 6 00:18:10.298585 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:18:10.297921Z","caller":"traceutil/trace.go:171","msg":"trace[576492990] range","detail":"{range_begin:/registry/replicasets; range_end:; response_count:0; response_revision:1043; }","duration":"122.749396ms","start":"2025-11-06T00:18:10.175162Z","end":"2025-11-06T00:18:10.297911Z","steps":["trace[576492990] 'agreement among raft nodes before linearized reading' (duration: 122.519736ms)"],"step_count":1} Nov 6 00:18:10.487103 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:18:10.486170Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.267572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/cilium-clustermesh\" limit:1 ","response":"range_response_count:0 size:5"} Nov 6 00:18:10.487103 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:18:10.486258Z","caller":"traceutil/trace.go:171","msg":"trace[1378766942] range","detail":"{range_begin:/registry/secrets/kube-system/cilium-clustermesh; range_end:; response_count:0; response_revision:1044; }","duration":"127.40257ms","start":"2025-11-06T00:18:10.358836Z","end":"2025-11-06T00:18:10.486239Z","steps":["trace[1378766942] 'range keys from in-memory index tree' (duration: 127.184843ms)"],"step_count":1} Nov 6 00:18:11.324618 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:18:11.324102Z","caller":"traceutil/trace.go:171","msg":"trace[1042283716] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"134.281296ms","start":"2025-11-06T00:18:11.189767Z","end":"2025-11-06T00:18:11.324049Z","steps":["trace[1042283716] 'process raft request' (duration: 95.065539ms)","trace[1042283716] 'compare' (duration: 38.922225ms)"],"step_count":2} Nov 6 00:18:12.597466 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:18:12.597093Z","caller":"traceutil/trace.go:171","msg":"trace[566605007] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"189.110977ms","start":"2025-11-06T00:18:12.407959Z","end":"2025-11-06T00:18:12.597070Z","steps":["trace[566605007] 'process raft request' (duration: 164.679179ms)","trace[566605007] 'compare' (duration: 24.348008ms)"],"step_count":2} Nov 6 00:18:12.770020 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:18:12.769634Z","caller":"traceutil/trace.go:171","msg":"trace[698059962] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"162.975567ms","start":"2025-11-06T00:18:12.606638Z","end":"2025-11-06T00:18:12.769613Z","steps":["trace[698059962] 'process raft request' (duration: 127.980443ms)","trace[698059962] 'compare' (duration: 34.903089ms)"],"step_count":2} Nov 6 00:18:12.945470 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:18:12.944984Z","caller":"traceutil/trace.go:171","msg":"trace[1719725728] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"125.283756ms","start":"2025-11-06T00:18:12.819665Z","end":"2025-11-06T00:18:12.944948Z","steps":["trace[1719725728] 'process raft request' (duration: 64.39158ms)","trace[1719725728] 'compare' (duration: 60.722381ms)"],"step_count":2} Nov 6 00:18:19.003169 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:18:19.002751Z","caller":"traceutil/trace.go:171","msg":"trace[1343023770] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"146.713021ms","start":"2025-11-06T00:18:18.856018Z","end":"2025-11-06T00:18:19.002731Z","steps":["trace[1343023770] 'process raft request' (duration: 146.609533ms)"],"step_count":1} Nov 6 00:18:19.024870 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:18:19.024489Z","caller":"traceutil/trace.go:171","msg":"trace[657487326] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"160.878307ms","start":"2025-11-06T00:18:18.863589Z","end":"2025-11-06T00:18:19.024468Z","steps":["trace[657487326] 'process raft request' (duration: 160.684897ms)"],"step_count":1} Nov 6 00:18:19.285998 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:18:19.285490Z","caller":"traceutil/trace.go:171","msg":"trace[1925177545] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"121.888285ms","start":"2025-11-06T00:18:19.163580Z","end":"2025-11-06T00:18:19.285468Z","steps":["trace[1925177545] 'process raft request' (duration: 121.708372ms)"],"step_count":1} Nov 6 00:18:20.548505 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:18:20.548059Z","caller":"traceutil/trace.go:171","msg":"trace[222927352] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"131.710929ms","start":"2025-11-06T00:18:20.416332Z","end":"2025-11-06T00:18:20.548043Z","steps":["trace[222927352] 'process raft request' (duration: 131.607432ms)"],"step_count":1} Nov 6 00:18:20.617496 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:18:20.617098Z","caller":"traceutil/trace.go:171","msg":"trace[1900669417] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"191.534529ms","start":"2025-11-06T00:18:20.425538Z","end":"2025-11-06T00:18:20.617073Z","steps":["trace[1900669417] 'process raft request' (duration: 153.731304ms)","trace[1900669417] 'compare' (duration: 37.722381ms)"],"step_count":2} Nov 6 00:18:21.423433 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:18:21.422615Z","caller":"traceutil/trace.go:171","msg":"trace[1296391596] linearizableReadLoop","detail":"{readStateIndex:1241; appliedIndex:1239; }","duration":"199.761266ms","start":"2025-11-06T00:18:21.222822Z","end":"2025-11-06T00:18:21.422583Z","steps":["trace[1296391596] 'read index received' (duration: 60.822655ms)","trace[1296391596] 'applied index is now lower than readState.Index' (duration: 138.937799ms)"],"step_count":2} Nov 6 00:18:21.423433 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:18:21.422727Z","caller":"traceutil/trace.go:171","msg":"trace[527786666] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"203.135909ms","start":"2025-11-06T00:18:21.219563Z","end":"2025-11-06T00:18:21.422699Z","steps":["trace[527786666] 'process raft request' (duration: 181.89909ms)","trace[527786666] 'compare' (duration: 21.023071ms)"],"step_count":2} Nov 6 00:18:21.423433 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:18:21.422842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.98826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/10.0.0.49\" limit:1 ","response":"range_response_count:1 size:1923"} Nov 6 00:18:21.423433 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:18:21.422865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.126141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Nov 6 00:18:21.423433 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:18:21.422883Z","caller":"traceutil/trace.go:171","msg":"trace[1905736418] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/10.0.0.49; range_end:; response_count:1; response_revision:1191; }","duration":"200.05684ms","start":"2025-11-06T00:18:21.222818Z","end":"2025-11-06T00:18:21.422875Z","steps":["trace[1905736418] 'agreement among raft nodes before linearized reading' (duration: 199.913748ms)"],"step_count":1} Nov 6 00:18:21.423795 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:18:21.422897Z","caller":"traceutil/trace.go:171","msg":"trace[1419976919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1191; }","duration":"167.165305ms","start":"2025-11-06T00:18:21.255721Z","end":"2025-11-06T00:18:21.422886Z","steps":["trace[1419976919] 'agreement among raft nodes before linearized reading' (duration: 167.111262ms)"],"step_count":1} Nov 6 00:18:21.622435 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:18:21.621925Z","caller":"traceutil/trace.go:171","msg":"trace[1936479064] linearizableReadLoop","detail":"{readStateIndex:1244; appliedIndex:1243; }","duration":"134.228847ms","start":"2025-11-06T00:18:21.487673Z","end":"2025-11-06T00:18:21.621902Z","steps":["trace[1936479064] 'read index received' (duration: 122.531943ms)","trace[1936479064] 'applied index is now lower than readState.Index' (duration: 11.695983ms)"],"step_count":2} Nov 6 00:18:21.622435 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:18:21.621998Z","caller":"traceutil/trace.go:171","msg":"trace[1327707156] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"180.746879ms","start":"2025-11-06T00:18:21.441233Z","end":"2025-11-06T00:18:21.621980Z","steps":["trace[1327707156] 'process raft request' (duration: 169.058721ms)","trace[1327707156] 'compare' (duration: 11.485571ms)"],"step_count":2} Nov 6 00:18:21.622435 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:18:21.622125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.436123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/10.0.0.49\" limit:1 ","response":"range_response_count:1 size:1859"} Nov 6 00:18:21.622435 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:18:21.622152Z","caller":"traceutil/trace.go:171","msg":"trace[1026836449] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/10.0.0.49; range_end:; response_count:1; response_revision:1194; }","duration":"134.481148ms","start":"2025-11-06T00:18:21.487663Z","end":"2025-11-06T00:18:21.622144Z","steps":["trace[1026836449] 'agreement among raft nodes before linearized reading' (duration: 134.351481ms)"],"step_count":1} Nov 6 00:18:21.818129 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:18:21.817588Z","caller":"traceutil/trace.go:171","msg":"trace[167361190] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"172.769878ms","start":"2025-11-06T00:18:21.644797Z","end":"2025-11-06T00:18:21.817567Z","steps":["trace[167361190] 'process raft request' (duration: 91.120206ms)","trace[167361190] 'compare' (duration: 81.420965ms)"],"step_count":2} Nov 6 00:18:22.159089 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:18:22.158692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.192505ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Nov 6 00:18:22.159089 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:18:22.158790Z","caller":"traceutil/trace.go:171","msg":"trace[2118366479] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"249.14765ms","start":"2025-11-06T00:18:21.909627Z","end":"2025-11-06T00:18:22.158775Z","steps":["trace[2118366479] 'process raft request' (duration: 100.793345ms)","trace[2118366479] 'compare' (duration: 148.086674ms)"],"step_count":2} Nov 6 00:18:23.142495 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:18:23.141771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.345621ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Nov 6 00:18:23.142495 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:18:23.142004Z","caller":"traceutil/trace.go:171","msg":"trace[986905481] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"150.519084ms","start":"2025-11-06T00:18:22.991470Z","end":"2025-11-06T00:18:23.141989Z","steps":["trace[986905481] 'process raft request' (duration: 44.888441ms)","trace[986905481] 'compare' (duration: 105.177438ms)"],"step_count":2} Nov 6 00:18:24.674566 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:18:24.674094Z","caller":"traceutil/trace.go:171","msg":"trace[1700571139] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"116.965507ms","start":"2025-11-06T00:18:24.557110Z","end":"2025-11-06T00:18:24.674075Z","steps":["trace[1700571139] 'process raft request' (duration: 116.842803ms)"],"step_count":1} Nov 6 00:18:24.675306 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:18:24.674854Z","caller":"traceutil/trace.go:171","msg":"trace[177806114] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"117.223038ms","start":"2025-11-06T00:18:24.557614Z","end":"2025-11-06T00:18:24.674837Z","steps":["trace[177806114] 'process raft request' (duration: 117.036763ms)"],"step_count":1} Nov 6 00:18:28.976221 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:18:28.975831Z","caller":"traceutil/trace.go:171","msg":"trace[594250316] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"292.183609ms","start":"2025-11-06T00:18:28.683628Z","end":"2025-11-06T00:18:28.975812Z","steps":["trace[594250316] 'process raft request' (duration: 292.082976ms)"],"step_count":1} Nov 6 00:18:29.146494 etcd-wrapper[1814]: {"level":"warn","ts":"2025-11-06T00:18:29.146076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.091526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 ","response":"range_response_count:1 size:492"} Nov 6 00:18:29.146494 etcd-wrapper[1814]: {"level":"info","ts":"2025-11-06T00:18:29.146139Z","caller":"traceutil/trace.go:171","msg":"trace[853606044] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1227; }","duration":"103.174304ms","start":"2025-11-06T00:18:29.042952Z","end":"2025-11-06T00:18:29.146126Z","steps":["trace[853606044] 'range keys from in-memory index tree' (duration: 102.969393ms)"],"step_count":1}