Dec 13 01:27:33.965456 kernel: Linux version 6.6.65-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 Dec 12 23:15:00 -00 2024 Dec 13 01:27:33.965500 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=2fdbba50b59d8c8a9877a81151806ddc16f473fe99b9ba0d8825997d654583ff Dec 13 01:27:33.965515 kernel: BIOS-provided physical RAM map: Dec 13 01:27:33.965524 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Dec 13 01:27:33.965532 kernel: BIOS-e820: [mem 0x0000000000100000-0x00000000007fffff] usable Dec 13 01:27:33.965540 kernel: BIOS-e820: [mem 0x0000000000800000-0x0000000000807fff] ACPI NVS Dec 13 01:27:33.965550 kernel: BIOS-e820: [mem 0x0000000000808000-0x000000000080afff] usable Dec 13 01:27:33.965559 kernel: BIOS-e820: [mem 0x000000000080b000-0x000000000080bfff] ACPI NVS Dec 13 01:27:33.965568 kernel: BIOS-e820: [mem 0x000000000080c000-0x000000000080ffff] usable Dec 13 01:27:33.965576 kernel: BIOS-e820: [mem 0x0000000000810000-0x00000000008fffff] ACPI NVS Dec 13 01:27:33.965592 kernel: BIOS-e820: [mem 0x0000000000900000-0x000000009c8eefff] usable Dec 13 01:27:33.965601 kernel: BIOS-e820: [mem 0x000000009c8ef000-0x000000009c9eefff] reserved Dec 13 01:27:33.965609 kernel: BIOS-e820: [mem 0x000000009c9ef000-0x000000009caeefff] type 20 Dec 13 01:27:33.965617 kernel: BIOS-e820: [mem 0x000000009caef000-0x000000009cb6efff] reserved Dec 13 01:27:33.965631 kernel: BIOS-e820: [mem 0x000000009cb6f000-0x000000009cb7efff] ACPI data Dec 13 01:27:33.965640 kernel: BIOS-e820: [mem 0x000000009cb7f000-0x000000009cbfefff] ACPI NVS Dec 13 01:27:33.965652 kernel: BIOS-e820: [mem 0x000000009cbff000-0x000000009cf3ffff] usable Dec 13 01:27:33.965661 kernel: BIOS-e820: [mem 0x000000009cf40000-0x000000009cf5ffff] reserved Dec 13 01:27:33.965668 kernel: BIOS-e820: [mem 0x000000009cf60000-0x000000009cffffff] ACPI NVS Dec 13 01:27:33.965674 kernel: BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved Dec 13 01:27:33.965681 kernel: NX (Execute Disable) protection: active Dec 13 01:27:33.965689 kernel: APIC: Static calls initialized Dec 13 01:27:33.965698 kernel: efi: EFI v2.7 by EDK II Dec 13 01:27:33.965708 kernel: efi: SMBIOS=0x9c9ab000 ACPI=0x9cb7e000 ACPI 2.0=0x9cb7e014 MEMATTR=0x9b674118 Dec 13 01:27:33.965717 kernel: SMBIOS 2.8 present. Dec 13 01:27:33.965726 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 0.0.0 02/06/2015 Dec 13 01:27:33.965735 kernel: Hypervisor detected: KVM Dec 13 01:27:33.965748 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Dec 13 01:27:33.965757 kernel: kvm-clock: using sched offset of 5697137811 cycles Dec 13 01:27:33.965767 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Dec 13 01:27:33.965776 kernel: tsc: Detected 2794.748 MHz processor Dec 13 01:27:33.965786 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Dec 13 01:27:33.965795 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Dec 13 01:27:33.965804 kernel: last_pfn = 0x9cf40 max_arch_pfn = 0x400000000 Dec 13 01:27:33.965814 kernel: MTRR map: 4 entries (2 fixed + 2 variable; max 18), built from 8 variable MTRRs Dec 13 01:27:33.965823 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Dec 13 01:27:33.965835 kernel: Using GB pages for direct mapping Dec 13 01:27:33.965844 kernel: Secure boot disabled Dec 13 01:27:33.965853 kernel: ACPI: Early table checksum verification disabled Dec 13 01:27:33.965863 kernel: ACPI: RSDP 0x000000009CB7E014 000024 (v02 BOCHS ) Dec 13 01:27:33.965881 kernel: ACPI: XSDT 0x000000009CB7D0E8 000054 (v01 BOCHS BXPC 00000001 01000013) Dec 13 01:27:33.965888 kernel: ACPI: FACP 0x000000009CB79000 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) Dec 13 01:27:33.965896 kernel: ACPI: DSDT 0x000000009CB7A000 0021A8 (v01 BOCHS BXPC 00000001 BXPC 00000001) Dec 13 01:27:33.965906 kernel: ACPI: FACS 0x000000009CBDD000 000040 Dec 13 01:27:33.965913 kernel: ACPI: APIC 0x000000009CB78000 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) Dec 13 01:27:33.965922 kernel: ACPI: HPET 0x000000009CB77000 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Dec 13 01:27:33.965933 kernel: ACPI: MCFG 0x000000009CB76000 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Dec 13 01:27:33.965943 kernel: ACPI: WAET 0x000000009CB75000 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Dec 13 01:27:33.965953 kernel: ACPI: BGRT 0x000000009CB74000 000038 (v01 INTEL EDK2 00000002 01000013) Dec 13 01:27:33.965960 kernel: ACPI: Reserving FACP table memory at [mem 0x9cb79000-0x9cb790f3] Dec 13 01:27:33.965980 kernel: ACPI: Reserving DSDT table memory at [mem 0x9cb7a000-0x9cb7c1a7] Dec 13 01:27:33.965988 kernel: ACPI: Reserving FACS table memory at [mem 0x9cbdd000-0x9cbdd03f] Dec 13 01:27:33.965995 kernel: ACPI: Reserving APIC table memory at [mem 0x9cb78000-0x9cb7808f] Dec 13 01:27:33.966003 kernel: ACPI: Reserving HPET table memory at [mem 0x9cb77000-0x9cb77037] Dec 13 01:27:33.966010 kernel: ACPI: Reserving MCFG table memory at [mem 0x9cb76000-0x9cb7603b] Dec 13 01:27:33.966017 kernel: ACPI: Reserving WAET table memory at [mem 0x9cb75000-0x9cb75027] Dec 13 01:27:33.966024 kernel: ACPI: Reserving BGRT table memory at [mem 0x9cb74000-0x9cb74037] Dec 13 01:27:33.966033 kernel: No NUMA configuration found Dec 13 01:27:33.966041 kernel: Faking a node at [mem 0x0000000000000000-0x000000009cf3ffff] Dec 13 01:27:33.966051 kernel: NODE_DATA(0) allocated [mem 0x9cea6000-0x9ceabfff] Dec 13 01:27:33.966058 kernel: Zone ranges: Dec 13 01:27:33.966081 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Dec 13 01:27:33.966088 kernel: DMA32 [mem 0x0000000001000000-0x000000009cf3ffff] Dec 13 01:27:33.966095 kernel: Normal empty Dec 13 01:27:33.966102 kernel: Movable zone start for each node Dec 13 01:27:33.966109 kernel: Early memory node ranges Dec 13 01:27:33.966116 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Dec 13 01:27:33.966124 kernel: node 0: [mem 0x0000000000100000-0x00000000007fffff] Dec 13 01:27:33.966134 kernel: node 0: [mem 0x0000000000808000-0x000000000080afff] Dec 13 01:27:33.966141 kernel: node 0: [mem 0x000000000080c000-0x000000000080ffff] Dec 13 01:27:33.966148 kernel: node 0: [mem 0x0000000000900000-0x000000009c8eefff] Dec 13 01:27:33.966155 kernel: node 0: [mem 0x000000009cbff000-0x000000009cf3ffff] Dec 13 01:27:33.966165 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009cf3ffff] Dec 13 01:27:33.966172 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Dec 13 01:27:33.966179 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Dec 13 01:27:33.966186 kernel: On node 0, zone DMA: 8 pages in unavailable ranges Dec 13 01:27:33.966193 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Dec 13 01:27:33.966201 kernel: On node 0, zone DMA: 240 pages in unavailable ranges Dec 13 01:27:33.966210 kernel: On node 0, zone DMA32: 784 pages in unavailable ranges Dec 13 01:27:33.966218 kernel: On node 0, zone DMA32: 12480 pages in unavailable ranges Dec 13 01:27:33.966225 kernel: ACPI: PM-Timer IO Port: 0x608 Dec 13 01:27:33.966232 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Dec 13 01:27:33.966239 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Dec 13 01:27:33.966247 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Dec 13 01:27:33.966254 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Dec 13 01:27:33.966261 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Dec 13 01:27:33.966268 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Dec 13 01:27:33.966278 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Dec 13 01:27:33.966285 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Dec 13 01:27:33.966292 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Dec 13 01:27:33.966300 kernel: TSC deadline timer available Dec 13 01:27:33.966307 kernel: smpboot: Allowing 4 CPUs, 0 hotplug CPUs Dec 13 01:27:33.966317 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Dec 13 01:27:33.966327 kernel: kvm-guest: KVM setup pv remote TLB flush Dec 13 01:27:33.966337 kernel: kvm-guest: setup PV sched yield Dec 13 01:27:33.966347 kernel: [mem 0xc0000000-0xffffffff] available for PCI devices Dec 13 01:27:33.966361 kernel: Booting paravirtualized kernel on KVM Dec 13 01:27:33.966371 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Dec 13 01:27:33.966381 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 Dec 13 01:27:33.966391 kernel: percpu: Embedded 58 pages/cpu s197032 r8192 d32344 u524288 Dec 13 01:27:33.966400 kernel: pcpu-alloc: s197032 r8192 d32344 u524288 alloc=1*2097152 Dec 13 01:27:33.966410 kernel: pcpu-alloc: [0] 0 1 2 3 Dec 13 01:27:33.966419 kernel: kvm-guest: PV spinlocks enabled Dec 13 01:27:33.966429 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Dec 13 01:27:33.966442 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=2fdbba50b59d8c8a9877a81151806ddc16f473fe99b9ba0d8825997d654583ff Dec 13 01:27:33.966454 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Dec 13 01:27:33.966461 kernel: random: crng init done Dec 13 01:27:33.966470 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Dec 13 01:27:33.966479 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Dec 13 01:27:33.966489 kernel: Fallback order for Node 0: 0 Dec 13 01:27:33.966500 kernel: Built 1 zonelists, mobility grouping on. Total pages: 629759 Dec 13 01:27:33.966511 kernel: Policy zone: DMA32 Dec 13 01:27:33.966523 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Dec 13 01:27:33.966539 kernel: Memory: 2395616K/2567000K available (12288K kernel code, 2299K rwdata, 22724K rodata, 42844K init, 2348K bss, 171124K reserved, 0K cma-reserved) Dec 13 01:27:33.966549 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Dec 13 01:27:33.966559 kernel: ftrace: allocating 37902 entries in 149 pages Dec 13 01:27:33.966569 kernel: ftrace: allocated 149 pages with 4 groups Dec 13 01:27:33.966579 kernel: Dynamic Preempt: voluntary Dec 13 01:27:33.966596 kernel: rcu: Preemptible hierarchical RCU implementation. Dec 13 01:27:33.966607 kernel: rcu: RCU event tracing is enabled. Dec 13 01:27:33.966615 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Dec 13 01:27:33.966623 kernel: Trampoline variant of Tasks RCU enabled. Dec 13 01:27:33.966630 kernel: Rude variant of Tasks RCU enabled. Dec 13 01:27:33.966638 kernel: Tracing variant of Tasks RCU enabled. Dec 13 01:27:33.966645 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Dec 13 01:27:33.966656 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Dec 13 01:27:33.966663 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 Dec 13 01:27:33.966674 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Dec 13 01:27:33.966682 kernel: Console: colour dummy device 80x25 Dec 13 01:27:33.966692 kernel: printk: console [ttyS0] enabled Dec 13 01:27:33.966700 kernel: ACPI: Core revision 20230628 Dec 13 01:27:33.966708 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Dec 13 01:27:33.966715 kernel: APIC: Switch to symmetric I/O mode setup Dec 13 01:27:33.966723 kernel: x2apic enabled Dec 13 01:27:33.966730 kernel: APIC: Switched APIC routing to: physical x2apic Dec 13 01:27:33.966738 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() Dec 13 01:27:33.966745 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() Dec 13 01:27:33.966753 kernel: kvm-guest: setup PV IPIs Dec 13 01:27:33.966760 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Dec 13 01:27:33.966770 kernel: tsc: Marking TSC unstable due to TSCs unsynchronized Dec 13 01:27:33.966778 kernel: Calibrating delay loop (skipped) preset value.. 5589.49 BogoMIPS (lpj=2794748) Dec 13 01:27:33.966785 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Dec 13 01:27:33.966793 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 Dec 13 01:27:33.966800 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 Dec 13 01:27:33.966808 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Dec 13 01:27:33.966817 kernel: Spectre V2 : Mitigation: Retpolines Dec 13 01:27:33.966828 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Dec 13 01:27:33.966841 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Dec 13 01:27:33.966849 kernel: Spectre V2 : Enabling Speculation Barrier for firmware calls Dec 13 01:27:33.966856 kernel: RETBleed: Mitigation: untrained return thunk Dec 13 01:27:33.966867 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Dec 13 01:27:33.966875 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Dec 13 01:27:33.966882 kernel: Speculative Return Stack Overflow: IBPB-extending microcode not applied! Dec 13 01:27:33.966890 kernel: Speculative Return Stack Overflow: WARNING: See https://kernel.org/doc/html/latest/admin-guide/hw-vuln/srso.html for mitigation options. Dec 13 01:27:33.966898 kernel: Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode Dec 13 01:27:33.966906 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Dec 13 01:27:33.966916 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Dec 13 01:27:33.966923 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Dec 13 01:27:33.966931 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Dec 13 01:27:33.966938 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format. Dec 13 01:27:33.966946 kernel: Freeing SMP alternatives memory: 32K Dec 13 01:27:33.966953 kernel: pid_max: default: 32768 minimum: 301 Dec 13 01:27:33.966961 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Dec 13 01:27:33.966969 kernel: landlock: Up and running. Dec 13 01:27:33.966986 kernel: SELinux: Initializing. Dec 13 01:27:33.966996 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Dec 13 01:27:33.967004 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Dec 13 01:27:33.967012 kernel: smpboot: CPU0: AMD EPYC 7402P 24-Core Processor (family: 0x17, model: 0x31, stepping: 0x0) Dec 13 01:27:33.967019 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Dec 13 01:27:33.967027 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Dec 13 01:27:33.967035 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Dec 13 01:27:33.967042 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver. Dec 13 01:27:33.967050 kernel: ... version: 0 Dec 13 01:27:33.967092 kernel: ... bit width: 48 Dec 13 01:27:33.967105 kernel: ... generic registers: 6 Dec 13 01:27:33.967115 kernel: ... value mask: 0000ffffffffffff Dec 13 01:27:33.967127 kernel: ... max period: 00007fffffffffff Dec 13 01:27:33.967138 kernel: ... fixed-purpose events: 0 Dec 13 01:27:33.967149 kernel: ... event mask: 000000000000003f Dec 13 01:27:33.967160 kernel: signal: max sigframe size: 1776 Dec 13 01:27:33.967172 kernel: rcu: Hierarchical SRCU implementation. Dec 13 01:27:33.967183 kernel: rcu: Max phase no-delay instances is 400. Dec 13 01:27:33.967193 kernel: smp: Bringing up secondary CPUs ... Dec 13 01:27:33.967211 kernel: smpboot: x86: Booting SMP configuration: Dec 13 01:27:33.967222 kernel: .... node #0, CPUs: #1 #2 #3 Dec 13 01:27:33.967232 kernel: smp: Brought up 1 node, 4 CPUs Dec 13 01:27:33.967244 kernel: smpboot: Max logical packages: 1 Dec 13 01:27:33.967256 kernel: smpboot: Total of 4 processors activated (22357.98 BogoMIPS) Dec 13 01:27:33.967267 kernel: devtmpfs: initialized Dec 13 01:27:33.967278 kernel: x86/mm: Memory block size: 128MB Dec 13 01:27:33.967290 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x00800000-0x00807fff] (32768 bytes) Dec 13 01:27:33.967302 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x0080b000-0x0080bfff] (4096 bytes) Dec 13 01:27:33.967318 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x00810000-0x008fffff] (983040 bytes) Dec 13 01:27:33.967329 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9cb7f000-0x9cbfefff] (524288 bytes) Dec 13 01:27:33.967342 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9cf60000-0x9cffffff] (655360 bytes) Dec 13 01:27:33.967353 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Dec 13 01:27:33.967364 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Dec 13 01:27:33.967375 kernel: pinctrl core: initialized pinctrl subsystem Dec 13 01:27:33.967386 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Dec 13 01:27:33.967395 kernel: audit: initializing netlink subsys (disabled) Dec 13 01:27:33.967403 kernel: audit: type=2000 audit(1734053252.267:1): state=initialized audit_enabled=0 res=1 Dec 13 01:27:33.967414 kernel: thermal_sys: Registered thermal governor 'step_wise' Dec 13 01:27:33.967422 kernel: thermal_sys: Registered thermal governor 'user_space' Dec 13 01:27:33.967429 kernel: cpuidle: using governor menu Dec 13 01:27:33.967437 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Dec 13 01:27:33.967444 kernel: dca service started, version 1.12.1 Dec 13 01:27:33.967452 kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xb0000000-0xbfffffff] (base 0xb0000000) Dec 13 01:27:33.967459 kernel: PCI: MMCONFIG at [mem 0xb0000000-0xbfffffff] reserved as E820 entry Dec 13 01:27:33.967467 kernel: PCI: Using configuration type 1 for base access Dec 13 01:27:33.967477 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Dec 13 01:27:33.967485 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Dec 13 01:27:33.967492 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Dec 13 01:27:33.967500 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Dec 13 01:27:33.967507 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Dec 13 01:27:33.967515 kernel: ACPI: Added _OSI(Module Device) Dec 13 01:27:33.967522 kernel: ACPI: Added _OSI(Processor Device) Dec 13 01:27:33.967530 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Dec 13 01:27:33.967537 kernel: ACPI: Added _OSI(Processor Aggregator Device) Dec 13 01:27:33.967548 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Dec 13 01:27:33.967556 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Dec 13 01:27:33.967566 kernel: ACPI: Interpreter enabled Dec 13 01:27:33.967577 kernel: ACPI: PM: (supports S0 S3 S5) Dec 13 01:27:33.967588 kernel: ACPI: Using IOAPIC for interrupt routing Dec 13 01:27:33.967599 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Dec 13 01:27:33.967609 kernel: PCI: Using E820 reservations for host bridge windows Dec 13 01:27:33.967620 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F Dec 13 01:27:33.967631 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Dec 13 01:27:33.967914 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Dec 13 01:27:33.968141 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] Dec 13 01:27:33.968315 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] Dec 13 01:27:33.968332 kernel: PCI host bridge to bus 0000:00 Dec 13 01:27:33.968508 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Dec 13 01:27:33.968658 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Dec 13 01:27:33.968793 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Dec 13 01:27:33.968919 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xafffffff window] Dec 13 01:27:33.969047 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] Dec 13 01:27:33.969182 kernel: pci_bus 0000:00: root bus resource [mem 0x800000000-0xfffffffff window] Dec 13 01:27:33.969311 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Dec 13 01:27:33.969511 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 Dec 13 01:27:33.969691 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 Dec 13 01:27:33.969861 kernel: pci 0000:00:01.0: reg 0x10: [mem 0xc0000000-0xc0ffffff pref] Dec 13 01:27:33.970021 kernel: pci 0000:00:01.0: reg 0x18: [mem 0xc1044000-0xc1044fff] Dec 13 01:27:33.970168 kernel: pci 0000:00:01.0: reg 0x30: [mem 0xffff0000-0xffffffff pref] Dec 13 01:27:33.970295 kernel: pci 0000:00:01.0: BAR 0: assigned to efifb Dec 13 01:27:33.970451 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Dec 13 01:27:33.970633 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 Dec 13 01:27:33.970829 kernel: pci 0000:00:02.0: reg 0x10: [io 0x6100-0x611f] Dec 13 01:27:33.971018 kernel: pci 0000:00:02.0: reg 0x14: [mem 0xc1043000-0xc1043fff] Dec 13 01:27:33.971227 kernel: pci 0000:00:02.0: reg 0x20: [mem 0x800000000-0x800003fff 64bit pref] Dec 13 01:27:33.971415 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 Dec 13 01:27:33.971574 kernel: pci 0000:00:03.0: reg 0x10: [io 0x6000-0x607f] Dec 13 01:27:33.971726 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xc1042000-0xc1042fff] Dec 13 01:27:33.971857 kernel: pci 0000:00:03.0: reg 0x20: [mem 0x800004000-0x800007fff 64bit pref] Dec 13 01:27:33.972046 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 Dec 13 01:27:33.972208 kernel: pci 0000:00:04.0: reg 0x10: [io 0x60e0-0x60ff] Dec 13 01:27:33.972340 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xc1041000-0xc1041fff] Dec 13 01:27:33.972486 kernel: pci 0000:00:04.0: reg 0x20: [mem 0x800008000-0x80000bfff 64bit pref] Dec 13 01:27:33.972617 kernel: pci 0000:00:04.0: reg 0x30: [mem 0xfffc0000-0xffffffff pref] Dec 13 01:27:33.972768 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 Dec 13 01:27:33.972905 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO Dec 13 01:27:33.973058 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 Dec 13 01:27:33.973212 kernel: pci 0000:00:1f.2: reg 0x20: [io 0x60c0-0x60df] Dec 13 01:27:33.973339 kernel: pci 0000:00:1f.2: reg 0x24: [mem 0xc1040000-0xc1040fff] Dec 13 01:27:33.973488 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 Dec 13 01:27:33.973617 kernel: pci 0000:00:1f.3: reg 0x20: [io 0x6080-0x60bf] Dec 13 01:27:33.973628 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Dec 13 01:27:33.973637 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Dec 13 01:27:33.973645 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Dec 13 01:27:33.973657 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Dec 13 01:27:33.973665 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 Dec 13 01:27:33.973673 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 Dec 13 01:27:33.973681 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 Dec 13 01:27:33.973689 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 Dec 13 01:27:33.973697 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 Dec 13 01:27:33.973705 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 Dec 13 01:27:33.973713 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 Dec 13 01:27:33.973720 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 Dec 13 01:27:33.973731 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 Dec 13 01:27:33.973739 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 Dec 13 01:27:33.973746 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 Dec 13 01:27:33.973754 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 Dec 13 01:27:33.973762 kernel: iommu: Default domain type: Translated Dec 13 01:27:33.973770 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Dec 13 01:27:33.973778 kernel: efivars: Registered efivars operations Dec 13 01:27:33.973785 kernel: PCI: Using ACPI for IRQ routing Dec 13 01:27:33.973793 kernel: PCI: pci_cache_line_size set to 64 bytes Dec 13 01:27:33.973805 kernel: e820: reserve RAM buffer [mem 0x0080b000-0x008fffff] Dec 13 01:27:33.973813 kernel: e820: reserve RAM buffer [mem 0x00810000-0x008fffff] Dec 13 01:27:33.973820 kernel: e820: reserve RAM buffer [mem 0x9c8ef000-0x9fffffff] Dec 13 01:27:33.973828 kernel: e820: reserve RAM buffer [mem 0x9cf40000-0x9fffffff] Dec 13 01:27:33.973954 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device Dec 13 01:27:33.974110 kernel: pci 0000:00:01.0: vgaarb: bridge control possible Dec 13 01:27:33.974237 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Dec 13 01:27:33.974248 kernel: vgaarb: loaded Dec 13 01:27:33.974260 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Dec 13 01:27:33.974268 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Dec 13 01:27:33.974276 kernel: clocksource: Switched to clocksource kvm-clock Dec 13 01:27:33.974284 kernel: VFS: Disk quotas dquot_6.6.0 Dec 13 01:27:33.974292 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Dec 13 01:27:33.974300 kernel: pnp: PnP ACPI init Dec 13 01:27:33.974466 kernel: system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved Dec 13 01:27:33.974496 kernel: pnp: PnP ACPI: found 6 devices Dec 13 01:27:33.974504 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Dec 13 01:27:33.974516 kernel: NET: Registered PF_INET protocol family Dec 13 01:27:33.974524 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Dec 13 01:27:33.974532 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Dec 13 01:27:33.974540 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Dec 13 01:27:33.974548 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Dec 13 01:27:33.974558 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Dec 13 01:27:33.974566 kernel: TCP: Hash tables configured (established 32768 bind 32768) Dec 13 01:27:33.974576 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Dec 13 01:27:33.974587 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Dec 13 01:27:33.974595 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Dec 13 01:27:33.974603 kernel: NET: Registered PF_XDP protocol family Dec 13 01:27:33.974734 kernel: pci 0000:00:04.0: can't claim BAR 6 [mem 0xfffc0000-0xffffffff pref]: no compatible bridge window Dec 13 01:27:33.974862 kernel: pci 0000:00:04.0: BAR 6: assigned [mem 0x9d000000-0x9d03ffff pref] Dec 13 01:27:33.974994 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Dec 13 01:27:33.975195 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Dec 13 01:27:33.975311 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Dec 13 01:27:33.975432 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xafffffff window] Dec 13 01:27:33.975546 kernel: pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] Dec 13 01:27:33.975660 kernel: pci_bus 0000:00: resource 9 [mem 0x800000000-0xfffffffff window] Dec 13 01:27:33.975671 kernel: PCI: CLS 0 bytes, default 64 Dec 13 01:27:33.975679 kernel: Initialise system trusted keyrings Dec 13 01:27:33.975687 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Dec 13 01:27:33.975695 kernel: Key type asymmetric registered Dec 13 01:27:33.975702 kernel: Asymmetric key parser 'x509' registered Dec 13 01:27:33.975710 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Dec 13 01:27:33.975722 kernel: io scheduler mq-deadline registered Dec 13 01:27:33.975730 kernel: io scheduler kyber registered Dec 13 01:27:33.975738 kernel: io scheduler bfq registered Dec 13 01:27:33.975745 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Dec 13 01:27:33.975754 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 Dec 13 01:27:33.975762 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 Dec 13 01:27:33.975769 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 Dec 13 01:27:33.975777 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Dec 13 01:27:33.975785 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Dec 13 01:27:33.975796 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Dec 13 01:27:33.975804 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Dec 13 01:27:33.975811 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Dec 13 01:27:33.975955 kernel: rtc_cmos 00:04: RTC can wake from S4 Dec 13 01:27:33.975967 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Dec 13 01:27:33.976137 kernel: rtc_cmos 00:04: registered as rtc0 Dec 13 01:27:33.976258 kernel: rtc_cmos 00:04: setting system clock to 2024-12-13T01:27:33 UTC (1734053253) Dec 13 01:27:33.976390 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs Dec 13 01:27:33.976409 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled Dec 13 01:27:33.976417 kernel: efifb: probing for efifb Dec 13 01:27:33.976425 kernel: efifb: framebuffer at 0xc0000000, using 1408k, total 1408k Dec 13 01:27:33.976433 kernel: efifb: mode is 800x600x24, linelength=2400, pages=1 Dec 13 01:27:33.976441 kernel: efifb: scrolling: redraw Dec 13 01:27:33.976449 kernel: efifb: Truecolor: size=0:8:8:8, shift=0:16:8:0 Dec 13 01:27:33.976457 kernel: Console: switching to colour frame buffer device 100x37 Dec 13 01:27:33.976483 kernel: fb0: EFI VGA frame buffer device Dec 13 01:27:33.976494 kernel: pstore: Using crash dump compression: deflate Dec 13 01:27:33.976504 kernel: pstore: Registered efi_pstore as persistent store backend Dec 13 01:27:33.976512 kernel: NET: Registered PF_INET6 protocol family Dec 13 01:27:33.976521 kernel: Segment Routing with IPv6 Dec 13 01:27:33.976529 kernel: In-situ OAM (IOAM) with IPv6 Dec 13 01:27:33.976537 kernel: NET: Registered PF_PACKET protocol family Dec 13 01:27:33.976545 kernel: Key type dns_resolver registered Dec 13 01:27:33.976553 kernel: IPI shorthand broadcast: enabled Dec 13 01:27:33.976561 kernel: sched_clock: Marking stable (1161002932, 148915453)->(1432304202, -122385817) Dec 13 01:27:33.976569 kernel: registered taskstats version 1 Dec 13 01:27:33.976579 kernel: Loading compiled-in X.509 certificates Dec 13 01:27:33.976588 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.65-flatcar: c82d546f528d79a5758dcebbc47fb6daf92836a0' Dec 13 01:27:33.976596 kernel: Key type .fscrypt registered Dec 13 01:27:33.976603 kernel: Key type fscrypt-provisioning registered Dec 13 01:27:33.976611 kernel: ima: No TPM chip found, activating TPM-bypass! Dec 13 01:27:33.976620 kernel: ima: Allocated hash algorithm: sha1 Dec 13 01:27:33.976628 kernel: ima: No architecture policies found Dec 13 01:27:33.976636 kernel: clk: Disabling unused clocks Dec 13 01:27:33.976644 kernel: Freeing unused kernel image (initmem) memory: 42844K Dec 13 01:27:33.976656 kernel: Write protecting the kernel read-only data: 36864k Dec 13 01:27:33.976665 kernel: Freeing unused kernel image (rodata/data gap) memory: 1852K Dec 13 01:27:33.976673 kernel: Run /init as init process Dec 13 01:27:33.976680 kernel: with arguments: Dec 13 01:27:33.976689 kernel: /init Dec 13 01:27:33.976696 kernel: with environment: Dec 13 01:27:33.976704 kernel: HOME=/ Dec 13 01:27:33.976712 kernel: TERM=linux Dec 13 01:27:33.976720 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Dec 13 01:27:33.976733 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) Dec 13 01:27:33.976744 systemd[1]: Detected virtualization kvm. Dec 13 01:27:33.976753 systemd[1]: Detected architecture x86-64. Dec 13 01:27:33.976761 systemd[1]: Running in initrd. Dec 13 01:27:33.976774 systemd[1]: No hostname configured, using default hostname. Dec 13 01:27:33.976783 systemd[1]: Hostname set to <localhost>. Dec 13 01:27:33.976791 systemd[1]: Initializing machine ID from VM UUID. Dec 13 01:27:33.976800 systemd[1]: Queued start job for default target initrd.target. Dec 13 01:27:33.976809 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Dec 13 01:27:33.976817 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Dec 13 01:27:33.976827 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Dec 13 01:27:33.976835 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Dec 13 01:27:33.976847 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Dec 13 01:27:33.976856 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Dec 13 01:27:33.976866 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Dec 13 01:27:33.976875 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Dec 13 01:27:33.976884 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Dec 13 01:27:33.976893 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Dec 13 01:27:33.976904 systemd[1]: Reached target paths.target - Path Units. Dec 13 01:27:33.976913 systemd[1]: Reached target slices.target - Slice Units. Dec 13 01:27:33.976922 systemd[1]: Reached target swap.target - Swaps. Dec 13 01:27:33.976930 systemd[1]: Reached target timers.target - Timer Units. Dec 13 01:27:33.976939 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Dec 13 01:27:33.976947 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Dec 13 01:27:33.976956 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Dec 13 01:27:33.976965 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Dec 13 01:27:33.976984 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Dec 13 01:27:33.976996 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Dec 13 01:27:33.977004 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Dec 13 01:27:33.977013 systemd[1]: Reached target sockets.target - Socket Units. Dec 13 01:27:33.977021 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Dec 13 01:27:33.977030 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Dec 13 01:27:33.977039 systemd[1]: Finished network-cleanup.service - Network Cleanup. Dec 13 01:27:33.977047 systemd[1]: Starting systemd-fsck-usr.service... Dec 13 01:27:33.977055 systemd[1]: Starting systemd-journald.service - Journal Service... Dec 13 01:27:33.977077 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Dec 13 01:27:33.977089 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Dec 13 01:27:33.977098 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Dec 13 01:27:33.977106 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Dec 13 01:27:33.977115 systemd[1]: Finished systemd-fsck-usr.service. Dec 13 01:27:33.977124 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Dec 13 01:27:33.977160 systemd-journald[193]: Collecting audit messages is disabled. Dec 13 01:27:33.977181 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Dec 13 01:27:33.977190 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Dec 13 01:27:33.977201 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Dec 13 01:27:33.977210 systemd-journald[193]: Journal started Dec 13 01:27:33.977229 systemd-journald[193]: Runtime Journal (/run/log/journal/7a9b27727fd44c9cb816ec73f8225bb3) is 6.0M, max 48.3M, 42.2M free. Dec 13 01:27:33.980095 systemd[1]: Started systemd-journald.service - Journal Service. Dec 13 01:27:33.982804 systemd-modules-load[194]: Inserted module 'overlay' Dec 13 01:27:33.994248 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Dec 13 01:27:33.995517 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Dec 13 01:27:34.009225 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Dec 13 01:27:34.012441 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Dec 13 01:27:34.018032 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Dec 13 01:27:34.028128 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Dec 13 01:27:34.031442 systemd-modules-load[194]: Inserted module 'br_netfilter' Dec 13 01:27:34.033215 kernel: Bridge firewalling registered Dec 13 01:27:34.032511 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Dec 13 01:27:34.036640 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Dec 13 01:27:34.038029 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Dec 13 01:27:34.052790 dracut-cmdline[223]: dracut-dracut-053 Dec 13 01:27:34.054513 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Dec 13 01:27:34.058825 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=2fdbba50b59d8c8a9877a81151806ddc16f473fe99b9ba0d8825997d654583ff Dec 13 01:27:34.059247 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Dec 13 01:27:34.099276 systemd-resolved[239]: Positive Trust Anchors: Dec 13 01:27:34.099311 systemd-resolved[239]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Dec 13 01:27:34.099344 systemd-resolved[239]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Dec 13 01:27:34.102290 systemd-resolved[239]: Defaulting to hostname 'linux'. Dec 13 01:27:34.103589 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Dec 13 01:27:34.109390 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Dec 13 01:27:34.175114 kernel: SCSI subsystem initialized Dec 13 01:27:34.185098 kernel: Loading iSCSI transport class v2.0-870. Dec 13 01:27:34.196109 kernel: iscsi: registered transport (tcp) Dec 13 01:27:34.218112 kernel: iscsi: registered transport (qla4xxx) Dec 13 01:27:34.218195 kernel: QLogic iSCSI HBA Driver Dec 13 01:27:34.327179 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Dec 13 01:27:34.340239 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Dec 13 01:27:34.381487 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Dec 13 01:27:34.381538 kernel: device-mapper: uevent: version 1.0.3 Dec 13 01:27:34.382550 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Dec 13 01:27:34.427103 kernel: raid6: avx2x4 gen() 29854 MB/s Dec 13 01:27:34.444106 kernel: raid6: avx2x2 gen() 31149 MB/s Dec 13 01:27:34.461212 kernel: raid6: avx2x1 gen() 23748 MB/s Dec 13 01:27:34.461277 kernel: raid6: using algorithm avx2x2 gen() 31149 MB/s Dec 13 01:27:34.479254 kernel: raid6: .... xor() 19417 MB/s, rmw enabled Dec 13 01:27:34.479342 kernel: raid6: using avx2x2 recovery algorithm Dec 13 01:27:34.500130 kernel: xor: automatically using best checksumming function avx Dec 13 01:27:34.670116 kernel: Btrfs loaded, zoned=no, fsverity=no Dec 13 01:27:34.685487 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Dec 13 01:27:34.693373 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Dec 13 01:27:34.709491 systemd-udevd[413]: Using default interface naming scheme 'v255'. Dec 13 01:27:34.715202 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Dec 13 01:27:34.731331 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Dec 13 01:27:34.746918 dracut-pre-trigger[415]: rd.md=0: removing MD RAID activation Dec 13 01:27:34.787573 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Dec 13 01:27:34.800359 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Dec 13 01:27:34.872401 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Dec 13 01:27:34.883401 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Dec 13 01:27:34.898092 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Dec 13 01:27:34.902806 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Dec 13 01:27:34.904737 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Dec 13 01:27:34.908792 systemd[1]: Reached target remote-fs.target - Remote File Systems. Dec 13 01:27:34.914091 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues Dec 13 01:27:34.926789 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Dec 13 01:27:34.927084 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Dec 13 01:27:34.919351 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Dec 13 01:27:34.937082 kernel: libata version 3.00 loaded. Dec 13 01:27:34.937568 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Dec 13 01:27:34.945233 kernel: ahci 0000:00:1f.2: version 3.0 Dec 13 01:27:34.966189 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 Dec 13 01:27:34.966215 kernel: ahci 0000:00:1f.2: AHCI 0001.0000 32 slots 6 ports 1.5 Gbps 0x3f impl SATA mode Dec 13 01:27:34.966437 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only Dec 13 01:27:34.966630 kernel: cryptd: max_cpu_qlen set to 1000 Dec 13 01:27:34.966646 kernel: scsi host0: ahci Dec 13 01:27:34.968400 kernel: AVX2 version of gcm_enc/dec engaged. Dec 13 01:27:34.968419 kernel: scsi host1: ahci Dec 13 01:27:34.968635 kernel: AES CTR mode by8 optimization enabled Dec 13 01:27:34.968659 kernel: scsi host2: ahci Dec 13 01:27:34.968869 kernel: scsi host3: ahci Dec 13 01:27:34.969137 kernel: scsi host4: ahci Dec 13 01:27:34.969363 kernel: scsi host5: ahci Dec 13 01:27:34.969584 kernel: ata1: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040100 irq 34 Dec 13 01:27:34.969602 kernel: ata2: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040180 irq 34 Dec 13 01:27:34.969616 kernel: ata3: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040200 irq 34 Dec 13 01:27:34.969630 kernel: ata4: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040280 irq 34 Dec 13 01:27:34.969645 kernel: ata5: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040300 irq 34 Dec 13 01:27:34.969660 kernel: ata6: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040380 irq 34 Dec 13 01:27:34.953256 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Dec 13 01:27:34.953427 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Dec 13 01:27:34.982839 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by (udev-worker) (475) Dec 13 01:27:34.955686 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Dec 13 01:27:34.986327 kernel: BTRFS: device fsid c3b72f8a-27ca-4d37-9d0e-1ec3c4bdc3be devid 1 transid 41 /dev/vda3 scanned by (udev-worker) (459) Dec 13 01:27:34.957156 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Dec 13 01:27:34.957335 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Dec 13 01:27:34.959265 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Dec 13 01:27:34.965387 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Dec 13 01:27:35.009441 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Dec 13 01:27:35.021526 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Dec 13 01:27:35.026745 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Dec 13 01:27:35.028051 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Dec 13 01:27:35.036614 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Dec 13 01:27:35.046384 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Dec 13 01:27:35.049019 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Dec 13 01:27:35.049120 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Dec 13 01:27:35.052694 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Dec 13 01:27:35.056097 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Dec 13 01:27:35.056293 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Dec 13 01:27:35.061084 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Dec 13 01:27:35.066087 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Dec 13 01:27:35.076402 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Dec 13 01:27:35.085318 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Dec 13 01:27:35.120982 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Dec 13 01:27:35.285102 kernel: ata6: SATA link down (SStatus 0 SControl 300) Dec 13 01:27:35.285177 kernel: ata5: SATA link down (SStatus 0 SControl 300) Dec 13 01:27:35.286093 kernel: ata1: SATA link down (SStatus 0 SControl 300) Dec 13 01:27:35.287107 kernel: ata4: SATA link down (SStatus 0 SControl 300) Dec 13 01:27:35.288095 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Dec 13 01:27:35.289177 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 Dec 13 01:27:35.289190 kernel: ata3.00: applying bridge limits Dec 13 01:27:35.290093 kernel: ata2: SATA link down (SStatus 0 SControl 300) Dec 13 01:27:35.291097 kernel: ata3.00: configured for UDMA/100 Dec 13 01:27:35.292090 kernel: scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 Dec 13 01:27:35.349118 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray Dec 13 01:27:35.371309 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Dec 13 01:27:35.371336 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 Dec 13 01:27:36.079106 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Dec 13 01:27:36.079176 disk-uuid[555]: The operation has completed successfully. Dec 13 01:27:36.111141 systemd[1]: disk-uuid.service: Deactivated successfully. Dec 13 01:27:36.111333 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Dec 13 01:27:36.152289 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Dec 13 01:27:36.156053 sh[598]: Success Dec 13 01:27:36.171115 kernel: device-mapper: verity: sha256 using implementation "sha256-ni" Dec 13 01:27:36.214529 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Dec 13 01:27:36.228083 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Dec 13 01:27:36.230974 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Dec 13 01:27:36.266267 kernel: BTRFS info (device dm-0): first mount of filesystem c3b72f8a-27ca-4d37-9d0e-1ec3c4bdc3be Dec 13 01:27:36.266347 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Dec 13 01:27:36.266365 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Dec 13 01:27:36.267463 kernel: BTRFS info (device dm-0): disabling log replay at mount time Dec 13 01:27:36.268322 kernel: BTRFS info (device dm-0): using free space tree Dec 13 01:27:36.275437 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Dec 13 01:27:36.278169 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Dec 13 01:27:36.287269 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Dec 13 01:27:36.290098 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Dec 13 01:27:36.299388 kernel: BTRFS info (device vda6): first mount of filesystem db063747-cac8-4176-8963-c216c1b11dcb Dec 13 01:27:36.299438 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Dec 13 01:27:36.299456 kernel: BTRFS info (device vda6): using free space tree Dec 13 01:27:36.303228 kernel: BTRFS info (device vda6): auto enabling async discard Dec 13 01:27:36.314648 systemd[1]: mnt-oem.mount: Deactivated successfully. Dec 13 01:27:36.316414 kernel: BTRFS info (device vda6): last unmount of filesystem db063747-cac8-4176-8963-c216c1b11dcb Dec 13 01:27:36.422813 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Dec 13 01:27:36.435405 systemd[1]: Starting systemd-networkd.service - Network Configuration... Dec 13 01:27:36.464368 systemd-networkd[776]: lo: Link UP Dec 13 01:27:36.464378 systemd-networkd[776]: lo: Gained carrier Dec 13 01:27:36.467747 systemd-networkd[776]: Enumeration completed Dec 13 01:27:36.468005 systemd[1]: Started systemd-networkd.service - Network Configuration. Dec 13 01:27:36.468765 systemd[1]: Reached target network.target - Network. Dec 13 01:27:36.472723 systemd-networkd[776]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Dec 13 01:27:36.472728 systemd-networkd[776]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Dec 13 01:27:36.473692 systemd[1]: Finished ignition-setup.service - Ignition (setup). Dec 13 01:27:36.479398 systemd-networkd[776]: eth0: Link UP Dec 13 01:27:36.479407 systemd-networkd[776]: eth0: Gained carrier Dec 13 01:27:36.479419 systemd-networkd[776]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Dec 13 01:27:36.489460 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Dec 13 01:27:36.506301 systemd-networkd[776]: eth0: DHCPv4 address 10.0.0.49/16, gateway 10.0.0.1 acquired from 10.0.0.1 Dec 13 01:27:36.561026 ignition[780]: Ignition 2.19.0 Dec 13 01:27:36.561040 ignition[780]: Stage: fetch-offline Dec 13 01:27:36.561118 ignition[780]: no configs at "/usr/lib/ignition/base.d" Dec 13 01:27:36.561133 ignition[780]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Dec 13 01:27:36.561257 ignition[780]: parsed url from cmdline: "" Dec 13 01:27:36.561262 ignition[780]: no config URL provided Dec 13 01:27:36.561269 ignition[780]: reading system config file "/usr/lib/ignition/user.ign" Dec 13 01:27:36.561281 ignition[780]: no config at "/usr/lib/ignition/user.ign" Dec 13 01:27:36.561317 ignition[780]: op(1): [started] loading QEMU firmware config module Dec 13 01:27:36.561324 ignition[780]: op(1): executing: "modprobe" "qemu_fw_cfg" Dec 13 01:27:36.577702 ignition[780]: op(1): [finished] loading QEMU firmware config module Dec 13 01:27:36.579353 ignition[780]: parsing config with SHA512: e22e1d6d01d60f96e2a093795a96551bddead5486bbf4bb6ac03a1ca21a692e2b2d8644a28bd8700a5aab0eb214fdfb65cb084eab18f51b652a65aa7ddcded59 Dec 13 01:27:36.588538 unknown[780]: fetched base config from "system" Dec 13 01:27:36.589556 unknown[780]: fetched user config from "qemu" Dec 13 01:27:36.589822 ignition[780]: fetch-offline: fetch-offline passed Dec 13 01:27:36.589979 ignition[780]: Ignition finished successfully Dec 13 01:27:36.593237 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Dec 13 01:27:36.595888 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Dec 13 01:27:36.605373 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Dec 13 01:27:36.620652 ignition[791]: Ignition 2.19.0 Dec 13 01:27:36.620667 ignition[791]: Stage: kargs Dec 13 01:27:36.620871 ignition[791]: no configs at "/usr/lib/ignition/base.d" Dec 13 01:27:36.620883 ignition[791]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Dec 13 01:27:36.621469 ignition[791]: kargs: kargs passed Dec 13 01:27:36.621517 ignition[791]: Ignition finished successfully Dec 13 01:27:36.625593 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Dec 13 01:27:36.634286 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Dec 13 01:27:36.656678 ignition[798]: Ignition 2.19.0 Dec 13 01:27:36.656697 ignition[798]: Stage: disks Dec 13 01:27:36.656988 ignition[798]: no configs at "/usr/lib/ignition/base.d" Dec 13 01:27:36.657009 ignition[798]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Dec 13 01:27:36.657932 ignition[798]: disks: disks passed Dec 13 01:27:36.660689 systemd[1]: Finished ignition-disks.service - Ignition (disks). Dec 13 01:27:36.658005 ignition[798]: Ignition finished successfully Dec 13 01:27:36.662381 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Dec 13 01:27:36.664126 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Dec 13 01:27:36.666757 systemd[1]: Reached target local-fs.target - Local File Systems. Dec 13 01:27:36.668082 systemd[1]: Reached target sysinit.target - System Initialization. Dec 13 01:27:36.669308 systemd[1]: Reached target basic.target - Basic System. Dec 13 01:27:36.679278 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Dec 13 01:27:36.697741 systemd-fsck[808]: ROOT: clean, 14/553520 files, 52654/553472 blocks Dec 13 01:27:36.705412 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Dec 13 01:27:36.717215 systemd[1]: Mounting sysroot.mount - /sysroot... Dec 13 01:27:36.814097 kernel: EXT4-fs (vda9): mounted filesystem 390119fa-ab9c-4f50-b046-3b5c76c46193 r/w with ordered data mode. Quota mode: none. Dec 13 01:27:36.814930 systemd[1]: Mounted sysroot.mount - /sysroot. Dec 13 01:27:36.816221 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Dec 13 01:27:36.831293 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Dec 13 01:27:36.833941 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Dec 13 01:27:36.834843 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Dec 13 01:27:36.834907 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Dec 13 01:27:36.843807 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/vda6 scanned by mount (816) Dec 13 01:27:36.843834 kernel: BTRFS info (device vda6): first mount of filesystem db063747-cac8-4176-8963-c216c1b11dcb Dec 13 01:27:36.834942 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Dec 13 01:27:36.849716 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Dec 13 01:27:36.849745 kernel: BTRFS info (device vda6): using free space tree Dec 13 01:27:36.849757 kernel: BTRFS info (device vda6): auto enabling async discard Dec 13 01:27:36.843943 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Dec 13 01:27:36.851512 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Dec 13 01:27:36.864470 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Dec 13 01:27:36.911711 initrd-setup-root[840]: cut: /sysroot/etc/passwd: No such file or directory Dec 13 01:27:36.916825 initrd-setup-root[847]: cut: /sysroot/etc/group: No such file or directory Dec 13 01:27:36.921371 initrd-setup-root[854]: cut: /sysroot/etc/shadow: No such file or directory Dec 13 01:27:36.925984 initrd-setup-root[861]: cut: /sysroot/etc/gshadow: No such file or directory Dec 13 01:27:37.029709 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Dec 13 01:27:37.041207 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Dec 13 01:27:37.043425 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Dec 13 01:27:37.051115 kernel: BTRFS info (device vda6): last unmount of filesystem db063747-cac8-4176-8963-c216c1b11dcb Dec 13 01:27:37.077680 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Dec 13 01:27:37.086756 ignition[929]: INFO : Ignition 2.19.0 Dec 13 01:27:37.086756 ignition[929]: INFO : Stage: mount Dec 13 01:27:37.088853 ignition[929]: INFO : no configs at "/usr/lib/ignition/base.d" Dec 13 01:27:37.088853 ignition[929]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Dec 13 01:27:37.088853 ignition[929]: INFO : mount: mount passed Dec 13 01:27:37.088853 ignition[929]: INFO : Ignition finished successfully Dec 13 01:27:37.094706 systemd[1]: Finished ignition-mount.service - Ignition (mount). Dec 13 01:27:37.114289 systemd[1]: Starting ignition-files.service - Ignition (files)... Dec 13 01:27:37.265193 systemd[1]: sysroot-oem.mount: Deactivated successfully. Dec 13 01:27:37.282286 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Dec 13 01:27:37.291804 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by mount (942) Dec 13 01:27:37.291854 kernel: BTRFS info (device vda6): first mount of filesystem db063747-cac8-4176-8963-c216c1b11dcb Dec 13 01:27:37.291869 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Dec 13 01:27:37.292747 kernel: BTRFS info (device vda6): using free space tree Dec 13 01:27:37.296819 kernel: BTRFS info (device vda6): auto enabling async discard Dec 13 01:27:37.298628 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Dec 13 01:27:37.323313 ignition[959]: INFO : Ignition 2.19.0 Dec 13 01:27:37.323313 ignition[959]: INFO : Stage: files Dec 13 01:27:37.325753 ignition[959]: INFO : no configs at "/usr/lib/ignition/base.d" Dec 13 01:27:37.325753 ignition[959]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Dec 13 01:27:37.325753 ignition[959]: DEBUG : files: compiled without relabeling support, skipping Dec 13 01:27:37.325753 ignition[959]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Dec 13 01:27:37.325753 ignition[959]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Dec 13 01:27:37.332970 ignition[959]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Dec 13 01:27:37.332970 ignition[959]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Dec 13 01:27:37.332970 ignition[959]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Dec 13 01:27:37.332970 ignition[959]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Dec 13 01:27:37.332970 ignition[959]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Dec 13 01:27:37.332970 ignition[959]: INFO : files: op(4): [started] processing unit "etcd-member.service" Dec 13 01:27:37.332970 ignition[959]: INFO : files: op(4): op(5): [started] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Dec 13 01:27:37.332970 ignition[959]: INFO : files: op(4): op(5): [finished] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Dec 13 01:27:37.332970 ignition[959]: INFO : files: op(4): [finished] processing unit "etcd-member.service" Dec 13 01:27:37.332970 ignition[959]: INFO : files: op(6): [started] processing unit "coreos-metadata.service" Dec 13 01:27:37.332970 ignition[959]: INFO : files: op(6): op(7): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Dec 13 01:27:37.332970 ignition[959]: INFO : files: op(6): op(7): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Dec 13 01:27:37.332970 ignition[959]: INFO : files: op(6): [finished] processing unit "coreos-metadata.service" Dec 13 01:27:37.332970 ignition[959]: INFO : files: op(8): [started] setting preset to disabled for "coreos-metadata.service" Dec 13 01:27:37.329353 unknown[959]: wrote ssh authorized keys file for user: core Dec 13 01:27:37.533138 ignition[959]: INFO : files: op(8): op(9): [started] removing enablement symlink(s) for "coreos-metadata.service" Dec 13 01:27:37.539187 ignition[959]: INFO : files: op(8): op(9): [finished] removing enablement symlink(s) for "coreos-metadata.service" Dec 13 01:27:37.540835 ignition[959]: INFO : files: op(8): [finished] setting preset to disabled for "coreos-metadata.service" Dec 13 01:27:37.540835 ignition[959]: INFO : files: op(a): [started] setting preset to enabled for "etcd-member.service" Dec 13 01:27:37.540835 ignition[959]: INFO : files: op(a): [finished] setting preset to enabled for "etcd-member.service" Dec 13 01:27:37.540835 ignition[959]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Dec 13 01:27:37.540835 ignition[959]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Dec 13 01:27:37.540835 ignition[959]: INFO : files: files passed Dec 13 01:27:37.540835 ignition[959]: INFO : Ignition finished successfully Dec 13 01:27:37.552543 systemd[1]: Finished ignition-files.service - Ignition (files). Dec 13 01:27:37.559266 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Dec 13 01:27:37.562393 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Dec 13 01:27:37.565302 systemd[1]: ignition-quench.service: Deactivated successfully. Dec 13 01:27:37.566413 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Dec 13 01:27:37.572372 initrd-setup-root-after-ignition[987]: grep: /sysroot/oem/oem-release: No such file or directory Dec 13 01:27:37.576361 initrd-setup-root-after-ignition[989]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Dec 13 01:27:37.576361 initrd-setup-root-after-ignition[989]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Dec 13 01:27:37.579960 initrd-setup-root-after-ignition[993]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Dec 13 01:27:37.579923 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Dec 13 01:27:37.581435 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Dec 13 01:27:37.594224 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Dec 13 01:27:37.624406 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Dec 13 01:27:37.624552 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Dec 13 01:27:37.626948 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Dec 13 01:27:37.628963 systemd[1]: Reached target initrd.target - Initrd Default Target. Dec 13 01:27:37.631000 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Dec 13 01:27:37.632167 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Dec 13 01:27:37.652222 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Dec 13 01:27:37.669175 systemd-networkd[776]: eth0: Gained IPv6LL Dec 13 01:27:37.671233 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Dec 13 01:27:37.681594 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Dec 13 01:27:37.683036 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Dec 13 01:27:37.685556 systemd[1]: Stopped target timers.target - Timer Units. Dec 13 01:27:37.687791 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Dec 13 01:27:37.687963 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Dec 13 01:27:37.690393 systemd[1]: Stopped target initrd.target - Initrd Default Target. Dec 13 01:27:37.692349 systemd[1]: Stopped target basic.target - Basic System. Dec 13 01:27:37.694657 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Dec 13 01:27:37.696943 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Dec 13 01:27:37.699244 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Dec 13 01:27:37.701600 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Dec 13 01:27:37.703994 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Dec 13 01:27:37.706593 systemd[1]: Stopped target sysinit.target - System Initialization. Dec 13 01:27:37.708848 systemd[1]: Stopped target local-fs.target - Local File Systems. Dec 13 01:27:37.711315 systemd[1]: Stopped target swap.target - Swaps. Dec 13 01:27:37.713365 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Dec 13 01:27:37.713525 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Dec 13 01:27:37.716020 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Dec 13 01:27:37.717908 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Dec 13 01:27:37.720276 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Dec 13 01:27:37.720423 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Dec 13 01:27:37.722730 systemd[1]: dracut-initqueue.service: Deactivated successfully. Dec 13 01:27:37.722903 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Dec 13 01:27:37.725418 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Dec 13 01:27:37.725571 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Dec 13 01:27:37.727884 systemd[1]: Stopped target paths.target - Path Units. Dec 13 01:27:37.729638 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Dec 13 01:27:37.729763 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Dec 13 01:27:37.732284 systemd[1]: Stopped target slices.target - Slice Units. Dec 13 01:27:37.734074 systemd[1]: Stopped target sockets.target - Socket Units. Dec 13 01:27:37.736035 systemd[1]: iscsid.socket: Deactivated successfully. Dec 13 01:27:37.736145 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Dec 13 01:27:37.737997 systemd[1]: iscsiuio.socket: Deactivated successfully. Dec 13 01:27:37.738098 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Dec 13 01:27:37.740058 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Dec 13 01:27:37.740188 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Dec 13 01:27:37.742079 systemd[1]: ignition-files.service: Deactivated successfully. Dec 13 01:27:37.742189 systemd[1]: Stopped ignition-files.service - Ignition (files). Dec 13 01:27:37.754263 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Dec 13 01:27:37.756098 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Dec 13 01:27:37.756264 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Dec 13 01:27:37.759181 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Dec 13 01:27:37.760140 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Dec 13 01:27:37.760296 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Dec 13 01:27:37.762762 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Dec 13 01:27:37.763099 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Dec 13 01:27:37.769018 systemd[1]: initrd-cleanup.service: Deactivated successfully. Dec 13 01:27:37.769190 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Dec 13 01:27:37.780257 ignition[1013]: INFO : Ignition 2.19.0 Dec 13 01:27:37.780257 ignition[1013]: INFO : Stage: umount Dec 13 01:27:37.782013 ignition[1013]: INFO : no configs at "/usr/lib/ignition/base.d" Dec 13 01:27:37.782013 ignition[1013]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Dec 13 01:27:37.782013 ignition[1013]: INFO : umount: umount passed Dec 13 01:27:37.782013 ignition[1013]: INFO : Ignition finished successfully Dec 13 01:27:37.783416 systemd[1]: ignition-mount.service: Deactivated successfully. Dec 13 01:27:37.783547 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Dec 13 01:27:37.785413 systemd[1]: Stopped target network.target - Network. Dec 13 01:27:37.786817 systemd[1]: ignition-disks.service: Deactivated successfully. Dec 13 01:27:37.786883 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Dec 13 01:27:37.788899 systemd[1]: ignition-kargs.service: Deactivated successfully. Dec 13 01:27:37.788953 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Dec 13 01:27:37.790778 systemd[1]: ignition-setup.service: Deactivated successfully. Dec 13 01:27:37.790826 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Dec 13 01:27:37.792740 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Dec 13 01:27:37.792790 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Dec 13 01:27:37.794759 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Dec 13 01:27:37.796697 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Dec 13 01:27:37.799520 systemd[1]: sysroot-boot.mount: Deactivated successfully. Dec 13 01:27:37.800096 systemd[1]: sysroot-boot.service: Deactivated successfully. Dec 13 01:27:37.800109 systemd-networkd[776]: eth0: DHCPv6 lease lost Dec 13 01:27:37.800206 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Dec 13 01:27:37.802077 systemd[1]: initrd-setup-root.service: Deactivated successfully. Dec 13 01:27:37.802165 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Dec 13 01:27:37.803771 systemd[1]: systemd-networkd.service: Deactivated successfully. Dec 13 01:27:37.803925 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Dec 13 01:27:37.805925 systemd[1]: systemd-resolved.service: Deactivated successfully. Dec 13 01:27:37.806048 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Dec 13 01:27:37.809938 systemd[1]: systemd-networkd.socket: Deactivated successfully. Dec 13 01:27:37.809993 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Dec 13 01:27:37.819168 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Dec 13 01:27:37.820443 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Dec 13 01:27:37.820500 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Dec 13 01:27:37.822747 systemd[1]: systemd-sysctl.service: Deactivated successfully. Dec 13 01:27:37.822801 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Dec 13 01:27:37.824927 systemd[1]: systemd-modules-load.service: Deactivated successfully. Dec 13 01:27:37.824976 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Dec 13 01:27:37.826129 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Dec 13 01:27:37.826178 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Dec 13 01:27:37.828268 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Dec 13 01:27:37.840747 systemd[1]: network-cleanup.service: Deactivated successfully. Dec 13 01:27:37.840891 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Dec 13 01:27:37.844798 systemd[1]: systemd-udevd.service: Deactivated successfully. Dec 13 01:27:37.845011 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Dec 13 01:27:37.847271 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Dec 13 01:27:37.847323 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Dec 13 01:27:37.849239 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Dec 13 01:27:37.849281 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Dec 13 01:27:37.851198 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Dec 13 01:27:37.851250 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Dec 13 01:27:37.853456 systemd[1]: dracut-cmdline.service: Deactivated successfully. Dec 13 01:27:37.853510 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Dec 13 01:27:37.855359 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Dec 13 01:27:37.855412 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Dec 13 01:27:37.867218 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Dec 13 01:27:37.868304 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Dec 13 01:27:37.868365 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Dec 13 01:27:37.870637 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Dec 13 01:27:37.870689 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Dec 13 01:27:37.874545 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Dec 13 01:27:37.874661 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Dec 13 01:27:37.876337 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Dec 13 01:27:37.878978 systemd[1]: Starting initrd-switch-root.service - Switch Root... Dec 13 01:27:37.889930 systemd[1]: Switching root. Dec 13 01:27:37.913095 systemd-journald[193]: Journal stopped Dec 13 01:27:38.871919 systemd-journald[193]: Received SIGTERM from PID 1 (systemd). Dec 13 01:27:38.872011 kernel: SELinux: policy capability network_peer_controls=1 Dec 13 01:27:38.872036 kernel: SELinux: policy capability open_perms=1 Dec 13 01:27:38.872055 kernel: SELinux: policy capability extended_socket_class=1 Dec 13 01:27:38.872081 kernel: SELinux: policy capability always_check_network=0 Dec 13 01:27:38.872092 kernel: SELinux: policy capability cgroup_seclabel=1 Dec 13 01:27:38.872114 kernel: SELinux: policy capability nnp_nosuid_transition=1 Dec 13 01:27:38.872126 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Dec 13 01:27:38.872137 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Dec 13 01:27:38.872149 kernel: audit: type=1403 audit(1734053257.997:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Dec 13 01:27:38.872162 systemd[1]: Successfully loaded SELinux policy in 41.451ms. Dec 13 01:27:38.872188 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 15.103ms. Dec 13 01:27:38.872201 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) Dec 13 01:27:38.872213 systemd[1]: Detected virtualization kvm. Dec 13 01:27:38.872225 systemd[1]: Detected architecture x86-64. Dec 13 01:27:38.872240 systemd[1]: Detected first boot. Dec 13 01:27:38.872252 systemd[1]: Initializing machine ID from VM UUID. Dec 13 01:27:38.872265 zram_generator::config[1057]: No configuration found. Dec 13 01:27:38.872278 systemd[1]: Populated /etc with preset unit settings. Dec 13 01:27:38.872291 systemd[1]: initrd-switch-root.service: Deactivated successfully. Dec 13 01:27:38.872303 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Dec 13 01:27:38.872316 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Dec 13 01:27:38.872331 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Dec 13 01:27:38.872351 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Dec 13 01:27:38.872363 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Dec 13 01:27:38.872375 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Dec 13 01:27:38.872387 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Dec 13 01:27:38.872399 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Dec 13 01:27:38.872412 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Dec 13 01:27:38.872427 systemd[1]: Created slice user.slice - User and Session Slice. Dec 13 01:27:38.872439 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Dec 13 01:27:38.872451 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Dec 13 01:27:38.872464 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Dec 13 01:27:38.872476 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Dec 13 01:27:38.872488 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Dec 13 01:27:38.872500 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Dec 13 01:27:38.872513 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Dec 13 01:27:38.872527 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Dec 13 01:27:38.872539 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Dec 13 01:27:38.872551 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Dec 13 01:27:38.872563 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Dec 13 01:27:38.872575 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Dec 13 01:27:38.872587 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Dec 13 01:27:38.872599 systemd[1]: Reached target remote-fs.target - Remote File Systems. Dec 13 01:27:38.872619 systemd[1]: Reached target slices.target - Slice Units. Dec 13 01:27:38.872634 systemd[1]: Reached target swap.target - Swaps. Dec 13 01:27:38.872646 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Dec 13 01:27:38.872658 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Dec 13 01:27:38.872671 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Dec 13 01:27:38.872683 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Dec 13 01:27:38.872695 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Dec 13 01:27:38.872706 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Dec 13 01:27:38.872722 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Dec 13 01:27:38.872739 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Dec 13 01:27:38.872758 systemd[1]: Mounting media.mount - External Media Directory... Dec 13 01:27:38.872770 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Dec 13 01:27:38.872783 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Dec 13 01:27:38.872798 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Dec 13 01:27:38.872822 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Dec 13 01:27:38.872849 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Dec 13 01:27:38.872866 systemd[1]: Reached target machines.target - Containers. Dec 13 01:27:38.872881 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Dec 13 01:27:38.872896 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Dec 13 01:27:38.872915 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Dec 13 01:27:38.872928 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Dec 13 01:27:38.872950 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Dec 13 01:27:38.872962 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Dec 13 01:27:38.872974 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Dec 13 01:27:38.872986 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Dec 13 01:27:38.872999 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Dec 13 01:27:38.873011 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Dec 13 01:27:38.873026 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Dec 13 01:27:38.873038 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Dec 13 01:27:38.873050 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Dec 13 01:27:38.875110 systemd[1]: Stopped systemd-fsck-usr.service. Dec 13 01:27:38.875134 kernel: fuse: init (API version 7.39) Dec 13 01:27:38.875147 systemd[1]: Starting systemd-journald.service - Journal Service... Dec 13 01:27:38.875160 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Dec 13 01:27:38.875172 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Dec 13 01:27:38.875185 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Dec 13 01:27:38.875209 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Dec 13 01:27:38.875222 systemd[1]: verity-setup.service: Deactivated successfully. Dec 13 01:27:38.875234 systemd[1]: Stopped verity-setup.service. Dec 13 01:27:38.875246 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Dec 13 01:27:38.875300 systemd-journald[1127]: Collecting audit messages is disabled. Dec 13 01:27:38.875324 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Dec 13 01:27:38.875337 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Dec 13 01:27:38.875353 systemd[1]: Mounted media.mount - External Media Directory. Dec 13 01:27:38.875366 systemd-journald[1127]: Journal started Dec 13 01:27:38.875388 systemd-journald[1127]: Runtime Journal (/run/log/journal/7a9b27727fd44c9cb816ec73f8225bb3) is 6.0M, max 48.3M, 42.2M free. Dec 13 01:27:38.595801 systemd[1]: Queued start job for default target multi-user.target. Dec 13 01:27:38.625159 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Dec 13 01:27:38.625732 systemd[1]: systemd-journald.service: Deactivated successfully. Dec 13 01:27:38.877159 systemd[1]: Started systemd-journald.service - Journal Service. Dec 13 01:27:38.878961 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Dec 13 01:27:38.881784 kernel: ACPI: bus type drm_connector registered Dec 13 01:27:38.881897 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Dec 13 01:27:38.883498 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Dec 13 01:27:38.885331 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Dec 13 01:27:38.887274 systemd[1]: modprobe@configfs.service: Deactivated successfully. Dec 13 01:27:38.887529 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Dec 13 01:27:38.890500 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Dec 13 01:27:38.890757 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Dec 13 01:27:38.892545 systemd[1]: modprobe@drm.service: Deactivated successfully. Dec 13 01:27:38.892786 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Dec 13 01:27:38.893093 kernel: loop: module loaded Dec 13 01:27:38.895739 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Dec 13 01:27:38.895989 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Dec 13 01:27:38.897925 systemd[1]: modprobe@fuse.service: Deactivated successfully. Dec 13 01:27:38.898209 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Dec 13 01:27:38.900213 systemd[1]: modprobe@loop.service: Deactivated successfully. Dec 13 01:27:38.900406 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Dec 13 01:27:38.902324 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Dec 13 01:27:38.904227 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Dec 13 01:27:38.906187 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Dec 13 01:27:38.925023 systemd[1]: Reached target network-pre.target - Preparation for Network. Dec 13 01:27:38.937317 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Dec 13 01:27:38.940938 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Dec 13 01:27:38.942423 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Dec 13 01:27:38.942468 systemd[1]: Reached target local-fs.target - Local File Systems. Dec 13 01:27:38.944724 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Dec 13 01:27:38.948012 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Dec 13 01:27:38.952762 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Dec 13 01:27:38.958200 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Dec 13 01:27:38.966764 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Dec 13 01:27:38.970184 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Dec 13 01:27:38.971587 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Dec 13 01:27:38.980478 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Dec 13 01:27:38.981930 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Dec 13 01:27:38.990277 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Dec 13 01:27:38.995031 systemd-journald[1127]: Time spent on flushing to /var/log/journal/7a9b27727fd44c9cb816ec73f8225bb3 is 21.624ms for 966 entries. Dec 13 01:27:38.995031 systemd-journald[1127]: System Journal (/var/log/journal/7a9b27727fd44c9cb816ec73f8225bb3) is 8.0M, max 195.6M, 187.6M free. Dec 13 01:27:39.225795 systemd-journald[1127]: Received client request to flush runtime journal. Dec 13 01:27:39.225863 kernel: loop0: detected capacity change from 0 to 142488 Dec 13 01:27:39.225882 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Dec 13 01:27:38.994260 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Dec 13 01:27:38.999468 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Dec 13 01:27:39.000755 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Dec 13 01:27:39.002341 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Dec 13 01:27:39.005576 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Dec 13 01:27:39.023468 systemd[1]: Starting systemd-sysusers.service - Create System Users... Dec 13 01:27:39.025311 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Dec 13 01:27:39.029050 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Dec 13 01:27:39.118654 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Dec 13 01:27:39.202105 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Dec 13 01:27:39.210675 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Dec 13 01:27:39.225706 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Dec 13 01:27:39.227922 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Dec 13 01:27:39.231056 systemd[1]: Finished systemd-sysusers.service - Create System Users. Dec 13 01:27:39.237298 udevadm[1178]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Dec 13 01:27:39.241112 kernel: loop1: detected capacity change from 0 to 140768 Dec 13 01:27:39.250683 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Dec 13 01:27:39.310310 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Dec 13 01:27:39.313569 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Dec 13 01:27:39.319101 systemd-tmpfiles[1191]: ACLs are not supported, ignoring. Dec 13 01:27:39.319126 systemd-tmpfiles[1191]: ACLs are not supported, ignoring. Dec 13 01:27:39.333001 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Dec 13 01:27:39.350133 kernel: loop2: detected capacity change from 0 to 142488 Dec 13 01:27:39.372103 kernel: loop3: detected capacity change from 0 to 140768 Dec 13 01:27:39.385721 (sd-merge)[1197]: Using extensions 'containerd-flatcar', 'docker-flatcar'. Dec 13 01:27:39.387974 (sd-merge)[1197]: Merged extensions into '/usr'. Dec 13 01:27:39.394013 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Dec 13 01:27:39.456215 systemd[1]: Starting ensure-sysext.service... Dec 13 01:27:39.463423 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Dec 13 01:27:39.479392 systemd[1]: Reloading requested from client PID 1199 ('systemctl') (unit ensure-sysext.service)... Dec 13 01:27:39.479420 systemd[1]: Reloading... Dec 13 01:27:39.504391 systemd-tmpfiles[1200]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Dec 13 01:27:39.504916 systemd-tmpfiles[1200]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Dec 13 01:27:39.506379 systemd-tmpfiles[1200]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Dec 13 01:27:39.507806 systemd-tmpfiles[1200]: ACLs are not supported, ignoring. Dec 13 01:27:39.507927 systemd-tmpfiles[1200]: ACLs are not supported, ignoring. Dec 13 01:27:39.514361 systemd-tmpfiles[1200]: Detected autofs mount point /boot during canonicalization of boot. Dec 13 01:27:39.514381 systemd-tmpfiles[1200]: Skipping /boot Dec 13 01:27:39.530523 systemd-tmpfiles[1200]: Detected autofs mount point /boot during canonicalization of boot. Dec 13 01:27:39.530543 systemd-tmpfiles[1200]: Skipping /boot Dec 13 01:27:39.637354 zram_generator::config[1225]: No configuration found. Dec 13 01:27:39.861312 ldconfig[1165]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Dec 13 01:27:39.908180 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. Dec 13 01:27:39.983904 systemd[1]: Reloading finished in 503 ms. Dec 13 01:27:40.039104 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Dec 13 01:27:40.056154 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Dec 13 01:27:40.084711 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Dec 13 01:27:40.105594 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Dec 13 01:27:40.110009 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Dec 13 01:27:40.114486 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Dec 13 01:27:40.120085 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Dec 13 01:27:40.124785 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Dec 13 01:27:40.125107 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Dec 13 01:27:40.131053 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Dec 13 01:27:40.138930 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Dec 13 01:27:40.268205 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Dec 13 01:27:40.269779 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Dec 13 01:27:40.269965 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Dec 13 01:27:40.287629 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Dec 13 01:27:40.293057 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Dec 13 01:27:40.293301 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Dec 13 01:27:40.293521 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Dec 13 01:27:40.293633 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Dec 13 01:27:40.298946 augenrules[1291]: No rules Dec 13 01:27:40.298687 systemd[1]: modprobe@loop.service: Deactivated successfully. Dec 13 01:27:40.299323 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Dec 13 01:27:40.304164 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Dec 13 01:27:40.306856 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Dec 13 01:27:40.309285 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Dec 13 01:27:40.311645 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Dec 13 01:27:40.312330 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Dec 13 01:27:40.314601 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Dec 13 01:27:40.314996 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Dec 13 01:27:40.329538 systemd[1]: Finished ensure-sysext.service. Dec 13 01:27:40.333970 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Dec 13 01:27:40.334166 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Dec 13 01:27:40.345263 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Dec 13 01:27:40.347045 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Dec 13 01:27:40.347119 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Dec 13 01:27:40.347176 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Dec 13 01:27:40.350240 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Dec 13 01:27:40.351675 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Dec 13 01:27:40.352026 systemd[1]: Started systemd-userdbd.service - User Database Manager. Dec 13 01:27:40.353961 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Dec 13 01:27:40.358240 systemd[1]: modprobe@drm.service: Deactivated successfully. Dec 13 01:27:40.358502 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Dec 13 01:27:40.362473 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Dec 13 01:27:40.561722 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Dec 13 01:27:40.563445 systemd[1]: Reached target time-set.target - System Time Set. Dec 13 01:27:40.576412 systemd-resolved[1278]: Positive Trust Anchors: Dec 13 01:27:40.576432 systemd-resolved[1278]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Dec 13 01:27:40.576465 systemd-resolved[1278]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Dec 13 01:27:40.580336 systemd-resolved[1278]: Defaulting to hostname 'linux'. Dec 13 01:27:40.583465 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Dec 13 01:27:40.584931 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Dec 13 01:27:40.628841 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Dec 13 01:27:40.643222 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Dec 13 01:27:40.645695 systemd[1]: Starting systemd-update-done.service - Update is Completed... Dec 13 01:27:40.665427 systemd[1]: Finished systemd-update-done.service - Update is Completed. Dec 13 01:27:40.674584 systemd-udevd[1316]: Using default interface naming scheme 'v255'. Dec 13 01:27:40.694339 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Dec 13 01:27:40.703759 systemd[1]: Starting systemd-networkd.service - Network Configuration... Dec 13 01:27:40.735202 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Dec 13 01:27:40.763097 kernel: BTRFS info: devid 1 device path /dev/mapper/usr changed to /dev/dm-0 scanned by (udev-worker) (1323) Dec 13 01:27:40.766094 kernel: BTRFS info: devid 1 device path /dev/dm-0 changed to /dev/mapper/usr scanned by (udev-worker) (1323) Dec 13 01:27:40.787097 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 41 scanned by (udev-worker) (1336) Dec 13 01:27:40.790260 systemd-networkd[1325]: lo: Link UP Dec 13 01:27:40.790277 systemd-networkd[1325]: lo: Gained carrier Dec 13 01:27:40.792197 systemd-networkd[1325]: Enumeration completed Dec 13 01:27:40.793320 systemd[1]: Started systemd-networkd.service - Network Configuration. Dec 13 01:27:40.793732 systemd-networkd[1325]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Dec 13 01:27:40.793737 systemd-networkd[1325]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Dec 13 01:27:40.794907 systemd-networkd[1325]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Dec 13 01:27:40.794945 systemd-networkd[1325]: eth0: Link UP Dec 13 01:27:40.794950 systemd-networkd[1325]: eth0: Gained carrier Dec 13 01:27:40.794959 systemd-networkd[1325]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Dec 13 01:27:40.795325 systemd[1]: Reached target network.target - Network. Dec 13 01:27:40.807453 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Dec 13 01:27:40.810181 systemd-networkd[1325]: eth0: DHCPv4 address 10.0.0.49/16, gateway 10.0.0.1 acquired from 10.0.0.1 Dec 13 01:27:40.811282 systemd-timesyncd[1308]: Network configuration changed, trying to establish connection. Dec 13 01:27:40.814342 systemd-timesyncd[1308]: Contacted time server 10.0.0.1:123 (10.0.0.1). Dec 13 01:27:40.814400 systemd-timesyncd[1308]: Initial clock synchronization to Fri 2024-12-13 01:27:40.732475 UTC. Dec 13 01:27:40.817096 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 Dec 13 01:27:40.824760 kernel: ACPI: button: Power Button [PWRF] Dec 13 01:27:40.926826 kernel: i801_smbus 0000:00:1f.3: Enabling SMBus device Dec 13 01:27:40.934003 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Dec 13 01:27:40.939208 kernel: i2c i2c-0: 1/1 memory slots populated (from DMI) Dec 13 01:27:40.939420 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Dec 13 01:27:40.940204 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Dec 13 01:27:40.943529 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 Dec 13 01:27:40.959088 kernel: mousedev: PS/2 mouse device common for all mice Dec 13 01:27:40.960481 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Dec 13 01:27:40.986490 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Dec 13 01:27:40.993134 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Dec 13 01:27:40.993906 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Dec 13 01:27:41.001625 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Dec 13 01:27:41.055340 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Dec 13 01:27:41.067528 kernel: kvm_amd: TSC scaling supported Dec 13 01:27:41.067602 kernel: kvm_amd: Nested Virtualization enabled Dec 13 01:27:41.067616 kernel: kvm_amd: Nested Paging enabled Dec 13 01:27:41.068504 kernel: kvm_amd: LBR virtualization supported Dec 13 01:27:41.068535 kernel: kvm_amd: Virtual VMLOAD VMSAVE supported Dec 13 01:27:41.071187 kernel: kvm_amd: Virtual GIF supported Dec 13 01:27:41.091181 kernel: EDAC MC: Ver: 3.0.0 Dec 13 01:27:41.125036 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Dec 13 01:27:41.133318 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Dec 13 01:27:41.153468 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Dec 13 01:27:41.161626 lvm[1364]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Dec 13 01:27:41.227984 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Dec 13 01:27:41.230042 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Dec 13 01:27:41.231551 systemd[1]: Reached target sysinit.target - System Initialization. Dec 13 01:27:41.233157 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Dec 13 01:27:41.234827 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Dec 13 01:27:41.236789 systemd[1]: Started logrotate.timer - Daily rotation of log files. Dec 13 01:27:41.238371 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Dec 13 01:27:41.240045 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Dec 13 01:27:41.241824 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Dec 13 01:27:41.241869 systemd[1]: Reached target paths.target - Path Units. Dec 13 01:27:41.243308 systemd[1]: Reached target timers.target - Timer Units. Dec 13 01:27:41.245698 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Dec 13 01:27:41.249372 systemd[1]: Starting docker.socket - Docker Socket for the API... Dec 13 01:27:41.259483 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Dec 13 01:27:41.262695 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Dec 13 01:27:41.264915 systemd[1]: Listening on docker.socket - Docker Socket for the API. Dec 13 01:27:41.266506 systemd[1]: Reached target sockets.target - Socket Units. Dec 13 01:27:41.267809 systemd[1]: Reached target basic.target - Basic System. Dec 13 01:27:41.269137 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Dec 13 01:27:41.269179 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Dec 13 01:27:41.270706 systemd[1]: Starting containerd.service - containerd container runtime... Dec 13 01:27:41.273173 lvm[1368]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Dec 13 01:27:41.276269 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Dec 13 01:27:41.279264 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Dec 13 01:27:41.284442 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Dec 13 01:27:41.285896 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Dec 13 01:27:41.290540 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Dec 13 01:27:41.292491 jq[1371]: false Dec 13 01:27:41.294469 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Dec 13 01:27:41.299449 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Dec 13 01:27:41.305171 systemd[1]: Starting systemd-logind.service - User Login Management... Dec 13 01:27:41.307336 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Dec 13 01:27:41.308165 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Dec 13 01:27:41.312295 systemd[1]: Starting update-engine.service - Update Engine... Dec 13 01:27:41.316344 dbus-daemon[1370]: [system] SELinux support is enabled Dec 13 01:27:41.323288 extend-filesystems[1372]: Found loop2 Dec 13 01:27:41.323288 extend-filesystems[1372]: Found loop3 Dec 13 01:27:41.323288 extend-filesystems[1372]: Found sr0 Dec 13 01:27:41.323288 extend-filesystems[1372]: Found vda Dec 13 01:27:41.323288 extend-filesystems[1372]: Found vda1 Dec 13 01:27:41.323288 extend-filesystems[1372]: Found vda2 Dec 13 01:27:41.323288 extend-filesystems[1372]: Found vda3 Dec 13 01:27:41.323288 extend-filesystems[1372]: Found usr Dec 13 01:27:41.323288 extend-filesystems[1372]: Found vda4 Dec 13 01:27:41.323288 extend-filesystems[1372]: Found vda6 Dec 13 01:27:41.319225 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Dec 13 01:27:41.347701 extend-filesystems[1372]: Found vda7 Dec 13 01:27:41.347701 extend-filesystems[1372]: Found vda9 Dec 13 01:27:41.347701 extend-filesystems[1372]: Checking size of /dev/vda9 Dec 13 01:27:41.347701 extend-filesystems[1372]: Old size kept for /dev/vda9 Dec 13 01:27:41.358890 update_engine[1384]: I20241213 01:27:41.346707 1384 main.cc:92] Flatcar Update Engine starting Dec 13 01:27:41.358890 update_engine[1384]: I20241213 01:27:41.356289 1384 update_check_scheduler.cc:74] Next update check in 11m7s Dec 13 01:27:41.321772 systemd[1]: Started dbus.service - D-Bus System Message Bus. Dec 13 01:27:41.364468 jq[1386]: true Dec 13 01:27:41.328701 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Dec 13 01:27:41.340711 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Dec 13 01:27:41.342150 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Dec 13 01:27:41.342623 systemd[1]: motdgen.service: Deactivated successfully. Dec 13 01:27:41.342892 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Dec 13 01:27:41.344766 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Dec 13 01:27:41.346118 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Dec 13 01:27:41.350407 systemd[1]: extend-filesystems.service: Deactivated successfully. Dec 13 01:27:41.350781 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Dec 13 01:27:41.363947 (ntainerd)[1393]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Dec 13 01:27:41.374613 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 41 scanned by (udev-worker) (1330) Dec 13 01:27:41.374729 jq[1394]: true Dec 13 01:27:41.397674 systemd-logind[1379]: Watching system buttons on /dev/input/event1 (Power Button) Dec 13 01:27:41.401236 systemd-logind[1379]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Dec 13 01:27:41.407280 systemd[1]: Started update-engine.service - Update Engine. Dec 13 01:27:41.408312 systemd-logind[1379]: New seat seat0. Dec 13 01:27:41.499802 sshd_keygen[1388]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Dec 13 01:27:41.510112 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Dec 13 01:27:41.510162 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Dec 13 01:27:41.513450 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Dec 13 01:27:41.513487 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Dec 13 01:27:41.524379 systemd[1]: Started locksmithd.service - Cluster reboot manager. Dec 13 01:27:41.526755 systemd[1]: Started systemd-logind.service - User Login Management. Dec 13 01:27:41.553806 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Dec 13 01:27:41.563644 systemd[1]: Starting issuegen.service - Generate /run/issue... Dec 13 01:27:41.564303 locksmithd[1421]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Dec 13 01:27:41.619873 systemd[1]: issuegen.service: Deactivated successfully. Dec 13 01:27:41.620161 systemd[1]: Finished issuegen.service - Generate /run/issue. Dec 13 01:27:41.637522 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Dec 13 01:27:41.694791 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Dec 13 01:27:41.716423 systemd[1]: Started getty@tty1.service - Getty on tty1. Dec 13 01:27:41.731827 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Dec 13 01:27:41.749835 systemd[1]: Reached target getty.target - Login Prompts. Dec 13 01:27:41.934912 bash[1425]: Updated "/home/core/.ssh/authorized_keys" Dec 13 01:27:41.937358 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Dec 13 01:27:41.940131 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Dec 13 01:27:42.026640 containerd[1393]: time="2024-12-13T01:27:42.026484319Z" level=info msg="starting containerd" revision=174e0d1785eeda18dc2beba45e1d5a188771636b version=v1.7.21 Dec 13 01:27:42.048312 containerd[1393]: time="2024-12-13T01:27:42.048241694Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Dec 13 01:27:42.050492 containerd[1393]: time="2024-12-13T01:27:42.050447811Z" 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.65-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Dec 13 01:27:42.050492 containerd[1393]: time="2024-12-13T01:27:42.050480685Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Dec 13 01:27:42.050541 containerd[1393]: time="2024-12-13T01:27:42.050496678Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Dec 13 01:27:42.050769 containerd[1393]: time="2024-12-13T01:27:42.050740035Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Dec 13 01:27:42.050769 containerd[1393]: time="2024-12-13T01:27:42.050761881Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Dec 13 01:27:42.050875 containerd[1393]: time="2024-12-13T01:27:42.050846095Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Dec 13 01:27:42.050875 containerd[1393]: time="2024-12-13T01:27:42.050866196Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Dec 13 01:27:42.051152 containerd[1393]: time="2024-12-13T01:27:42.051121677Z" 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 Dec 13 01:27:42.051152 containerd[1393]: time="2024-12-13T01:27:42.051141619Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Dec 13 01:27:42.051192 containerd[1393]: time="2024-12-13T01:27:42.051156744Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Dec 13 01:27:42.051192 containerd[1393]: time="2024-12-13T01:27:42.051167085Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Dec 13 01:27:42.051303 containerd[1393]: time="2024-12-13T01:27:42.051281080Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Dec 13 01:27:42.051589 containerd[1393]: time="2024-12-13T01:27:42.051558916Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Dec 13 01:27:42.051716 containerd[1393]: time="2024-12-13T01:27:42.051689005Z" 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 Dec 13 01:27:42.051716 containerd[1393]: time="2024-12-13T01:27:42.051706394Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Dec 13 01:27:42.051849 containerd[1393]: time="2024-12-13T01:27:42.051821428Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Dec 13 01:27:42.051908 containerd[1393]: time="2024-12-13T01:27:42.051887893Z" level=info msg="metadata content store policy set" policy=shared Dec 13 01:27:42.057724 containerd[1393]: time="2024-12-13T01:27:42.057241699Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Dec 13 01:27:42.057724 containerd[1393]: time="2024-12-13T01:27:42.057583797Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Dec 13 01:27:42.057724 containerd[1393]: time="2024-12-13T01:27:42.057610230Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Dec 13 01:27:42.057724 containerd[1393]: time="2024-12-13T01:27:42.057641039Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Dec 13 01:27:42.057724 containerd[1393]: time="2024-12-13T01:27:42.057667312Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Dec 13 01:27:42.057972 containerd[1393]: time="2024-12-13T01:27:42.057930771Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Dec 13 01:27:42.059421 containerd[1393]: time="2024-12-13T01:27:42.059391501Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Dec 13 01:27:42.059590 containerd[1393]: time="2024-12-13T01:27:42.059563268Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Dec 13 01:27:42.059613 containerd[1393]: time="2024-12-13T01:27:42.059590289Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Dec 13 01:27:42.059634 containerd[1393]: time="2024-12-13T01:27:42.059618896Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Dec 13 01:27:42.059667 containerd[1393]: time="2024-12-13T01:27:42.059638149Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Dec 13 01:27:42.059688 containerd[1393]: time="2024-12-13T01:27:42.059661870Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Dec 13 01:27:42.059707 containerd[1393]: time="2024-12-13T01:27:42.059685450Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Dec 13 01:27:42.059727 containerd[1393]: time="2024-12-13T01:27:42.059705143Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Dec 13 01:27:42.059747 containerd[1393]: time="2024-12-13T01:27:42.059725074Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Dec 13 01:27:42.059767 containerd[1393]: time="2024-12-13T01:27:42.059744119Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Dec 13 01:27:42.059787 containerd[1393]: time="2024-12-13T01:27:42.059761947Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Dec 13 01:27:42.059787 containerd[1393]: time="2024-12-13T01:27:42.059778209Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Dec 13 01:27:42.059829 containerd[1393]: time="2024-12-13T01:27:42.059807981Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Dec 13 01:27:42.059849 containerd[1393]: time="2024-12-13T01:27:42.059826617Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Dec 13 01:27:42.059849 containerd[1393]: time="2024-12-13T01:27:42.059843368Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Dec 13 01:27:42.059898 containerd[1393]: time="2024-12-13T01:27:42.059860986Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Dec 13 01:27:42.059898 containerd[1393]: time="2024-12-13T01:27:42.059879971Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Dec 13 01:27:42.059934 containerd[1393]: time="2024-12-13T01:27:42.059897609Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Dec 13 01:27:42.059934 containerd[1393]: time="2024-12-13T01:27:42.059911518Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Dec 13 01:27:42.059934 containerd[1393]: time="2024-12-13T01:27:42.059927163Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Dec 13 01:27:42.059991 containerd[1393]: time="2024-12-13T01:27:42.059943046Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Dec 13 01:27:42.059991 containerd[1393]: time="2024-12-13T01:27:42.059962031Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Dec 13 01:27:42.059991 containerd[1393]: time="2024-12-13T01:27:42.059978493Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Dec 13 01:27:42.060043 containerd[1393]: time="2024-12-13T01:27:42.059993967Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Dec 13 01:27:42.060043 containerd[1393]: time="2024-12-13T01:27:42.060010109Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Dec 13 01:27:42.060043 containerd[1393]: time="2024-12-13T01:27:42.060032295Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Dec 13 01:27:42.060132 containerd[1393]: time="2024-12-13T01:27:42.060082538Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Dec 13 01:27:42.060132 containerd[1393]: time="2024-12-13T01:27:42.060113427Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Dec 13 01:27:42.060169 containerd[1393]: time="2024-12-13T01:27:42.060130467Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Dec 13 01:27:42.060246 containerd[1393]: time="2024-12-13T01:27:42.060199654Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Dec 13 01:27:42.060246 containerd[1393]: time="2024-12-13T01:27:42.060233735Z" 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 Dec 13 01:27:42.060246 containerd[1393]: time="2024-12-13T01:27:42.060255640Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Dec 13 01:27:42.060491 containerd[1393]: time="2024-12-13T01:27:42.060279860Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Dec 13 01:27:42.060491 containerd[1393]: time="2024-12-13T01:27:42.060303081Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Dec 13 01:27:42.060491 containerd[1393]: time="2024-12-13T01:27:42.060346145Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Dec 13 01:27:42.060491 containerd[1393]: time="2024-12-13T01:27:42.060375998Z" level=info msg="NRI interface is disabled by configuration." Dec 13 01:27:42.060491 containerd[1393]: time="2024-12-13T01:27:42.060396747Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Dec 13 01:27:42.060854 containerd[1393]: time="2024-12-13T01:27:42.060774331Z" 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}" Dec 13 01:27:42.060854 containerd[1393]: time="2024-12-13T01:27:42.060851225Z" level=info msg="Connect containerd service" Dec 13 01:27:42.061054 containerd[1393]: time="2024-12-13T01:27:42.060901668Z" level=info msg="using legacy CRI server" Dec 13 01:27:42.061054 containerd[1393]: time="2024-12-13T01:27:42.060914600Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Dec 13 01:27:42.061129 containerd[1393]: time="2024-12-13T01:27:42.061085150Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Dec 13 01:27:42.062760 containerd[1393]: time="2024-12-13T01:27:42.062717798Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Dec 13 01:27:42.062937 containerd[1393]: time="2024-12-13T01:27:42.062864847Z" level=info msg="Start subscribing containerd event" Dec 13 01:27:42.062981 containerd[1393]: time="2024-12-13T01:27:42.062959101Z" level=info msg="Start recovering state" Dec 13 01:27:42.063203 containerd[1393]: time="2024-12-13T01:27:42.063175417Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Dec 13 01:27:42.063239 containerd[1393]: time="2024-12-13T01:27:42.063178159Z" level=info msg="Start event monitor" Dec 13 01:27:42.063239 containerd[1393]: time="2024-12-13T01:27:42.063227316Z" level=info msg="Start snapshots syncer" Dec 13 01:27:42.063288 containerd[1393]: time="2024-12-13T01:27:42.063246449Z" level=info msg="Start cni network conf syncer for default" Dec 13 01:27:42.063288 containerd[1393]: time="2024-12-13T01:27:42.063252082Z" level=info msg=serving... address=/run/containerd/containerd.sock Dec 13 01:27:42.063288 containerd[1393]: time="2024-12-13T01:27:42.063259999Z" level=info msg="Start streaming server" Dec 13 01:27:42.063400 containerd[1393]: time="2024-12-13T01:27:42.063371254Z" level=info msg="containerd successfully booted in 0.070780s" Dec 13 01:27:42.063528 systemd[1]: Started containerd.service - containerd container runtime. Dec 13 01:27:42.661347 systemd-networkd[1325]: eth0: Gained IPv6LL Dec 13 01:27:42.665251 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Dec 13 01:27:42.667282 systemd[1]: Reached target network-online.target - Network is Online. Dec 13 01:27:42.681390 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Dec 13 01:27:42.684142 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Dec 13 01:27:42.708307 systemd[1]: coreos-metadata.service: Deactivated successfully. Dec 13 01:27:42.708657 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Dec 13 01:27:42.722545 systemd[1]: Starting etcd-member.service - etcd (System Application Container)... Dec 13 01:27:42.722750 (-wrapper)[1467]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS Dec 13 01:27:42.724011 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Dec 13 01:27:42.724667 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Dec 13 01:27:43.120572 systemd[1]: Starting docker.service - Docker Application Container Engine... Dec 13 01:27:43.120747 (dockerd)[1478]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU Dec 13 01:27:43.800157 dockerd[1478]: time="2024-12-13T01:27:43.800038507Z" level=info msg="Starting up" Dec 13 01:27:44.025325 dockerd[1478]: time="2024-12-13T01:27:44.025233518Z" level=info msg="Loading containers: start." Dec 13 01:27:44.155090 kernel: Initializing XFRM netlink socket Dec 13 01:27:44.247401 systemd-networkd[1325]: docker0: Link UP Dec 13 01:27:44.354774 dockerd[1478]: time="2024-12-13T01:27:44.354662649Z" level=info msg="Loading containers: done." Dec 13 01:27:44.373861 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck2663875745-merged.mount: Deactivated successfully. Dec 13 01:27:44.375982 dockerd[1478]: time="2024-12-13T01:27:44.375905861Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 Dec 13 01:27:44.376200 dockerd[1478]: time="2024-12-13T01:27:44.376056628Z" level=info msg="Docker daemon" commit=061aa95809be396a6b5542618d8a34b02a21ff77 containerd-snapshotter=false storage-driver=overlay2 version=26.1.0 Dec 13 01:27:44.376255 dockerd[1478]: time="2024-12-13T01:27:44.376206038Z" level=info msg="Daemon has completed initialization" Dec 13 01:27:44.419911 dockerd[1478]: time="2024-12-13T01:27:44.419814981Z" level=info msg="API listen on /run/docker.sock" Dec 13 01:27:44.420118 systemd[1]: Started docker.service - Docker Application Container Engine. Dec 13 01:27:44.423010 etcd-wrapper[1472]: Error response from daemon: No such container: etcd-member Dec 13 01:27:44.450028 etcd-wrapper[1605]: Error response from daemon: No such container: etcd-member Dec 13 01:27:44.547269 etcd-wrapper[1622]: Unable to find image 'quay.io/coreos/etcd:v3.5.16' locally Dec 13 01:27:45.818814 etcd-wrapper[1622]: v3.5.16: Pulling from coreos/etcd Dec 13 01:27:47.398881 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Dec 13 01:27:47.410756 systemd[1]: Started sshd@0-10.0.0.49:22-10.0.0.1:44550.service - OpenSSH per-connection server daemon (10.0.0.1:44550). Dec 13 01:27:47.463659 sshd[1638]: Accepted publickey for core from 10.0.0.1 port 44550 ssh2: RSA SHA256:x0r+OYSWSaRwllGtX4o4H8bWGnkqZzK3ZUwKdtfgOO0 Dec 13 01:27:47.466400 sshd[1638]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 13 01:27:47.479997 systemd-logind[1379]: New session 1 of user core. Dec 13 01:27:47.481981 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Dec 13 01:27:47.500672 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Dec 13 01:27:47.523280 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Dec 13 01:27:47.536557 systemd[1]: Starting user@500.service - User Manager for UID 500... Dec 13 01:27:47.540891 (systemd)[1642]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Dec 13 01:27:47.666140 systemd[1642]: Queued start job for default target default.target. Dec 13 01:27:47.677724 systemd[1642]: Created slice app.slice - User Application Slice. Dec 13 01:27:47.677761 systemd[1642]: Reached target paths.target - Paths. Dec 13 01:27:47.677781 systemd[1642]: Reached target timers.target - Timers. Dec 13 01:27:47.679772 systemd[1642]: Starting dbus.socket - D-Bus User Message Bus Socket... Dec 13 01:27:47.698677 systemd[1642]: Listening on dbus.socket - D-Bus User Message Bus Socket. Dec 13 01:27:47.698906 systemd[1642]: Reached target sockets.target - Sockets. Dec 13 01:27:47.698931 systemd[1642]: Reached target basic.target - Basic System. Dec 13 01:27:47.698995 systemd[1642]: Reached target default.target - Main User Target. Dec 13 01:27:47.699038 systemd[1642]: Startup finished in 149ms. Dec 13 01:27:47.699964 systemd[1]: Started user@500.service - User Manager for UID 500. Dec 13 01:27:47.718383 systemd[1]: Started session-1.scope - Session 1 of User core. Dec 13 01:27:47.785433 systemd[1]: Started sshd@1-10.0.0.49:22-10.0.0.1:44558.service - OpenSSH per-connection server daemon (10.0.0.1:44558). Dec 13 01:27:47.833185 sshd[1653]: Accepted publickey for core from 10.0.0.1 port 44558 ssh2: RSA SHA256:x0r+OYSWSaRwllGtX4o4H8bWGnkqZzK3ZUwKdtfgOO0 Dec 13 01:27:47.835592 sshd[1653]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 13 01:27:47.841084 systemd-logind[1379]: New session 2 of user core. Dec 13 01:27:47.862377 systemd[1]: Started session-2.scope - Session 2 of User core. Dec 13 01:27:47.918864 sshd[1653]: pam_unix(sshd:session): session closed for user core Dec 13 01:27:47.932288 systemd[1]: sshd@1-10.0.0.49:22-10.0.0.1:44558.service: Deactivated successfully. Dec 13 01:27:47.935501 systemd[1]: session-2.scope: Deactivated successfully. Dec 13 01:27:47.937562 systemd-logind[1379]: Session 2 logged out. Waiting for processes to exit. Dec 13 01:27:47.952917 systemd[1]: Started sshd@2-10.0.0.49:22-10.0.0.1:44572.service - OpenSSH per-connection server daemon (10.0.0.1:44572). Dec 13 01:27:47.954418 systemd-logind[1379]: Removed session 2. Dec 13 01:27:47.988923 sshd[1660]: Accepted publickey for core from 10.0.0.1 port 44572 ssh2: RSA SHA256:x0r+OYSWSaRwllGtX4o4H8bWGnkqZzK3ZUwKdtfgOO0 Dec 13 01:27:47.990847 sshd[1660]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 13 01:27:47.995816 systemd-logind[1379]: New session 3 of user core. Dec 13 01:27:48.006317 systemd[1]: Started session-3.scope - Session 3 of User core. Dec 13 01:27:48.062406 sshd[1660]: pam_unix(sshd:session): session closed for user core Dec 13 01:27:48.066802 systemd[1]: sshd@2-10.0.0.49:22-10.0.0.1:44572.service: Deactivated successfully. Dec 13 01:27:48.069006 systemd[1]: session-3.scope: Deactivated successfully. Dec 13 01:27:48.069667 systemd-logind[1379]: Session 3 logged out. Waiting for processes to exit. Dec 13 01:27:48.070670 systemd-logind[1379]: Removed session 3. Dec 13 01:27:48.117213 etcd-wrapper[1622]: 804c8aba2cc6: Pulling fs layer Dec 13 01:27:48.117213 etcd-wrapper[1622]: 2ae710cd8bfe: Pulling fs layer Dec 13 01:27:48.117213 etcd-wrapper[1622]: d462aa345367: Pulling fs layer Dec 13 01:27:48.117213 etcd-wrapper[1622]: 0f8b424aa0b9: Pulling fs layer Dec 13 01:27:48.117213 etcd-wrapper[1622]: d557676654e5: Pulling fs layer Dec 13 01:27:48.117213 etcd-wrapper[1622]: c8022d07192e: Pulling fs layer Dec 13 01:27:48.117213 etcd-wrapper[1622]: d858cbc252ad: Pulling fs layer Dec 13 01:27:48.117820 etcd-wrapper[1622]: 1069fc2daed1: Pulling fs layer Dec 13 01:27:48.117820 etcd-wrapper[1622]: b40161cd83fc: Pulling fs layer Dec 13 01:27:48.117820 etcd-wrapper[1622]: 5318d93a3a65: Pulling fs layer Dec 13 01:27:48.117820 etcd-wrapper[1622]: 307c1adadb60: Pulling fs layer Dec 13 01:27:48.117820 etcd-wrapper[1622]: fbb01d9e9dc9: Pulling fs layer Dec 13 01:27:48.117820 etcd-wrapper[1622]: fbfea02ac3cf: Pulling fs layer Dec 13 01:27:48.117820 etcd-wrapper[1622]: 8c26e4bf18e2: Pulling fs layer Dec 13 01:27:48.117820 etcd-wrapper[1622]: 1e59a65f8816: Pulling fs layer Dec 13 01:27:48.117820 etcd-wrapper[1622]: ffbd4ca5f0bd: Pulling fs layer Dec 13 01:27:48.117820 etcd-wrapper[1622]: 0f8b424aa0b9: Waiting Dec 13 01:27:48.117820 etcd-wrapper[1622]: d557676654e5: Waiting Dec 13 01:27:48.117820 etcd-wrapper[1622]: c8022d07192e: Waiting Dec 13 01:27:48.117820 etcd-wrapper[1622]: d858cbc252ad: Waiting Dec 13 01:27:48.117820 etcd-wrapper[1622]: 1069fc2daed1: Waiting Dec 13 01:27:48.117820 etcd-wrapper[1622]: 5318d93a3a65: Waiting Dec 13 01:27:48.117820 etcd-wrapper[1622]: b40161cd83fc: Waiting Dec 13 01:27:48.117820 etcd-wrapper[1622]: 8c26e4bf18e2: Waiting Dec 13 01:27:48.117820 etcd-wrapper[1622]: 1e59a65f8816: Waiting Dec 13 01:27:48.117820 etcd-wrapper[1622]: 307c1adadb60: Waiting Dec 13 01:27:48.117820 etcd-wrapper[1622]: ffbd4ca5f0bd: Waiting Dec 13 01:27:48.117820 etcd-wrapper[1622]: fbb01d9e9dc9: Waiting Dec 13 01:27:48.117820 etcd-wrapper[1622]: fbfea02ac3cf: Waiting Dec 13 01:27:48.428876 etcd-wrapper[1622]: d462aa345367: Download complete Dec 13 01:27:48.438957 etcd-wrapper[1622]: 804c8aba2cc6: Verifying Checksum Dec 13 01:27:48.438957 etcd-wrapper[1622]: 804c8aba2cc6: Download complete Dec 13 01:27:48.606851 etcd-wrapper[1622]: 2ae710cd8bfe: Download complete Dec 13 01:27:48.689045 etcd-wrapper[1622]: 0f8b424aa0b9: Verifying Checksum Dec 13 01:27:48.689045 etcd-wrapper[1622]: 0f8b424aa0b9: Download complete Dec 13 01:27:48.801605 etcd-wrapper[1622]: 804c8aba2cc6: Pull complete Dec 13 01:27:48.938268 etcd-wrapper[1622]: d858cbc252ad: Verifying Checksum Dec 13 01:27:48.938268 etcd-wrapper[1622]: d858cbc252ad: Download complete Dec 13 01:27:48.974605 systemd[1]: var-lib-docker-overlay2-fd8f233a1b7138710cdf59cc25a239edaee89c6a745b1229d76273fc49a8cf8e-merged.mount: Deactivated successfully. Dec 13 01:27:48.982717 etcd-wrapper[1622]: d557676654e5: Verifying Checksum Dec 13 01:27:48.982717 etcd-wrapper[1622]: d557676654e5: Download complete Dec 13 01:27:49.223648 etcd-wrapper[1622]: 2ae710cd8bfe: Pull complete Dec 13 01:27:49.308694 etcd-wrapper[1622]: 1069fc2daed1: Verifying Checksum Dec 13 01:27:49.308694 etcd-wrapper[1622]: 1069fc2daed1: Download complete Dec 13 01:27:49.743974 etcd-wrapper[1622]: 5318d93a3a65: Download complete Dec 13 01:27:49.747984 etcd-wrapper[1622]: b40161cd83fc: Verifying Checksum Dec 13 01:27:49.747984 etcd-wrapper[1622]: b40161cd83fc: Download complete Dec 13 01:27:49.842303 etcd-wrapper[1622]: c8022d07192e: Verifying Checksum Dec 13 01:27:49.842303 etcd-wrapper[1622]: c8022d07192e: Download complete Dec 13 01:27:49.983098 systemd[1]: var-lib-docker-overlay2-5d39922e8ffaa5a458a285d28170f82da02b5049c3f94d47fa624e3840c82f0b-merged.mount: Deactivated successfully. Dec 13 01:27:50.013914 etcd-wrapper[1622]: 307c1adadb60: Verifying Checksum Dec 13 01:27:50.013914 etcd-wrapper[1622]: 307c1adadb60: Download complete Dec 13 01:27:50.202021 etcd-wrapper[1622]: d462aa345367: Pull complete Dec 13 01:27:50.222843 systemd[1]: var-lib-docker-overlay2-381e00f29225b760c90635cba66d470db2daa93ca74c3e586a5a5dbfaa38a103-merged.mount: Deactivated successfully. Dec 13 01:27:50.304275 etcd-wrapper[1622]: fbb01d9e9dc9: Verifying Checksum Dec 13 01:27:50.304275 etcd-wrapper[1622]: fbb01d9e9dc9: Download complete Dec 13 01:27:50.360136 etcd-wrapper[1622]: 0f8b424aa0b9: Pull complete Dec 13 01:27:50.680808 etcd-wrapper[1622]: 1e59a65f8816: Verifying Checksum Dec 13 01:27:50.680808 etcd-wrapper[1622]: 1e59a65f8816: Download complete Dec 13 01:27:50.815794 systemd[1]: var-lib-docker-overlay2-e0740d425b5ab8cb56dbc3d07969db4be27a4c2e4b6056a5fb8140fbc86a0926-merged.mount: Deactivated successfully. Dec 13 01:27:50.856319 etcd-wrapper[1622]: d557676654e5: Pull complete Dec 13 01:27:50.885701 etcd-wrapper[1622]: c8022d07192e: Pull complete Dec 13 01:27:50.911638 etcd-wrapper[1622]: d858cbc252ad: Pull complete Dec 13 01:27:50.947134 etcd-wrapper[1622]: 1069fc2daed1: Pull complete Dec 13 01:27:51.031437 etcd-wrapper[1622]: ffbd4ca5f0bd: Verifying Checksum Dec 13 01:27:51.031437 etcd-wrapper[1622]: ffbd4ca5f0bd: Download complete Dec 13 01:27:51.178606 etcd-wrapper[1622]: 8c26e4bf18e2: Verifying Checksum Dec 13 01:27:51.178606 etcd-wrapper[1622]: 8c26e4bf18e2: Download complete Dec 13 01:27:51.372136 etcd-wrapper[1622]: b40161cd83fc: Pull complete Dec 13 01:27:51.392583 systemd[1]: var-lib-docker-overlay2-5cab0580953418385bff003081bb9a1c86c7018772a7133d268a3410d8a6b687-merged.mount: Deactivated successfully. Dec 13 01:27:51.419814 etcd-wrapper[1622]: 5318d93a3a65: Pull complete Dec 13 01:27:51.438934 systemd[1]: var-lib-docker-overlay2-b1f1b60f5081eefd49d34ab20e785f03e3b4779a940311ea860af6b8ea4b9ff9-merged.mount: Deactivated successfully. Dec 13 01:27:51.452528 etcd-wrapper[1622]: 307c1adadb60: Pull complete Dec 13 01:27:51.691478 etcd-wrapper[1622]: fbb01d9e9dc9: Pull complete Dec 13 01:27:52.188251 etcd-wrapper[1622]: fbfea02ac3cf: Verifying Checksum Dec 13 01:27:52.188251 etcd-wrapper[1622]: fbfea02ac3cf: Download complete Dec 13 01:27:52.493936 systemd[1]: var-lib-docker-overlay2-66e05c9d3c670dd0222fb426c54d6f75d8a00beca8d9ae06b1b2094a9829c247-merged.mount: Deactivated successfully. Dec 13 01:27:53.035767 etcd-wrapper[1622]: fbfea02ac3cf: Pull complete Dec 13 01:27:53.310187 systemd[1]: var-lib-docker-overlay2-4afc10ca1265f0b39e644178a3a2eea0c1d6eab6ed8a87e7a97c9f9f16202b5d-merged.mount: Deactivated successfully. Dec 13 01:27:53.671389 etcd-wrapper[1622]: 8c26e4bf18e2: Pull complete Dec 13 01:27:53.691596 systemd[1]: var-lib-docker-overlay2-84d5b68db8a00ea787a3c298d79ed04c84c2cfa539cc4766629953e9bd2b00e5-merged.mount: Deactivated successfully. Dec 13 01:27:53.705078 etcd-wrapper[1622]: 1e59a65f8816: Pull complete Dec 13 01:27:53.736453 etcd-wrapper[1622]: ffbd4ca5f0bd: Pull complete Dec 13 01:27:53.747645 etcd-wrapper[1622]: Digest: sha256:d967d98a12dc220a1a290794711dba7eba04b8ce465e12b02383d1bfbb33e159 Dec 13 01:27:53.751888 etcd-wrapper[1622]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.16 Dec 13 01:27:54.066772 systemd[1]: var-lib-docker-overlay2-b91fb9de42f6965bb7c27fd6632acdb62e536c1a03444a431da28955a44ce4a5-merged.mount: Deactivated successfully. Dec 13 01:27:54.246928 containerd[1393]: time="2024-12-13T01:27:54.246759124Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Dec 13 01:27:54.246928 containerd[1393]: time="2024-12-13T01:27:54.246854452Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Dec 13 01:27:54.246928 containerd[1393]: time="2024-12-13T01:27:54.246873656Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Dec 13 01:27:54.247513 containerd[1393]: time="2024-12-13T01:27:54.247042989Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Dec 13 01:27:54.283316 systemd[1]: Started docker-6085b89f17bf0bebf2d2fbcc40a8cb19c1d4b69a4ca495a48bdbb9b42dd90c02.scope - libcontainer container 6085b89f17bf0bebf2d2fbcc40a8cb19c1d4b69a4ca495a48bdbb9b42dd90c02. Dec 13 01:27:54.307413 systemd-resolved[1278]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Dec 13 01:27:54.423095 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.422363Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} Dec 13 01:27:54.423095 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.422552Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"7a9b27727fd44c9cb816ec73f8225bb3"} Dec 13 01:27:54.423095 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:27:54.422583Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} Dec 13 01:27:54.423095 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:27:54.422605Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.16"} Dec 13 01:27:54.423095 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:27:54.422614Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} Dec 13 01:27:54.423095 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:27:54.422620Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} Dec 13 01:27:54.423095 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:27:54.422646Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Dec 13 01:27:54.423095 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.422692Z","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.49:2379"]} Dec 13 01:27:54.423887 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:27:54.422794Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Dec 13 01:27:54.423887 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.422812Z","caller":"embed/etcd.go:128","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]} Dec 13 01:27:54.423887 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.423451Z","caller":"embed/etcd.go:136","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} Dec 13 01:27:54.424021 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.423659Z","caller":"embed/etcd.go:311","msg":"starting an etcd server","etcd-version":"3.5.16","git-sha":"f20bbad","go-version":"go1.22.7","go-os":"linux","go-arch":"amd64","max-cpu-set":4,"max-cpu-available":4,"member-initialized":false,"name":"7a9b27727fd44c9cb816ec73f8225bb3","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.49:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"7a9b27727fd44c9cb816ec73f8225bb3=http://localhost:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":false,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} Dec 13 01:27:54.427720 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.427393Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.111641ms"} Dec 13 01:27:54.432788 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.432409Z","caller":"etcdserver/raft.go:505","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"} Dec 13 01:27:54.432788 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.432504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"} Dec 13 01:27:54.432788 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.432556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"} Dec 13 01:27:54.432788 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.432566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} Dec 13 01:27:54.432788 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.432585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"} Dec 13 01:27:54.433037 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.432622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Dec 13 01:27:54.438679 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:27:54.438324Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} Dec 13 01:27:54.439997 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.439648Z","caller":"mvcc/kvstore.go:423","msg":"kvstore restored","current-rev":1} Dec 13 01:27:54.441091 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.440825Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} Dec 13 01:27:54.442926 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.442162Z","caller":"etcdserver/server.go:873","msg":"starting etcd server","local-member-id":"8e9e05c52164694d","local-server-version":"3.5.16","cluster-version":"to_be_decided"} Dec 13 01:27:54.442926 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.442428Z","caller":"etcdserver/server.go:757","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8e9e05c52164694d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} Dec 13 01:27:54.442926 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.442475Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Dec 13 01:27:54.443110 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.442626Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} Dec 13 01:27:54.443110 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.442712Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} Dec 13 01:27:54.443110 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.442724Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} Dec 13 01:27:54.443937 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.443727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Dec 13 01:27:54.444551 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.444376Z","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"]} Dec 13 01:27:54.445666 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.445394Z","caller":"embed/etcd.go:600","msg":"serving peer traffic","address":"127.0.0.1:2380"} Dec 13 01:27:54.445666 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.445417Z","caller":"embed/etcd.go:572","msg":"cmux::serve","address":"127.0.0.1:2380"} Dec 13 01:27:54.445816 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.445508Z","caller":"embed/etcd.go:280","msg":"now serving peer/client/metrics","local-member-id":"8e9e05c52164694d","initial-advertise-peer-urls":["http://localhost:2380"],"listen-peer-urls":["http://localhost:2380"],"advertise-client-urls":["http://10.0.0.49:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} Dec 13 01:27:54.634400 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.633852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"} Dec 13 01:27:54.634400 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.633934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"} Dec 13 01:27:54.634400 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.633992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"} Dec 13 01:27:54.634400 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.634014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"} Dec 13 01:27:54.634400 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.634031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"} Dec 13 01:27:54.634400 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.634045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"} Dec 13 01:27:54.634400 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.634085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"} Dec 13 01:27:54.672024 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.671609Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} Dec 13 01:27:54.672024 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.671614Z","caller":"etcdserver/server.go:2651","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} Dec 13 01:27:54.672024 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.671611Z","caller":"etcdserver/server.go:2140","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:7a9b27727fd44c9cb816ec73f8225bb3 ClientURLs:[http://10.0.0.49:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"} Dec 13 01:27:54.672024 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.671803Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} Dec 13 01:27:54.672320 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.671955Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} Dec 13 01:27:54.672428 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.672137Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Dec 13 01:27:54.672790 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.672560Z","caller":"embed/serve.go:187","msg":"serving client traffic insecurely; this is strongly discouraged!","traffic":"grpc+http","address":"[::]:2379"} Dec 13 01:27:54.672990 systemd[1]: Started etcd-member.service - etcd (System Application Container). Dec 13 01:27:54.673374 systemd[1]: Reached target multi-user.target - Multi-User System. Dec 13 01:27:54.674713 systemd[1]: Startup finished in 1.307s (kernel) + 4.265s (initrd) + 16.717s (userspace) = 22.290s. Dec 13 01:27:54.687009 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.686818Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"} Dec 13 01:27:54.687009 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.686887Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} Dec 13 01:27:54.687009 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:27:54.686922Z","caller":"etcdserver/server.go:2675","msg":"cluster version is updated","cluster-version":"3.5"} Dec 13 01:27:58.050689 systemd[1]: Started sshd@3-10.0.0.49:22-10.0.0.1:34482.service - OpenSSH per-connection server daemon (10.0.0.1:34482). Dec 13 01:27:58.090234 sshd[1798]: Accepted publickey for core from 10.0.0.1 port 34482 ssh2: RSA SHA256:x0r+OYSWSaRwllGtX4o4H8bWGnkqZzK3ZUwKdtfgOO0 Dec 13 01:27:58.092764 sshd[1798]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 13 01:27:58.097733 systemd-logind[1379]: New session 4 of user core. Dec 13 01:27:58.107307 systemd[1]: Started session-4.scope - Session 4 of User core. Dec 13 01:27:58.166329 sshd[1798]: pam_unix(sshd:session): session closed for user core Dec 13 01:27:58.186708 systemd[1]: sshd@3-10.0.0.49:22-10.0.0.1:34482.service: Deactivated successfully. Dec 13 01:27:58.188927 systemd[1]: session-4.scope: Deactivated successfully. Dec 13 01:27:58.190952 systemd-logind[1379]: Session 4 logged out. Waiting for processes to exit. Dec 13 01:27:58.201424 systemd[1]: Started sshd@4-10.0.0.49:22-10.0.0.1:34488.service - OpenSSH per-connection server daemon (10.0.0.1:34488). Dec 13 01:27:58.202845 systemd-logind[1379]: Removed session 4. Dec 13 01:27:58.237733 sshd[1805]: Accepted publickey for core from 10.0.0.1 port 34488 ssh2: RSA SHA256:x0r+OYSWSaRwllGtX4o4H8bWGnkqZzK3ZUwKdtfgOO0 Dec 13 01:27:58.239629 sshd[1805]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 13 01:27:58.244539 systemd-logind[1379]: New session 5 of user core. Dec 13 01:27:58.258365 systemd[1]: Started session-5.scope - Session 5 of User core. Dec 13 01:27:58.311134 sshd[1805]: pam_unix(sshd:session): session closed for user core Dec 13 01:27:58.332491 systemd[1]: sshd@4-10.0.0.49:22-10.0.0.1:34488.service: Deactivated successfully. Dec 13 01:27:58.334658 systemd[1]: session-5.scope: Deactivated successfully. Dec 13 01:27:58.336631 systemd-logind[1379]: Session 5 logged out. Waiting for processes to exit. Dec 13 01:27:58.345383 systemd[1]: Started sshd@5-10.0.0.49:22-10.0.0.1:34492.service - OpenSSH per-connection server daemon (10.0.0.1:34492). Dec 13 01:27:58.346446 systemd-logind[1379]: Removed session 5. Dec 13 01:27:58.380665 sshd[1812]: Accepted publickey for core from 10.0.0.1 port 34492 ssh2: RSA SHA256:x0r+OYSWSaRwllGtX4o4H8bWGnkqZzK3ZUwKdtfgOO0 Dec 13 01:27:58.382485 sshd[1812]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 13 01:27:58.387130 systemd-logind[1379]: New session 6 of user core. Dec 13 01:27:58.398250 systemd[1]: Started session-6.scope - Session 6 of User core. Dec 13 01:27:58.452388 sshd[1812]: pam_unix(sshd:session): session closed for user core Dec 13 01:27:58.463791 systemd[1]: sshd@5-10.0.0.49:22-10.0.0.1:34492.service: Deactivated successfully. Dec 13 01:27:58.465582 systemd[1]: session-6.scope: Deactivated successfully. Dec 13 01:27:58.467001 systemd-logind[1379]: Session 6 logged out. Waiting for processes to exit. Dec 13 01:27:58.468243 systemd[1]: Started sshd@6-10.0.0.49:22-10.0.0.1:34508.service - OpenSSH per-connection server daemon (10.0.0.1:34508). Dec 13 01:27:58.468980 systemd-logind[1379]: Removed session 6. Dec 13 01:27:58.506855 sshd[1819]: Accepted publickey for core from 10.0.0.1 port 34508 ssh2: RSA SHA256:x0r+OYSWSaRwllGtX4o4H8bWGnkqZzK3ZUwKdtfgOO0 Dec 13 01:27:58.508465 sshd[1819]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 13 01:27:58.512125 systemd-logind[1379]: New session 7 of user core. Dec 13 01:27:58.522175 systemd[1]: Started session-7.scope - Session 7 of User core. Dec 13 01:27:58.572496 sshd[1819]: pam_unix(sshd:session): session closed for user core Dec 13 01:27:58.596785 systemd[1]: sshd@6-10.0.0.49:22-10.0.0.1:34508.service: Deactivated successfully. Dec 13 01:27:58.598781 systemd[1]: session-7.scope: Deactivated successfully. Dec 13 01:27:58.600243 systemd-logind[1379]: Session 7 logged out. Waiting for processes to exit. Dec 13 01:27:58.601600 systemd[1]: Started sshd@7-10.0.0.49:22-10.0.0.1:34512.service - OpenSSH per-connection server daemon (10.0.0.1:34512). Dec 13 01:27:58.602554 systemd-logind[1379]: Removed session 7. Dec 13 01:27:58.639245 sshd[1828]: Accepted publickey for core from 10.0.0.1 port 34512 ssh2: RSA SHA256:x0r+OYSWSaRwllGtX4o4H8bWGnkqZzK3ZUwKdtfgOO0 Dec 13 01:27:58.640797 sshd[1828]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 13 01:27:58.644714 systemd-logind[1379]: New session 8 of user core. Dec 13 01:27:58.660184 systemd[1]: Started session-8.scope - Session 8 of User core. Dec 13 01:27:58.760450 sshd[1828]: pam_unix(sshd:session): session closed for user core Dec 13 01:27:58.763961 systemd[1]: sshd@7-10.0.0.49:22-10.0.0.1:34512.service: Deactivated successfully. Dec 13 01:27:58.766242 systemd[1]: session-8.scope: Deactivated successfully. Dec 13 01:27:58.768390 systemd-logind[1379]: Session 8 logged out. Waiting for processes to exit. Dec 13 01:27:58.769473 systemd-logind[1379]: Removed session 8. Dec 13 01:28:26.390272 update_engine[1384]: I20241213 01:28:26.390141 1384 update_attempter.cc:509] Updating boot flags... Dec 13 01:28:26.557206 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 41 scanned by (udev-worker) (1846) Dec 13 01:28:26.600094 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 41 scanned by (udev-worker) (1847) Dec 13 01:28:26.659096 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 41 scanned by (udev-worker) (1847) Dec 13 01:29:08.466484 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:08.465681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.793505ms","expected-duration":"100ms","prefix":"","request":"header:<ID:7587883389850698373 > txn:<compare:<target:MOD key:\"/registry/events/default/localhost.1810985537707849\" mod_revision:0 > success:<request_put:<key:\"/registry/events/default/localhost.1810985537707849\" value_size:574 lease:7587883389850698230 >> failure:<>>","response":"size:16"} Dec 13 01:29:08.466484 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:08.465840Z","caller":"traceutil/trace.go:171","msg":"trace[977603805] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"144.108046ms","start":"2024-12-13T01:29:08.321712Z","end":"2024-12-13T01:29:08.465820Z","steps":["trace[977603805] 'process raft request' (duration: 17.694701ms)","trace[977603805] 'compare' (duration: 125.587957ms)"],"step_count":2} Dec 13 01:29:08.504672 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:08.503923Z","caller":"traceutil/trace.go:171","msg":"trace[2738637] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"180.145381ms","start":"2024-12-13T01:29:08.323753Z","end":"2024-12-13T01:29:08.503898Z","steps":["trace[2738637] 'process raft request' (duration: 180.029302ms)"],"step_count":1} Dec 13 01:29:09.724517 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:09.723891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.907919ms","expected-duration":"100ms","prefix":"","request":"header:<ID:7587883389850698404 > txn:<compare:<target:MOD key:\"/registry/events/kube-system/kube-apiserver-localhost.181098555a832c25\" mod_revision:300 > success:<request_put:<key:\"/registry/events/kube-system/kube-apiserver-localhost.181098555a832c25\" value_size:714 lease:7587883389850698230 >> failure:<request_range:<key:\"/registry/events/kube-system/kube-apiserver-localhost.181098555a832c25\" > >>","response":"size:16"} Dec 13 01:29:09.725364 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:09.724135Z","caller":"traceutil/trace.go:171","msg":"trace[1105241567] linearizableReadLoop","detail":"{readStateIndex:317; appliedIndex:315; }","duration":"181.800761ms","start":"2024-12-13T01:29:09.542316Z","end":"2024-12-13T01:29:09.724116Z","steps":["trace[1105241567] 'read index received' (duration: 66.437173ms)","trace[1105241567] 'applied index is now lower than readState.Index' (duration: 115.362646ms)"],"step_count":2} Dec 13 01:29:09.725364 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:09.724139Z","caller":"traceutil/trace.go:171","msg":"trace[1873182097] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"217.164545ms","start":"2024-12-13T01:29:09.506952Z","end":"2024-12-13T01:29:09.724117Z","steps":["trace[1873182097] 'process raft request' (duration: 101.79205ms)","trace[1873182097] 'compare' (duration: 114.807149ms)"],"step_count":2} Dec 13 01:29:09.725364 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:09.724207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.883036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/flannel\" ","response":"range_response_count:0 size:5"} Dec 13 01:29:09.725364 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:09.724229Z","caller":"traceutil/trace.go:171","msg":"trace[1696958113] range","detail":"{range_begin:/registry/clusterrolebindings/flannel; range_end:; response_count:0; response_revision:308; }","duration":"181.934154ms","start":"2024-12-13T01:29:09.542287Z","end":"2024-12-13T01:29:09.724221Z","steps":["trace[1696958113] 'agreement among raft nodes before linearized reading' (duration: 181.894578ms)"],"step_count":1} Dec 13 01:29:09.725511 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:09.724288Z","caller":"traceutil/trace.go:171","msg":"trace[1591335981] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"216.158548ms","start":"2024-12-13T01:29:09.508123Z","end":"2024-12-13T01:29:09.724281Z","steps":["trace[1591335981] 'process raft request' (duration: 215.877167ms)"],"step_count":1} Dec 13 01:29:09.746711 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:09.746247Z","caller":"traceutil/trace.go:171","msg":"trace[1921077030] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"177.018598ms","start":"2024-12-13T01:29:09.569209Z","end":"2024-12-13T01:29:09.746227Z","steps":["trace[1921077030] 'process raft request' (duration: 176.79137ms)"],"step_count":1} Dec 13 01:29:09.964158 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:09.963646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.865992ms","expected-duration":"100ms","prefix":"","request":"header:<ID:7587883389850698414 > txn:<compare:<target:MOD key:\"/registry/serviceaccounts/kube-flannel/flannel\" mod_revision:0 > success:<request_put:<key:\"/registry/serviceaccounts/kube-flannel/flannel\" value_size:543 >> failure:<>>","response":"size:16"} Dec 13 01:29:09.964158 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:09.963745Z","caller":"traceutil/trace.go:171","msg":"trace[155922536] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"151.137325ms","start":"2024-12-13T01:29:09.812586Z","end":"2024-12-13T01:29:09.963723Z","steps":["trace[155922536] 'process raft request' (duration: 34.13194ms)","trace[155922536] 'compare' (duration: 116.741938ms)"],"step_count":2} Dec 13 01:29:09.980009 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:09.979427Z","caller":"traceutil/trace.go:171","msg":"trace[825686772] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"162.146107ms","start":"2024-12-13T01:29:09.817255Z","end":"2024-12-13T01:29:09.979401Z","steps":["trace[825686772] 'process raft request' (duration: 161.976517ms)"],"step_count":1} Dec 13 01:29:10.187023 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:10.186552Z","caller":"traceutil/trace.go:171","msg":"trace[832986359] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"121.890979ms","start":"2024-12-13T01:29:10.064638Z","end":"2024-12-13T01:29:10.186529Z","steps":["trace[832986359] 'process raft request' (duration: 121.75865ms)"],"step_count":1} Dec 13 01:29:17.461519 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:17.461043Z","caller":"traceutil/trace.go:171","msg":"trace[965903026] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"126.646497ms","start":"2024-12-13T01:29:17.334374Z","end":"2024-12-13T01:29:17.461021Z","steps":["trace[965903026] 'process raft request' (duration: 107.296327ms)","trace[965903026] 'compare' (duration: 19.264419ms)"],"step_count":2} Dec 13 01:29:22.138719 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:22.136427Z","caller":"traceutil/trace.go:171","msg":"trace[1489243296] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"111.991473ms","start":"2024-12-13T01:29:22.023646Z","end":"2024-12-13T01:29:22.135638Z","steps":["trace[1489243296] 'process raft request' (duration: 77.03239ms)","trace[1489243296] 'compare' (duration: 34.817285ms)"],"step_count":2} Dec 13 01:29:22.746176 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:22.745589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.959041ms","expected-duration":"100ms","prefix":"","request":"header:<ID:7587883389850698607 > txn:<compare:<target:MOD key:\"/registry/serviceaccounts/kube-public/default\" mod_revision:0 > success:<request_put:<key:\"/registry/serviceaccounts/kube-public/default\" value_size:112 >> failure:<>>","response":"size:16"} Dec 13 01:29:22.746176 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:22.745681Z","caller":"traceutil/trace.go:171","msg":"trace[1164251971] linearizableReadLoop","detail":"{readStateIndex:397; appliedIndex:396; }","duration":"135.910931ms","start":"2024-12-13T01:29:22.609757Z","end":"2024-12-13T01:29:22.745668Z","steps":["trace[1164251971] 'read index received' (duration: 26.773644ms)","trace[1164251971] 'applied index is now lower than readState.Index' (duration: 109.136515ms)"],"step_count":2} Dec 13 01:29:22.746176 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:22.745761Z","caller":"traceutil/trace.go:171","msg":"trace[1256235471] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"137.609471ms","start":"2024-12-13T01:29:22.608112Z","end":"2024-12-13T01:29:22.745721Z","steps":["trace[1256235471] 'process raft request' (duration: 28.473187ms)","trace[1256235471] 'compare' (duration: 108.790474ms)"],"step_count":2} Dec 13 01:29:22.746176 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:22.745855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.076403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking\" ","response":"range_response_count:1 size:370"} Dec 13 01:29:22.746653 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:22.745925Z","caller":"traceutil/trace.go:171","msg":"trace[1192515716] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking; range_end:; response_count:1; response_revision:386; }","duration":"136.17564ms","start":"2024-12-13T01:29:22.609734Z","end":"2024-12-13T01:29:22.745909Z","steps":["trace[1192515716] 'agreement among raft nodes before linearized reading' (duration: 136.003747ms)"],"step_count":1} Dec 13 01:29:22.944219 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:22.943435Z","caller":"traceutil/trace.go:171","msg":"trace[1969093000] linearizableReadLoop","detail":"{readStateIndex:400; appliedIndex:398; }","duration":"187.418189ms","start":"2024-12-13T01:29:22.755995Z","end":"2024-12-13T01:29:22.943413Z","steps":["trace[1969093000] 'read index received' (duration: 20.020162ms)","trace[1969093000] 'applied index is now lower than readState.Index' (duration: 167.397356ms)"],"step_count":2} Dec 13 01:29:22.944219 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:22.943553Z","caller":"traceutil/trace.go:171","msg":"trace[383930819] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"193.376744ms","start":"2024-12-13T01:29:22.750162Z","end":"2024-12-13T01:29:22.943539Z","steps":["trace[383930819] 'process raft request' (duration: 193.169834ms)"],"step_count":1} Dec 13 01:29:22.944219 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:22.943436Z","caller":"traceutil/trace.go:171","msg":"trace[65248338] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"193.689963ms","start":"2024-12-13T01:29:22.749711Z","end":"2024-12-13T01:29:22.943401Z","steps":["trace[65248338] 'process raft request' (duration: 104.917548ms)","trace[65248338] 'compare' (duration: 88.577518ms)"],"step_count":2} Dec 13 01:29:22.944219 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:22.943596Z","caller":"traceutil/trace.go:171","msg":"trace[399591735] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"178.605147ms","start":"2024-12-13T01:29:22.764979Z","end":"2024-12-13T01:29:22.943584Z","steps":["trace[399591735] 'process raft request' (duration: 178.392176ms)"],"step_count":1} Dec 13 01:29:22.944219 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:22.943654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.763692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} Dec 13 01:29:22.944595 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:22.943694Z","caller":"traceutil/trace.go:171","msg":"trace[971795187] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:390; }","duration":"137.810922ms","start":"2024-12-13T01:29:22.805869Z","end":"2024-12-13T01:29:22.943680Z","steps":["trace[971795187] 'agreement among raft nodes before linearized reading' (duration: 137.743825ms)"],"step_count":1} Dec 13 01:29:22.944595 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:22.943712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.709438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} Dec 13 01:29:22.944595 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:22.943743Z","caller":"traceutil/trace.go:171","msg":"trace[2027022828] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:390; }","duration":"187.764591ms","start":"2024-12-13T01:29:22.755968Z","end":"2024-12-13T01:29:22.943732Z","steps":["trace[2027022828] 'agreement among raft nodes before linearized reading' (duration: 187.656277ms)"],"step_count":1} Dec 13 01:29:23.135144 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:23.134228Z","caller":"traceutil/trace.go:171","msg":"trace[354814186] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"183.522494ms","start":"2024-12-13T01:29:22.950692Z","end":"2024-12-13T01:29:23.134214Z","steps":["trace[354814186] 'process raft request' (duration: 183.41352ms)"],"step_count":1} Dec 13 01:29:23.135144 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:23.134320Z","caller":"traceutil/trace.go:171","msg":"trace[1670922747] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"182.148857ms","start":"2024-12-13T01:29:22.952161Z","end":"2024-12-13T01:29:23.134310Z","steps":["trace[1670922747] 'process raft request' (duration: 182.089456ms)"],"step_count":1} Dec 13 01:29:23.135144 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:23.134327Z","caller":"traceutil/trace.go:171","msg":"trace[243648230] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"182.863022ms","start":"2024-12-13T01:29:22.951450Z","end":"2024-12-13T01:29:23.134313Z","steps":["trace[243648230] 'process raft request' (duration: 182.752474ms)"],"step_count":1} Dec 13 01:29:23.135144 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:23.134358Z","caller":"traceutil/trace.go:171","msg":"trace[1173655162] linearizableReadLoop","detail":"{readStateIndex:409; appliedIndex:401; }","duration":"148.813892ms","start":"2024-12-13T01:29:22.985536Z","end":"2024-12-13T01:29:23.134350Z","steps":["trace[1173655162] 'read index received' (duration: 14.839372ms)","trace[1173655162] 'applied index is now lower than readState.Index' (duration: 133.973849ms)"],"step_count":2} Dec 13 01:29:23.135412 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:23.134228Z","caller":"traceutil/trace.go:171","msg":"trace[1784566429] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"185.623692ms","start":"2024-12-13T01:29:22.948582Z","end":"2024-12-13T01:29:23.134206Z","steps":["trace[1784566429] 'process raft request' (duration: 163.730886ms)","trace[1784566429] 'compare' (duration: 21.672582ms)"],"step_count":2} Dec 13 01:29:23.135412 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:23.134423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.87654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Dec 13 01:29:23.135412 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:23.134432Z","caller":"traceutil/trace.go:171","msg":"trace[1697113413] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"182.705316ms","start":"2024-12-13T01:29:22.951720Z","end":"2024-12-13T01:29:23.134425Z","steps":["trace[1697113413] 'process raft request' (duration: 182.504648ms)"],"step_count":1} Dec 13 01:29:23.135412 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:23.134447Z","caller":"traceutil/trace.go:171","msg":"trace[1263342000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:398; }","duration":"148.936352ms","start":"2024-12-13T01:29:22.985504Z","end":"2024-12-13T01:29:23.134441Z","steps":["trace[1263342000] 'agreement among raft nodes before linearized reading' (duration: 148.877813ms)"],"step_count":1} Dec 13 01:29:23.135412 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:23.134503Z","caller":"traceutil/trace.go:171","msg":"trace[249223347] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"181.956004ms","start":"2024-12-13T01:29:22.952533Z","end":"2024-12-13T01:29:23.134489Z","steps":["trace[249223347] 'process raft request' (duration: 181.753833ms)"],"step_count":1} Dec 13 01:29:23.135848 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:23.134506Z","caller":"traceutil/trace.go:171","msg":"trace[1022121191] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"183.62049ms","start":"2024-12-13T01:29:22.950865Z","end":"2024-12-13T01:29:23.134486Z","steps":["trace[1022121191] 'process raft request' (duration: 183.311648ms)"],"step_count":1} Dec 13 01:29:23.300930 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:23.300543Z","caller":"traceutil/trace.go:171","msg":"trace[522045600] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"160.255371ms","start":"2024-12-13T01:29:23.140261Z","end":"2024-12-13T01:29:23.300516Z","steps":["trace[522045600] 'process raft request' (duration: 97.970704ms)","trace[522045600] 'compare' (duration: 62.17531ms)"],"step_count":2} Dec 13 01:29:23.319611 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:23.318873Z","caller":"traceutil/trace.go:171","msg":"trace[1742767448] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"178.024629ms","start":"2024-12-13T01:29:23.140823Z","end":"2024-12-13T01:29:23.318848Z","steps":["trace[1742767448] 'process raft request' (duration: 177.919211ms)"],"step_count":1} Dec 13 01:29:23.319611 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:23.318937Z","caller":"traceutil/trace.go:171","msg":"trace[2023314973] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"176.327663ms","start":"2024-12-13T01:29:23.142591Z","end":"2024-12-13T01:29:23.318919Z","steps":["trace[2023314973] 'process raft request' (duration: 176.235239ms)"],"step_count":1} Dec 13 01:29:23.319611 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:23.318920Z","caller":"traceutil/trace.go:171","msg":"trace[1556846662] linearizableReadLoop","detail":"{readStateIndex:411; appliedIndex:409; }","duration":"178.239845ms","start":"2024-12-13T01:29:23.140662Z","end":"2024-12-13T01:29:23.318901Z","steps":["trace[1556846662] 'read index received' (duration: 97.578034ms)","trace[1556846662] 'applied index is now lower than readState.Index' (duration: 80.66118ms)"],"step_count":2} Dec 13 01:29:23.319611 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:23.318988Z","caller":"traceutil/trace.go:171","msg":"trace[95396921] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"178.504102ms","start":"2024-12-13T01:29:23.140479Z","end":"2024-12-13T01:29:23.318983Z","steps":["trace[95396921] 'process raft request' (duration: 178.15211ms)"],"step_count":1} Dec 13 01:29:23.319611 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:23.318893Z","caller":"traceutil/trace.go:171","msg":"trace[163261701] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"177.833922ms","start":"2024-12-13T01:29:23.141048Z","end":"2024-12-13T01:29:23.318882Z","steps":["trace[163261701] 'process raft request' (duration: 177.744172ms)"],"step_count":1} Dec 13 01:29:23.319951 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:23.319037Z","caller":"traceutil/trace.go:171","msg":"trace[688040765] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"174.36679ms","start":"2024-12-13T01:29:23.144661Z","end":"2024-12-13T01:29:23.319028Z","steps":["trace[688040765] 'process raft request' (duration: 174.212139ms)"],"step_count":1} Dec 13 01:29:23.319951 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:23.319099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.098094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-flannel/\" range_end:\"/registry/limitranges/kube-flannel0\" ","response":"range_response_count:0 size:5"} Dec 13 01:29:23.319951 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:23.319123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.462616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/localhost\" ","response":"range_response_count:1 size:4170"} Dec 13 01:29:23.319951 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:23.319142Z","caller":"traceutil/trace.go:171","msg":"trace[2144204210] range","detail":"{range_begin:/registry/minions/localhost; range_end:; response_count:1; response_revision:404; }","duration":"178.492171ms","start":"2024-12-13T01:29:23.140643Z","end":"2024-12-13T01:29:23.319135Z","steps":["trace[2144204210] 'agreement among raft nodes before linearized reading' (duration: 178.451944ms)"],"step_count":1} Dec 13 01:29:23.319951 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:23.319157Z","caller":"traceutil/trace.go:171","msg":"trace[367210641] range","detail":"{range_begin:/registry/limitranges/kube-flannel/; range_end:/registry/limitranges/kube-flannel0; response_count:0; response_revision:404; }","duration":"177.172775ms","start":"2024-12-13T01:29:23.141944Z","end":"2024-12-13T01:29:23.319116Z","steps":["trace[367210641] 'agreement among raft nodes before linearized reading' (duration: 177.100068ms)"],"step_count":1} Dec 13 01:29:23.320091 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:23.319185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.971877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} Dec 13 01:29:23.320091 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:23.319217Z","caller":"traceutil/trace.go:171","msg":"trace[1738351307] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:404; }","duration":"177.025808ms","start":"2024-12-13T01:29:23.142184Z","end":"2024-12-13T01:29:23.319209Z","steps":["trace[1738351307] 'agreement among raft nodes before linearized reading' (duration: 176.963581ms)"],"step_count":1} Dec 13 01:29:23.618689 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:23.618247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.188416ms","expected-duration":"100ms","prefix":"","request":"header:<ID:7587883389850698638 > txn:<compare:<target:MOD key:\"/registry/pods/kube-flannel/kube-flannel-ds-w9fqv\" mod_revision:0 > success:<request_put:<key:\"/registry/pods/kube-flannel/kube-flannel-ds-w9fqv\" value_size:5169 >> failure:<>>","response":"size:16"} Dec 13 01:29:23.618689 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:23.618343Z","caller":"traceutil/trace.go:171","msg":"trace[2083201916] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"296.835328ms","start":"2024-12-13T01:29:23.321493Z","end":"2024-12-13T01:29:23.618328Z","steps":["trace[2083201916] 'process raft request' (duration: 167.529401ms)","trace[2083201916] 'compare' (duration: 129.018274ms)"],"step_count":2} Dec 13 01:29:23.618689 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:23.618328Z","caller":"traceutil/trace.go:171","msg":"trace[1684642042] linearizableReadLoop","detail":"{readStateIndex:416; appliedIndex:415; }","duration":"295.56789ms","start":"2024-12-13T01:29:23.322749Z","end":"2024-12-13T01:29:23.618317Z","steps":["trace[1684642042] 'read index received' (duration: 166.399693ms)","trace[1684642042] 'applied index is now lower than readState.Index' (duration: 129.167606ms)"],"step_count":2} Dec 13 01:29:23.618689 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:23.618443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.688998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-localhost\" ","response":"range_response_count:1 size:6476"} Dec 13 01:29:23.618996 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:23.618469Z","caller":"traceutil/trace.go:171","msg":"trace[1619870272] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-localhost; range_end:; response_count:1; response_revision:405; }","duration":"295.709768ms","start":"2024-12-13T01:29:23.322746Z","end":"2024-12-13T01:29:23.618456Z","steps":["trace[1619870272] 'agreement among raft nodes before linearized reading' (duration: 295.631991ms)"],"step_count":1} Dec 13 01:29:23.651949 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:23.651408Z","caller":"traceutil/trace.go:171","msg":"trace[1140936674] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"327.210982ms","start":"2024-12-13T01:29:23.324171Z","end":"2024-12-13T01:29:23.651381Z","steps":["trace[1140936674] 'process raft request' (duration: 327.11428ms)"],"step_count":1} Dec 13 01:29:23.652225 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:23.651538Z","caller":"traceutil/trace.go:171","msg":"trace[434153379] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"326.263778ms","start":"2024-12-13T01:29:23.325269Z","end":"2024-12-13T01:29:23.651533Z","steps":["trace[434153379] 'process raft request' (duration: 326.038784ms)"],"step_count":1} Dec 13 01:29:23.652225 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:23.651562Z","caller":"traceutil/trace.go:171","msg":"trace[260765929] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"328.071904ms","start":"2024-12-13T01:29:23.323483Z","end":"2024-12-13T01:29:23.651555Z","steps":["trace[260765929] 'process raft request' (duration: 327.726613ms)"],"step_count":1} Dec 13 01:29:23.652225 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:23.651599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-12-13T01:29:23.323474Z","time spent":"328.10204ms","remote":"10.0.0.52:42366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":669,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/events/kube-system/kube-proxy.18109858b665ce1b\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-system/kube-proxy.18109858b665ce1b\" value_size:595 lease:7587883389850698230 >> failure:<>"} Dec 13 01:29:23.652225 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:23.651609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-12-13T01:29:23.325259Z","time spent":"326.309484ms","remote":"10.0.0.52:42520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3403,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/kube-proxy-t24fn\" mod_revision:402 > success:<request_put:<key:\"/registry/pods/kube-system/kube-proxy-t24fn\" value_size:3352 >> failure:<request_range:<key:\"/registry/pods/kube-system/kube-proxy-t24fn\" > >"} Dec 13 01:29:23.652460 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:23.651423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.212572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3684"} Dec 13 01:29:23.652460 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:23.651657Z","caller":"traceutil/trace.go:171","msg":"trace[332976239] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:410; }","duration":"326.478563ms","start":"2024-12-13T01:29:23.325172Z","end":"2024-12-13T01:29:23.651651Z","steps":["trace[332976239] 'agreement among raft nodes before linearized reading' (duration: 326.204666ms)"],"step_count":1} Dec 13 01:29:23.652460 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:23.651623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-12-13T01:29:23.324162Z","time spent":"327.388556ms","remote":"10.0.0.52:42410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/configmaps/kube-public/kube-root-ca.crt\" mod_revision:0 > success:<request_put:<key:\"/registry/configmaps/kube-public/kube-root-ca.crt\" value_size:1674 >> failure:<>"} Dec 13 01:29:23.652460 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:23.651682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-12-13T01:29:23.325165Z","time spent":"326.505072ms","remote":"10.0.0.52:42768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":3708,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} Dec 13 01:29:23.652695 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:23.651407Z","caller":"traceutil/trace.go:171","msg":"trace[1752320894] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"325.303609ms","start":"2024-12-13T01:29:23.326082Z","end":"2024-12-13T01:29:23.651385Z","steps":["trace[1752320894] 'process raft request' (duration: 325.254556ms)"],"step_count":1} Dec 13 01:29:23.652695 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:23.651738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-12-13T01:29:23.326055Z","time spent":"325.669167ms","remote":"10.0.0.52:42520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3505,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/coredns-7db6d8ff4d-4kq2t\" mod_revision:0 > success:<request_put:<key:\"/registry/pods/kube-system/coredns-7db6d8ff4d-4kq2t\" value_size:3446 >> failure:<>"} Dec 13 01:29:23.652695 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:23.651487Z","caller":"traceutil/trace.go:171","msg":"trace[76754632] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"325.337874ms","start":"2024-12-13T01:29:23.326138Z","end":"2024-12-13T01:29:23.651476Z","steps":["trace[76754632] 'process raft request' (duration: 325.21401ms)"],"step_count":1} Dec 13 01:29:23.652695 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:23.651797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-12-13T01:29:23.326130Z","time spent":"325.639663ms","remote":"10.0.0.52:42780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2863,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/daemonsets/kube-system/kube-proxy\" mod_revision:280 > success:<request_put:<key:\"/registry/daemonsets/kube-system/kube-proxy\" value_size:2812 >> failure:<request_range:<key:\"/registry/daemonsets/kube-system/kube-proxy\" > >"} Dec 13 01:29:24.078804 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:24.078288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.344866ms","expected-duration":"100ms","prefix":"","request":"header:<ID:7587883389850698645 > txn:<compare:<target:MOD key:\"/registry/pods/kube-flannel/kube-flannel-ds-w9fqv\" mod_revision:405 > success:<request_put:<key:\"/registry/pods/kube-flannel/kube-flannel-ds-w9fqv\" value_size:5216 >> failure:<request_range:<key:\"/registry/pods/kube-flannel/kube-flannel-ds-w9fqv\" > >>","response":"size:16"} Dec 13 01:29:24.079186 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:24.078477Z","caller":"traceutil/trace.go:171","msg":"trace[907787728] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"455.406182ms","start":"2024-12-13T01:29:23.623056Z","end":"2024-12-13T01:29:24.078462Z","steps":["trace[907787728] 'process raft request' (duration: 455.369813ms)"],"step_count":1} Dec 13 01:29:24.079186 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:24.078489Z","caller":"traceutil/trace.go:171","msg":"trace[598788241] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"456.948478ms","start":"2024-12-13T01:29:23.621519Z","end":"2024-12-13T01:29:24.078467Z","steps":["trace[598788241] 'process raft request' (duration: 260.360143ms)","trace[598788241] 'compare' (duration: 196.217426ms)"],"step_count":2} Dec 13 01:29:24.079186 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:24.078538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-12-13T01:29:23.623048Z","time spent":"455.464343ms","remote":"10.0.0.52:42520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6269,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/kube-apiserver-localhost\" mod_revision:315 > success:<request_put:<key:\"/registry/pods/kube-system/kube-apiserver-localhost\" value_size:6210 >> failure:<request_range:<key:\"/registry/pods/kube-system/kube-apiserver-localhost\" > >"} Dec 13 01:29:24.079576 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:24.078598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-12-13T01:29:23.621498Z","time spent":"457.040901ms","remote":"10.0.0.52:42520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5273,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/pods/kube-flannel/kube-flannel-ds-w9fqv\" mod_revision:405 > success:<request_put:<key:\"/registry/pods/kube-flannel/kube-flannel-ds-w9fqv\" value_size:5216 >> failure:<request_range:<key:\"/registry/pods/kube-flannel/kube-flannel-ds-w9fqv\" > >"} Dec 13 01:29:24.079576 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:24.078628Z","caller":"traceutil/trace.go:171","msg":"trace[1974215526] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"455.672303ms","start":"2024-12-13T01:29:23.622948Z","end":"2024-12-13T01:29:24.078620Z","steps":["trace[1974215526] 'process raft request' (duration: 455.429045ms)"],"step_count":1} Dec 13 01:29:24.079576 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:24.078667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-12-13T01:29:23.622938Z","time spent":"455.708481ms","remote":"10.0.0.52:42780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7062,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/daemonsets/kube-flannel/kube-flannel-ds\" mod_revision:316 > success:<request_put:<key:\"/registry/daemonsets/kube-flannel/kube-flannel-ds\" value_size:7005 >> failure:<request_range:<key:\"/registry/daemonsets/kube-flannel/kube-flannel-ds\" > >"} Dec 13 01:29:24.121818 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:24.120688Z","caller":"traceutil/trace.go:171","msg":"trace[968850649] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"461.928338ms","start":"2024-12-13T01:29:23.658730Z","end":"2024-12-13T01:29:24.120659Z","steps":["trace[968850649] 'process raft request' (duration: 461.809915ms)"],"step_count":1} Dec 13 01:29:24.121818 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:24.120876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.816731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2878"} Dec 13 01:29:24.121818 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:24.120930Z","caller":"traceutil/trace.go:171","msg":"trace[2003915993] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"459.977414ms","start":"2024-12-13T01:29:23.660944Z","end":"2024-12-13T01:29:24.120921Z","steps":["trace[2003915993] 'process raft request' (duration: 459.752169ms)"],"step_count":1} Dec 13 01:29:24.121818 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:24.120961Z","caller":"traceutil/trace.go:171","msg":"trace[1205838646] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"455.242867ms","start":"2024-12-13T01:29:23.665713Z","end":"2024-12-13T01:29:24.120956Z","steps":["trace[1205838646] 'process raft request' (duration: 455.001282ms)"],"step_count":1} Dec 13 01:29:24.122204 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:24.120905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-12-13T01:29:23.658717Z","time spent":"462.140088ms","remote":"10.0.0.52:42366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":692,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/events/kube-flannel/kube-flannel-ds.18109858c821a9f0\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-flannel/kube-flannel-ds.18109858c821a9f0\" value_size:612 lease:7587883389850698230 >> failure:<>"} Dec 13 01:29:24.122204 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:24.121153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-12-13T01:29:23.665704Z","time spent":"455.384583ms","remote":"10.0.0.52:42520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3978,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/coredns-7db6d8ff4d-4kq2t\" mod_revision:409 > success:<request_put:<key:\"/registry/pods/kube-system/coredns-7db6d8ff4d-4kq2t\" value_size:3919 >> failure:<request_range:<key:\"/registry/pods/kube-system/coredns-7db6d8ff4d-4kq2t\" > >"} Dec 13 01:29:24.122204 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:24.121031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-12-13T01:29:23.660934Z","time spent":"460.045092ms","remote":"10.0.0.52:42366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/events/kube-system/coredns-7db6d8ff4d.18109858ca2be2da\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-system/coredns-7db6d8ff4d.18109858ca2be2da\" value_size:622 lease:7587883389850698230 >> failure:<>"} Dec 13 01:29:24.122398 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:24.120937Z","caller":"traceutil/trace.go:171","msg":"trace[426859287] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:420; }","duration":"459.914494ms","start":"2024-12-13T01:29:23.661015Z","end":"2024-12-13T01:29:24.120929Z","steps":["trace[426859287] 'agreement among raft nodes before linearized reading' (duration: 459.766135ms)"],"step_count":1} Dec 13 01:29:24.122398 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:24.121336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-12-13T01:29:23.661005Z","time spent":"460.314227ms","remote":"10.0.0.52:42780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":2902,"request content":"key:\"/registry/daemonsets/kube-system/kube-proxy\" "} Dec 13 01:29:24.122398 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:24.120749Z","caller":"traceutil/trace.go:171","msg":"trace[1390992112] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:421; }","duration":"459.700322ms","start":"2024-12-13T01:29:23.661039Z","end":"2024-12-13T01:29:24.120739Z","steps":["trace[1390992112] 'read index received' (duration: 220.97606ms)","trace[1390992112] 'applied index is now lower than readState.Index' (duration: 238.72334ms)"],"step_count":2} Dec 13 01:29:24.122398 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:24.120787Z","caller":"traceutil/trace.go:171","msg":"trace[306535888] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"460.236922ms","start":"2024-12-13T01:29:23.660542Z","end":"2024-12-13T01:29:24.120779Z","steps":["trace[306535888] 'process raft request' (duration: 460.104473ms)"],"step_count":1} Dec 13 01:29:24.122554 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:24.121452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-12-13T01:29:23.660532Z","time spent":"460.8888ms","remote":"10.0.0.52:42768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3980,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/deployments/kube-system/coredns\" mod_revision:403 > success:<request_put:<key:\"/registry/deployments/kube-system/coredns\" value_size:3931 >> failure:<request_range:<key:\"/registry/deployments/kube-system/coredns\" > >"} Dec 13 01:29:24.122554 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:24.120786Z","caller":"traceutil/trace.go:171","msg":"trace[1998546757] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"461.667467ms","start":"2024-12-13T01:29:23.659095Z","end":"2024-12-13T01:29:24.120762Z","steps":["trace[1998546757] 'process raft request' (duration: 461.524959ms)"],"step_count":1} Dec 13 01:29:24.122554 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:24.121552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-12-13T01:29:23.659088Z","time spent":"462.41781ms","remote":"10.0.0.52:42520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3505,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/coredns-7db6d8ff4d-kznsk\" mod_revision:0 > success:<request_put:<key:\"/registry/pods/kube-system/coredns-7db6d8ff4d-kznsk\" value_size:3446 >> failure:<>"} Dec 13 01:29:24.122554 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:24.120716Z","caller":"traceutil/trace.go:171","msg":"trace[545944529] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"462.934203ms","start":"2024-12-13T01:29:23.657737Z","end":"2024-12-13T01:29:24.120671Z","steps":["trace[545944529] 'process raft request' (duration: 462.680205ms)"],"step_count":1} Dec 13 01:29:24.122718 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:24.120939Z","caller":"traceutil/trace.go:171","msg":"trace[1722770670] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"462.183589ms","start":"2024-12-13T01:29:23.658748Z","end":"2024-12-13T01:29:24.120931Z","steps":["trace[1722770670] 'process raft request' (duration: 461.836896ms)"],"step_count":1} Dec 13 01:29:24.122718 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:24.122492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-12-13T01:29:23.658738Z","time spent":"463.691279ms","remote":"10.0.0.52:42410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/configmaps/kube-system/kube-root-ca.crt\" mod_revision:0 > success:<request_put:<key:\"/registry/configmaps/kube-system/kube-root-ca.crt\" value_size:1674 >> failure:<>"} Dec 13 01:29:24.122718 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:24.122283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-12-13T01:29:23.657715Z","time spent":"464.448384ms","remote":"10.0.0.52:42366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":688,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/events/kube-system/kube-proxy-t24fn.18109858ca264c09\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-system/kube-proxy-t24fn.18109858ca264c09\" value_size:608 lease:7587883389850698230 >> failure:<>"} Dec 13 01:29:24.142916 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:24.142409Z","caller":"traceutil/trace.go:171","msg":"trace[1608553400] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"178.242467ms","start":"2024-12-13T01:29:23.964149Z","end":"2024-12-13T01:29:24.142391Z","steps":["trace[1608553400] 'process raft request' (duration: 178.156746ms)"],"step_count":1} Dec 13 01:29:24.143308 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:24.142721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.444329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} Dec 13 01:29:24.143308 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:24.142808Z","caller":"traceutil/trace.go:171","msg":"trace[481935902] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:421; }","duration":"232.57654ms","start":"2024-12-13T01:29:23.910215Z","end":"2024-12-13T01:29:24.142791Z","steps":["trace[481935902] 'agreement among raft nodes before linearized reading' (duration: 232.396731ms)"],"step_count":1} Dec 13 01:29:24.366849 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:24.366081Z","caller":"traceutil/trace.go:171","msg":"trace[1408096158] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"241.209318ms","start":"2024-12-13T01:29:24.124830Z","end":"2024-12-13T01:29:24.366039Z","steps":["trace[1408096158] 'process raft request' (duration: 175.919183ms)","trace[1408096158] 'compare' (duration: 64.995852ms)"],"step_count":2} Dec 13 01:29:24.366849 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:24.366176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.331038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2878"} Dec 13 01:29:24.366849 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:24.366107Z","caller":"traceutil/trace.go:171","msg":"trace[901460153] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:432; }","duration":"223.571229ms","start":"2024-12-13T01:29:24.142522Z","end":"2024-12-13T01:29:24.366093Z","steps":["trace[901460153] 'read index received' (duration: 158.199239ms)","trace[901460153] 'applied index is now lower than readState.Index' (duration: 65.371389ms)"],"step_count":2} Dec 13 01:29:24.366849 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:24.366207Z","caller":"traceutil/trace.go:171","msg":"trace[1198673290] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:425; }","duration":"238.414355ms","start":"2024-12-13T01:29:24.127785Z","end":"2024-12-13T01:29:24.366199Z","steps":["trace[1198673290] 'agreement among raft nodes before linearized reading' (duration: 238.3427ms)"],"step_count":1} Dec 13 01:29:24.368058 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:24.366111Z","caller":"traceutil/trace.go:171","msg":"trace[2123705262] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"239.786058ms","start":"2024-12-13T01:29:24.126302Z","end":"2024-12-13T01:29:24.366088Z","steps":["trace[2123705262] 'process raft request' (duration: 239.647517ms)"],"step_count":1} Dec 13 01:29:24.368058 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:24.366297Z","caller":"traceutil/trace.go:171","msg":"trace[537564064] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"239.179476ms","start":"2024-12-13T01:29:24.127109Z","end":"2024-12-13T01:29:24.366289Z","steps":["trace[537564064] 'process raft request' (duration: 238.936839ms)"],"step_count":1} Dec 13 01:29:24.368058 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:24.366301Z","caller":"traceutil/trace.go:171","msg":"trace[377343819] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"239.650242ms","start":"2024-12-13T01:29:24.126640Z","end":"2024-12-13T01:29:24.366290Z","steps":["trace[377343819] 'process raft request' (duration: 239.378741ms)"],"step_count":1} Dec 13 01:29:24.423667 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:24.423044Z","caller":"traceutil/trace.go:171","msg":"trace[122536486] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"274.385753ms","start":"2024-12-13T01:29:24.148639Z","end":"2024-12-13T01:29:24.423025Z","steps":["trace[122536486] 'process raft request' (duration: 274.261279ms)"],"step_count":1} Dec 13 01:29:24.423667 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:24.423122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.42836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Dec 13 01:29:24.423667 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:24.423142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.892209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-flannel/flannel\" ","response":"range_response_count:1 size:612"} Dec 13 01:29:24.423667 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:24.423160Z","caller":"traceutil/trace.go:171","msg":"trace[1940258598] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:426; }","duration":"176.513262ms","start":"2024-12-13T01:29:24.246637Z","end":"2024-12-13T01:29:24.423150Z","steps":["trace[1940258598] 'agreement among raft nodes before linearized reading' (duration: 176.409937ms)"],"step_count":1} Dec 13 01:29:24.423667 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:24.423169Z","caller":"traceutil/trace.go:171","msg":"trace[1456154912] range","detail":"{range_begin:/registry/serviceaccounts/kube-flannel/flannel; range_end:; response_count:1; response_revision:426; }","duration":"109.944758ms","start":"2024-12-13T01:29:24.313216Z","end":"2024-12-13T01:29:24.423161Z","steps":["trace[1456154912] 'agreement among raft nodes before linearized reading' (duration: 109.867783ms)"],"step_count":1} Dec 13 01:29:24.424091 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:24.423202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.560756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-flannel/kube-flannel-ds\" ","response":"range_response_count:1 size:7077"} Dec 13 01:29:24.424091 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:24.423222Z","caller":"traceutil/trace.go:171","msg":"trace[503463586] range","detail":"{range_begin:/registry/daemonsets/kube-flannel/kube-flannel-ds; range_end:; response_count:1; response_revision:426; }","duration":"278.606431ms","start":"2024-12-13T01:29:24.144609Z","end":"2024-12-13T01:29:24.423215Z","steps":["trace[503463586] 'agreement among raft nodes before linearized reading' (duration: 278.565975ms)"],"step_count":1} Dec 13 01:29:24.424091 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:24.423276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.359831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-t24fn\" ","response":"range_response_count:1 size:4610"} Dec 13 01:29:24.424091 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:24.423298Z","caller":"traceutil/trace.go:171","msg":"trace[732253519] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-t24fn; range_end:; response_count:1; response_revision:426; }","duration":"279.412991ms","start":"2024-12-13T01:29:24.143878Z","end":"2024-12-13T01:29:24.423291Z","steps":["trace[732253519] 'agreement among raft nodes before linearized reading' (duration: 279.372354ms)"],"step_count":1} Dec 13 01:29:24.812086 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:24.811506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.621237ms","expected-duration":"100ms","prefix":"","request":"header:<ID:7587883389850698665 > txn:<compare:<target:MOD key:\"/registry/events/kube-flannel/kube-flannel-ds-w9fqv.18109858e3c57dfd\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-flannel/kube-flannel-ds-w9fqv.18109858e3c57dfd\" value_size:626 lease:7587883389850698230 >> failure:<>>","response":"size:16"} Dec 13 01:29:24.812613 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:24.811709Z","caller":"traceutil/trace.go:171","msg":"trace[2032643867] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"218.264966ms","start":"2024-12-13T01:29:24.593431Z","end":"2024-12-13T01:29:24.811696Z","steps":["trace[2032643867] 'process raft request' (duration: 218.237935ms)"],"step_count":1} Dec 13 01:29:24.812613 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:24.811859Z","caller":"traceutil/trace.go:171","msg":"trace[1328965747] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"439.474537ms","start":"2024-12-13T01:29:24.372377Z","end":"2024-12-13T01:29:24.811852Z","steps":["trace[1328965747] 'process raft request' (duration: 439.233883ms)"],"step_count":1} Dec 13 01:29:24.812613 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:24.811917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-12-13T01:29:24.372365Z","time spent":"439.513561ms","remote":"10.0.0.52:42788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3782,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/replicasets/kube-system/coredns-7db6d8ff4d\" mod_revision:424 > success:<request_put:<key:\"/registry/replicasets/kube-system/coredns-7db6d8ff4d\" value_size:3722 >> failure:<request_range:<key:\"/registry/replicasets/kube-system/coredns-7db6d8ff4d\" > >"} Dec 13 01:29:24.812613 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:24.811935Z","caller":"traceutil/trace.go:171","msg":"trace[368159291] linearizableReadLoop","detail":"{readStateIndex:439; appliedIndex:437; }","duration":"388.91772ms","start":"2024-12-13T01:29:24.423004Z","end":"2024-12-13T01:29:24.811922Z","steps":["trace[368159291] 'read index received' (duration: 240.802591ms)","trace[368159291] 'applied index is now lower than readState.Index' (duration: 148.113997ms)"],"step_count":2} Dec 13 01:29:24.812856 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:24.811917Z","caller":"traceutil/trace.go:171","msg":"trace[1129107320] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"441.317568ms","start":"2024-12-13T01:29:24.370547Z","end":"2024-12-13T01:29:24.811865Z","steps":["trace[1129107320] 'process raft request' (duration: 293.278715ms)","trace[1129107320] 'compare' (duration: 147.502834ms)"],"step_count":2} Dec 13 01:29:24.812856 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:24.811995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.358609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2878"} Dec 13 01:29:24.812856 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:24.812019Z","caller":"traceutil/trace.go:171","msg":"trace[601599628] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:429; }","duration":"439.400397ms","start":"2024-12-13T01:29:24.372609Z","end":"2024-12-13T01:29:24.812010Z","steps":["trace[601599628] 'agreement among raft nodes before linearized reading' (duration: 439.360121ms)"],"step_count":1} Dec 13 01:29:24.812856 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:24.812025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.863854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Dec 13 01:29:24.812856 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:24.812042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-12-13T01:29:24.372600Z","time spent":"439.434483ms","remote":"10.0.0.52:42780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":2902,"request content":"key:\"/registry/daemonsets/kube-system/kube-proxy\" "} Dec 13 01:29:24.813014 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:24.812046Z","caller":"traceutil/trace.go:171","msg":"trace[2067615] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:429; }","duration":"387.918287ms","start":"2024-12-13T01:29:24.424121Z","end":"2024-12-13T01:29:24.812040Z","steps":["trace[2067615] 'agreement among raft nodes before linearized reading' (duration: 387.883571ms)"],"step_count":1} Dec 13 01:29:24.813014 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:24.812099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-12-13T01:29:24.424107Z","time spent":"387.980995ms","remote":"10.0.0.52:42294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} Dec 13 01:29:24.813014 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:24.812166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.039372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-flannel/kube-flannel-ds\" ","response":"range_response_count:1 size:7077"} Dec 13 01:29:24.813014 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:24.812177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.779209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-flannel/kube-flannel-ds-w9fqv\" ","response":"range_response_count:1 size:5288"} Dec 13 01:29:24.813014 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:24.812186Z","caller":"traceutil/trace.go:171","msg":"trace[247685442] range","detail":"{range_begin:/registry/daemonsets/kube-flannel/kube-flannel-ds; range_end:; response_count:1; response_revision:429; }","duration":"381.074358ms","start":"2024-12-13T01:29:24.431106Z","end":"2024-12-13T01:29:24.812180Z","steps":["trace[247685442] 'agreement among raft nodes before linearized reading' (duration: 381.035714ms)"],"step_count":1} Dec 13 01:29:24.813211 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:24.812171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-12-13T01:29:24.370525Z","time spent":"441.469855ms","remote":"10.0.0.52:42366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":712,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/events/kube-flannel/kube-flannel-ds-w9fqv.18109858e3c57dfd\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-flannel/kube-flannel-ds-w9fqv.18109858e3c57dfd\" value_size:626 lease:7587883389850698230 >> failure:<>"} Dec 13 01:29:24.813211 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:24.812199Z","caller":"traceutil/trace.go:171","msg":"trace[277404661] range","detail":"{range_begin:/registry/pods/kube-flannel/kube-flannel-ds-w9fqv; range_end:; response_count:1; response_revision:429; }","duration":"382.835095ms","start":"2024-12-13T01:29:24.429357Z","end":"2024-12-13T01:29:24.812193Z","steps":["trace[277404661] 'agreement among raft nodes before linearized reading' (duration: 382.792454ms)"],"step_count":1} Dec 13 01:29:24.813211 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:24.812225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-12-13T01:29:24.429337Z","time spent":"382.881421ms","remote":"10.0.0.52:42520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":5312,"request content":"key:\"/registry/pods/kube-flannel/kube-flannel-ds-w9fqv\" "} Dec 13 01:29:24.813211 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:24.812232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.020881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-flannel/kube-flannel-ds-w9fqv\" ","response":"range_response_count:1 size:5288"} Dec 13 01:29:24.813387 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:24.812249Z","caller":"traceutil/trace.go:171","msg":"trace[8616886] range","detail":"{range_begin:/registry/pods/kube-flannel/kube-flannel-ds-w9fqv; range_end:; response_count:1; response_revision:429; }","duration":"388.066206ms","start":"2024-12-13T01:29:24.424177Z","end":"2024-12-13T01:29:24.812244Z","steps":["trace[8616886] 'agreement among raft nodes before linearized reading' (duration: 388.037111ms)"],"step_count":1} Dec 13 01:29:24.813387 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:24.812268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-12-13T01:29:24.424160Z","time spent":"388.102444ms","remote":"10.0.0.52:42520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":5312,"request content":"key:\"/registry/pods/kube-flannel/kube-flannel-ds-w9fqv\" "} Dec 13 01:29:24.813387 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:24.812205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-12-13T01:29:24.431096Z","time spent":"381.103112ms","remote":"10.0.0.52:42780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":7101,"request content":"key:\"/registry/daemonsets/kube-flannel/kube-flannel-ds\" "} Dec 13 01:29:25.188494 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:25.187695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.207452ms","expected-duration":"100ms","prefix":"","request":"header:<ID:7587883389850698672 > txn:<compare:<target:MOD key:\"/registry/events/kube-system/coredns-7db6d8ff4d-kznsk.18109858e6246dc3\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-system/coredns-7db6d8ff4d-kznsk.18109858e6246dc3\" value_size:741 lease:7587883389850698230 >> failure:<>>","response":"size:16"} Dec 13 01:29:25.188494 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:25.187902Z","caller":"traceutil/trace.go:171","msg":"trace[1048899573] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"361.592723ms","start":"2024-12-13T01:29:24.826293Z","end":"2024-12-13T01:29:25.187886Z","steps":["trace[1048899573] 'process raft request' (duration: 361.516049ms)"],"step_count":1} Dec 13 01:29:25.188494 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:25.187985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-12-13T01:29:24.826277Z","time spent":"361.668857ms","remote":"10.0.0.52:42520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6805,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/pods/kube-flannel/kube-flannel-ds-w9fqv\" mod_revision:411 > success:<request_put:<key:\"/registry/pods/kube-flannel/kube-flannel-ds-w9fqv\" value_size:6748 >> failure:<request_range:<key:\"/registry/pods/kube-flannel/kube-flannel-ds-w9fqv\" > >"} Dec 13 01:29:25.188494 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:25.188094Z","caller":"traceutil/trace.go:171","msg":"trace[2007258190] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"364.898239ms","start":"2024-12-13T01:29:24.823154Z","end":"2024-12-13T01:29:25.188052Z","steps":["trace[2007258190] 'process raft request' (duration: 223.269793ms)","trace[2007258190] 'compare' (duration: 141.036321ms)"],"step_count":2} Dec 13 01:29:25.188861 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:25.188145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-12-13T01:29:24.823128Z","time spent":"364.997667ms","remote":"10.0.0.52:42366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":829,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/events/kube-system/coredns-7db6d8ff4d-kznsk.18109858e6246dc3\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-system/coredns-7db6d8ff4d-kznsk.18109858e6246dc3\" value_size:741 lease:7587883389850698230 >> failure:<>"} Dec 13 01:29:25.214610 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:25.214246Z","caller":"traceutil/trace.go:171","msg":"trace[1788706759] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"220.487736ms","start":"2024-12-13T01:29:24.993738Z","end":"2024-12-13T01:29:25.214226Z","steps":["trace[1788706759] 'process raft request' (duration: 220.452388ms)"],"step_count":1} Dec 13 01:29:25.214924 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:25.214317Z","caller":"traceutil/trace.go:171","msg":"trace[1105574747] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"263.942566ms","start":"2024-12-13T01:29:24.950347Z","end":"2024-12-13T01:29:25.214290Z","steps":["trace[1105574747] 'process raft request' (duration: 263.743001ms)"],"step_count":1} Dec 13 01:29:25.443310 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:25.442754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.523549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-proxy-t24fn.1810985900e52453\" ","response":"range_response_count:1 size:800"} Dec 13 01:29:25.443310 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:25.442812Z","caller":"traceutil/trace.go:171","msg":"trace[231877671] range","detail":"{range_begin:/registry/events/kube-system/kube-proxy-t24fn.1810985900e52453; range_end:; response_count:1; response_revision:434; }","duration":"107.635981ms","start":"2024-12-13T01:29:25.335165Z","end":"2024-12-13T01:29:25.442801Z","steps":["trace[231877671] 'range keys from in-memory index tree' (duration: 107.422508ms)"],"step_count":1} Dec 13 01:29:26.330152 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:26.329592Z","caller":"traceutil/trace.go:171","msg":"trace[353428798] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"123.141929ms","start":"2024-12-13T01:29:26.206414Z","end":"2024-12-13T01:29:26.329556Z","steps":["trace[353428798] 'process raft request' (duration: 87.198116ms)","trace[353428798] 'compare' (duration: 35.762912ms)"],"step_count":2} Dec 13 01:29:26.532350 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:26.531787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.409444ms","expected-duration":"100ms","prefix":"","request":"header:<ID:7587883389850698690 > lease_grant:<ttl:3660-second id:694d93bda12343c1>","response":"size:42"} Dec 13 01:29:26.532938 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:26.531985Z","caller":"traceutil/trace.go:171","msg":"trace[1222109283] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"100.208707ms","start":"2024-12-13T01:29:26.431763Z","end":"2024-12-13T01:29:26.531972Z","steps":["trace[1222109283] 'process raft request' (duration: 100.126712ms)"],"step_count":1} Dec 13 01:29:26.532938 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:26.531994Z","caller":"traceutil/trace.go:171","msg":"trace[1583591695] linearizableReadLoop","detail":"{readStateIndex:453; appliedIndex:452; }","duration":"118.844108ms","start":"2024-12-13T01:29:26.413134Z","end":"2024-12-13T01:29:26.531978Z","steps":["trace[1583591695] 'read index received' (duration: 18.185724ms)","trace[1583591695] 'applied index is now lower than readState.Index' (duration: 100.65668ms)"],"step_count":2} Dec 13 01:29:26.533902 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:26.533012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.842978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:417"} Dec 13 01:29:26.533902 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:26.533278Z","caller":"traceutil/trace.go:171","msg":"trace[1919310682] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:441; }","duration":"120.148594ms","start":"2024-12-13T01:29:26.413110Z","end":"2024-12-13T01:29:26.533259Z","steps":["trace[1919310682] 'agreement among raft nodes before linearized reading' (duration: 118.90411ms)"],"step_count":1} Dec 13 01:29:33.915584 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:33.915240Z","caller":"traceutil/trace.go:171","msg":"trace[853007827] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"197.438918ms","start":"2024-12-13T01:29:33.717783Z","end":"2024-12-13T01:29:33.915222Z","steps":["trace[853007827] 'process raft request' (duration: 197.335944ms)"],"step_count":1} Dec 13 01:29:48.706793 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:48.706294Z","caller":"traceutil/trace.go:171","msg":"trace[1976817550] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"179.795486ms","start":"2024-12-13T01:29:48.526479Z","end":"2024-12-13T01:29:48.706274Z","steps":["trace[1976817550] 'read index received' (duration: 156.829441ms)","trace[1976817550] 'applied index is now lower than readState.Index' (duration: 22.965554ms)"],"step_count":2} Dec 13 01:29:48.706793 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:48.706353Z","caller":"traceutil/trace.go:171","msg":"trace[272115176] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"217.461122ms","start":"2024-12-13T01:29:48.488879Z","end":"2024-12-13T01:29:48.706340Z","steps":["trace[272115176] 'process raft request' (duration: 194.408493ms)","trace[272115176] 'compare' (duration: 22.88812ms)"],"step_count":2} Dec 13 01:29:48.706793 etcd-wrapper[1622]: {"level":"warn","ts":"2024-12-13T01:29:48.706453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.964163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/coredns-7db6d8ff4d-4kq2t.1810985ad63023c5\" ","response":"range_response_count:1 size:824"} Dec 13 01:29:48.706793 etcd-wrapper[1622]: {"level":"info","ts":"2024-12-13T01:29:48.706488Z","caller":"traceutil/trace.go:171","msg":"trace[1501395900] range","detail":"{range_begin:/registry/events/kube-system/coredns-7db6d8ff4d-4kq2t.1810985ad63023c5; range_end:; response_count:1; response_revision:621; }","duration":"180.035136ms","start":"2024-12-13T01:29:48.526440Z","end":"2024-12-13T01:29:48.706475Z","steps":["trace[1501395900] 'agreement among raft nodes before linearized reading' (duration: 179.926001ms)"],"step_count":1}