Oct 31 00:36:34.995720 kernel: Linux version 6.6.113-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p17) 13.3.1 20240614, GNU ld (Gentoo 2.42 p3) 2.42.0) #1 SMP PREEMPT_DYNAMIC Thu Oct 30 22:59:39 -00 2025 Oct 31 00:36:34.995745 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=950876ad7bc3e9634b7585a81697da4ef03ac6558969e5c002165369dd7c7885 Oct 31 00:36:34.995758 kernel: BIOS-provided physical RAM map: Oct 31 00:36:34.995765 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Oct 31 00:36:34.995771 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Oct 31 00:36:34.995778 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Oct 31 00:36:34.995785 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000009cfdbfff] usable Oct 31 00:36:34.995792 kernel: BIOS-e820: [mem 0x000000009cfdc000-0x000000009cffffff] reserved Oct 31 00:36:34.995798 kernel: BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved Oct 31 00:36:34.995807 kernel: BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved Oct 31 00:36:34.995814 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Oct 31 00:36:34.995820 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Oct 31 00:36:34.995829 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Oct 31 00:36:34.995836 kernel: NX (Execute Disable) protection: active Oct 31 00:36:34.995848 kernel: APIC: Static calls initialized Oct 31 00:36:34.995865 kernel: SMBIOS 2.8 present. Oct 31 00:36:34.995873 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.16.2-debian-1.16.2-1 04/01/2014 Oct 31 00:36:34.995880 kernel: Hypervisor detected: KVM Oct 31 00:36:34.995887 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Oct 31 00:36:34.995893 kernel: kvm-clock: using sched offset of 3076876400 cycles Oct 31 00:36:34.995901 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Oct 31 00:36:34.995908 kernel: tsc: Detected 2794.748 MHz processor Oct 31 00:36:34.995915 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Oct 31 00:36:34.995923 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Oct 31 00:36:34.995934 kernel: last_pfn = 0x9cfdc max_arch_pfn = 0x400000000 Oct 31 00:36:34.995941 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Oct 31 00:36:34.995948 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Oct 31 00:36:34.995955 kernel: Using GB pages for direct mapping Oct 31 00:36:34.995962 kernel: ACPI: Early table checksum verification disabled Oct 31 00:36:34.995969 kernel: ACPI: RSDP 0x00000000000F59D0 000014 (v00 BOCHS ) Oct 31 00:36:34.995977 kernel: ACPI: RSDT 0x000000009CFE241A 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 00:36:34.995984 kernel: ACPI: FACP 0x000000009CFE21FA 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 00:36:34.995991 kernel: ACPI: DSDT 0x000000009CFE0040 0021BA (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 00:36:34.996001 kernel: ACPI: FACS 0x000000009CFE0000 000040 Oct 31 00:36:34.996008 kernel: ACPI: APIC 0x000000009CFE22EE 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 00:36:34.996015 kernel: ACPI: HPET 0x000000009CFE237E 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 00:36:34.996022 kernel: ACPI: MCFG 0x000000009CFE23B6 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 00:36:34.996030 kernel: ACPI: WAET 0x000000009CFE23F2 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 00:36:34.996037 kernel: ACPI: Reserving FACP table memory at [mem 0x9cfe21fa-0x9cfe22ed] Oct 31 00:36:34.996044 kernel: ACPI: Reserving DSDT table memory at [mem 0x9cfe0040-0x9cfe21f9] Oct 31 00:36:34.996055 kernel: ACPI: Reserving FACS table memory at [mem 0x9cfe0000-0x9cfe003f] Oct 31 00:36:34.996065 kernel: ACPI: Reserving APIC table memory at [mem 0x9cfe22ee-0x9cfe237d] Oct 31 00:36:34.996072 kernel: ACPI: Reserving HPET table memory at [mem 0x9cfe237e-0x9cfe23b5] Oct 31 00:36:34.996080 kernel: ACPI: Reserving MCFG table memory at [mem 0x9cfe23b6-0x9cfe23f1] Oct 31 00:36:34.996099 kernel: ACPI: Reserving WAET table memory at [mem 0x9cfe23f2-0x9cfe2419] Oct 31 00:36:34.996106 kernel: No NUMA configuration found Oct 31 00:36:34.996114 kernel: Faking a node at [mem 0x0000000000000000-0x000000009cfdbfff] Oct 31 00:36:34.996125 kernel: NODE_DATA(0) allocated [mem 0x9cfd6000-0x9cfdbfff] Oct 31 00:36:34.996132 kernel: Zone ranges: Oct 31 00:36:34.996140 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Oct 31 00:36:34.996147 kernel: DMA32 [mem 0x0000000001000000-0x000000009cfdbfff] Oct 31 00:36:34.996154 kernel: Normal empty Oct 31 00:36:34.996162 kernel: Movable zone start for each node Oct 31 00:36:34.996169 kernel: Early memory node ranges Oct 31 00:36:34.996176 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Oct 31 00:36:34.996184 kernel: node 0: [mem 0x0000000000100000-0x000000009cfdbfff] Oct 31 00:36:34.996191 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009cfdbfff] Oct 31 00:36:34.996201 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Oct 31 00:36:34.996211 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Oct 31 00:36:34.996219 kernel: On node 0, zone DMA32: 12324 pages in unavailable ranges Oct 31 00:36:34.996226 kernel: ACPI: PM-Timer IO Port: 0x608 Oct 31 00:36:34.996237 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Oct 31 00:36:34.996253 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Oct 31 00:36:34.996261 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Oct 31 00:36:34.996269 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Oct 31 00:36:34.996276 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Oct 31 00:36:34.996287 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Oct 31 00:36:34.996295 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Oct 31 00:36:34.996302 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Oct 31 00:36:34.996310 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Oct 31 00:36:34.996324 kernel: TSC deadline timer available Oct 31 00:36:34.996334 kernel: smpboot: Allowing 4 CPUs, 0 hotplug CPUs Oct 31 00:36:34.996343 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Oct 31 00:36:34.996353 kernel: kvm-guest: KVM setup pv remote TLB flush Oct 31 00:36:34.996367 kernel: kvm-guest: setup PV sched yield Oct 31 00:36:34.996381 kernel: [mem 0xc0000000-0xfed1bfff] available for PCI devices Oct 31 00:36:34.996391 kernel: Booting paravirtualized kernel on KVM Oct 31 00:36:34.996401 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Oct 31 00:36:34.996412 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 Oct 31 00:36:34.996421 kernel: percpu: Embedded 58 pages/cpu s196712 r8192 d32664 u524288 Oct 31 00:36:34.996428 kernel: pcpu-alloc: s196712 r8192 d32664 u524288 alloc=1*2097152 Oct 31 00:36:34.996436 kernel: pcpu-alloc: [0] 0 1 2 3 Oct 31 00:36:34.996443 kernel: kvm-guest: PV spinlocks enabled Oct 31 00:36:34.996450 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Oct 31 00:36:34.996463 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=950876ad7bc3e9634b7585a81697da4ef03ac6558969e5c002165369dd7c7885 Oct 31 00:36:34.996470 kernel: random: crng init done Oct 31 00:36:34.996478 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 31 00:36:34.996486 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 31 00:36:34.996495 kernel: Fallback order for Node 0: 0 Oct 31 00:36:34.996502 kernel: Built 1 zonelists, mobility grouping on. Total pages: 632732 Oct 31 00:36:34.996509 kernel: Policy zone: DMA32 Oct 31 00:36:34.996517 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 31 00:36:34.996530 kernel: Memory: 2434592K/2571752K available (12288K kernel code, 2288K rwdata, 22748K rodata, 42880K init, 2320K bss, 136900K reserved, 0K cma-reserved) Oct 31 00:36:34.996540 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 31 00:36:34.996551 kernel: ftrace: allocating 37980 entries in 149 pages Oct 31 00:36:34.996561 kernel: ftrace: allocated 149 pages with 4 groups Oct 31 00:36:34.996591 kernel: Dynamic Preempt: voluntary Oct 31 00:36:34.996603 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 31 00:36:34.996616 kernel: rcu: RCU event tracing is enabled. Oct 31 00:36:34.996627 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 31 00:36:34.996640 kernel: Trampoline variant of Tasks RCU enabled. Oct 31 00:36:34.996659 kernel: Rude variant of Tasks RCU enabled. Oct 31 00:36:34.996668 kernel: Tracing variant of Tasks RCU enabled. Oct 31 00:36:34.996676 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 31 00:36:34.996683 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 31 00:36:34.996699 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 Oct 31 00:36:34.996708 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Oct 31 00:36:34.996715 kernel: Console: colour VGA+ 80x25 Oct 31 00:36:34.996722 kernel: printk: console [ttyS0] enabled Oct 31 00:36:34.996730 kernel: ACPI: Core revision 20230628 Oct 31 00:36:34.996738 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Oct 31 00:36:34.996749 kernel: APIC: Switch to symmetric I/O mode setup Oct 31 00:36:34.996756 kernel: x2apic enabled Oct 31 00:36:34.996764 kernel: APIC: Switched APIC routing to: physical x2apic Oct 31 00:36:34.996772 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() Oct 31 00:36:34.996779 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() Oct 31 00:36:34.996787 kernel: kvm-guest: setup PV IPIs Oct 31 00:36:34.996794 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Oct 31 00:36:34.996814 kernel: tsc: Marking TSC unstable due to TSCs unsynchronized Oct 31 00:36:34.996822 kernel: Calibrating delay loop (skipped) preset value.. 5589.49 BogoMIPS (lpj=2794748) Oct 31 00:36:34.996830 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Oct 31 00:36:34.996838 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 Oct 31 00:36:34.996848 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 Oct 31 00:36:34.996856 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Oct 31 00:36:34.996864 kernel: Spectre V2 : Mitigation: Retpolines Oct 31 00:36:34.996872 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Oct 31 00:36:34.996879 kernel: Spectre V2 : Enabling Speculation Barrier for firmware calls Oct 31 00:36:34.996890 kernel: active return thunk: retbleed_return_thunk Oct 31 00:36:34.996898 kernel: RETBleed: Mitigation: untrained return thunk Oct 31 00:36:34.996909 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Oct 31 00:36:34.996917 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Oct 31 00:36:34.996924 kernel: Speculative Return Stack Overflow: IBPB-extending microcode not applied! Oct 31 00:36:34.996933 kernel: Speculative Return Stack Overflow: WARNING: See https://kernel.org/doc/html/latest/admin-guide/hw-vuln/srso.html for mitigation options. Oct 31 00:36:34.996941 kernel: active return thunk: srso_return_thunk Oct 31 00:36:34.996949 kernel: Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode Oct 31 00:36:34.996959 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Oct 31 00:36:34.996967 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Oct 31 00:36:34.996975 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Oct 31 00:36:34.996983 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Oct 31 00:36:34.996991 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format. Oct 31 00:36:34.996999 kernel: Freeing SMP alternatives memory: 32K Oct 31 00:36:34.997006 kernel: pid_max: default: 32768 minimum: 301 Oct 31 00:36:34.997014 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Oct 31 00:36:34.997022 kernel: landlock: Up and running. Oct 31 00:36:34.997032 kernel: SELinux: Initializing. Oct 31 00:36:34.997040 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 31 00:36:34.997048 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 31 00:36:34.997056 kernel: smpboot: CPU0: AMD EPYC 7402P 24-Core Processor (family: 0x17, model: 0x31, stepping: 0x0) Oct 31 00:36:34.997064 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Oct 31 00:36:34.997072 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Oct 31 00:36:34.997080 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Oct 31 00:36:34.997119 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver. Oct 31 00:36:34.997129 kernel: ... version: 0 Oct 31 00:36:34.997141 kernel: ... bit width: 48 Oct 31 00:36:34.997149 kernel: ... generic registers: 6 Oct 31 00:36:34.997157 kernel: ... value mask: 0000ffffffffffff Oct 31 00:36:34.997164 kernel: ... max period: 00007fffffffffff Oct 31 00:36:34.997172 kernel: ... fixed-purpose events: 0 Oct 31 00:36:34.997180 kernel: ... event mask: 000000000000003f Oct 31 00:36:34.997187 kernel: signal: max sigframe size: 1776 Oct 31 00:36:34.997195 kernel: rcu: Hierarchical SRCU implementation. Oct 31 00:36:34.997203 kernel: rcu: Max phase no-delay instances is 400. Oct 31 00:36:34.997214 kernel: smp: Bringing up secondary CPUs ... Oct 31 00:36:34.997221 kernel: smpboot: x86: Booting SMP configuration: Oct 31 00:36:34.997229 kernel: .... node #0, CPUs: #1 #2 #3 Oct 31 00:36:34.997237 kernel: smp: Brought up 1 node, 4 CPUs Oct 31 00:36:34.997244 kernel: smpboot: Max logical packages: 1 Oct 31 00:36:34.997252 kernel: smpboot: Total of 4 processors activated (22357.98 BogoMIPS) Oct 31 00:36:34.997260 kernel: devtmpfs: initialized Oct 31 00:36:34.997268 kernel: x86/mm: Memory block size: 128MB Oct 31 00:36:34.997275 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 31 00:36:34.997286 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 31 00:36:34.997293 kernel: pinctrl core: initialized pinctrl subsystem Oct 31 00:36:34.997301 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 31 00:36:34.997309 kernel: audit: initializing netlink subsys (disabled) Oct 31 00:36:34.997317 kernel: audit: type=2000 audit(1761870993.293:1): state=initialized audit_enabled=0 res=1 Oct 31 00:36:34.997335 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 31 00:36:34.997345 kernel: thermal_sys: Registered thermal governor 'user_space' Oct 31 00:36:34.997356 kernel: cpuidle: using governor menu Oct 31 00:36:34.997366 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 31 00:36:34.997380 kernel: dca service started, version 1.12.1 Oct 31 00:36:34.997390 kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xb0000000-0xbfffffff] (base 0xb0000000) Oct 31 00:36:34.997401 kernel: PCI: MMCONFIG at [mem 0xb0000000-0xbfffffff] reserved as E820 entry Oct 31 00:36:34.997412 kernel: PCI: Using configuration type 1 for base access Oct 31 00:36:34.997421 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Oct 31 00:36:34.997429 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Oct 31 00:36:34.997437 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Oct 31 00:36:34.997444 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Oct 31 00:36:34.997452 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Oct 31 00:36:34.997463 kernel: ACPI: Added _OSI(Module Device) Oct 31 00:36:34.997471 kernel: ACPI: Added _OSI(Processor Device) Oct 31 00:36:34.997478 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 31 00:36:34.997486 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 31 00:36:34.997494 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Oct 31 00:36:34.997502 kernel: ACPI: Interpreter enabled Oct 31 00:36:34.997510 kernel: ACPI: PM: (supports S0 S3 S5) Oct 31 00:36:34.997518 kernel: ACPI: Using IOAPIC for interrupt routing Oct 31 00:36:34.997525 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Oct 31 00:36:34.997536 kernel: PCI: Using E820 reservations for host bridge windows Oct 31 00:36:34.997563 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F Oct 31 00:36:34.997579 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 31 00:36:34.997878 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 31 00:36:34.998027 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] Oct 31 00:36:34.998179 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] Oct 31 00:36:34.998191 kernel: PCI host bridge to bus 0000:00 Oct 31 00:36:34.998349 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Oct 31 00:36:34.998489 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Oct 31 00:36:34.998610 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Oct 31 00:36:34.998738 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xafffffff window] Oct 31 00:36:34.998858 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] Oct 31 00:36:34.998976 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] Oct 31 00:36:34.999131 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 31 00:36:34.999305 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 Oct 31 00:36:34.999481 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 Oct 31 00:36:34.999614 kernel: pci 0000:00:01.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref] Oct 31 00:36:34.999744 kernel: pci 0000:00:01.0: reg 0x18: [mem 0xfebd0000-0xfebd0fff] Oct 31 00:36:34.999873 kernel: pci 0000:00:01.0: reg 0x30: [mem 0xfebc0000-0xfebcffff pref] Oct 31 00:36:35.000001 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Oct 31 00:36:35.000183 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 Oct 31 00:36:35.000335 kernel: pci 0000:00:02.0: reg 0x10: [io 0xc0c0-0xc0df] Oct 31 00:36:35.000479 kernel: pci 0000:00:02.0: reg 0x14: [mem 0xfebd1000-0xfebd1fff] Oct 31 00:36:35.000611 kernel: pci 0000:00:02.0: reg 0x20: [mem 0xfe000000-0xfe003fff 64bit pref] Oct 31 00:36:35.000757 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 Oct 31 00:36:35.000888 kernel: pci 0000:00:03.0: reg 0x10: [io 0xc000-0xc07f] Oct 31 00:36:35.001017 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xfebd2000-0xfebd2fff] Oct 31 00:36:35.001172 kernel: pci 0000:00:03.0: reg 0x20: [mem 0xfe004000-0xfe007fff 64bit pref] Oct 31 00:36:35.001339 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 Oct 31 00:36:35.001482 kernel: pci 0000:00:04.0: reg 0x10: [io 0xc0e0-0xc0ff] Oct 31 00:36:35.001640 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xfebd3000-0xfebd3fff] Oct 31 00:36:35.001771 kernel: pci 0000:00:04.0: reg 0x20: [mem 0xfe008000-0xfe00bfff 64bit pref] Oct 31 00:36:35.001899 kernel: pci 0000:00:04.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] Oct 31 00:36:35.002047 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 Oct 31 00:36:35.002214 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO Oct 31 00:36:35.002382 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 Oct 31 00:36:35.002522 kernel: pci 0000:00:1f.2: reg 0x20: [io 0xc100-0xc11f] Oct 31 00:36:35.002665 kernel: pci 0000:00:1f.2: reg 0x24: [mem 0xfebd4000-0xfebd4fff] Oct 31 00:36:35.002883 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 Oct 31 00:36:35.003049 kernel: pci 0000:00:1f.3: reg 0x20: [io 0x0700-0x073f] Oct 31 00:36:35.003063 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Oct 31 00:36:35.003077 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Oct 31 00:36:35.003159 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Oct 31 00:36:35.003174 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Oct 31 00:36:35.003183 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 Oct 31 00:36:35.003191 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 Oct 31 00:36:35.003199 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 Oct 31 00:36:35.003207 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 Oct 31 00:36:35.003215 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 Oct 31 00:36:35.003223 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 Oct 31 00:36:35.003236 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 Oct 31 00:36:35.003244 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 Oct 31 00:36:35.003252 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 Oct 31 00:36:35.003260 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 Oct 31 00:36:35.003268 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 Oct 31 00:36:35.003279 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 Oct 31 00:36:35.003290 kernel: iommu: Default domain type: Translated Oct 31 00:36:35.003302 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Oct 31 00:36:35.003311 kernel: PCI: Using ACPI for IRQ routing Oct 31 00:36:35.003335 kernel: PCI: pci_cache_line_size set to 64 bytes Oct 31 00:36:35.003345 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Oct 31 00:36:35.003356 kernel: e820: reserve RAM buffer [mem 0x9cfdc000-0x9fffffff] Oct 31 00:36:35.003517 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device Oct 31 00:36:35.003666 kernel: pci 0000:00:01.0: vgaarb: bridge control possible Oct 31 00:36:35.003815 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Oct 31 00:36:35.003832 kernel: vgaarb: loaded Oct 31 00:36:35.003843 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Oct 31 00:36:35.003852 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Oct 31 00:36:35.003865 kernel: clocksource: Switched to clocksource kvm-clock Oct 31 00:36:35.003873 kernel: VFS: Disk quotas dquot_6.6.0 Oct 31 00:36:35.003901 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 31 00:36:35.003910 kernel: pnp: PnP ACPI init Oct 31 00:36:35.004107 kernel: system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved Oct 31 00:36:35.004126 kernel: pnp: PnP ACPI: found 6 devices Oct 31 00:36:35.004137 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Oct 31 00:36:35.004148 kernel: NET: Registered PF_INET protocol family Oct 31 00:36:35.004166 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 31 00:36:35.004174 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 31 00:36:35.004183 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 31 00:36:35.004191 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 31 00:36:35.004199 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Oct 31 00:36:35.004207 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 31 00:36:35.004215 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 31 00:36:35.004223 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 31 00:36:35.004231 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 31 00:36:35.004242 kernel: NET: Registered PF_XDP protocol family Oct 31 00:36:35.004394 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Oct 31 00:36:35.004545 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Oct 31 00:36:35.004697 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Oct 31 00:36:35.004818 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xafffffff window] Oct 31 00:36:35.004934 kernel: pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] Oct 31 00:36:35.005075 kernel: pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] Oct 31 00:36:35.005109 kernel: PCI: CLS 0 bytes, default 64 Oct 31 00:36:35.005126 kernel: Initialise system trusted keyrings Oct 31 00:36:35.005137 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 31 00:36:35.005148 kernel: Key type asymmetric registered Oct 31 00:36:35.005159 kernel: Asymmetric key parser 'x509' registered Oct 31 00:36:35.005169 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 31 00:36:35.005180 kernel: io scheduler mq-deadline registered Oct 31 00:36:35.005191 kernel: io scheduler kyber registered Oct 31 00:36:35.005207 kernel: io scheduler bfq registered Oct 31 00:36:35.005221 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Oct 31 00:36:35.005236 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 Oct 31 00:36:35.005244 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 Oct 31 00:36:35.005253 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 Oct 31 00:36:35.005261 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 31 00:36:35.005269 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Oct 31 00:36:35.005277 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Oct 31 00:36:35.005285 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Oct 31 00:36:35.005293 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Oct 31 00:36:35.005501 kernel: rtc_cmos 00:04: RTC can wake from S4 Oct 31 00:36:35.005520 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Oct 31 00:36:35.005665 kernel: rtc_cmos 00:04: registered as rtc0 Oct 31 00:36:35.005845 kernel: rtc_cmos 00:04: setting system clock to 2025-10-31T00:36:34 UTC (1761870994) Oct 31 00:36:35.005987 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs Oct 31 00:36:35.006000 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled Oct 31 00:36:35.006008 kernel: NET: Registered PF_INET6 protocol family Oct 31 00:36:35.006016 kernel: Segment Routing with IPv6 Oct 31 00:36:35.006024 kernel: In-situ OAM (IOAM) with IPv6 Oct 31 00:36:35.006037 kernel: NET: Registered PF_PACKET protocol family Oct 31 00:36:35.006048 kernel: Key type dns_resolver registered Oct 31 00:36:35.006060 kernel: IPI shorthand broadcast: enabled Oct 31 00:36:35.006070 kernel: sched_clock: Marking stable (1142003501, 194080884)->(1397978839, -61894454) Oct 31 00:36:35.006078 kernel: registered taskstats version 1 Oct 31 00:36:35.006106 kernel: Loading compiled-in X.509 certificates Oct 31 00:36:35.006114 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.113-flatcar: 3640cadef2ce00a652278ae302be325ebb54a228' Oct 31 00:36:35.006122 kernel: Key type .fscrypt registered Oct 31 00:36:35.006130 kernel: Key type fscrypt-provisioning registered Oct 31 00:36:35.006143 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 31 00:36:35.006151 kernel: ima: Allocated hash algorithm: sha1 Oct 31 00:36:35.006159 kernel: ima: No architecture policies found Oct 31 00:36:35.006167 kernel: clk: Disabling unused clocks Oct 31 00:36:35.006175 kernel: Freeing unused kernel image (initmem) memory: 42880K Oct 31 00:36:35.006199 kernel: Write protecting the kernel read-only data: 36864k Oct 31 00:36:35.006208 kernel: Freeing unused kernel image (rodata/data gap) memory: 1828K Oct 31 00:36:35.006216 kernel: Run /init as init process Oct 31 00:36:35.006224 kernel: with arguments: Oct 31 00:36:35.006235 kernel: /init Oct 31 00:36:35.006243 kernel: with environment: Oct 31 00:36:35.006251 kernel: HOME=/ Oct 31 00:36:35.006258 kernel: TERM=linux Oct 31 00:36:35.006269 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Oct 31 00:36:35.006279 systemd[1]: Detected virtualization kvm. Oct 31 00:36:35.006288 systemd[1]: Detected architecture x86-64. Oct 31 00:36:35.006296 systemd[1]: Running in initrd. Oct 31 00:36:35.006307 systemd[1]: No hostname configured, using default hostname. Oct 31 00:36:35.006315 systemd[1]: Hostname set to . Oct 31 00:36:35.006335 systemd[1]: Initializing machine ID from VM UUID. Oct 31 00:36:35.006346 systemd[1]: Queued start job for default target initrd.target. Oct 31 00:36:35.006357 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 31 00:36:35.006369 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 31 00:36:35.006381 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Oct 31 00:36:35.006392 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 31 00:36:35.006408 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Oct 31 00:36:35.006430 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Oct 31 00:36:35.006443 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Oct 31 00:36:35.006452 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Oct 31 00:36:35.006463 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 31 00:36:35.006472 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 31 00:36:35.006480 systemd[1]: Reached target paths.target - Path Units. Oct 31 00:36:35.006489 systemd[1]: Reached target slices.target - Slice Units. Oct 31 00:36:35.006497 systemd[1]: Reached target swap.target - Swaps. Oct 31 00:36:35.006506 systemd[1]: Reached target timers.target - Timer Units. Oct 31 00:36:35.006514 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Oct 31 00:36:35.006523 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 31 00:36:35.006532 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Oct 31 00:36:35.006543 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Oct 31 00:36:35.006552 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 31 00:36:35.006561 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 31 00:36:35.006569 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 31 00:36:35.006578 systemd[1]: Reached target sockets.target - Socket Units. Oct 31 00:36:35.006589 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Oct 31 00:36:35.006598 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 31 00:36:35.006607 systemd[1]: Finished network-cleanup.service - Network Cleanup. Oct 31 00:36:35.006616 systemd[1]: Starting systemd-fsck-usr.service... Oct 31 00:36:35.006628 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 31 00:36:35.006636 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 31 00:36:35.006645 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 31 00:36:35.006654 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Oct 31 00:36:35.006663 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 31 00:36:35.006671 systemd[1]: Finished systemd-fsck-usr.service. Oct 31 00:36:35.006707 systemd-journald[193]: Collecting audit messages is disabled. Oct 31 00:36:35.006729 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 31 00:36:35.006741 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 31 00:36:35.006751 systemd-journald[193]: Journal started Oct 31 00:36:35.006769 systemd-journald[193]: Runtime Journal (/run/log/journal/51375c6ef37b472992d27876d0ed793f) is 6.0M, max 48.4M, 42.3M free. Oct 31 00:36:34.990358 systemd-modules-load[194]: Inserted module 'overlay' Oct 31 00:36:35.009157 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 31 00:36:35.024119 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Oct 31 00:36:35.025996 systemd-modules-load[194]: Inserted module 'br_netfilter' Oct 31 00:36:35.088332 kernel: Bridge firewalling registered Oct 31 00:36:35.088360 systemd[1]: Started systemd-journald.service - Journal Service. Oct 31 00:36:35.089482 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 31 00:36:35.124196 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 31 00:36:35.139462 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 31 00:36:35.144622 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 31 00:36:35.151467 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 31 00:36:35.152774 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 31 00:36:35.156409 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 31 00:36:35.159252 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Oct 31 00:36:35.164152 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 31 00:36:35.180434 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 31 00:36:35.187246 dracut-cmdline[223]: dracut-dracut-053 Oct 31 00:36:35.192936 dracut-cmdline[223]: Using kernel command line parameters: rd.driver.pre=btrfs rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected verity.usrhash=950876ad7bc3e9634b7585a81697da4ef03ac6558969e5c002165369dd7c7885 Oct 31 00:36:35.192230 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 31 00:36:35.233345 systemd-resolved[235]: Positive Trust Anchors: Oct 31 00:36:35.233366 systemd-resolved[235]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 31 00:36:35.233398 systemd-resolved[235]: 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 Oct 31 00:36:35.236170 systemd-resolved[235]: Defaulting to hostname 'linux'. Oct 31 00:36:35.237663 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 31 00:36:35.250742 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 31 00:36:35.302134 kernel: SCSI subsystem initialized Oct 31 00:36:35.313133 kernel: Loading iSCSI transport class v2.0-870. Oct 31 00:36:35.325117 kernel: iscsi: registered transport (tcp) Oct 31 00:36:35.349395 kernel: iscsi: registered transport (qla4xxx) Oct 31 00:36:35.349484 kernel: QLogic iSCSI HBA Driver Oct 31 00:36:35.407831 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Oct 31 00:36:35.418350 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Oct 31 00:36:35.446952 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Oct 31 00:36:35.447040 kernel: device-mapper: uevent: version 1.0.3 Oct 31 00:36:35.447114 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Oct 31 00:36:35.494147 kernel: raid6: avx2x4 gen() 25545 MB/s Oct 31 00:36:35.511123 kernel: raid6: avx2x2 gen() 30102 MB/s Oct 31 00:36:35.528890 kernel: raid6: avx2x1 gen() 25230 MB/s Oct 31 00:36:35.528938 kernel: raid6: using algorithm avx2x2 gen() 30102 MB/s Oct 31 00:36:35.601610 kernel: raid6: .... xor() 19235 MB/s, rmw enabled Oct 31 00:36:35.601717 kernel: raid6: using avx2x2 recovery algorithm Oct 31 00:36:35.647122 kernel: xor: automatically using best checksumming function avx Oct 31 00:36:35.830146 kernel: Btrfs loaded, zoned=no, fsverity=no Oct 31 00:36:35.844601 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Oct 31 00:36:35.860403 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 31 00:36:35.903471 systemd-udevd[413]: Using default interface naming scheme 'v255'. Oct 31 00:36:35.909165 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 31 00:36:35.911061 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Oct 31 00:36:35.933048 dracut-pre-trigger[415]: rd.md=0: removing MD RAID activation Oct 31 00:36:35.974659 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Oct 31 00:36:35.995286 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 31 00:36:36.082727 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 31 00:36:36.149370 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Oct 31 00:36:36.163724 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Oct 31 00:36:36.168891 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Oct 31 00:36:36.172580 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 31 00:36:36.178085 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 31 00:36:36.188113 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues Oct 31 00:36:36.190344 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Oct 31 00:36:36.255618 kernel: cryptd: max_cpu_qlen set to 1000 Oct 31 00:36:36.255646 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Oct 31 00:36:36.246808 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Oct 31 00:36:36.261108 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 31 00:36:36.268104 kernel: libata version 3.00 loaded. Oct 31 00:36:36.264170 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 31 00:36:36.264300 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 31 00:36:36.272769 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 31 00:36:36.326722 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 31 00:36:36.329830 kernel: ahci 0000:00:1f.2: version 3.0 Oct 31 00:36:36.330130 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 Oct 31 00:36:36.330150 kernel: ahci 0000:00:1f.2: AHCI 0001.0000 32 slots 6 ports 1.5 Gbps 0x3f impl SATA mode Oct 31 00:36:36.330749 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 31 00:36:36.338226 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only Oct 31 00:36:36.338487 kernel: scsi host0: ahci Oct 31 00:36:36.338730 kernel: AVX2 version of gcm_enc/dec engaged. Oct 31 00:36:36.340857 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Oct 31 00:36:36.368072 kernel: scsi host1: ahci Oct 31 00:36:36.368299 kernel: BTRFS: device fsid 1021cdf2-f4a0-46ed-8fe0-b31d3115a6e0 devid 1 transid 37 /dev/vda3 scanned by (udev-worker) (473) Oct 31 00:36:36.368312 kernel: scsi host2: ahci Oct 31 00:36:36.368479 kernel: scsi host3: ahci Oct 31 00:36:36.368653 kernel: AES CTR mode by8 optimization enabled Oct 31 00:36:36.368665 kernel: BTRFS: device label OEM devid 1 transid 9 /dev/vda6 scanned by (udev-worker) (462) Oct 31 00:36:36.368675 kernel: scsi host4: ahci Oct 31 00:36:36.368829 kernel: scsi host5: ahci Oct 31 00:36:36.368979 kernel: ata1: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4100 irq 34 Oct 31 00:36:36.368991 kernel: ata2: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4180 irq 34 Oct 31 00:36:36.369001 kernel: ata3: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4200 irq 34 Oct 31 00:36:36.369011 kernel: ata4: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4280 irq 34 Oct 31 00:36:36.369021 kernel: ata5: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4300 irq 34 Oct 31 00:36:36.369031 kernel: ata6: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4380 irq 34 Oct 31 00:36:36.369489 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 31 00:36:36.396399 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Oct 31 00:36:36.416373 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Oct 31 00:36:36.484854 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 31 00:36:36.490274 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Oct 31 00:36:36.491337 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 31 00:36:36.502175 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Oct 31 00:36:36.567360 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Oct 31 00:36:36.572512 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 31 00:36:36.594783 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 31 00:36:36.658148 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 31 00:36:36.680769 kernel: ata6: SATA link down (SStatus 0 SControl 300) Oct 31 00:36:36.680827 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Oct 31 00:36:36.681106 kernel: ata1: SATA link down (SStatus 0 SControl 300) Oct 31 00:36:36.683151 kernel: ata2: SATA link down (SStatus 0 SControl 300) Oct 31 00:36:36.684112 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 31 00:36:36.686112 kernel: ata5: SATA link down (SStatus 0 SControl 300) Oct 31 00:36:36.687135 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 Oct 31 00:36:36.689020 kernel: ata3.00: applying bridge limits Oct 31 00:36:36.692130 kernel: ata4: SATA link down (SStatus 0 SControl 300) Oct 31 00:36:36.692192 kernel: ata3.00: configured for UDMA/100 Oct 31 00:36:36.697110 kernel: scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 Oct 31 00:36:36.814398 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray Oct 31 00:36:36.814754 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Oct 31 00:36:36.829202 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 Oct 31 00:36:37.692154 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 31 00:36:37.692823 disk-uuid[563]: The operation has completed successfully. Oct 31 00:36:37.726820 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 31 00:36:37.726986 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Oct 31 00:36:37.759403 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Oct 31 00:36:37.763546 sh[590]: Success Oct 31 00:36:37.778131 kernel: device-mapper: verity: sha256 using implementation "sha256-ni" Oct 31 00:36:37.821408 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Oct 31 00:36:37.837483 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Oct 31 00:36:37.840425 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Oct 31 00:36:37.863644 kernel: BTRFS info (device dm-0): first mount of filesystem 1021cdf2-f4a0-46ed-8fe0-b31d3115a6e0 Oct 31 00:36:37.863715 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Oct 31 00:36:37.863731 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Oct 31 00:36:37.865301 kernel: BTRFS info (device dm-0): disabling log replay at mount time Oct 31 00:36:37.867593 kernel: BTRFS info (device dm-0): using free space tree Oct 31 00:36:37.874440 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Oct 31 00:36:37.876173 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Oct 31 00:36:37.885357 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Oct 31 00:36:37.888617 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Oct 31 00:36:37.901249 kernel: BTRFS info (device vda6): first mount of filesystem 1a1fe00d-a5e5-45c6-a30a-fcc91f19f9ea Oct 31 00:36:37.901921 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Oct 31 00:36:37.901944 kernel: BTRFS info (device vda6): using free space tree Oct 31 00:36:37.907144 kernel: BTRFS info (device vda6): auto enabling async discard Oct 31 00:36:37.919168 systemd[1]: mnt-oem.mount: Deactivated successfully. Oct 31 00:36:37.922461 kernel: BTRFS info (device vda6): last unmount of filesystem 1a1fe00d-a5e5-45c6-a30a-fcc91f19f9ea Oct 31 00:36:37.933290 systemd[1]: Finished ignition-setup.service - Ignition (setup). Oct 31 00:36:37.941346 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Oct 31 00:36:38.013637 ignition[684]: Ignition 2.19.0 Oct 31 00:36:38.013654 ignition[684]: Stage: fetch-offline Oct 31 00:36:38.013699 ignition[684]: no configs at "/usr/lib/ignition/base.d" Oct 31 00:36:38.013710 ignition[684]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 31 00:36:38.013808 ignition[684]: parsed url from cmdline: "" Oct 31 00:36:38.013812 ignition[684]: no config URL provided Oct 31 00:36:38.013817 ignition[684]: reading system config file "/usr/lib/ignition/user.ign" Oct 31 00:36:38.013827 ignition[684]: no config at "/usr/lib/ignition/user.ign" Oct 31 00:36:38.013854 ignition[684]: op(1): [started] loading QEMU firmware config module Oct 31 00:36:38.013860 ignition[684]: op(1): executing: "modprobe" "qemu_fw_cfg" Oct 31 00:36:38.022792 ignition[684]: op(1): [finished] loading QEMU firmware config module Oct 31 00:36:38.023865 ignition[684]: parsing config with SHA512: 98ce1535c5317def260b8295072dd75aa82a8b2e22f18451cab1aada10558cf82f2cb4de1bd8458b410a8255fa559abefae00cff18a7dcb2333026bd3b18d333 Oct 31 00:36:38.032304 unknown[684]: fetched base config from "system" Oct 31 00:36:38.032553 ignition[684]: fetch-offline: fetch-offline passed Oct 31 00:36:38.032322 unknown[684]: fetched user config from "qemu" Oct 31 00:36:38.032686 ignition[684]: Ignition finished successfully Oct 31 00:36:38.040768 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Oct 31 00:36:38.069703 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 31 00:36:38.084269 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 31 00:36:38.116860 systemd-networkd[780]: lo: Link UP Oct 31 00:36:38.116875 systemd-networkd[780]: lo: Gained carrier Oct 31 00:36:38.118995 systemd-networkd[780]: Enumeration completed Oct 31 00:36:38.119143 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 31 00:36:38.119537 systemd-networkd[780]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 31 00:36:38.119542 systemd-networkd[780]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 31 00:36:38.121940 systemd[1]: Reached target network.target - Network. Oct 31 00:36:38.121980 systemd-networkd[780]: eth0: Link UP Oct 31 00:36:38.121986 systemd-networkd[780]: eth0: Gained carrier Oct 31 00:36:38.122001 systemd-networkd[780]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 31 00:36:38.124779 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Oct 31 00:36:38.136319 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Oct 31 00:36:38.137312 systemd-networkd[780]: eth0: DHCPv4 address 10.0.0.55/16, gateway 10.0.0.1 acquired from 10.0.0.1 Oct 31 00:36:38.155695 ignition[783]: Ignition 2.19.0 Oct 31 00:36:38.155711 ignition[783]: Stage: kargs Oct 31 00:36:38.155935 ignition[783]: no configs at "/usr/lib/ignition/base.d" Oct 31 00:36:38.155951 ignition[783]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 31 00:36:38.160803 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Oct 31 00:36:38.156758 ignition[783]: kargs: kargs passed Oct 31 00:36:38.156819 ignition[783]: Ignition finished successfully Oct 31 00:36:38.170293 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Oct 31 00:36:38.187610 ignition[792]: Ignition 2.19.0 Oct 31 00:36:38.187625 ignition[792]: Stage: disks Oct 31 00:36:38.187845 ignition[792]: no configs at "/usr/lib/ignition/base.d" Oct 31 00:36:38.187862 ignition[792]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 31 00:36:38.192301 systemd[1]: Finished ignition-disks.service - Ignition (disks). Oct 31 00:36:38.188727 ignition[792]: disks: disks passed Oct 31 00:36:38.194969 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Oct 31 00:36:38.188788 ignition[792]: Ignition finished successfully Oct 31 00:36:38.198775 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Oct 31 00:36:38.201017 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 31 00:36:38.204746 systemd[1]: Reached target sysinit.target - System Initialization. Oct 31 00:36:38.206558 systemd[1]: Reached target basic.target - Basic System. Oct 31 00:36:38.217364 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Oct 31 00:36:38.237713 systemd-fsck[804]: ROOT: clean, 14/553520 files, 52654/553472 blocks Oct 31 00:36:38.245897 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Oct 31 00:36:38.255239 systemd[1]: Mounting sysroot.mount - /sysroot... Oct 31 00:36:38.351140 kernel: EXT4-fs (vda9): mounted filesystem 044ea9d4-3e15-48f6-be3f-240ec74f6b62 r/w with ordered data mode. Quota mode: none. Oct 31 00:36:38.352259 systemd[1]: Mounted sysroot.mount - /sysroot. Oct 31 00:36:38.354621 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Oct 31 00:36:38.370208 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 31 00:36:38.374019 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Oct 31 00:36:38.380377 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/vda6 scanned by mount (812) Oct 31 00:36:38.377388 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Oct 31 00:36:38.391117 kernel: BTRFS info (device vda6): first mount of filesystem 1a1fe00d-a5e5-45c6-a30a-fcc91f19f9ea Oct 31 00:36:38.391151 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Oct 31 00:36:38.391166 kernel: BTRFS info (device vda6): using free space tree Oct 31 00:36:38.377471 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Oct 31 00:36:38.398771 kernel: BTRFS info (device vda6): auto enabling async discard Oct 31 00:36:38.377522 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Oct 31 00:36:38.393436 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Oct 31 00:36:38.401607 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 31 00:36:38.417325 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Oct 31 00:36:38.515881 initrd-setup-root[836]: cut: /sysroot/etc/passwd: No such file or directory Oct 31 00:36:38.522111 initrd-setup-root[843]: cut: /sysroot/etc/group: No such file or directory Oct 31 00:36:38.528145 initrd-setup-root[850]: cut: /sysroot/etc/shadow: No such file or directory Oct 31 00:36:38.533717 initrd-setup-root[857]: cut: /sysroot/etc/gshadow: No such file or directory Oct 31 00:36:38.638318 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Oct 31 00:36:38.651208 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Oct 31 00:36:38.652904 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Oct 31 00:36:38.672139 kernel: BTRFS info (device vda6): last unmount of filesystem 1a1fe00d-a5e5-45c6-a30a-fcc91f19f9ea Oct 31 00:36:38.688598 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Oct 31 00:36:38.705423 ignition[928]: INFO : Ignition 2.19.0 Oct 31 00:36:38.705423 ignition[928]: INFO : Stage: mount Oct 31 00:36:38.708265 ignition[928]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 31 00:36:38.708265 ignition[928]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 31 00:36:38.711994 ignition[928]: INFO : mount: mount passed Oct 31 00:36:38.713329 ignition[928]: INFO : Ignition finished successfully Oct 31 00:36:38.717143 systemd[1]: Finished ignition-mount.service - Ignition (mount). Oct 31 00:36:38.734228 systemd[1]: Starting ignition-files.service - Ignition (files)... Oct 31 00:36:38.861988 systemd[1]: sysroot-oem.mount: Deactivated successfully. Oct 31 00:36:38.878296 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 31 00:36:39.254139 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 scanned by mount (939) Oct 31 00:36:39.259156 kernel: BTRFS info (device vda6): first mount of filesystem 1a1fe00d-a5e5-45c6-a30a-fcc91f19f9ea Oct 31 00:36:39.259249 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Oct 31 00:36:39.259263 kernel: BTRFS info (device vda6): using free space tree Oct 31 00:36:39.265138 kernel: BTRFS info (device vda6): auto enabling async discard Oct 31 00:36:39.267123 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 31 00:36:39.317454 ignition[956]: INFO : Ignition 2.19.0 Oct 31 00:36:39.317454 ignition[956]: INFO : Stage: files Oct 31 00:36:39.320700 ignition[956]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 31 00:36:39.320700 ignition[956]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 31 00:36:39.320700 ignition[956]: DEBUG : files: compiled without relabeling support, skipping Oct 31 00:36:39.326810 ignition[956]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Oct 31 00:36:39.326810 ignition[956]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Oct 31 00:36:39.326810 ignition[956]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Oct 31 00:36:39.334305 ignition[956]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Oct 31 00:36:39.334305 ignition[956]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Oct 31 00:36:39.334305 ignition[956]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Oct 31 00:36:39.334305 ignition[956]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Oct 31 00:36:39.334305 ignition[956]: INFO : files: op(4): [started] processing unit "etcd-member.service" Oct 31 00:36:39.334305 ignition[956]: 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" Oct 31 00:36:39.334305 ignition[956]: 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" Oct 31 00:36:39.334305 ignition[956]: INFO : files: op(4): [finished] processing unit "etcd-member.service" Oct 31 00:36:39.334305 ignition[956]: INFO : files: op(6): [started] processing unit "coreos-metadata.service" Oct 31 00:36:39.334305 ignition[956]: INFO : files: op(6): op(7): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Oct 31 00:36:39.334305 ignition[956]: INFO : files: op(6): op(7): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Oct 31 00:36:39.334305 ignition[956]: INFO : files: op(6): [finished] processing unit "coreos-metadata.service" Oct 31 00:36:39.334305 ignition[956]: INFO : files: op(8): [started] setting preset to disabled for "coreos-metadata.service" Oct 31 00:36:39.327732 unknown[956]: wrote ssh authorized keys file for user: core Oct 31 00:36:39.577120 ignition[956]: INFO : files: op(8): op(9): [started] removing enablement symlink(s) for "coreos-metadata.service" Oct 31 00:36:39.584456 ignition[956]: INFO : files: op(8): op(9): [finished] removing enablement symlink(s) for "coreos-metadata.service" Oct 31 00:36:39.588793 ignition[956]: INFO : files: op(8): [finished] setting preset to disabled for "coreos-metadata.service" Oct 31 00:36:39.588793 ignition[956]: INFO : files: op(a): [started] setting preset to enabled for "etcd-member.service" Oct 31 00:36:39.588793 ignition[956]: INFO : files: op(a): [finished] setting preset to enabled for "etcd-member.service" Oct 31 00:36:39.588793 ignition[956]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Oct 31 00:36:39.588793 ignition[956]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Oct 31 00:36:39.588793 ignition[956]: INFO : files: files passed Oct 31 00:36:39.588793 ignition[956]: INFO : Ignition finished successfully Oct 31 00:36:39.586937 systemd[1]: Finished ignition-files.service - Ignition (files). Oct 31 00:36:39.596276 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Oct 31 00:36:39.601172 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Oct 31 00:36:39.605484 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 31 00:36:39.617786 initrd-setup-root-after-ignition[984]: grep: /sysroot/oem/oem-release: No such file or directory Oct 31 00:36:39.605607 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Oct 31 00:36:39.626135 initrd-setup-root-after-ignition[986]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 31 00:36:39.626135 initrd-setup-root-after-ignition[986]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Oct 31 00:36:39.618005 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 31 00:36:39.633453 initrd-setup-root-after-ignition[991]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 31 00:36:39.623054 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Oct 31 00:36:39.637316 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Oct 31 00:36:39.665369 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 31 00:36:39.665511 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Oct 31 00:36:39.669470 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Oct 31 00:36:39.672966 systemd[1]: Reached target initrd.target - Initrd Default Target. Oct 31 00:36:39.676329 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Oct 31 00:36:39.694302 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Oct 31 00:36:39.711649 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 31 00:36:39.725270 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Oct 31 00:36:39.734727 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Oct 31 00:36:39.736867 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 31 00:36:39.738948 systemd[1]: Stopped target timers.target - Timer Units. Oct 31 00:36:39.742271 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 31 00:36:39.742414 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 31 00:36:39.746523 systemd[1]: Stopped target initrd.target - Initrd Default Target. Oct 31 00:36:39.749399 systemd[1]: Stopped target basic.target - Basic System. Oct 31 00:36:39.752467 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Oct 31 00:36:39.756147 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Oct 31 00:36:39.759685 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Oct 31 00:36:39.762954 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Oct 31 00:36:39.766392 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Oct 31 00:36:39.770517 systemd[1]: Stopped target sysinit.target - System Initialization. Oct 31 00:36:39.774385 systemd[1]: Stopped target local-fs.target - Local File Systems. Oct 31 00:36:39.777938 systemd[1]: Stopped target swap.target - Swaps. Oct 31 00:36:39.781839 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 31 00:36:39.782027 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Oct 31 00:36:39.786139 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Oct 31 00:36:39.789582 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 31 00:36:39.793336 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Oct 31 00:36:39.793553 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 31 00:36:39.795805 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 31 00:36:39.795922 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Oct 31 00:36:39.796775 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Oct 31 00:36:39.796893 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Oct 31 00:36:39.797206 systemd[1]: Stopped target paths.target - Path Units. Oct 31 00:36:39.797421 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 31 00:36:39.801199 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 31 00:36:39.801629 systemd[1]: Stopped target slices.target - Slice Units. Oct 31 00:36:39.801902 systemd[1]: Stopped target sockets.target - Socket Units. Oct 31 00:36:39.802214 systemd[1]: iscsid.socket: Deactivated successfully. Oct 31 00:36:39.852340 ignition[1011]: INFO : Ignition 2.19.0 Oct 31 00:36:39.852340 ignition[1011]: INFO : Stage: umount Oct 31 00:36:39.852340 ignition[1011]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 31 00:36:39.852340 ignition[1011]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 31 00:36:39.852340 ignition[1011]: INFO : umount: umount passed Oct 31 00:36:39.852340 ignition[1011]: INFO : Ignition finished successfully Oct 31 00:36:39.802326 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Oct 31 00:36:39.802506 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 31 00:36:39.802600 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 31 00:36:39.802805 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Oct 31 00:36:39.802923 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 31 00:36:39.803065 systemd[1]: ignition-files.service: Deactivated successfully. Oct 31 00:36:39.803212 systemd[1]: Stopped ignition-files.service - Ignition (files). Oct 31 00:36:39.824336 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Oct 31 00:36:39.827287 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Oct 31 00:36:39.829859 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 31 00:36:39.830026 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Oct 31 00:36:39.833042 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 31 00:36:39.833242 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Oct 31 00:36:39.842467 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 31 00:36:39.842644 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Oct 31 00:36:39.853804 systemd[1]: ignition-mount.service: Deactivated successfully. Oct 31 00:36:39.853969 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Oct 31 00:36:39.857011 systemd[1]: Stopped target network.target - Network. Oct 31 00:36:39.860001 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 31 00:36:39.860081 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Oct 31 00:36:39.864595 systemd[1]: ignition-kargs.service: Deactivated successfully. Oct 31 00:36:39.864688 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Oct 31 00:36:39.867046 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 31 00:36:39.867127 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Oct 31 00:36:39.870284 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Oct 31 00:36:39.870350 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Oct 31 00:36:39.912144 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Oct 31 00:36:39.915132 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Oct 31 00:36:39.919169 systemd-networkd[780]: eth0: DHCPv6 lease lost Oct 31 00:36:39.919432 systemd[1]: sysroot-boot.mount: Deactivated successfully. Oct 31 00:36:39.920051 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 31 00:36:39.920202 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Oct 31 00:36:39.923171 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 31 00:36:39.923288 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Oct 31 00:36:39.926945 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 31 00:36:39.927197 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Oct 31 00:36:39.931921 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 31 00:36:39.932274 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Oct 31 00:36:39.937732 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 31 00:36:39.937832 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Oct 31 00:36:39.954441 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Oct 31 00:36:39.956515 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Oct 31 00:36:39.956613 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 31 00:36:39.960378 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 31 00:36:39.960445 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Oct 31 00:36:39.964154 systemd[1]: systemd-modules-load.service: Deactivated successfully. Oct 31 00:36:39.964231 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Oct 31 00:36:39.967909 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 31 00:36:39.967976 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 31 00:36:39.972044 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 31 00:36:39.986109 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 31 00:36:39.986328 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Oct 31 00:36:40.040313 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 31 00:36:40.040588 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 31 00:36:40.043467 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 31 00:36:40.043542 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Oct 31 00:36:40.046665 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 31 00:36:40.046725 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Oct 31 00:36:40.050683 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 31 00:36:40.050758 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Oct 31 00:36:40.054654 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 31 00:36:40.054712 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Oct 31 00:36:40.058356 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 31 00:36:40.058421 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 31 00:36:40.072251 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Oct 31 00:36:40.075631 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 31 00:36:40.075709 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 31 00:36:40.079432 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Oct 31 00:36:40.079494 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 31 00:36:40.083494 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 31 00:36:40.083562 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Oct 31 00:36:40.087569 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 31 00:36:40.087648 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 31 00:36:40.092135 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 31 00:36:40.092296 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Oct 31 00:36:40.096528 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Oct 31 00:36:40.125584 systemd[1]: Starting initrd-switch-root.service - Switch Root... Oct 31 00:36:40.137600 systemd[1]: Switching root. Oct 31 00:36:40.182694 systemd-journald[193]: Journal stopped Oct 31 00:36:41.460025 systemd-journald[193]: Received SIGTERM from PID 1 (systemd). Oct 31 00:36:41.460130 kernel: SELinux: policy capability network_peer_controls=1 Oct 31 00:36:41.460165 kernel: SELinux: policy capability open_perms=1 Oct 31 00:36:41.460181 kernel: SELinux: policy capability extended_socket_class=1 Oct 31 00:36:41.460202 kernel: SELinux: policy capability always_check_network=0 Oct 31 00:36:41.460218 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 31 00:36:41.460237 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 31 00:36:41.460252 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 31 00:36:41.460272 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Oct 31 00:36:41.460288 kernel: audit: type=1403 audit(1761871000.424:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 31 00:36:41.460312 systemd[1]: Successfully loaded SELinux policy in 49.013ms. Oct 31 00:36:41.460342 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 22.881ms. Oct 31 00:36:41.460360 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Oct 31 00:36:41.460377 systemd[1]: Detected virtualization kvm. Oct 31 00:36:41.460393 systemd[1]: Detected architecture x86-64. Oct 31 00:36:41.460408 systemd[1]: Detected first boot. Oct 31 00:36:41.460428 systemd[1]: Initializing machine ID from VM UUID. Oct 31 00:36:41.460444 zram_generator::config[1056]: No configuration found. Oct 31 00:36:41.460461 systemd[1]: Populated /etc with preset unit settings. Oct 31 00:36:41.460477 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 31 00:36:41.460493 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Oct 31 00:36:41.460509 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 31 00:36:41.460527 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Oct 31 00:36:41.460544 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Oct 31 00:36:41.460560 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Oct 31 00:36:41.460581 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Oct 31 00:36:41.460597 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Oct 31 00:36:41.460615 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Oct 31 00:36:41.460631 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Oct 31 00:36:41.460647 systemd[1]: Created slice user.slice - User and Session Slice. Oct 31 00:36:41.460667 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 31 00:36:41.460685 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 31 00:36:41.460706 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Oct 31 00:36:41.460723 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Oct 31 00:36:41.460740 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Oct 31 00:36:41.460758 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 31 00:36:41.460775 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Oct 31 00:36:41.460793 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 31 00:36:41.460809 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Oct 31 00:36:41.460831 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Oct 31 00:36:41.460849 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Oct 31 00:36:41.460865 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Oct 31 00:36:41.460882 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 31 00:36:41.460899 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 31 00:36:41.460916 systemd[1]: Reached target slices.target - Slice Units. Oct 31 00:36:41.460933 systemd[1]: Reached target swap.target - Swaps. Oct 31 00:36:41.460949 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Oct 31 00:36:41.460970 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Oct 31 00:36:41.460989 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 31 00:36:41.461007 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 31 00:36:41.461023 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 31 00:36:41.461048 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Oct 31 00:36:41.461065 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Oct 31 00:36:41.461098 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Oct 31 00:36:41.461118 systemd[1]: Mounting media.mount - External Media Directory... Oct 31 00:36:41.461146 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 31 00:36:41.461169 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Oct 31 00:36:41.461186 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Oct 31 00:36:41.461204 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Oct 31 00:36:41.461221 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). Oct 31 00:36:41.461238 systemd[1]: Reached target machines.target - Containers. Oct 31 00:36:41.461254 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Oct 31 00:36:41.461271 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 31 00:36:41.461288 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 31 00:36:41.461310 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Oct 31 00:36:41.461327 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 31 00:36:41.461344 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 31 00:36:41.461361 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 31 00:36:41.461378 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Oct 31 00:36:41.461397 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 31 00:36:41.461415 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Oct 31 00:36:41.461439 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 31 00:36:41.461456 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Oct 31 00:36:41.461478 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 31 00:36:41.461495 systemd[1]: Stopped systemd-fsck-usr.service. Oct 31 00:36:41.461512 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 31 00:36:41.461529 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 31 00:36:41.461572 systemd-journald[1119]: Collecting audit messages is disabled. Oct 31 00:36:41.461603 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 31 00:36:41.461619 systemd-journald[1119]: Journal started Oct 31 00:36:41.461652 systemd-journald[1119]: Runtime Journal (/run/log/journal/51375c6ef37b472992d27876d0ed793f) is 6.0M, max 48.4M, 42.3M free. Oct 31 00:36:41.010582 systemd[1]: Queued start job for default target multi-user.target. Oct 31 00:36:41.032048 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Oct 31 00:36:41.032703 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 31 00:36:41.033279 systemd[1]: systemd-journald.service: Consumed 1.299s CPU time. Oct 31 00:36:41.464213 kernel: fuse: init (API version 7.39) Oct 31 00:36:41.464245 kernel: loop: module loaded Oct 31 00:36:41.471722 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Oct 31 00:36:41.477269 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 31 00:36:41.480124 systemd[1]: verity-setup.service: Deactivated successfully. Oct 31 00:36:41.483120 systemd[1]: Stopped verity-setup.service. Oct 31 00:36:41.487125 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 31 00:36:41.491374 systemd[1]: Started systemd-journald.service - Journal Service. Oct 31 00:36:41.492368 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Oct 31 00:36:41.494415 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Oct 31 00:36:41.496455 systemd[1]: Mounted media.mount - External Media Directory. Oct 31 00:36:41.498290 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Oct 31 00:36:41.500306 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Oct 31 00:36:41.502406 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Oct 31 00:36:41.504486 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 31 00:36:41.507046 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 31 00:36:41.507329 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Oct 31 00:36:41.509679 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 31 00:36:41.509916 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 31 00:36:41.512972 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 31 00:36:41.513420 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 31 00:36:41.516143 kernel: ACPI: bus type drm_connector registered Oct 31 00:36:41.516874 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 31 00:36:41.517064 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 31 00:36:41.519268 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 31 00:36:41.519470 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Oct 31 00:36:41.521637 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 31 00:36:41.521818 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 31 00:36:41.524517 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 31 00:36:41.526768 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 31 00:36:41.529233 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Oct 31 00:36:41.546335 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 31 00:36:41.554246 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Oct 31 00:36:41.558306 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Oct 31 00:36:41.560370 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Oct 31 00:36:41.560410 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 31 00:36:41.563317 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Oct 31 00:36:41.573339 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Oct 31 00:36:41.579175 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Oct 31 00:36:41.581280 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 31 00:36:41.584313 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Oct 31 00:36:41.588519 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Oct 31 00:36:41.591334 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 31 00:36:41.598515 systemd-journald[1119]: Time spent on flushing to /var/log/journal/51375c6ef37b472992d27876d0ed793f is 23.685ms for 918 entries. Oct 31 00:36:41.598515 systemd-journald[1119]: System Journal (/var/log/journal/51375c6ef37b472992d27876d0ed793f) is 8.0M, max 195.6M, 187.6M free. Oct 31 00:36:41.643977 systemd-journald[1119]: Received client request to flush runtime journal. Oct 31 00:36:41.599250 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Oct 31 00:36:41.602857 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 31 00:36:41.605262 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 31 00:36:41.614289 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Oct 31 00:36:41.618570 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 31 00:36:41.624210 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Oct 31 00:36:41.626925 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 31 00:36:41.629551 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Oct 31 00:36:41.632344 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Oct 31 00:36:41.660208 kernel: loop0: detected capacity change from 0 to 140768 Oct 31 00:36:41.657483 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Oct 31 00:36:41.663279 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Oct 31 00:36:41.666124 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Oct 31 00:36:41.701550 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Oct 31 00:36:41.806921 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Oct 31 00:36:41.827452 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Oct 31 00:36:41.832150 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 31 00:36:41.837116 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 31 00:36:41.859270 udevadm[1185]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Oct 31 00:36:41.889304 systemd-tmpfiles[1170]: ACLs are not supported, ignoring. Oct 31 00:36:41.889330 systemd-tmpfiles[1170]: ACLs are not supported, ignoring. Oct 31 00:36:41.900949 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 31 00:36:41.916364 systemd[1]: Starting systemd-sysusers.service - Create System Users... Oct 31 00:36:41.933165 kernel: loop1: detected capacity change from 0 to 142488 Oct 31 00:36:41.954480 systemd[1]: Finished systemd-sysusers.service - Create System Users. Oct 31 00:36:41.972281 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 31 00:36:41.976142 kernel: loop2: detected capacity change from 0 to 140768 Oct 31 00:36:42.001585 systemd-tmpfiles[1192]: ACLs are not supported, ignoring. Oct 31 00:36:42.002058 systemd-tmpfiles[1192]: ACLs are not supported, ignoring. Oct 31 00:36:42.038465 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 31 00:36:42.047149 kernel: loop3: detected capacity change from 0 to 142488 Oct 31 00:36:42.105972 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 31 00:36:42.106989 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Oct 31 00:36:42.114379 (sd-merge)[1193]: Using extensions 'containerd-flatcar', 'docker-flatcar'. Oct 31 00:36:42.114991 (sd-merge)[1193]: Merged extensions into '/usr'. Oct 31 00:36:42.120787 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Oct 31 00:36:42.153478 systemd[1]: Starting ensure-sysext.service... Oct 31 00:36:42.157546 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 31 00:36:42.167007 systemd[1]: Reloading requested from client PID 1198 ('systemctl') (unit ensure-sysext.service)... Oct 31 00:36:42.167024 systemd[1]: Reloading... Oct 31 00:36:42.295135 zram_generator::config[1223]: No configuration found. Oct 31 00:36:42.318688 systemd-tmpfiles[1199]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Oct 31 00:36:42.319082 systemd-tmpfiles[1199]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Oct 31 00:36:42.320188 systemd-tmpfiles[1199]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Oct 31 00:36:42.320537 systemd-tmpfiles[1199]: ACLs are not supported, ignoring. Oct 31 00:36:42.320617 systemd-tmpfiles[1199]: ACLs are not supported, ignoring. Oct 31 00:36:42.328824 systemd-tmpfiles[1199]: Detected autofs mount point /boot during canonicalization of boot. Oct 31 00:36:42.329026 systemd-tmpfiles[1199]: Skipping /boot Oct 31 00:36:42.408983 systemd-tmpfiles[1199]: Detected autofs mount point /boot during canonicalization of boot. Oct 31 00:36:42.409000 systemd-tmpfiles[1199]: Skipping /boot Oct 31 00:36:42.495689 ldconfig[1157]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 31 00:36:42.501648 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Oct 31 00:36:42.553955 systemd[1]: Reloading finished in 386 ms. Oct 31 00:36:42.608052 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 31 00:36:42.630406 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Oct 31 00:36:42.634222 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Oct 31 00:36:42.637702 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Oct 31 00:36:42.646336 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 31 00:36:42.651346 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Oct 31 00:36:42.656937 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 31 00:36:42.657162 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 31 00:36:42.658524 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 31 00:36:42.661577 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 31 00:36:42.678015 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 31 00:36:42.679995 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 31 00:36:42.681961 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Oct 31 00:36:42.683783 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 31 00:36:42.684858 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 31 00:36:42.685050 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 31 00:36:42.687578 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 31 00:36:42.687765 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 31 00:36:42.701430 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 31 00:36:42.701647 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 31 00:36:42.708083 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 31 00:36:42.708349 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 31 00:36:42.723522 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 31 00:36:42.740962 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 31 00:36:42.751079 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 31 00:36:42.765367 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 31 00:36:42.765682 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 31 00:36:42.768564 systemd[1]: Started systemd-userdbd.service - User Database Manager. Oct 31 00:36:42.771135 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 31 00:36:42.771375 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 31 00:36:42.775966 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 31 00:36:42.776327 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 31 00:36:42.786490 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 31 00:36:42.786758 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 31 00:36:42.797593 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 31 00:36:42.803035 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 31 00:36:42.806552 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 31 00:36:42.808432 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 31 00:36:42.808860 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 31 00:36:42.810014 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Oct 31 00:36:42.812605 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 31 00:36:42.812788 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 31 00:36:42.817397 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 31 00:36:42.817617 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 31 00:36:42.820446 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 31 00:36:42.820631 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 31 00:36:42.825498 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Oct 31 00:36:42.844567 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 31 00:36:42.844771 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 31 00:36:42.861472 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Oct 31 00:36:42.867784 systemd[1]: Finished ensure-sysext.service. Oct 31 00:36:42.872556 systemd-resolved[1268]: Positive Trust Anchors: Oct 31 00:36:42.872845 systemd-resolved[1268]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 31 00:36:42.872940 systemd-resolved[1268]: 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 Oct 31 00:36:42.876849 systemd-resolved[1268]: Defaulting to hostname 'linux'. Oct 31 00:36:42.880351 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 31 00:36:42.881595 augenrules[1314]: No rules Oct 31 00:36:42.909529 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Oct 31 00:36:42.913144 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 31 00:36:42.915694 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 31 00:36:42.915777 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 31 00:36:42.924393 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Oct 31 00:36:42.939305 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Oct 31 00:36:42.942281 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). Oct 31 00:36:42.952565 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Oct 31 00:36:42.958122 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 31 00:36:42.962808 systemd[1]: Starting systemd-update-done.service - Update is Completed... Oct 31 00:36:42.983666 systemd[1]: Finished systemd-update-done.service - Update is Completed. Oct 31 00:36:43.000623 systemd-udevd[1323]: Using default interface naming scheme 'v255'. Oct 31 00:36:43.016152 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Oct 31 00:36:43.019252 systemd[1]: Reached target time-set.target - System Time Set. Oct 31 00:36:43.029488 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 31 00:36:43.042175 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 31 00:36:43.078145 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 37 scanned by (udev-worker) (1328) Oct 31 00:36:43.133882 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Oct 31 00:36:43.141075 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Oct 31 00:36:43.163272 systemd-networkd[1332]: lo: Link UP Oct 31 00:36:43.163294 systemd-networkd[1332]: lo: Gained carrier Oct 31 00:36:43.165693 systemd-networkd[1332]: Enumeration completed Oct 31 00:36:43.166367 systemd-networkd[1332]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 31 00:36:43.166380 systemd-networkd[1332]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 31 00:36:43.167417 systemd-networkd[1332]: eth0: Link UP Oct 31 00:36:43.167430 systemd-networkd[1332]: eth0: Gained carrier Oct 31 00:36:43.167446 systemd-networkd[1332]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 31 00:36:43.389692 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Oct 31 00:36:43.391239 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Oct 31 00:36:43.392238 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 Oct 31 00:36:43.398345 kernel: i2c i2c-0: 1/1 memory slots populated (from DMI) Oct 31 00:36:43.398740 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Oct 31 00:36:43.398941 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 31 00:36:43.400200 systemd-networkd[1332]: eth0: DHCPv4 address 10.0.0.55/16, gateway 10.0.0.1 acquired from 10.0.0.1 Oct 31 00:36:43.402374 systemd-timesyncd[1320]: Network configuration changed, trying to establish connection. Oct 31 00:36:43.406328 systemd[1]: Reached target network.target - Network. Oct 31 00:36:43.411133 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 Oct 31 00:36:43.411194 kernel: mousedev: PS/2 mouse device common for all mice Oct 31 00:36:43.415681 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Oct 31 00:36:43.419201 kernel: ACPI: button: Power Button [PWRF] Oct 31 00:36:43.422122 systemd-networkd[1332]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 31 00:36:43.423251 systemd-timesyncd[1320]: Contacted time server 10.0.0.1:123 (10.0.0.1). Oct 31 00:36:43.423310 systemd-timesyncd[1320]: Initial clock synchronization to Fri 2025-10-31 00:36:43.407136 UTC. Oct 31 00:36:43.437165 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 31 00:36:43.439854 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Oct 31 00:36:43.799205 kernel: kvm_amd: TSC scaling supported Oct 31 00:36:43.799279 kernel: kvm_amd: Nested Virtualization enabled Oct 31 00:36:43.799293 kernel: kvm_amd: Nested Paging enabled Oct 31 00:36:43.801732 kernel: kvm_amd: LBR virtualization supported Oct 31 00:36:43.801765 kernel: kvm_amd: Virtual VMLOAD VMSAVE supported Oct 31 00:36:43.803146 kernel: kvm_amd: Virtual GIF supported Oct 31 00:36:43.826137 kernel: EDAC MC: Ver: 3.0.0 Oct 31 00:36:43.863618 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Oct 31 00:36:43.902726 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 31 00:36:43.917255 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Oct 31 00:36:43.926908 lvm[1370]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 31 00:36:43.966826 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Oct 31 00:36:43.969410 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 31 00:36:43.971260 systemd[1]: Reached target sysinit.target - System Initialization. Oct 31 00:36:43.973141 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Oct 31 00:36:43.975237 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 31 00:36:43.977528 systemd[1]: Started logrotate.timer - Daily rotation of log files. Oct 31 00:36:43.979409 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Oct 31 00:36:43.981637 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 31 00:36:43.983691 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Oct 31 00:36:43.983726 systemd[1]: Reached target paths.target - Path Units. Oct 31 00:36:43.985235 systemd[1]: Reached target timers.target - Timer Units. Oct 31 00:36:43.987647 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Oct 31 00:36:43.991193 systemd[1]: Starting docker.socket - Docker Socket for the API... Oct 31 00:36:44.005936 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Oct 31 00:36:44.009205 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Oct 31 00:36:44.011953 systemd[1]: Listening on docker.socket - Docker Socket for the API. Oct 31 00:36:44.013850 systemd[1]: Reached target sockets.target - Socket Units. Oct 31 00:36:44.014502 systemd[1]: Reached target basic.target - Basic System. Oct 31 00:36:44.016907 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Oct 31 00:36:44.016940 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Oct 31 00:36:44.018288 systemd[1]: Starting containerd.service - containerd container runtime... Oct 31 00:36:44.021065 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Oct 31 00:36:44.021822 lvm[1374]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 31 00:36:44.024191 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Oct 31 00:36:44.031545 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Oct 31 00:36:44.033693 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). Oct 31 00:36:44.037260 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Oct 31 00:36:44.039169 jq[1377]: false Oct 31 00:36:44.040377 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Oct 31 00:36:44.044414 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Oct 31 00:36:44.054153 extend-filesystems[1378]: Found loop2 Oct 31 00:36:44.054153 extend-filesystems[1378]: Found loop3 Oct 31 00:36:44.054153 extend-filesystems[1378]: Found sr0 Oct 31 00:36:44.054153 extend-filesystems[1378]: Found vda Oct 31 00:36:44.054153 extend-filesystems[1378]: Found vda1 Oct 31 00:36:44.054153 extend-filesystems[1378]: Found vda2 Oct 31 00:36:44.054153 extend-filesystems[1378]: Found vda3 Oct 31 00:36:44.054153 extend-filesystems[1378]: Found usr Oct 31 00:36:44.054153 extend-filesystems[1378]: Found vda4 Oct 31 00:36:44.054153 extend-filesystems[1378]: Found vda6 Oct 31 00:36:44.054153 extend-filesystems[1378]: Found vda7 Oct 31 00:36:44.054153 extend-filesystems[1378]: Found vda9 Oct 31 00:36:44.054153 extend-filesystems[1378]: Checking size of /dev/vda9 Oct 31 00:36:44.085696 extend-filesystems[1378]: Old size kept for /dev/vda9 Oct 31 00:36:44.059874 dbus-daemon[1376]: [system] SELinux support is enabled Oct 31 00:36:44.060272 systemd[1]: Starting systemd-logind.service - User Login Management... Oct 31 00:36:44.062556 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Oct 31 00:36:44.063034 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 31 00:36:44.065951 systemd[1]: Starting update-engine.service - Update Engine... Oct 31 00:36:44.082207 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Oct 31 00:36:44.088697 systemd[1]: Started dbus.service - D-Bus System Message Bus. Oct 31 00:36:44.094674 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Oct 31 00:36:44.103476 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Oct 31 00:36:44.103691 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Oct 31 00:36:44.104024 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 31 00:36:44.104250 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Oct 31 00:36:44.106562 systemd[1]: motdgen.service: Deactivated successfully. Oct 31 00:36:44.106780 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Oct 31 00:36:44.108932 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 31 00:36:44.108997 update_engine[1391]: I20251031 00:36:44.108888 1391 main.cc:92] Flatcar Update Engine starting Oct 31 00:36:44.109307 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Oct 31 00:36:44.110450 update_engine[1391]: I20251031 00:36:44.110417 1391 update_check_scheduler.cc:74] Next update check in 9m7s Oct 31 00:36:44.114128 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 37 scanned by (udev-worker) (1328) Oct 31 00:36:44.117131 jq[1396]: true Oct 31 00:36:44.122016 (ntainerd)[1400]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Oct 31 00:36:44.128785 jq[1401]: true Oct 31 00:36:44.134787 systemd-logind[1385]: Watching system buttons on /dev/input/event2 (Power Button) Oct 31 00:36:44.134822 systemd-logind[1385]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Oct 31 00:36:44.137254 systemd-logind[1385]: New seat seat0. Oct 31 00:36:44.140575 systemd[1]: Started systemd-logind.service - User Login Management. Oct 31 00:36:44.158826 systemd[1]: Started update-engine.service - Update Engine. Oct 31 00:36:44.164386 sshd_keygen[1398]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Oct 31 00:36:44.175634 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). Oct 31 00:36:44.175820 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Oct 31 00:36:44.177989 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). Oct 31 00:36:44.178501 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Oct 31 00:36:44.189506 systemd[1]: Started locksmithd.service - Cluster reboot manager. Oct 31 00:36:44.195748 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Oct 31 00:36:44.200250 systemd[1]: Starting issuegen.service - Generate /run/issue... Oct 31 00:36:44.214142 systemd[1]: issuegen.service: Deactivated successfully. Oct 31 00:36:44.214363 systemd[1]: Finished issuegen.service - Generate /run/issue. Oct 31 00:36:44.217597 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Oct 31 00:36:44.244202 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Oct 31 00:36:44.245112 bash[1432]: Updated "/home/core/.ssh/authorized_keys" Oct 31 00:36:44.247118 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Oct 31 00:36:44.283767 systemd[1]: Started getty@tty1.service - Getty on tty1. Oct 31 00:36:44.287174 locksmithd[1431]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 31 00:36:44.288148 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Oct 31 00:36:44.290484 systemd[1]: Reached target getty.target - Login Prompts. Oct 31 00:36:44.292734 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Oct 31 00:36:44.516312 systemd-networkd[1332]: eth0: Gained IPv6LL Oct 31 00:36:44.520678 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Oct 31 00:36:44.523625 systemd[1]: Reached target network-online.target - Network is Online. Oct 31 00:36:44.540443 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Oct 31 00:36:44.543734 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Oct 31 00:36:44.566807 systemd[1]: coreos-metadata.service: Deactivated successfully. Oct 31 00:36:44.567078 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Oct 31 00:36:44.599445 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Oct 31 00:36:44.687680 systemd[1]: Starting etcd-member.service - etcd (System Application Container)... Oct 31 00:36:44.687911 (-wrapper)[1469]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS Oct 31 00:36:44.700432 containerd[1400]: time="2025-10-31T00:36:44.700321894Z" level=info msg="starting containerd" revision=174e0d1785eeda18dc2beba45e1d5a188771636b version=v1.7.21 Oct 31 00:36:44.723430 containerd[1400]: time="2025-10-31T00:36:44.723371418Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Oct 31 00:36:44.725505 containerd[1400]: time="2025-10-31T00:36:44.725451701Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.113-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Oct 31 00:36:44.725505 containerd[1400]: time="2025-10-31T00:36:44.725481562Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Oct 31 00:36:44.725505 containerd[1400]: time="2025-10-31T00:36:44.725496222Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Oct 31 00:36:44.725748 containerd[1400]: time="2025-10-31T00:36:44.725713972Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Oct 31 00:36:44.725748 containerd[1400]: time="2025-10-31T00:36:44.725735571Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Oct 31 00:36:44.725857 containerd[1400]: time="2025-10-31T00:36:44.725835589Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Oct 31 00:36:44.725857 containerd[1400]: time="2025-10-31T00:36:44.725852953Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Oct 31 00:36:44.726123 containerd[1400]: time="2025-10-31T00:36:44.726068950Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Oct 31 00:36:44.726123 containerd[1400]: time="2025-10-31T00:36:44.726108555Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Oct 31 00:36:44.726185 containerd[1400]: time="2025-10-31T00:36:44.726128623Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Oct 31 00:36:44.726185 containerd[1400]: time="2025-10-31T00:36:44.726143132Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Oct 31 00:36:44.726280 containerd[1400]: time="2025-10-31T00:36:44.726257970Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Oct 31 00:36:44.726587 containerd[1400]: time="2025-10-31T00:36:44.726549221Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Oct 31 00:36:44.726739 containerd[1400]: time="2025-10-31T00:36:44.726702902Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Oct 31 00:36:44.726739 containerd[1400]: time="2025-10-31T00:36:44.726723010Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Oct 31 00:36:44.726859 containerd[1400]: time="2025-10-31T00:36:44.726838649Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Oct 31 00:36:44.726927 containerd[1400]: time="2025-10-31T00:36:44.726907995Z" level=info msg="metadata content store policy set" policy=shared Oct 31 00:36:44.754432 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Oct 31 00:36:44.806241 containerd[1400]: time="2025-10-31T00:36:44.806067262Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Oct 31 00:36:44.806241 containerd[1400]: time="2025-10-31T00:36:44.806176022Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Oct 31 00:36:44.806241 containerd[1400]: time="2025-10-31T00:36:44.806195890Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Oct 31 00:36:44.806241 containerd[1400]: time="2025-10-31T00:36:44.806214275Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Oct 31 00:36:44.806241 containerd[1400]: time="2025-10-31T00:36:44.806230957Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Oct 31 00:36:44.806476 containerd[1400]: time="2025-10-31T00:36:44.806429351Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Oct 31 00:36:44.806867 containerd[1400]: time="2025-10-31T00:36:44.806819468Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Oct 31 00:36:44.807052 containerd[1400]: time="2025-10-31T00:36:44.807014636Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Oct 31 00:36:44.807052 containerd[1400]: time="2025-10-31T00:36:44.807038458Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Oct 31 00:36:44.807052 containerd[1400]: time="2025-10-31T00:36:44.807052348Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Oct 31 00:36:44.807236 containerd[1400]: time="2025-10-31T00:36:44.807067940Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Oct 31 00:36:44.807236 containerd[1400]: time="2025-10-31T00:36:44.807081598Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Oct 31 00:36:44.807236 containerd[1400]: time="2025-10-31T00:36:44.807108645Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Oct 31 00:36:44.807236 containerd[1400]: time="2025-10-31T00:36:44.807122965Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Oct 31 00:36:44.807236 containerd[1400]: time="2025-10-31T00:36:44.807137535Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Oct 31 00:36:44.807236 containerd[1400]: time="2025-10-31T00:36:44.807150242Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Oct 31 00:36:44.807236 containerd[1400]: time="2025-10-31T00:36:44.807165374Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Oct 31 00:36:44.807236 containerd[1400]: time="2025-10-31T00:36:44.807176769Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Oct 31 00:36:44.807425 containerd[1400]: time="2025-10-31T00:36:44.807347354Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Oct 31 00:36:44.807425 containerd[1400]: time="2025-10-31T00:36:44.807371006Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Oct 31 00:36:44.808106 containerd[1400]: time="2025-10-31T00:36:44.808048278Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Oct 31 00:36:44.808318 containerd[1400]: time="2025-10-31T00:36:44.808266068Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Oct 31 00:36:44.808318 containerd[1400]: time="2025-10-31T00:36:44.808295849Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Oct 31 00:36:44.808318 containerd[1400]: time="2025-10-31T00:36:44.808311871Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Oct 31 00:36:44.808318 containerd[1400]: time="2025-10-31T00:36:44.808325610Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Oct 31 00:36:44.808524 containerd[1400]: time="2025-10-31T00:36:44.808341051Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Oct 31 00:36:44.808524 containerd[1400]: time="2025-10-31T00:36:44.808355330Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Oct 31 00:36:44.808524 containerd[1400]: time="2025-10-31T00:36:44.808374477Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Oct 31 00:36:44.808524 containerd[1400]: time="2025-10-31T00:36:44.808388547Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Oct 31 00:36:44.808524 containerd[1400]: time="2025-10-31T00:36:44.808404849Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Oct 31 00:36:44.808524 containerd[1400]: time="2025-10-31T00:36:44.808421092Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Oct 31 00:36:44.808524 containerd[1400]: time="2025-10-31T00:36:44.808439576Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Oct 31 00:36:44.808524 containerd[1400]: time="2025-10-31T00:36:44.808470359Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Oct 31 00:36:44.808524 containerd[1400]: time="2025-10-31T00:36:44.808485299Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Oct 31 00:36:44.808524 containerd[1400]: time="2025-10-31T00:36:44.808498328Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Oct 31 00:36:44.808741 containerd[1400]: time="2025-10-31T00:36:44.808549938Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Oct 31 00:36:44.808741 containerd[1400]: time="2025-10-31T00:36:44.808570056Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Oct 31 00:36:44.808741 containerd[1400]: time="2025-10-31T00:36:44.808582954Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Oct 31 00:36:44.808741 containerd[1400]: time="2025-10-31T00:36:44.808595992Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Oct 31 00:36:44.808741 containerd[1400]: time="2025-10-31T00:36:44.808606847Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Oct 31 00:36:44.808741 containerd[1400]: time="2025-10-31T00:36:44.808627144Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Oct 31 00:36:44.808741 containerd[1400]: time="2025-10-31T00:36:44.808656275Z" level=info msg="NRI interface is disabled by configuration." Oct 31 00:36:44.808741 containerd[1400]: time="2025-10-31T00:36:44.808674139Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Oct 31 00:36:44.809166 containerd[1400]: time="2025-10-31T00:36:44.809071105Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Oct 31 00:36:44.809166 containerd[1400]: time="2025-10-31T00:36:44.809170943Z" level=info msg="Connect containerd service" Oct 31 00:36:44.809440 containerd[1400]: time="2025-10-31T00:36:44.809212010Z" level=info msg="using legacy CRI server" Oct 31 00:36:44.809440 containerd[1400]: time="2025-10-31T00:36:44.809219730Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Oct 31 00:36:44.809440 containerd[1400]: time="2025-10-31T00:36:44.809333447Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Oct 31 00:36:44.810115 containerd[1400]: time="2025-10-31T00:36:44.810073685Z" 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" Oct 31 00:36:44.810315 containerd[1400]: time="2025-10-31T00:36:44.810266230Z" level=info msg="Start subscribing containerd event" Oct 31 00:36:44.810371 containerd[1400]: time="2025-10-31T00:36:44.810333202Z" level=info msg="Start recovering state" Oct 31 00:36:44.810414 containerd[1400]: time="2025-10-31T00:36:44.810393105Z" level=info msg="Start event monitor" Oct 31 00:36:44.810436 containerd[1400]: time="2025-10-31T00:36:44.810415285Z" level=info msg="Start snapshots syncer" Oct 31 00:36:44.810436 containerd[1400]: time="2025-10-31T00:36:44.810424798Z" level=info msg="Start cni network conf syncer for default" Oct 31 00:36:44.810436 containerd[1400]: time="2025-10-31T00:36:44.810433600Z" level=info msg="Start streaming server" Oct 31 00:36:44.810528 containerd[1400]: time="2025-10-31T00:36:44.810505049Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 31 00:36:44.810588 containerd[1400]: time="2025-10-31T00:36:44.810564721Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 31 00:36:44.811592 containerd[1400]: time="2025-10-31T00:36:44.810650699Z" level=info msg="containerd successfully booted in 0.111793s" Oct 31 00:36:44.810785 systemd[1]: Started containerd.service - containerd container runtime. Oct 31 00:36:44.980339 systemd[1]: Starting docker.service - Docker Application Container Engine... Oct 31 00:36:44.980569 (dockerd)[1483]: 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 Oct 31 00:36:45.591462 dockerd[1483]: time="2025-10-31T00:36:45.591381319Z" level=info msg="Starting up" Oct 31 00:36:45.958813 dockerd[1483]: time="2025-10-31T00:36:45.958699600Z" level=info msg="Loading containers: start." Oct 31 00:36:46.189136 kernel: Initializing XFRM netlink socket Oct 31 00:36:46.291140 systemd-networkd[1332]: docker0: Link UP Oct 31 00:36:46.540345 dockerd[1483]: time="2025-10-31T00:36:46.540254120Z" level=info msg="Loading containers: done." Oct 31 00:36:46.563064 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck1146768605-merged.mount: Deactivated successfully. Oct 31 00:36:46.563672 dockerd[1483]: time="2025-10-31T00:36:46.563584028Z" 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 Oct 31 00:36:46.563850 dockerd[1483]: time="2025-10-31T00:36:46.563793182Z" level=info msg="Docker daemon" commit=061aa95809be396a6b5542618d8a34b02a21ff77 containerd-snapshotter=false storage-driver=overlay2 version=26.1.0 Oct 31 00:36:46.564025 dockerd[1483]: time="2025-10-31T00:36:46.563995126Z" level=info msg="Daemon has completed initialization" Oct 31 00:36:46.603377 dockerd[1483]: time="2025-10-31T00:36:46.603237938Z" level=info msg="API listen on /run/docker.sock" Oct 31 00:36:46.603520 systemd[1]: Started docker.service - Docker Application Container Engine. Oct 31 00:36:46.608275 etcd-wrapper[1476]: Error response from daemon: No such container: etcd-member Oct 31 00:36:46.636326 etcd-wrapper[1609]: Error response from daemon: No such container: etcd-member Oct 31 00:36:46.695446 etcd-wrapper[1627]: Unable to find image 'quay.io/coreos/etcd:v3.5.22' locally Oct 31 00:36:47.847743 etcd-wrapper[1627]: v3.5.22: Pulling from coreos/etcd Oct 31 00:36:48.202574 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Oct 31 00:36:48.243622 systemd[1]: Started sshd@0-10.0.0.55:22-10.0.0.1:51016.service - OpenSSH per-connection server daemon (10.0.0.1:51016). Oct 31 00:36:48.317618 sshd[1638]: Accepted publickey for core from 10.0.0.1 port 51016 ssh2: RSA SHA256:fxbg+cDPAGAOxNy6Apu5lF9WK7GP5km5dh02Op5u+wc Oct 31 00:36:48.321016 sshd[1638]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:36:48.333962 systemd-logind[1385]: New session 1 of user core. Oct 31 00:36:48.335665 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Oct 31 00:36:48.357660 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Oct 31 00:36:48.371856 etcd-wrapper[1627]: f5083dc2076d: Pulling fs layer Oct 31 00:36:48.371856 etcd-wrapper[1627]: 2ae710cd8bfe: Pulling fs layer Oct 31 00:36:48.371856 etcd-wrapper[1627]: d462aa345367: Pulling fs layer Oct 31 00:36:48.371856 etcd-wrapper[1627]: 0f8b424aa0b9: Pulling fs layer Oct 31 00:36:48.371856 etcd-wrapper[1627]: d557676654e5: Pulling fs layer Oct 31 00:36:48.371856 etcd-wrapper[1627]: c8022d07192e: Pulling fs layer Oct 31 00:36:48.371856 etcd-wrapper[1627]: d858cbc252ad: Pulling fs layer Oct 31 00:36:48.371856 etcd-wrapper[1627]: 1069fc2daed1: Pulling fs layer Oct 31 00:36:48.371856 etcd-wrapper[1627]: b40161cd83fc: Pulling fs layer Oct 31 00:36:48.371856 etcd-wrapper[1627]: 5318d93a3a65: Pulling fs layer Oct 31 00:36:48.371856 etcd-wrapper[1627]: 307c1adadb60: Pulling fs layer Oct 31 00:36:48.371856 etcd-wrapper[1627]: 60a82e1b19c6: Pulling fs layer Oct 31 00:36:48.371856 etcd-wrapper[1627]: 6f77055ebb6e: Pulling fs layer Oct 31 00:36:48.371856 etcd-wrapper[1627]: 67f98bbc9874: Pulling fs layer Oct 31 00:36:48.371856 etcd-wrapper[1627]: 1069fc2daed1: Waiting Oct 31 00:36:48.371856 etcd-wrapper[1627]: b40161cd83fc: Waiting Oct 31 00:36:48.371856 etcd-wrapper[1627]: 5318d93a3a65: Waiting Oct 31 00:36:48.371856 etcd-wrapper[1627]: 307c1adadb60: Waiting Oct 31 00:36:48.371856 etcd-wrapper[1627]: 60a82e1b19c6: Waiting Oct 31 00:36:48.371856 etcd-wrapper[1627]: 6f77055ebb6e: Waiting Oct 31 00:36:48.371856 etcd-wrapper[1627]: 67f98bbc9874: Waiting Oct 31 00:36:48.371856 etcd-wrapper[1627]: 0f8b424aa0b9: Waiting Oct 31 00:36:48.371856 etcd-wrapper[1627]: c8022d07192e: Waiting Oct 31 00:36:48.371856 etcd-wrapper[1627]: d858cbc252ad: Waiting Oct 31 00:36:48.371856 etcd-wrapper[1627]: d557676654e5: Waiting Oct 31 00:36:48.377325 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Oct 31 00:36:48.433736 systemd[1]: Starting user@500.service - User Manager for UID 500... Oct 31 00:36:48.461074 (systemd)[1642]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 31 00:36:48.589957 etcd-wrapper[1627]: 2ae710cd8bfe: Download complete Oct 31 00:36:48.593211 etcd-wrapper[1627]: d462aa345367: Verifying Checksum Oct 31 00:36:48.593211 etcd-wrapper[1627]: d462aa345367: Download complete Oct 31 00:36:48.593920 systemd[1642]: Queued start job for default target default.target. Oct 31 00:36:48.608482 systemd[1642]: Created slice app.slice - User Application Slice. Oct 31 00:36:48.608511 systemd[1642]: Reached target paths.target - Paths. Oct 31 00:36:48.608525 systemd[1642]: Reached target timers.target - Timers. Oct 31 00:36:48.610412 systemd[1642]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 31 00:36:48.627642 systemd[1642]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 31 00:36:48.627789 systemd[1642]: Reached target sockets.target - Sockets. Oct 31 00:36:48.627804 systemd[1642]: Reached target basic.target - Basic System. Oct 31 00:36:48.627843 systemd[1642]: Reached target default.target - Main User Target. Oct 31 00:36:48.627878 systemd[1642]: Startup finished in 157ms. Oct 31 00:36:48.628542 systemd[1]: Started user@500.service - User Manager for UID 500. Oct 31 00:36:48.632222 systemd[1]: Started session-1.scope - Session 1 of User core. Oct 31 00:36:48.657989 etcd-wrapper[1627]: f5083dc2076d: Download complete Oct 31 00:36:48.703480 systemd[1]: Started sshd@1-10.0.0.55:22-10.0.0.1:51028.service - OpenSSH per-connection server daemon (10.0.0.1:51028). Oct 31 00:36:48.741764 sshd[1659]: Accepted publickey for core from 10.0.0.1 port 51028 ssh2: RSA SHA256:fxbg+cDPAGAOxNy6Apu5lF9WK7GP5km5dh02Op5u+wc Oct 31 00:36:48.743965 sshd[1659]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:36:48.748902 systemd-logind[1385]: New session 2 of user core. Oct 31 00:36:48.763324 systemd[1]: Started session-2.scope - Session 2 of User core. Oct 31 00:36:48.767024 etcd-wrapper[1627]: f5083dc2076d: Pull complete Oct 31 00:36:48.793781 systemd[1]: var-lib-docker-overlay2-b9262d4010e23501b0de58a515aa0100670df8c53fc554c0b2b296bdd4809b4c-merged.mount: Deactivated successfully. Oct 31 00:36:48.816997 etcd-wrapper[1627]: 2ae710cd8bfe: Pull complete Oct 31 00:36:48.817729 etcd-wrapper[1627]: d557676654e5: Verifying Checksum Oct 31 00:36:48.817729 etcd-wrapper[1627]: d557676654e5: Download complete Oct 31 00:36:48.824116 etcd-wrapper[1627]: 0f8b424aa0b9: Verifying Checksum Oct 31 00:36:48.824116 etcd-wrapper[1627]: 0f8b424aa0b9: Download complete Oct 31 00:36:48.825008 sshd[1659]: pam_unix(sshd:session): session closed for user core Oct 31 00:36:48.838134 systemd[1]: sshd@1-10.0.0.55:22-10.0.0.1:51028.service: Deactivated successfully. Oct 31 00:36:48.840125 systemd[1]: session-2.scope: Deactivated successfully. Oct 31 00:36:48.841768 systemd-logind[1385]: Session 2 logged out. Waiting for processes to exit. Oct 31 00:36:48.848392 systemd[1]: Started sshd@2-10.0.0.55:22-10.0.0.1:51038.service - OpenSSH per-connection server daemon (10.0.0.1:51038). Oct 31 00:36:48.850454 systemd-logind[1385]: Removed session 2. Oct 31 00:36:48.964111 sshd[1676]: Accepted publickey for core from 10.0.0.1 port 51038 ssh2: RSA SHA256:fxbg+cDPAGAOxNy6Apu5lF9WK7GP5km5dh02Op5u+wc Oct 31 00:36:48.966638 sshd[1676]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:36:48.971230 systemd-logind[1385]: New session 3 of user core. Oct 31 00:36:48.974160 etcd-wrapper[1627]: c8022d07192e: Download complete Oct 31 00:36:48.977278 systemd[1]: Started session-3.scope - Session 3 of User core. Oct 31 00:36:49.037722 sshd[1676]: pam_unix(sshd:session): session closed for user core Oct 31 00:36:49.043339 systemd[1]: sshd@2-10.0.0.55:22-10.0.0.1:51038.service: Deactivated successfully. Oct 31 00:36:49.045906 systemd[1]: session-3.scope: Deactivated successfully. Oct 31 00:36:49.046628 systemd-logind[1385]: Session 3 logged out. Waiting for processes to exit. Oct 31 00:36:49.047680 systemd-logind[1385]: Removed session 3. Oct 31 00:36:49.109213 etcd-wrapper[1627]: 1069fc2daed1: Verifying Checksum Oct 31 00:36:49.109213 etcd-wrapper[1627]: 1069fc2daed1: Download complete Oct 31 00:36:49.124166 etcd-wrapper[1627]: d858cbc252ad: Download complete Oct 31 00:36:49.209941 etcd-wrapper[1627]: b40161cd83fc: Verifying Checksum Oct 31 00:36:49.209941 etcd-wrapper[1627]: b40161cd83fc: Download complete Oct 31 00:36:49.398642 etcd-wrapper[1627]: 5318d93a3a65: Verifying Checksum Oct 31 00:36:49.398986 etcd-wrapper[1627]: 5318d93a3a65: Download complete Oct 31 00:36:49.400999 etcd-wrapper[1627]: 307c1adadb60: Download complete Oct 31 00:36:49.425713 systemd[1]: var-lib-docker-overlay2-f20fcd020d7d063bac7f9206de60b769b318639eb6f22585f14c5baa3d5baea3-merged.mount: Deactivated successfully. Oct 31 00:36:49.714155 etcd-wrapper[1627]: 67f98bbc9874: Verifying Checksum Oct 31 00:36:49.714155 etcd-wrapper[1627]: 67f98bbc9874: Download complete Oct 31 00:36:49.824205 etcd-wrapper[1627]: 6f77055ebb6e: Verifying Checksum Oct 31 00:36:49.824205 etcd-wrapper[1627]: 6f77055ebb6e: Download complete Oct 31 00:36:50.172670 etcd-wrapper[1627]: 60a82e1b19c6: Download complete Oct 31 00:36:50.289933 etcd-wrapper[1627]: d462aa345367: Pull complete Oct 31 00:36:50.350632 systemd[1]: var-lib-docker-overlay2-f457ce4b145cb11b814883c30c76171c24bcccf1fc5d0c5aa7b7bdb3d6db90a3-merged.mount: Deactivated successfully. Oct 31 00:36:50.446468 etcd-wrapper[1627]: 0f8b424aa0b9: Pull complete Oct 31 00:36:50.461483 systemd[1]: var-lib-docker-overlay2-684280da2d6be4d790608344dd6f08fd4447e855b82e958ab1bdfc2a71479165-merged.mount: Deactivated successfully. Oct 31 00:36:50.473712 etcd-wrapper[1627]: d557676654e5: Pull complete Oct 31 00:36:50.488670 systemd[1]: var-lib-docker-overlay2-e888ed0e7f762ee626ad0db4ca9df998a8d80f52d040da0d2abf8ba406b606d0-merged.mount: Deactivated successfully. Oct 31 00:36:50.500596 etcd-wrapper[1627]: c8022d07192e: Pull complete Oct 31 00:36:50.531710 etcd-wrapper[1627]: d858cbc252ad: Pull complete Oct 31 00:36:50.562954 etcd-wrapper[1627]: 1069fc2daed1: Pull complete Oct 31 00:36:50.586237 etcd-wrapper[1627]: b40161cd83fc: Pull complete Oct 31 00:36:50.612769 etcd-wrapper[1627]: 5318d93a3a65: Pull complete Oct 31 00:36:50.645004 etcd-wrapper[1627]: 307c1adadb60: Pull complete Oct 31 00:36:50.869835 etcd-wrapper[1627]: 60a82e1b19c6: Pull complete Oct 31 00:36:51.057402 etcd-wrapper[1627]: 6f77055ebb6e: Pull complete Oct 31 00:36:51.218269 etcd-wrapper[1627]: 67f98bbc9874: Pull complete Oct 31 00:36:51.230662 etcd-wrapper[1627]: Digest: sha256:51efcfc4f3a863c072ebe745942f790878c2a268e713a6d140c4f2593271adc6 Oct 31 00:36:51.316909 etcd-wrapper[1627]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.22 Oct 31 00:36:51.459810 systemd[1]: var-lib-docker-overlay2-96991afc967afac29efd4a8323ad8fdff03a15c9cbcd1ce2c94377547f21217d-merged.mount: Deactivated successfully. Oct 31 00:36:51.603033 containerd[1400]: time="2025-10-31T00:36:51.602939803Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 31 00:36:51.603482 containerd[1400]: time="2025-10-31T00:36:51.603075544Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 31 00:36:51.603482 containerd[1400]: time="2025-10-31T00:36:51.603138447Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 31 00:36:51.603482 containerd[1400]: time="2025-10-31T00:36:51.603266356Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 31 00:36:51.633239 systemd[1]: Started docker-c08cf68f5c935b94b777b5dab4a1c6da9b1b84e22a8672e44bd7c5850cfb7311.scope - libcontainer container c08cf68f5c935b94b777b5dab4a1c6da9b1b84e22a8672e44bd7c5850cfb7311. Oct 31 00:36:51.648779 systemd-resolved[1268]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Oct 31 00:36:51.693337 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.692776Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} Oct 31 00:36:51.693337 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.692986Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"51375c6ef37b472992d27876d0ed793f"} Oct 31 00:36:51.693337 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:36:51.693010Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} Oct 31 00:36:51.693337 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:36:51.693016Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.22"} Oct 31 00:36:51.693337 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:36:51.693021Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} Oct 31 00:36:51.693337 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:36:51.693026Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} Oct 31 00:36:51.693337 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:36:51.693072Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Oct 31 00:36:51.693337 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.693145Z","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.55:2379"]} Oct 31 00:36:51.693704 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:36:51.693295Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Oct 31 00:36:51.693704 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.693341Z","caller":"embed/etcd.go:140","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]} Oct 31 00:36:51.694059 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.693902Z","caller":"embed/etcd.go:148","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} Oct 31 00:36:51.694273 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.694077Z","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":"51375c6ef37b472992d27876d0ed793f","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.55:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"51375c6ef37b472992d27876d0ed793f=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"} Oct 31 00:36:51.780789 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.780480Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"85.421909ms"} Oct 31 00:36:51.788873 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.788580Z","caller":"etcdserver/raft.go:507","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"} Oct 31 00:36:51.788873 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.788649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"} Oct 31 00:36:51.788873 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.788677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"} Oct 31 00:36:51.788873 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.788689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} Oct 31 00:36:51.788873 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.788698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"} Oct 31 00:36:51.788873 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.788743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Oct 31 00:36:51.795402 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:36:51.795141Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} Oct 31 00:36:51.797997 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.797746Z","caller":"mvcc/kvstore.go:425","msg":"kvstore restored","current-rev":1} Oct 31 00:36:51.797997 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.797837Z","caller":"etcdserver/server.go:628","msg":"restore consistentIndex","index":0} Oct 31 00:36:51.799416 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.799137Z","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"} Oct 31 00:36:51.800818 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.800599Z","caller":"etcdserver/server.go:875","msg":"starting etcd server","local-member-id":"8e9e05c52164694d","local-server-version":"3.5.22","cluster-version":"to_be_decided"} Oct 31 00:36:51.801043 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.800873Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Oct 31 00:36:51.801238 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.801051Z","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"} Oct 31 00:36:51.801610 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.801205Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} Oct 31 00:36:51.801610 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.801391Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} Oct 31 00:36:51.801610 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.801405Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} Oct 31 00:36:51.802124 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.801873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Oct 31 00:36:51.802334 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.802071Z","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} Oct 31 00:36:51.803705 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.803481Z","caller":"embed/etcd.go:633","msg":"serving peer traffic","address":"127.0.0.1:2380"} Oct 31 00:36:51.803705 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.803510Z","caller":"embed/etcd.go:603","msg":"cmux::serve","address":"127.0.0.1:2380"} Oct 31 00:36:51.803705 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.803533Z","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.55:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} Oct 31 00:36:51.990013 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.989403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"} Oct 31 00:36:51.990013 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.989466Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"} Oct 31 00:36:51.990013 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.989486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"} Oct 31 00:36:51.990013 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.989501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"} Oct 31 00:36:51.990013 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.989630Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"} Oct 31 00:36:51.990013 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.989644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"} Oct 31 00:36:51.990013 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.989656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"} Oct 31 00:36:51.991521 systemd[1]: Started etcd-member.service - etcd (System Application Container). Oct 31 00:36:51.992218 systemd[1]: Reached target multi-user.target - Multi-User System. Oct 31 00:36:51.992457 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.990813Z","caller":"etcdserver/server.go:2144","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:51375c6ef37b472992d27876d0ed793f ClientURLs:[http://10.0.0.55:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"} Oct 31 00:36:51.992457 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.990825Z","caller":"etcdserver/server.go:2697","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} Oct 31 00:36:51.992457 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.990985Z","caller":"embed/serve.go:124","msg":"ready to serve client requests"} Oct 31 00:36:51.992457 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.991107Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} Oct 31 00:36:51.992457 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.991223Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} Oct 31 00:36:51.992457 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.991466Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Oct 31 00:36:51.992613 systemd[1]: Startup finished in 1.303s (kernel) + 5.675s (initrd) + 11.616s (userspace) = 18.595s. Oct 31 00:36:51.992862 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.991930Z","caller":"embed/serve.go:210","msg":"serving client traffic insecurely; this is strongly discouraged!","traffic":"grpc+http","address":"[::]:2379"} Oct 31 00:36:51.992862 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.992275Z","caller":"membership/cluster.go:587","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"} Oct 31 00:36:51.992862 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.992357Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} Oct 31 00:36:51.992862 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:36:51.992381Z","caller":"etcdserver/server.go:2721","msg":"cluster version is updated","cluster-version":"3.5"} Oct 31 00:36:59.047517 systemd[1]: Started sshd@3-10.0.0.55:22-10.0.0.1:32926.service - OpenSSH per-connection server daemon (10.0.0.1:32926). Oct 31 00:36:59.082738 sshd[1794]: Accepted publickey for core from 10.0.0.1 port 32926 ssh2: RSA SHA256:fxbg+cDPAGAOxNy6Apu5lF9WK7GP5km5dh02Op5u+wc Oct 31 00:36:59.084541 sshd[1794]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:36:59.089214 systemd-logind[1385]: New session 4 of user core. Oct 31 00:36:59.103368 systemd[1]: Started session-4.scope - Session 4 of User core. Oct 31 00:36:59.162033 sshd[1794]: pam_unix(sshd:session): session closed for user core Oct 31 00:36:59.173311 systemd[1]: sshd@3-10.0.0.55:22-10.0.0.1:32926.service: Deactivated successfully. Oct 31 00:36:59.175534 systemd[1]: session-4.scope: Deactivated successfully. Oct 31 00:36:59.177236 systemd-logind[1385]: Session 4 logged out. Waiting for processes to exit. Oct 31 00:36:59.189457 systemd[1]: Started sshd@4-10.0.0.55:22-10.0.0.1:32940.service - OpenSSH per-connection server daemon (10.0.0.1:32940). Oct 31 00:36:59.190665 systemd-logind[1385]: Removed session 4. Oct 31 00:36:59.217542 sshd[1801]: Accepted publickey for core from 10.0.0.1 port 32940 ssh2: RSA SHA256:fxbg+cDPAGAOxNy6Apu5lF9WK7GP5km5dh02Op5u+wc Oct 31 00:36:59.219212 sshd[1801]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:36:59.223130 systemd-logind[1385]: New session 5 of user core. Oct 31 00:36:59.234224 systemd[1]: Started session-5.scope - Session 5 of User core. Oct 31 00:36:59.285000 sshd[1801]: pam_unix(sshd:session): session closed for user core Oct 31 00:36:59.295933 systemd[1]: sshd@4-10.0.0.55:22-10.0.0.1:32940.service: Deactivated successfully. Oct 31 00:36:59.297796 systemd[1]: session-5.scope: Deactivated successfully. Oct 31 00:36:59.299276 systemd-logind[1385]: Session 5 logged out. Waiting for processes to exit. Oct 31 00:36:59.308337 systemd[1]: Started sshd@5-10.0.0.55:22-10.0.0.1:32950.service - OpenSSH per-connection server daemon (10.0.0.1:32950). Oct 31 00:36:59.309245 systemd-logind[1385]: Removed session 5. Oct 31 00:36:59.336220 sshd[1808]: Accepted publickey for core from 10.0.0.1 port 32950 ssh2: RSA SHA256:fxbg+cDPAGAOxNy6Apu5lF9WK7GP5km5dh02Op5u+wc Oct 31 00:36:59.337770 sshd[1808]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:36:59.341755 systemd-logind[1385]: New session 6 of user core. Oct 31 00:36:59.350220 systemd[1]: Started session-6.scope - Session 6 of User core. Oct 31 00:36:59.404418 sshd[1808]: pam_unix(sshd:session): session closed for user core Oct 31 00:36:59.420763 systemd[1]: sshd@5-10.0.0.55:22-10.0.0.1:32950.service: Deactivated successfully. Oct 31 00:36:59.422406 systemd[1]: session-6.scope: Deactivated successfully. Oct 31 00:36:59.424054 systemd-logind[1385]: Session 6 logged out. Waiting for processes to exit. Oct 31 00:36:59.434325 systemd[1]: Started sshd@6-10.0.0.55:22-10.0.0.1:32954.service - OpenSSH per-connection server daemon (10.0.0.1:32954). Oct 31 00:36:59.435586 systemd-logind[1385]: Removed session 6. Oct 31 00:36:59.464676 sshd[1815]: Accepted publickey for core from 10.0.0.1 port 32954 ssh2: RSA SHA256:fxbg+cDPAGAOxNy6Apu5lF9WK7GP5km5dh02Op5u+wc Oct 31 00:36:59.466437 sshd[1815]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:36:59.470547 systemd-logind[1385]: New session 7 of user core. Oct 31 00:36:59.480203 systemd[1]: Started session-7.scope - Session 7 of User core. Oct 31 00:36:59.530690 sshd[1815]: pam_unix(sshd:session): session closed for user core Oct 31 00:36:59.543010 systemd[1]: sshd@6-10.0.0.55:22-10.0.0.1:32954.service: Deactivated successfully. Oct 31 00:36:59.545039 systemd[1]: session-7.scope: Deactivated successfully. Oct 31 00:36:59.546523 systemd-logind[1385]: Session 7 logged out. Waiting for processes to exit. Oct 31 00:36:59.548010 systemd[1]: Started sshd@7-10.0.0.55:22-10.0.0.1:32962.service - OpenSSH per-connection server daemon (10.0.0.1:32962). Oct 31 00:36:59.550124 systemd-logind[1385]: Removed session 7. Oct 31 00:36:59.581801 sshd[1824]: Accepted publickey for core from 10.0.0.1 port 32962 ssh2: RSA SHA256:fxbg+cDPAGAOxNy6Apu5lF9WK7GP5km5dh02Op5u+wc Oct 31 00:36:59.583386 sshd[1824]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:36:59.587516 systemd-logind[1385]: New session 8 of user core. Oct 31 00:36:59.602234 systemd[1]: Started session-8.scope - Session 8 of User core. Oct 31 00:36:59.768486 sshd[1824]: pam_unix(sshd:session): session closed for user core Oct 31 00:36:59.772509 systemd[1]: sshd@7-10.0.0.55:22-10.0.0.1:32962.service: Deactivated successfully. Oct 31 00:36:59.774389 systemd[1]: session-8.scope: Deactivated successfully. Oct 31 00:36:59.774975 systemd-logind[1385]: Session 8 logged out. Waiting for processes to exit. Oct 31 00:36:59.776764 systemd-logind[1385]: Removed session 8. Oct 31 00:37:29.399434 update_engine[1391]: I20251031 00:37:29.399266 1391 update_attempter.cc:509] Updating boot flags... Oct 31 00:37:29.433150 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 37 scanned by (udev-worker) (1843) Oct 31 00:37:29.477184 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 37 scanned by (udev-worker) (1841) Oct 31 00:37:29.509122 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 37 scanned by (udev-worker) (1841) Oct 31 00:37:49.889936 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:37:49.889171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.8337ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} Oct 31 00:37:49.889936 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:37:49.889298Z","caller":"traceutil/trace.go:171","msg":"trace[1493576196] linearizableReadLoop","detail":"{readStateIndex:70; appliedIndex:69; }","duration":"121.297788ms","start":"2025-10-31T00:37:49.767983Z","end":"2025-10-31T00:37:49.889281Z","steps":["trace[1493576196] 'read index received' (duration: 34.922µs)","trace[1493576196] 'applied index is now lower than readState.Index' (duration: 121.262235ms)"],"step_count":2} Oct 31 00:37:49.889936 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:37:49.889311Z","caller":"traceutil/trace.go:171","msg":"trace[1445620730] transaction","detail":"{read_only:false; response_revision:65; number_of_response:1; }","duration":"291.821481ms","start":"2025-10-31T00:37:49.597475Z","end":"2025-10-31T00:37:49.889297Z","steps":["trace[1445620730] 'process raft request' (duration: 72.19781ms)","trace[1445620730] 'compare' (duration: 218.639337ms)"],"step_count":2} Oct 31 00:37:49.889936 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:37:49.889340Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.357867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/localhost\" limit:1 ","response":"range_response_count:0 size:4"} Oct 31 00:37:49.890733 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:37:49.889359Z","caller":"traceutil/trace.go:171","msg":"trace[45019156] range","detail":"{range_begin:/registry/csinodes/localhost; range_end:; response_count:0; response_revision:65; }","duration":"121.371592ms","start":"2025-10-31T00:37:49.767976Z","end":"2025-10-31T00:37:49.889348Z","steps":["trace[45019156] 'agreement among raft nodes before linearized reading' (duration: 121.346336ms)"],"step_count":1} Oct 31 00:37:50.036673 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:37:50.036191Z","caller":"traceutil/trace.go:171","msg":"trace[1297000764] transaction","detail":"{read_only:false; response_revision:67; number_of_response:1; }","duration":"143.429824ms","start":"2025-10-31T00:37:49.892743Z","end":"2025-10-31T00:37:50.036173Z","steps":["trace[1297000764] 'process raft request' (duration: 143.391074ms)"],"step_count":1} Oct 31 00:37:50.036673 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:37:50.036215Z","caller":"traceutil/trace.go:171","msg":"trace[1511511139] transaction","detail":"{read_only:false; response_revision:66; number_of_response:1; }","duration":"144.084873ms","start":"2025-10-31T00:37:49.892116Z","end":"2025-10-31T00:37:50.036201Z","steps":["trace[1511511139] 'process raft request' (duration: 132.05374ms)","trace[1511511139] 'compare' (duration: 11.847219ms)"],"step_count":2} Oct 31 00:37:52.899158 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:37:52.898651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.855439ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Oct 31 00:37:52.899158 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:37:52.898749Z","caller":"traceutil/trace.go:171","msg":"trace[135372765] linearizableReadLoop","detail":"{readStateIndex:256; appliedIndex:255; }","duration":"214.67456ms","start":"2025-10-31T00:37:52.684063Z","end":"2025-10-31T00:37:52.898737Z","steps":["trace[135372765] 'read index received' (duration: 70.69299ms)","trace[135372765] 'applied index is now lower than readState.Index' (duration: 143.981059ms)"],"step_count":2} Oct 31 00:37:52.899158 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:37:52.898826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.755978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" limit:1 ","response":"range_response_count:0 size:5"} Oct 31 00:37:52.899158 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:37:52.898847Z","caller":"traceutil/trace.go:171","msg":"trace[1449481955] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:0; response_revision:248; }","duration":"214.782646ms","start":"2025-10-31T00:37:52.684058Z","end":"2025-10-31T00:37:52.898841Z","steps":["trace[1449481955] 'agreement among raft nodes before linearized reading' (duration: 214.731053ms)"],"step_count":1} Oct 31 00:37:52.900016 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:37:52.898817Z","caller":"traceutil/trace.go:171","msg":"trace[1099171098] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"249.840261ms","start":"2025-10-31T00:37:52.648935Z","end":"2025-10-31T00:37:52.898775Z","steps":["trace[1099171098] 'process raft request' (duration: 105.810483ms)","trace[1099171098] 'compare' (duration: 143.738558ms)"],"step_count":2} Oct 31 00:37:52.907208 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:37:52.906696Z","caller":"traceutil/trace.go:171","msg":"trace[1755351265] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"217.637296ms","start":"2025-10-31T00:37:52.689045Z","end":"2025-10-31T00:37:52.906682Z","steps":["trace[1755351265] 'process raft request' (duration: 217.492041ms)"],"step_count":1} Oct 31 00:37:52.907208 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:37:52.906738Z","caller":"traceutil/trace.go:171","msg":"trace[974102804] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"153.138705ms","start":"2025-10-31T00:37:52.753571Z","end":"2025-10-31T00:37:52.906710Z","steps":["trace[974102804] 'process raft request' (duration: 153.060413ms)"],"step_count":1} Oct 31 00:37:54.747265 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:37:54.746437Z","caller":"traceutil/trace.go:171","msg":"trace[1506022378] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"183.359268ms","start":"2025-10-31T00:37:54.563053Z","end":"2025-10-31T00:37:54.746413Z","steps":["trace[1506022378] 'process raft request' (duration: 183.30573ms)"],"step_count":1} Oct 31 00:37:54.747265 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:37:54.746492Z","caller":"traceutil/trace.go:171","msg":"trace[1210268037] linearizableReadLoop","detail":"{readStateIndex:297; appliedIndex:296; }","duration":"244.880945ms","start":"2025-10-31T00:37:54.501570Z","end":"2025-10-31T00:37:54.746451Z","steps":["trace[1210268037] 'read index received' (duration: 153.989917ms)","trace[1210268037] 'applied index is now lower than readState.Index' (duration: 90.885107ms)"],"step_count":2} Oct 31 00:37:54.747265 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:37:54.746432Z","caller":"traceutil/trace.go:171","msg":"trace[1738672557] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"262.171245ms","start":"2025-10-31T00:37:54.484210Z","end":"2025-10-31T00:37:54.746381Z","steps":["trace[1738672557] 'process raft request' (duration: 171.351266ms)","trace[1738672557] 'compare' (duration: 90.649027ms)"],"step_count":2} Oct 31 00:37:54.747265 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:37:54.746666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.05975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" limit:1 ","response":"range_response_count:0 size:5"} Oct 31 00:37:54.748169 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:37:54.746725Z","caller":"traceutil/trace.go:171","msg":"trace[863660448] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cronjob-controller; range_end:; response_count:0; response_revision:289; }","duration":"245.133765ms","start":"2025-10-31T00:37:54.501565Z","end":"2025-10-31T00:37:54.746699Z","steps":["trace[863660448] 'agreement among raft nodes before linearized reading' (duration: 245.024416ms)"],"step_count":1} Oct 31 00:37:57.578869 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:37:57.578366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.632459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" limit:1 ","response":"range_response_count:1 size:207"} Oct 31 00:37:57.578869 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:37:57.578438Z","caller":"traceutil/trace.go:171","msg":"trace[1322683772] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:311; }","duration":"127.719238ms","start":"2025-10-31T00:37:57.450700Z","end":"2025-10-31T00:37:57.578419Z","steps":["trace[1322683772] 'range keys from in-memory index tree' (duration: 127.54538ms)"],"step_count":1} Oct 31 00:37:57.727617 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:37:57.726712Z","caller":"traceutil/trace.go:171","msg":"trace[1243941975] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"142.319863ms","start":"2025-10-31T00:37:57.584377Z","end":"2025-10-31T00:37:57.726697Z","steps":["trace[1243941975] 'process raft request' (duration: 101.509977ms)","trace[1243941975] 'compare' (duration: 40.678336ms)"],"step_count":2} Oct 31 00:37:57.727617 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:37:57.726827Z","caller":"traceutil/trace.go:171","msg":"trace[491864170] linearizableReadLoop","detail":"{readStateIndex:323; appliedIndex:320; }","duration":"126.522925ms","start":"2025-10-31T00:37:57.600297Z","end":"2025-10-31T00:37:57.726820Z","steps":["trace[491864170] 'read index received' (duration: 63.809258ms)","trace[491864170] 'applied index is now lower than readState.Index' (duration: 62.713146ms)"],"step_count":2} Oct 31 00:37:57.727617 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:37:57.726869Z","caller":"traceutil/trace.go:171","msg":"trace[418502183] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"142.488791ms","start":"2025-10-31T00:37:57.584377Z","end":"2025-10-31T00:37:57.726866Z","steps":["trace[418502183] 'process raft request' (duration: 142.296129ms)"],"step_count":1} Oct 31 00:37:57.727617 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:37:57.727067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.747324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" limit:1 ","response":"range_response_count:1 size:193"} Oct 31 00:37:57.727887 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:37:57.727170Z","caller":"traceutil/trace.go:171","msg":"trace[1766552129] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:314; }","duration":"126.863957ms","start":"2025-10-31T00:37:57.600291Z","end":"2025-10-31T00:37:57.727155Z","steps":["trace[1766552129] 'agreement among raft nodes before linearized reading' (duration: 126.631953ms)"],"step_count":1} Oct 31 00:37:57.966002 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:37:57.965481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.51552ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Oct 31 00:37:57.966002 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:37:57.965606Z","caller":"traceutil/trace.go:171","msg":"trace[1549891014] linearizableReadLoop","detail":"{readStateIndex:330; appliedIndex:328; }","duration":"115.174571ms","start":"2025-10-31T00:37:57.850422Z","end":"2025-10-31T00:37:57.965596Z","steps":["trace[1549891014] 'read index received' (duration: 31.220486ms)","trace[1549891014] 'applied index is now lower than readState.Index' (duration: 83.953564ms)"],"step_count":2} Oct 31 00:37:57.966270 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:37:57.965606Z","caller":"traceutil/trace.go:171","msg":"trace[459328933] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"151.553674ms","start":"2025-10-31T00:37:57.814039Z","end":"2025-10-31T00:37:57.965592Z","steps":["trace[459328933] 'process raft request' (duration: 16.856573ms)","trace[459328933] 'compare' (duration: 134.417902ms)"],"step_count":2} Oct 31 00:37:57.966270 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:37:57.965812Z","caller":"traceutil/trace.go:171","msg":"trace[489019365] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"139.654083ms","start":"2025-10-31T00:37:57.826151Z","end":"2025-10-31T00:37:57.965805Z","steps":["trace[489019365] 'process raft request' (duration: 139.408345ms)"],"step_count":1} Oct 31 00:37:57.966270 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:37:57.965832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.375347ms","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"} Oct 31 00:37:57.966270 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:37:57.965894Z","caller":"traceutil/trace.go:171","msg":"trace[657946984] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/legacy-service-account-token-cleaner; range_end:; response_count:1; response_revision:321; }","duration":"115.467395ms","start":"2025-10-31T00:37:57.850411Z","end":"2025-10-31T00:37:57.965878Z","steps":["trace[657946984] 'agreement among raft nodes before linearized reading' (duration: 115.27875ms)"],"step_count":1} Oct 31 00:37:58.330376 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:37:58.329760Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.910928ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Oct 31 00:37:58.330376 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:37:58.329831Z","caller":"traceutil/trace.go:171","msg":"trace[799250612] linearizableReadLoop","detail":"{readStateIndex:334; appliedIndex:333; }","duration":"266.705453ms","start":"2025-10-31T00:37:58.063115Z","end":"2025-10-31T00:37:58.329820Z","steps":["trace[799250612] 'read index received' (duration: 127.69861ms)","trace[799250612] 'applied index is now lower than readState.Index' (duration: 139.006152ms)"],"step_count":2} Oct 31 00:37:58.330376 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:37:58.329926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.918494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" limit:1 ","response":"range_response_count:1 size:214"} Oct 31 00:37:58.330376 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:37:58.329927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.436796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" limit:1 ","response":"range_response_count:1 size:207"} Oct 31 00:37:58.330667 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:37:58.329954Z","caller":"traceutil/trace.go:171","msg":"trace[849917209] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:325; }","duration":"278.954399ms","start":"2025-10-31T00:37:58.050991Z","end":"2025-10-31T00:37:58.329946Z","steps":["trace[849917209] 'agreement among raft nodes before linearized reading' (duration: 278.87795ms)"],"step_count":1} Oct 31 00:37:58.330667 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:37:58.329957Z","caller":"traceutil/trace.go:171","msg":"trace[537934590] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:325; }","duration":"329.468294ms","start":"2025-10-31T00:37:58.000482Z","end":"2025-10-31T00:37:58.329950Z","steps":["trace[537934590] 'agreement among raft nodes before linearized reading' (duration: 329.390131ms)"],"step_count":1} Oct 31 00:37:58.330667 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:37:58.329979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-10-31T00:37:58.000467Z","time spent":"329.506353ms","remote":"10.0.0.58:33612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" limit:1 "} Oct 31 00:37:58.330667 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:37:58.329892Z","caller":"traceutil/trace.go:171","msg":"trace[1301500639] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"318.890101ms","start":"2025-10-31T00:37:58.010955Z","end":"2025-10-31T00:37:58.329846Z","steps":["trace[1301500639] 'process raft request' (duration: 179.845709ms)","trace[1301500639] 'compare' (duration: 138.801388ms)"],"step_count":2} Oct 31 00:37:58.330808 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:37:58.330049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-10-31T00:37:58.010942Z","time spent":"319.09207ms","remote":"10.0.0.58:33506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} Oct 31 00:37:58.331399 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:37:58.330953Z","caller":"traceutil/trace.go:171","msg":"trace[341976899] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"265.12829ms","start":"2025-10-31T00:37:58.065810Z","end":"2025-10-31T00:37:58.330938Z","steps":["trace[341976899] 'process raft request' (duration: 264.935227ms)"],"step_count":1} Oct 31 00:37:58.331399 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:37:58.331030Z","caller":"traceutil/trace.go:171","msg":"trace[1108144418] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"264.748436ms","start":"2025-10-31T00:37:58.066272Z","end":"2025-10-31T00:37:58.331020Z","steps":["trace[1108144418] 'process raft request' (duration: 264.555543ms)"],"step_count":1} Oct 31 00:37:58.331399 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:37:58.330969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.620687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" limit:1 ","response":"range_response_count:1 size:207"} Oct 31 00:37:58.331399 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:37:58.331071Z","caller":"traceutil/trace.go:171","msg":"trace[1897746735] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:328; }","duration":"130.722954ms","start":"2025-10-31T00:37:58.200341Z","end":"2025-10-31T00:37:58.331064Z","steps":["trace[1897746735] 'agreement among raft nodes before linearized reading' (duration: 130.571016ms)"],"step_count":1} Oct 31 00:37:58.331399 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:37:58.330970Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.715255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" limit:1 ","response":"range_response_count:1 size:203"} Oct 31 00:37:58.331609 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:37:58.331121Z","caller":"traceutil/trace.go:171","msg":"trace[115268071] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:328; }","duration":"229.888692ms","start":"2025-10-31T00:37:58.101227Z","end":"2025-10-31T00:37:58.331115Z","steps":["trace[115268071] 'agreement among raft nodes before linearized reading' (duration: 229.661707ms)"],"step_count":1} Oct 31 00:37:58.331609 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:37:58.330991Z","caller":"traceutil/trace.go:171","msg":"trace[1759061857] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"263.630904ms","start":"2025-10-31T00:37:58.067354Z","end":"2025-10-31T00:37:58.330985Z","steps":["trace[1759061857] 'process raft request' (duration: 263.505024ms)"],"step_count":1} Oct 31 00:37:58.331609 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:37:58.331002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.331125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" limit:1 ","response":"range_response_count:1 size:193"} Oct 31 00:37:58.331609 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:37:58.331167Z","caller":"traceutil/trace.go:171","msg":"trace[1366378808] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:328; }","duration":"180.496446ms","start":"2025-10-31T00:37:58.150667Z","end":"2025-10-31T00:37:58.331163Z","steps":["trace[1366378808] 'agreement among raft nodes before linearized reading' (duration: 180.322479ms)"],"step_count":1} Oct 31 00:37:59.568208 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:37:59.567808Z","caller":"traceutil/trace.go:171","msg":"trace[50408648] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"102.893444ms","start":"2025-10-31T00:37:59.464887Z","end":"2025-10-31T00:37:59.567781Z","steps":["trace[50408648] 'process raft request' (duration: 102.753559ms)"],"step_count":1} Oct 31 00:37:59.771417 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:37:59.770891Z","caller":"traceutil/trace.go:171","msg":"trace[239942094] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"301.880512ms","start":"2025-10-31T00:37:59.468972Z","end":"2025-10-31T00:37:59.770852Z","steps":["trace[239942094] 'process raft request' (duration: 282.373129ms)","trace[239942094] 'compare' (duration: 19.335368ms)"],"step_count":2} Oct 31 00:37:59.771417 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:37:59.771048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-10-31T00:37:59.468949Z","time spent":"302.021229ms","remote":"10.0.0.58:33594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4786,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Oct 31 00:38:01.707602 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:38:01.707119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.257132ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Oct 31 00:38:01.707602 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:01.707232Z","caller":"traceutil/trace.go:171","msg":"trace[403381718] linearizableReadLoop","detail":"{readStateIndex:394; appliedIndex:393; }","duration":"236.897627ms","start":"2025-10-31T00:38:01.470321Z","end":"2025-10-31T00:38:01.707219Z","steps":["trace[403381718] 'read index received' (duration: 40.518921ms)","trace[403381718] 'applied index is now lower than readState.Index' (duration: 196.377894ms)"],"step_count":2} Oct 31 00:38:01.707602 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:01.707269Z","caller":"traceutil/trace.go:171","msg":"trace[2067204775] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"263.841961ms","start":"2025-10-31T00:38:01.443413Z","end":"2025-10-31T00:38:01.707255Z","steps":["trace[2067204775] 'process raft request' (duration: 67.360798ms)","trace[2067204775] 'compare' (duration: 196.127235ms)"],"step_count":2} Oct 31 00:38:01.707602 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:38:01.707278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.951496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-scheduler-localhost.18736c67ed0a321f\" limit:1 ","response":"range_response_count:1 size:817"} Oct 31 00:38:01.708471 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:01.707303Z","caller":"traceutil/trace.go:171","msg":"trace[1426020884] range","detail":"{range_begin:/registry/events/kube-system/kube-scheduler-localhost.18736c67ed0a321f; range_end:; response_count:1; response_revision:383; }","duration":"236.97643ms","start":"2025-10-31T00:38:01.470319Z","end":"2025-10-31T00:38:01.707296Z","steps":["trace[1426020884] 'agreement among raft nodes before linearized reading' (duration: 236.933782ms)"],"step_count":1} Oct 31 00:38:01.717395 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:01.716908Z","caller":"traceutil/trace.go:171","msg":"trace[485395158] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"243.952872ms","start":"2025-10-31T00:38:01.472937Z","end":"2025-10-31T00:38:01.716890Z","steps":["trace[485395158] 'process raft request' (duration: 243.840086ms)"],"step_count":1} Oct 31 00:38:02.200411 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:38:02.199967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.426206ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Oct 31 00:38:02.200411 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:02.200056Z","caller":"traceutil/trace.go:171","msg":"trace[1195956172] linearizableReadLoop","detail":"{readStateIndex:396; appliedIndex:395; }","duration":"480.346418ms","start":"2025-10-31T00:38:01.719699Z","end":"2025-10-31T00:38:02.200045Z","steps":["trace[1195956172] 'read index received' (duration: 281.687446ms)","trace[1195956172] 'applied index is now lower than readState.Index' (duration: 198.658521ms)"],"step_count":2} Oct 31 00:38:02.200411 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:02.200116Z","caller":"traceutil/trace.go:171","msg":"trace[1862019923] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"490.967677ms","start":"2025-10-31T00:38:01.709115Z","end":"2025-10-31T00:38:02.200083Z","steps":["trace[1862019923] 'process raft request' (duration: 292.357865ms)","trace[1862019923] 'compare' (duration: 198.316514ms)"],"step_count":2} Oct 31 00:38:02.200411 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:38:02.200182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.490091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-localhost\" limit:1 ","response":"range_response_count:1 size:4700"} Oct 31 00:38:02.200744 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:38:02.200192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-10-31T00:38:01.709072Z","time spent":"491.086614ms","remote":"10.0.0.58:33482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":802,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Oct 31 00:38:02.200744 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:02.200205Z","caller":"traceutil/trace.go:171","msg":"trace[1432325141] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-localhost; range_end:; response_count:1; response_revision:385; }","duration":"480.513243ms","start":"2025-10-31T00:38:01.719684Z","end":"2025-10-31T00:38:02.200197Z","steps":["trace[1432325141] 'agreement among raft nodes before linearized reading' (duration: 480.389356ms)"],"step_count":1} Oct 31 00:38:02.200744 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:38:02.200235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-10-31T00:38:01.719668Z","time spent":"480.556242ms","remote":"10.0.0.58:33594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":4724,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-localhost\" limit:1 "} Oct 31 00:38:02.775777 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:38:02.775236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.94734ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Oct 31 00:38:02.775777 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:02.775416Z","caller":"traceutil/trace.go:171","msg":"trace[1528365483] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"570.097267ms","start":"2025-10-31T00:38:02.205303Z","end":"2025-10-31T00:38:02.775400Z","steps":["trace[1528365483] 'process raft request' (duration: 179.918563ms)","trace[1528365483] 'compare' (duration: 389.799178ms)"],"step_count":2} Oct 31 00:38:02.775777 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:02.775475Z","caller":"traceutil/trace.go:171","msg":"trace[387978306] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"507.836879ms","start":"2025-10-31T00:38:02.267630Z","end":"2025-10-31T00:38:02.775467Z","steps":["trace[387978306] 'process raft request' (duration: 507.697243ms)"],"step_count":1} Oct 31 00:38:02.775777 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:38:02.775481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-10-31T00:38:02.205283Z","time spent":"570.168247ms","remote":"10.0.0.58:33594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4493,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Oct 31 00:38:02.776495 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:38:02.775511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-10-31T00:38:02.267605Z","time spent":"507.890417ms","remote":"10.0.0.58:33482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":750,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} Oct 31 00:38:04.126233 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:04.125815Z","caller":"traceutil/trace.go:171","msg":"trace[905020660] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"212.259655ms","start":"2025-10-31T00:38:03.913536Z","end":"2025-10-31T00:38:04.125796Z","steps":["trace[905020660] 'process raft request' (duration: 180.779087ms)","trace[905020660] 'compare' (duration: 31.357942ms)"],"step_count":2} Oct 31 00:38:04.378911 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:38:04.378477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.621347ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Oct 31 00:38:04.378911 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:04.378550Z","caller":"traceutil/trace.go:171","msg":"trace[1166309516] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"243.073952ms","start":"2025-10-31T00:38:04.135465Z","end":"2025-10-31T00:38:04.378539Z","steps":["trace[1166309516] 'process raft request' (duration: 96.335058ms)","trace[1166309516] 'compare' (duration: 146.472895ms)"],"step_count":2} Oct 31 00:38:06.135386 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:38:06.134957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.153066ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Oct 31 00:38:06.135386 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:06.135060Z","caller":"traceutil/trace.go:171","msg":"trace[45152659] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"178.573264ms","start":"2025-10-31T00:38:05.956467Z","end":"2025-10-31T00:38:06.135040Z","steps":["trace[45152659] 'process raft request' (duration: 34.286242ms)","trace[45152659] 'compare' (duration: 144.015583ms)"],"step_count":2} Oct 31 00:38:06.159125 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:06.158507Z","caller":"traceutil/trace.go:171","msg":"trace[1415683613] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:422; }","duration":"141.917482ms","start":"2025-10-31T00:38:06.016571Z","end":"2025-10-31T00:38:06.158488Z","steps":["trace[1415683613] 'read index received' (duration: 141.740316ms)","trace[1415683613] 'applied index is now lower than readState.Index' (duration: 176.394µs)"],"step_count":2} Oct 31 00:38:06.159125 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:06.158581Z","caller":"traceutil/trace.go:171","msg":"trace[59816092] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"199.34796ms","start":"2025-10-31T00:38:05.959202Z","end":"2025-10-31T00:38:06.158550Z","steps":["trace[59816092] 'process raft request' (duration: 199.11376ms)"],"step_count":1} Oct 31 00:38:06.159125 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:38:06.158639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.031251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" limit:1 ","response":"range_response_count:1 size:179"} Oct 31 00:38:06.159125 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:06.158702Z","caller":"traceutil/trace.go:171","msg":"trace[746216960] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:412; }","duration":"142.123821ms","start":"2025-10-31T00:38:06.016563Z","end":"2025-10-31T00:38:06.158687Z","steps":["trace[746216960] 'agreement among raft nodes before linearized reading' (duration: 141.996477ms)"],"step_count":1} Oct 31 00:38:06.721920 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:06.721362Z","caller":"traceutil/trace.go:171","msg":"trace[965136870] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"239.4735ms","start":"2025-10-31T00:38:06.481854Z","end":"2025-10-31T00:38:06.721328Z","steps":["trace[965136870] 'process raft request' (duration: 224.356089ms)","trace[965136870] 'compare' (duration: 14.94265ms)"],"step_count":2} Oct 31 00:38:06.735545 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:06.734542Z","caller":"traceutil/trace.go:171","msg":"trace[1489122169] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"245.714853ms","start":"2025-10-31T00:38:06.488803Z","end":"2025-10-31T00:38:06.734518Z","steps":["trace[1489122169] 'process raft request' (duration: 245.31105ms)"],"step_count":1} Oct 31 00:38:08.163922 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:08.163517Z","caller":"traceutil/trace.go:171","msg":"trace[712256296] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"128.083443ms","start":"2025-10-31T00:38:08.035415Z","end":"2025-10-31T00:38:08.163498Z","steps":["trace[712256296] 'process raft request' (duration: 127.948664ms)"],"step_count":1} Oct 31 00:38:08.446997 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:08.446464Z","caller":"traceutil/trace.go:171","msg":"trace[2059360066] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"251.115414ms","start":"2025-10-31T00:38:08.195330Z","end":"2025-10-31T00:38:08.446445Z","steps":["trace[2059360066] 'process raft request' (duration: 226.038937ms)","trace[2059360066] 'compare' (duration: 24.978155ms)"],"step_count":2} Oct 31 00:38:10.030399 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:10.029865Z","caller":"traceutil/trace.go:171","msg":"trace[1932601275] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"157.394131ms","start":"2025-10-31T00:38:09.872452Z","end":"2025-10-31T00:38:10.029846Z","steps":["trace[1932601275] 'process raft request' (duration: 77.491882ms)","trace[1932601275] 'compare' (duration: 79.815379ms)"],"step_count":2} Oct 31 00:38:20.945824 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:20.945353Z","caller":"traceutil/trace.go:171","msg":"trace[163140066] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"265.246309ms","start":"2025-10-31T00:38:20.680066Z","end":"2025-10-31T00:38:20.945312Z","steps":["trace[163140066] 'process raft request' (duration: 175.662981ms)","trace[163140066] 'compare' (duration: 89.448088ms)"],"step_count":2} Oct 31 00:38:27.815599 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:38:27.815226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.663842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/10.0.0.67\" limit:1 ","response":"range_response_count:0 size:5"} Oct 31 00:38:27.815599 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:27.815291Z","caller":"traceutil/trace.go:171","msg":"trace[2115361271] range","detail":"{range_begin:/registry/minions/10.0.0.67; range_end:; response_count:0; response_revision:488; }","duration":"108.745131ms","start":"2025-10-31T00:38:27.706530Z","end":"2025-10-31T00:38:27.815276Z","steps":["trace[2115361271] 'range keys from in-memory index tree' (duration: 108.586188ms)"],"step_count":1} Oct 31 00:38:28.313548 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:38:28.313047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.689539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/10.0.0.67\" limit:1 ","response":"range_response_count:0 size:5"} Oct 31 00:38:28.313548 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:28.313149Z","caller":"traceutil/trace.go:171","msg":"trace[1098637356] range","detail":"{range_begin:/registry/minions/10.0.0.67; range_end:; response_count:0; response_revision:489; }","duration":"109.809272ms","start":"2025-10-31T00:38:28.203327Z","end":"2025-10-31T00:38:28.313137Z","steps":["trace[1098637356] 'range keys from in-memory index tree' (duration: 109.631582ms)"],"step_count":1} Oct 31 00:38:28.941267 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:38:28.940913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.580868ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Oct 31 00:38:28.941267 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:28.940987Z","caller":"traceutil/trace.go:171","msg":"trace[1681287340] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"232.956645ms","start":"2025-10-31T00:38:28.708019Z","end":"2025-10-31T00:38:28.940976Z","steps":["trace[1681287340] 'process raft request' (duration: 129.263328ms)","trace[1681287340] 'compare' (duration: 103.514766ms)"],"step_count":2} Oct 31 00:38:29.110612 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:29.110259Z","caller":"traceutil/trace.go:171","msg":"trace[455171056] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"128.450832ms","start":"2025-10-31T00:38:28.981788Z","end":"2025-10-31T00:38:29.110239Z","steps":["trace[455171056] 'process raft request' (duration: 77.47418ms)","trace[455171056] 'compare' (duration: 50.868401ms)"],"step_count":2} Oct 31 00:38:29.110612 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:29.110273Z","caller":"traceutil/trace.go:171","msg":"trace[1885659412] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"127.201563ms","start":"2025-10-31T00:38:28.983062Z","end":"2025-10-31T00:38:29.110263Z","steps":["trace[1885659412] 'process raft request' (duration: 127.148845ms)"],"step_count":1} Oct 31 00:38:29.911278 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:38:29.910903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.997096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" limit:1 ","response":"range_response_count:1 size:185"} Oct 31 00:38:29.911278 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:38:29.910929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.034235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-flannel/flannel\" limit:1 ","response":"range_response_count:1 size:612"} Oct 31 00:38:29.911278 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:29.911027Z","caller":"traceutil/trace.go:171","msg":"trace[1581443342] range","detail":"{range_begin:/registry/serviceaccounts/kube-flannel/flannel; range_end:; response_count:1; response_revision:511; }","duration":"172.150362ms","start":"2025-10-31T00:38:29.738858Z","end":"2025-10-31T00:38:29.911009Z","steps":["trace[1581443342] 'range keys from in-memory index tree' (duration: 171.937264ms)"],"step_count":1} Oct 31 00:38:29.911278 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:29.910966Z","caller":"traceutil/trace.go:171","msg":"trace[1626550834] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:511; }","duration":"172.068619ms","start":"2025-10-31T00:38:29.738886Z","end":"2025-10-31T00:38:29.910954Z","steps":["trace[1626550834] 'range keys from in-memory index tree' (duration: 171.952593ms)"],"step_count":1} Oct 31 00:38:34.709840 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:34.709399Z","caller":"traceutil/trace.go:171","msg":"trace[2118900506] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"151.779386ms","start":"2025-10-31T00:38:34.557599Z","end":"2025-10-31T00:38:34.709378Z","steps":["trace[2118900506] 'process raft request' (duration: 151.663621ms)"],"step_count":1} Oct 31 00:38:34.764124 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:34.763743Z","caller":"traceutil/trace.go:171","msg":"trace[1364659331] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"202.327132ms","start":"2025-10-31T00:38:34.561383Z","end":"2025-10-31T00:38:34.763711Z","steps":["trace[1364659331] 'process raft request' (duration: 202.20269ms)"],"step_count":1} Oct 31 00:38:35.021966 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:35.021463Z","caller":"traceutil/trace.go:171","msg":"trace[965658777] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"253.473941ms","start":"2025-10-31T00:38:34.767966Z","end":"2025-10-31T00:38:35.021440Z","steps":["trace[965658777] 'process raft request' (duration: 205.723248ms)","trace[965658777] 'compare' (duration: 47.632514ms)"],"step_count":2} Oct 31 00:38:40.765492 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:40.765162Z","caller":"traceutil/trace.go:171","msg":"trace[1221915410] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"255.831695ms","start":"2025-10-31T00:38:40.509313Z","end":"2025-10-31T00:38:40.765144Z","steps":["trace[1221915410] 'process raft request' (duration: 255.630028ms)"],"step_count":1} Oct 31 00:38:40.934500 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:40.933953Z","caller":"traceutil/trace.go:171","msg":"trace[1696132758] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:573; }","duration":"163.870426ms","start":"2025-10-31T00:38:40.770061Z","end":"2025-10-31T00:38:40.933932Z","steps":["trace[1696132758] 'read index received' (duration: 124.083863ms)","trace[1696132758] 'applied index is now lower than readState.Index' (duration: 39.785782ms)"],"step_count":2} Oct 31 00:38:40.934500 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:40.934048Z","caller":"traceutil/trace.go:171","msg":"trace[1023404269] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"354.911517ms","start":"2025-10-31T00:38:40.579122Z","end":"2025-10-31T00:38:40.934033Z","steps":["trace[1023404269] 'process raft request' (duration: 314.918699ms)","trace[1023404269] 'compare' (duration: 39.711304ms)"],"step_count":2} Oct 31 00:38:40.934500 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:40.934036Z","caller":"traceutil/trace.go:171","msg":"trace[24068552] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"351.688172ms","start":"2025-10-31T00:38:40.582321Z","end":"2025-10-31T00:38:40.934009Z","steps":["trace[24068552] 'process raft request' (duration: 351.557389ms)"],"step_count":1} Oct 31 00:38:40.934500 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:38:40.934156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-10-31T00:38:40.579074Z","time spent":"355.034416ms","remote":"10.0.0.58:33482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":621,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} Oct 31 00:38:40.934782 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:38:40.934172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.101236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-flannel/kube-flannel-ds-kfz8j\" limit:1 ","response":"range_response_count:1 size:7526"} Oct 31 00:38:40.934782 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:40.934199Z","caller":"traceutil/trace.go:171","msg":"trace[1680906256] range","detail":"{range_begin:/registry/pods/kube-flannel/kube-flannel-ds-kfz8j; range_end:; response_count:1; response_revision:556; }","duration":"164.142584ms","start":"2025-10-31T00:38:40.770050Z","end":"2025-10-31T00:38:40.934192Z","steps":["trace[1680906256] 'agreement among raft nodes before linearized reading' (duration: 163.973549ms)"],"step_count":1} Oct 31 00:38:40.934782 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:38:40.934211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-10-31T00:38:40.582299Z","time spent":"351.833093ms","remote":"10.0.0.58:33578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2656,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Oct 31 00:38:41.217040 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:38:41.216604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.937764ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Oct 31 00:38:41.217040 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:41.216795Z","caller":"traceutil/trace.go:171","msg":"trace[1667841710] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:575; }","duration":"270.748378ms","start":"2025-10-31T00:38:40.946033Z","end":"2025-10-31T00:38:41.216781Z","steps":["trace[1667841710] 'read index received' (duration: 32.740348ms)","trace[1667841710] 'applied index is now lower than readState.Index' (duration: 238.007008ms)"],"step_count":2} Oct 31 00:38:41.217496 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:41.216902Z","caller":"traceutil/trace.go:171","msg":"trace[1247405535] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"273.440734ms","start":"2025-10-31T00:38:40.943454Z","end":"2025-10-31T00:38:41.216895Z","steps":["trace[1247405535] 'process raft request' (duration: 273.270958ms)"],"step_count":1} Oct 31 00:38:41.217496 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:38:41.216939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.898487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/10.0.0.58\" limit:1 ","response":"range_response_count:1 size:125"} Oct 31 00:38:41.217496 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:41.216976Z","caller":"traceutil/trace.go:171","msg":"trace[1191704338] range","detail":"{range_begin:/registry/masterleases/10.0.0.58; range_end:; response_count:1; response_revision:559; }","duration":"270.938172ms","start":"2025-10-31T00:38:40.946029Z","end":"2025-10-31T00:38:41.216967Z","steps":["trace[1191704338] 'agreement among raft nodes before linearized reading' (duration: 270.866809ms)"],"step_count":1} Oct 31 00:38:41.217496 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:41.217050Z","caller":"traceutil/trace.go:171","msg":"trace[297422922] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"276.098705ms","start":"2025-10-31T00:38:40.940897Z","end":"2025-10-31T00:38:41.216995Z","steps":["trace[297422922] 'process raft request' (duration: 112.716443ms)","trace[297422922] 'compare' (duration: 162.838529ms)"],"step_count":2} Oct 31 00:38:41.244242 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:38:41.243868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.93865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-flannel/kube-flannel-ds-kfz8j.18736c709fb40db4\" limit:1 ","response":"range_response_count:1 size:818"} Oct 31 00:38:41.244242 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:41.243935Z","caller":"traceutil/trace.go:171","msg":"trace[1336554687] range","detail":"{range_begin:/registry/events/kube-flannel/kube-flannel-ds-kfz8j.18736c709fb40db4; range_end:; response_count:1; response_revision:560; }","duration":"262.023336ms","start":"2025-10-31T00:38:40.981895Z","end":"2025-10-31T00:38:41.243919Z","steps":["trace[1336554687] 'agreement among raft nodes before linearized reading' (duration: 261.857407ms)"],"step_count":1} Oct 31 00:38:42.324696 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:38:42.324386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.110313ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Oct 31 00:38:42.324696 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:42.324469Z","caller":"traceutil/trace.go:171","msg":"trace[1761261276] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"545.288961ms","start":"2025-10-31T00:38:41.779167Z","end":"2025-10-31T00:38:42.324456Z","steps":["trace[1761261276] 'process raft request' (duration: 377.994374ms)","trace[1761261276] 'compare' (duration: 167.002181ms)"],"step_count":2} Oct 31 00:38:42.324696 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:38:42.324523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-10-31T00:38:41.779143Z","time spent":"545.350505ms","remote":"10.0.0.58:33594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7680,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Oct 31 00:38:42.348467 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:42.348307Z","caller":"traceutil/trace.go:171","msg":"trace[1722176528] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:583; }","duration":"467.174947ms","start":"2025-10-31T00:38:41.881123Z","end":"2025-10-31T00:38:42.348298Z","steps":["trace[1722176528] 'read index received' (duration: 276.115673ms)","trace[1722176528] 'applied index is now lower than readState.Index' (duration: 191.058723ms)"],"step_count":2} Oct 31 00:38:42.348686 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:42.348353Z","caller":"traceutil/trace.go:171","msg":"trace[327362089] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"240.887252ms","start":"2025-10-31T00:38:42.107453Z","end":"2025-10-31T00:38:42.348340Z","steps":["trace[327362089] 'process raft request' (duration: 240.787596ms)"],"step_count":1} Oct 31 00:38:42.348686 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:38:42.348367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.248895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Oct 31 00:38:42.348686 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:42.348387Z","caller":"traceutil/trace.go:171","msg":"trace[313078531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:567; }","duration":"467.270545ms","start":"2025-10-31T00:38:41.881110Z","end":"2025-10-31T00:38:42.348380Z","steps":["trace[313078531] 'agreement among raft nodes before linearized reading' (duration: 467.227175ms)"],"step_count":1} Oct 31 00:38:42.348686 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:38:42.348408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-10-31T00:38:41.881075Z","time spent":"467.3262ms","remote":"10.0.0.58:33430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} Oct 31 00:38:42.348686 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:42.348416Z","caller":"traceutil/trace.go:171","msg":"trace[28003647] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"549.182523ms","start":"2025-10-31T00:38:41.799226Z","end":"2025-10-31T00:38:42.348408Z","steps":["trace[28003647] 'process raft request' (duration: 548.960749ms)"],"step_count":1} Oct 31 00:38:42.348877 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:38:42.348461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-10-31T00:38:41.799207Z","time spent":"549.230734ms","remote":"10.0.0.58:33482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} Oct 31 00:38:42.348877 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:38:42.348482Z","caller":"traceutil/trace.go:171","msg":"trace[1483991412] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"208.475049ms","start":"2025-10-31T00:38:42.139998Z","end":"2025-10-31T00:38:42.348473Z","steps":["trace[1483991412] 'process raft request' (duration: 208.273093ms)"],"step_count":1} Oct 31 00:38:46.550900 etcd-wrapper[1627]: {"level":"warn","ts":"2025-10-31T00:38:46.550537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.000886ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} Oct 31 00:39:10.686905 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:39:10.686440Z","caller":"traceutil/trace.go:171","msg":"trace[957623602] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"119.519967ms","start":"2025-10-31T00:39:10.566898Z","end":"2025-10-31T00:39:10.686418Z","steps":["trace[957623602] 'process raft request' (duration: 58.090888ms)","trace[957623602] 'compare' (duration: 61.336021ms)"],"step_count":2} Oct 31 00:39:12.792772 etcd-wrapper[1627]: {"level":"info","ts":"2025-10-31T00:39:12.792385Z","caller":"traceutil/trace.go:171","msg":"trace[1600314094] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"101.763572ms","start":"2025-10-31T00:39:12.690591Z","end":"2025-10-31T00:39:12.792354Z","steps":["trace[1600314094] 'process raft request' (duration: 101.535685ms)"],"step_count":1}