Feb 13 20:28:54.954964 kernel: Linux version 6.6.74-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241116 p3) 14.2.1 20241116, GNU ld (Gentoo 2.42 p6) 2.42.0) #1 SMP PREEMPT_DYNAMIC Thu Feb 13 17:41:03 -00 2025 Feb 13 20:28:54.954990 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,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=openstack flatcar.autologin verity.usrhash=015d1d9e5e601f6a4e226c935072d3d0819e7eb2da20e68715973498f21aa3fe Feb 13 20:28:54.955001 kernel: BIOS-provided physical RAM map: Feb 13 20:28:54.955009 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Feb 13 20:28:54.955017 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Feb 13 20:28:54.955027 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Feb 13 20:28:54.955036 kernel: BIOS-e820: [mem 0x0000000000100000-0x00000000bffdcfff] usable Feb 13 20:28:54.955044 kernel: BIOS-e820: [mem 0x00000000bffdd000-0x00000000bfffffff] reserved Feb 13 20:28:54.955051 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Feb 13 20:28:54.955059 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Feb 13 20:28:54.955067 kernel: BIOS-e820: [mem 0x0000000100000000-0x000000013fffffff] usable Feb 13 20:28:54.955075 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Feb 13 20:28:54.955083 kernel: NX (Execute Disable) protection: active Feb 13 20:28:54.955091 kernel: APIC: Static calls initialized Feb 13 20:28:54.955102 kernel: SMBIOS 3.0.0 present. Feb 13 20:28:54.955111 kernel: DMI: OpenStack Foundation OpenStack Nova, BIOS 1.16.3-debian-1.16.3-2 04/01/2014 Feb 13 20:28:54.955119 kernel: Hypervisor detected: KVM Feb 13 20:28:54.955127 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Feb 13 20:28:54.955135 kernel: kvm-clock: using sched offset of 3451187038 cycles Feb 13 20:28:54.955145 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Feb 13 20:28:54.955154 kernel: tsc: Detected 1996.249 MHz processor Feb 13 20:28:54.955163 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Feb 13 20:28:54.955172 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Feb 13 20:28:54.955180 kernel: last_pfn = 0x140000 max_arch_pfn = 0x400000000 Feb 13 20:28:54.955189 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Feb 13 20:28:54.955198 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Feb 13 20:28:54.955206 kernel: last_pfn = 0xbffdd max_arch_pfn = 0x400000000 Feb 13 20:28:54.955215 kernel: ACPI: Early table checksum verification disabled Feb 13 20:28:54.955225 kernel: ACPI: RSDP 0x00000000000F51E0 000014 (v00 BOCHS ) Feb 13 20:28:54.955233 kernel: ACPI: RSDT 0x00000000BFFE1B65 000030 (v01 BOCHS BXPC 00000001 BXPC 00000001) Feb 13 20:28:54.955242 kernel: ACPI: FACP 0x00000000BFFE1A49 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) Feb 13 20:28:54.955250 kernel: ACPI: DSDT 0x00000000BFFE0040 001A09 (v01 BOCHS BXPC 00000001 BXPC 00000001) Feb 13 20:28:54.955259 kernel: ACPI: FACS 0x00000000BFFE0000 000040 Feb 13 20:28:54.955267 kernel: ACPI: APIC 0x00000000BFFE1ABD 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Feb 13 20:28:54.955276 kernel: ACPI: WAET 0x00000000BFFE1B3D 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Feb 13 20:28:54.955284 kernel: ACPI: Reserving FACP table memory at [mem 0xbffe1a49-0xbffe1abc] Feb 13 20:28:54.955292 kernel: ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1a48] Feb 13 20:28:54.955303 kernel: ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] Feb 13 20:28:54.955311 kernel: ACPI: Reserving APIC table memory at [mem 0xbffe1abd-0xbffe1b3c] Feb 13 20:28:54.955319 kernel: ACPI: Reserving WAET table memory at [mem 0xbffe1b3d-0xbffe1b64] Feb 13 20:28:54.955331 kernel: No NUMA configuration found Feb 13 20:28:54.955340 kernel: Faking a node at [mem 0x0000000000000000-0x000000013fffffff] Feb 13 20:28:54.955348 kernel: NODE_DATA(0) allocated [mem 0x13fff7000-0x13fffcfff] Feb 13 20:28:54.955357 kernel: Zone ranges: Feb 13 20:28:54.955368 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Feb 13 20:28:54.955377 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Feb 13 20:28:54.955386 kernel: Normal [mem 0x0000000100000000-0x000000013fffffff] Feb 13 20:28:54.955394 kernel: Movable zone start for each node Feb 13 20:28:54.955403 kernel: Early memory node ranges Feb 13 20:28:54.955412 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Feb 13 20:28:54.955420 kernel: node 0: [mem 0x0000000000100000-0x00000000bffdcfff] Feb 13 20:28:54.955429 kernel: node 0: [mem 0x0000000100000000-0x000000013fffffff] Feb 13 20:28:54.955440 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000013fffffff] Feb 13 20:28:54.955449 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Feb 13 20:28:54.955458 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Feb 13 20:28:54.955466 kernel: On node 0, zone Normal: 35 pages in unavailable ranges Feb 13 20:28:54.956041 kernel: ACPI: PM-Timer IO Port: 0x608 Feb 13 20:28:54.956051 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Feb 13 20:28:54.956060 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Feb 13 20:28:54.956068 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Feb 13 20:28:54.956077 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Feb 13 20:28:54.956090 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Feb 13 20:28:54.956099 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Feb 13 20:28:54.956108 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Feb 13 20:28:54.956116 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Feb 13 20:28:54.956125 kernel: smpboot: Allowing 2 CPUs, 0 hotplug CPUs Feb 13 20:28:54.956134 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Feb 13 20:28:54.956143 kernel: [mem 0xc0000000-0xfeffbfff] available for PCI devices Feb 13 20:28:54.956151 kernel: Booting paravirtualized kernel on KVM Feb 13 20:28:54.956160 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Feb 13 20:28:54.956171 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 Feb 13 20:28:54.956180 kernel: percpu: Embedded 58 pages/cpu s197032 r8192 d32344 u1048576 Feb 13 20:28:54.956189 kernel: pcpu-alloc: s197032 r8192 d32344 u1048576 alloc=1*2097152 Feb 13 20:28:54.956197 kernel: pcpu-alloc: [0] 0 1 Feb 13 20:28:54.956206 kernel: kvm-guest: PV spinlocks disabled, no host support Feb 13 20:28:54.956216 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,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=openstack flatcar.autologin verity.usrhash=015d1d9e5e601f6a4e226c935072d3d0819e7eb2da20e68715973498f21aa3fe Feb 13 20:28:54.956226 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Feb 13 20:28:54.956235 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Feb 13 20:28:54.956246 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Feb 13 20:28:54.956255 kernel: Fallback order for Node 0: 0 Feb 13 20:28:54.956263 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1031901 Feb 13 20:28:54.956272 kernel: Policy zone: Normal Feb 13 20:28:54.956281 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Feb 13 20:28:54.956290 kernel: software IO TLB: area num 2. Feb 13 20:28:54.956299 kernel: Memory: 3964152K/4193772K available (14336K kernel code, 2301K rwdata, 22800K rodata, 43320K init, 1752K bss, 229360K reserved, 0K cma-reserved) Feb 13 20:28:54.956308 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Feb 13 20:28:54.956319 kernel: ftrace: allocating 37893 entries in 149 pages Feb 13 20:28:54.956328 kernel: ftrace: allocated 149 pages with 4 groups Feb 13 20:28:54.956336 kernel: Dynamic Preempt: voluntary Feb 13 20:28:54.956345 kernel: rcu: Preemptible hierarchical RCU implementation. Feb 13 20:28:54.956354 kernel: rcu: RCU event tracing is enabled. Feb 13 20:28:54.956364 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Feb 13 20:28:54.956373 kernel: Trampoline variant of Tasks RCU enabled. Feb 13 20:28:54.956381 kernel: Rude variant of Tasks RCU enabled. Feb 13 20:28:54.956390 kernel: Tracing variant of Tasks RCU enabled. Feb 13 20:28:54.956399 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Feb 13 20:28:54.956410 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Feb 13 20:28:54.956418 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 16 Feb 13 20:28:54.956427 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Feb 13 20:28:54.956436 kernel: Console: colour VGA+ 80x25 Feb 13 20:28:54.956445 kernel: printk: console [tty0] enabled Feb 13 20:28:54.956453 kernel: printk: console [ttyS0] enabled Feb 13 20:28:54.956462 kernel: ACPI: Core revision 20230628 Feb 13 20:28:54.956500 kernel: APIC: Switch to symmetric I/O mode setup Feb 13 20:28:54.956509 kernel: x2apic enabled Feb 13 20:28:54.956521 kernel: APIC: Switched APIC routing to: physical x2apic Feb 13 20:28:54.956530 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Feb 13 20:28:54.956539 kernel: tsc: Marking TSC unstable due to TSCs unsynchronized Feb 13 20:28:54.956548 kernel: Calibrating delay loop (skipped) preset value.. 3992.49 BogoMIPS (lpj=1996249) Feb 13 20:28:54.956557 kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 Feb 13 20:28:54.956565 kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 Feb 13 20:28:54.956574 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Feb 13 20:28:54.956583 kernel: Spectre V2 : Mitigation: Retpolines Feb 13 20:28:54.956592 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Feb 13 20:28:54.956603 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Feb 13 20:28:54.956612 kernel: Speculative Store Bypass: Vulnerable Feb 13 20:28:54.956620 kernel: x86/fpu: x87 FPU will use FXSAVE Feb 13 20:28:54.956629 kernel: Freeing SMP alternatives memory: 32K Feb 13 20:28:54.956644 kernel: pid_max: default: 32768 minimum: 301 Feb 13 20:28:54.956655 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Feb 13 20:28:54.956664 kernel: landlock: Up and running. Feb 13 20:28:54.956673 kernel: SELinux: Initializing. Feb 13 20:28:54.956683 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Feb 13 20:28:54.956704 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Feb 13 20:28:54.956719 kernel: smpboot: CPU0: AMD Intel Core i7 9xx (Nehalem Class Core i7) (family: 0x6, model: 0x1a, stepping: 0x3) Feb 13 20:28:54.956735 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Feb 13 20:28:54.956744 kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Feb 13 20:28:54.956754 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Feb 13 20:28:54.956763 kernel: Performance Events: AMD PMU driver. Feb 13 20:28:54.956772 kernel: ... version: 0 Feb 13 20:28:54.956784 kernel: ... bit width: 48 Feb 13 20:28:54.956793 kernel: ... generic registers: 4 Feb 13 20:28:54.956803 kernel: ... value mask: 0000ffffffffffff Feb 13 20:28:54.956812 kernel: ... max period: 00007fffffffffff Feb 13 20:28:54.956821 kernel: ... fixed-purpose events: 0 Feb 13 20:28:54.956830 kernel: ... event mask: 000000000000000f Feb 13 20:28:54.956839 kernel: signal: max sigframe size: 1440 Feb 13 20:28:54.956848 kernel: rcu: Hierarchical SRCU implementation. Feb 13 20:28:54.956858 kernel: rcu: Max phase no-delay instances is 400. Feb 13 20:28:54.956867 kernel: smp: Bringing up secondary CPUs ... Feb 13 20:28:54.956878 kernel: smpboot: x86: Booting SMP configuration: Feb 13 20:28:54.956888 kernel: .... node #0, CPUs: #1 Feb 13 20:28:54.956897 kernel: smp: Brought up 1 node, 2 CPUs Feb 13 20:28:54.956906 kernel: smpboot: Max logical packages: 2 Feb 13 20:28:54.956915 kernel: smpboot: Total of 2 processors activated (7984.99 BogoMIPS) Feb 13 20:28:54.956924 kernel: devtmpfs: initialized Feb 13 20:28:54.956933 kernel: x86/mm: Memory block size: 128MB Feb 13 20:28:54.956943 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Feb 13 20:28:54.956952 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Feb 13 20:28:54.956963 kernel: pinctrl core: initialized pinctrl subsystem Feb 13 20:28:54.956973 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Feb 13 20:28:54.956982 kernel: audit: initializing netlink subsys (disabled) Feb 13 20:28:54.956991 kernel: audit: type=2000 audit(1739478534.224:1): state=initialized audit_enabled=0 res=1 Feb 13 20:28:54.957000 kernel: thermal_sys: Registered thermal governor 'step_wise' Feb 13 20:28:54.957009 kernel: thermal_sys: Registered thermal governor 'user_space' Feb 13 20:28:54.957018 kernel: cpuidle: using governor menu Feb 13 20:28:54.957028 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Feb 13 20:28:54.957037 kernel: dca service started, version 1.12.1 Feb 13 20:28:54.957048 kernel: PCI: Using configuration type 1 for base access Feb 13 20:28:54.957057 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Feb 13 20:28:54.957067 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Feb 13 20:28:54.957076 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Feb 13 20:28:54.957085 kernel: ACPI: Added _OSI(Module Device) Feb 13 20:28:54.957094 kernel: ACPI: Added _OSI(Processor Device) Feb 13 20:28:54.957103 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Feb 13 20:28:54.957112 kernel: ACPI: Added _OSI(Processor Aggregator Device) Feb 13 20:28:54.957122 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Feb 13 20:28:54.957133 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Feb 13 20:28:54.957142 kernel: ACPI: Interpreter enabled Feb 13 20:28:54.957151 kernel: ACPI: PM: (supports S0 S3 S5) Feb 13 20:28:54.957160 kernel: ACPI: Using IOAPIC for interrupt routing Feb 13 20:28:54.957170 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Feb 13 20:28:54.957179 kernel: PCI: Using E820 reservations for host bridge windows Feb 13 20:28:54.957188 kernel: ACPI: Enabled 2 GPEs in block 00 to 0F Feb 13 20:28:54.957197 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Feb 13 20:28:54.957328 kernel: acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] Feb 13 20:28:54.957441 kernel: acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] Feb 13 20:28:54.959086 kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge Feb 13 20:28:54.959105 kernel: acpiphp: Slot [3] registered Feb 13 20:28:54.959115 kernel: acpiphp: Slot [4] registered Feb 13 20:28:54.959125 kernel: acpiphp: Slot [5] registered Feb 13 20:28:54.959135 kernel: acpiphp: Slot [6] registered Feb 13 20:28:54.959145 kernel: acpiphp: Slot [7] registered Feb 13 20:28:54.959159 kernel: acpiphp: Slot [8] registered Feb 13 20:28:54.959169 kernel: acpiphp: Slot [9] registered Feb 13 20:28:54.959179 kernel: acpiphp: Slot [10] registered Feb 13 20:28:54.959189 kernel: acpiphp: Slot [11] registered Feb 13 20:28:54.959199 kernel: acpiphp: Slot [12] registered Feb 13 20:28:54.959208 kernel: acpiphp: Slot [13] registered Feb 13 20:28:54.959218 kernel: acpiphp: Slot [14] registered Feb 13 20:28:54.959228 kernel: acpiphp: Slot [15] registered Feb 13 20:28:54.959238 kernel: acpiphp: Slot [16] registered Feb 13 20:28:54.959247 kernel: acpiphp: Slot [17] registered Feb 13 20:28:54.959259 kernel: acpiphp: Slot [18] registered Feb 13 20:28:54.959269 kernel: acpiphp: Slot [19] registered Feb 13 20:28:54.959279 kernel: acpiphp: Slot [20] registered Feb 13 20:28:54.959288 kernel: acpiphp: Slot [21] registered Feb 13 20:28:54.959298 kernel: acpiphp: Slot [22] registered Feb 13 20:28:54.959308 kernel: acpiphp: Slot [23] registered Feb 13 20:28:54.959318 kernel: acpiphp: Slot [24] registered Feb 13 20:28:54.959328 kernel: acpiphp: Slot [25] registered Feb 13 20:28:54.959337 kernel: acpiphp: Slot [26] registered Feb 13 20:28:54.959349 kernel: acpiphp: Slot [27] registered Feb 13 20:28:54.959359 kernel: acpiphp: Slot [28] registered Feb 13 20:28:54.959368 kernel: acpiphp: Slot [29] registered Feb 13 20:28:54.959378 kernel: acpiphp: Slot [30] registered Feb 13 20:28:54.959388 kernel: acpiphp: Slot [31] registered Feb 13 20:28:54.959398 kernel: PCI host bridge to bus 0000:00 Feb 13 20:28:54.960552 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Feb 13 20:28:54.960641 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Feb 13 20:28:54.960742 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Feb 13 20:28:54.960826 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] Feb 13 20:28:54.960906 kernel: pci_bus 0000:00: root bus resource [mem 0xc000000000-0xc07fffffff window] Feb 13 20:28:54.960988 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Feb 13 20:28:54.961090 kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 Feb 13 20:28:54.961190 kernel: pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 Feb 13 20:28:54.961287 kernel: pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 Feb 13 20:28:54.961386 kernel: pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] Feb 13 20:28:54.961492 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] Feb 13 20:28:54.962629 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] Feb 13 20:28:54.962721 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] Feb 13 20:28:54.962811 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] Feb 13 20:28:54.962914 kernel: pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 Feb 13 20:28:54.963011 kernel: pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI Feb 13 20:28:54.963101 kernel: pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB Feb 13 20:28:54.963199 kernel: pci 0000:00:02.0: [1af4:1050] type 00 class 0x030000 Feb 13 20:28:54.963290 kernel: pci 0000:00:02.0: reg 0x10: [mem 0xfe000000-0xfe7fffff pref] Feb 13 20:28:54.963382 kernel: pci 0000:00:02.0: reg 0x18: [mem 0xc000000000-0xc000003fff 64bit pref] Feb 13 20:28:54.964537 kernel: pci 0000:00:02.0: reg 0x20: [mem 0xfeb90000-0xfeb90fff] Feb 13 20:28:54.964649 kernel: pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfeb8ffff pref] Feb 13 20:28:54.964766 kernel: pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Feb 13 20:28:54.964867 kernel: pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 Feb 13 20:28:54.964957 kernel: pci 0000:00:03.0: reg 0x10: [io 0xc080-0xc0bf] Feb 13 20:28:54.965045 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xfeb91000-0xfeb91fff] Feb 13 20:28:54.965134 kernel: pci 0000:00:03.0: reg 0x20: [mem 0xc000004000-0xc000007fff 64bit pref] Feb 13 20:28:54.965222 kernel: pci 0000:00:03.0: reg 0x30: [mem 0xfeb00000-0xfeb7ffff pref] Feb 13 20:28:54.965318 kernel: pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 Feb 13 20:28:54.965419 kernel: pci 0000:00:04.0: reg 0x10: [io 0xc000-0xc07f] Feb 13 20:28:54.966551 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xfeb92000-0xfeb92fff] Feb 13 20:28:54.966645 kernel: pci 0000:00:04.0: reg 0x20: [mem 0xc000008000-0xc00000bfff 64bit pref] Feb 13 20:28:54.966743 kernel: pci 0000:00:05.0: [1af4:1002] type 00 class 0x00ff00 Feb 13 20:28:54.966833 kernel: pci 0000:00:05.0: reg 0x10: [io 0xc0c0-0xc0ff] Feb 13 20:28:54.966922 kernel: pci 0000:00:05.0: reg 0x20: [mem 0xc00000c000-0xc00000ffff 64bit pref] Feb 13 20:28:54.967019 kernel: pci 0000:00:06.0: [1af4:1005] type 00 class 0x00ff00 Feb 13 20:28:54.967114 kernel: pci 0000:00:06.0: reg 0x10: [io 0xc100-0xc11f] Feb 13 20:28:54.967204 kernel: pci 0000:00:06.0: reg 0x14: [mem 0xfeb93000-0xfeb93fff] Feb 13 20:28:54.967293 kernel: pci 0000:00:06.0: reg 0x20: [mem 0xc000010000-0xc000013fff 64bit pref] Feb 13 20:28:54.967308 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Feb 13 20:28:54.967318 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Feb 13 20:28:54.967328 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Feb 13 20:28:54.967337 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Feb 13 20:28:54.967347 kernel: ACPI: PCI: Interrupt link LNKS configured for IRQ 9 Feb 13 20:28:54.967359 kernel: iommu: Default domain type: Translated Feb 13 20:28:54.967369 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Feb 13 20:28:54.967378 kernel: PCI: Using ACPI for IRQ routing Feb 13 20:28:54.967388 kernel: PCI: pci_cache_line_size set to 64 bytes Feb 13 20:28:54.967397 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Feb 13 20:28:54.967406 kernel: e820: reserve RAM buffer [mem 0xbffdd000-0xbfffffff] Feb 13 20:28:54.970257 kernel: pci 0000:00:02.0: vgaarb: setting as boot VGA device Feb 13 20:28:54.970355 kernel: pci 0000:00:02.0: vgaarb: bridge control possible Feb 13 20:28:54.970451 kernel: pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Feb 13 20:28:54.970465 kernel: vgaarb: loaded Feb 13 20:28:54.970491 kernel: clocksource: Switched to clocksource kvm-clock Feb 13 20:28:54.970501 kernel: VFS: Disk quotas dquot_6.6.0 Feb 13 20:28:54.970510 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Feb 13 20:28:54.970520 kernel: pnp: PnP ACPI init Feb 13 20:28:54.970614 kernel: pnp 00:03: [dma 2] Feb 13 20:28:54.970630 kernel: pnp: PnP ACPI: found 5 devices Feb 13 20:28:54.970639 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Feb 13 20:28:54.970652 kernel: NET: Registered PF_INET protocol family Feb 13 20:28:54.970662 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Feb 13 20:28:54.970671 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Feb 13 20:28:54.970681 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Feb 13 20:28:54.970690 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Feb 13 20:28:54.970700 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Feb 13 20:28:54.970709 kernel: TCP: Hash tables configured (established 32768 bind 32768) Feb 13 20:28:54.970718 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Feb 13 20:28:54.970728 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Feb 13 20:28:54.970739 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Feb 13 20:28:54.970748 kernel: NET: Registered PF_XDP protocol family Feb 13 20:28:54.970831 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Feb 13 20:28:54.970917 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Feb 13 20:28:54.970997 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Feb 13 20:28:54.971077 kernel: pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] Feb 13 20:28:54.971160 kernel: pci_bus 0000:00: resource 8 [mem 0xc000000000-0xc07fffffff window] Feb 13 20:28:54.971254 kernel: pci 0000:00:01.0: PIIX3: Enabling Passive Release Feb 13 20:28:54.971353 kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers Feb 13 20:28:54.971368 kernel: PCI: CLS 0 bytes, default 64 Feb 13 20:28:54.971377 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Feb 13 20:28:54.971427 kernel: software IO TLB: mapped [mem 0x00000000bbfdd000-0x00000000bffdd000] (64MB) Feb 13 20:28:54.971437 kernel: Initialise system trusted keyrings Feb 13 20:28:54.971446 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Feb 13 20:28:54.971456 kernel: Key type asymmetric registered Feb 13 20:28:54.971465 kernel: Asymmetric key parser 'x509' registered Feb 13 20:28:54.971501 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Feb 13 20:28:54.971511 kernel: io scheduler mq-deadline registered Feb 13 20:28:54.971521 kernel: io scheduler kyber registered Feb 13 20:28:54.971530 kernel: io scheduler bfq registered Feb 13 20:28:54.971540 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Feb 13 20:28:54.971550 kernel: ACPI: \_SB_.LNKB: Enabled at IRQ 10 Feb 13 20:28:54.971559 kernel: ACPI: \_SB_.LNKC: Enabled at IRQ 11 Feb 13 20:28:54.971569 kernel: ACPI: \_SB_.LNKD: Enabled at IRQ 11 Feb 13 20:28:54.971578 kernel: ACPI: \_SB_.LNKA: Enabled at IRQ 10 Feb 13 20:28:54.971590 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Feb 13 20:28:54.971599 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Feb 13 20:28:54.971609 kernel: random: crng init done Feb 13 20:28:54.971618 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Feb 13 20:28:54.971627 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Feb 13 20:28:54.971637 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Feb 13 20:28:54.971741 kernel: rtc_cmos 00:04: RTC can wake from S4 Feb 13 20:28:54.971757 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Feb 13 20:28:54.971843 kernel: rtc_cmos 00:04: registered as rtc0 Feb 13 20:28:54.971936 kernel: rtc_cmos 00:04: setting system clock to 2025-02-13T20:28:54 UTC (1739478534) Feb 13 20:28:54.972024 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram Feb 13 20:28:54.972039 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled Feb 13 20:28:54.972049 kernel: NET: Registered PF_INET6 protocol family Feb 13 20:28:54.972059 kernel: Segment Routing with IPv6 Feb 13 20:28:54.972069 kernel: In-situ OAM (IOAM) with IPv6 Feb 13 20:28:54.972079 kernel: NET: Registered PF_PACKET protocol family Feb 13 20:28:54.972089 kernel: Key type dns_resolver registered Feb 13 20:28:54.972102 kernel: IPI shorthand broadcast: enabled Feb 13 20:28:54.972113 kernel: sched_clock: Marking stable (984043892, 176295029)->(1195149874, -34810953) Feb 13 20:28:54.972123 kernel: registered taskstats version 1 Feb 13 20:28:54.972133 kernel: Loading compiled-in X.509 certificates Feb 13 20:28:54.972143 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.74-flatcar: b3acedbed401b3cd9632ee9302ddcce254d8924d' Feb 13 20:28:54.972153 kernel: Key type .fscrypt registered Feb 13 20:28:54.972162 kernel: Key type fscrypt-provisioning registered Feb 13 20:28:54.972173 kernel: ima: No TPM chip found, activating TPM-bypass! Feb 13 20:28:54.972183 kernel: ima: Allocated hash algorithm: sha1 Feb 13 20:28:54.972194 kernel: ima: No architecture policies found Feb 13 20:28:54.972204 kernel: clk: Disabling unused clocks Feb 13 20:28:54.972214 kernel: Freeing unused kernel image (initmem) memory: 43320K Feb 13 20:28:54.972224 kernel: Write protecting the kernel read-only data: 38912k Feb 13 20:28:54.972234 kernel: Freeing unused kernel image (rodata/data gap) memory: 1776K Feb 13 20:28:54.972244 kernel: Run /init as init process Feb 13 20:28:54.972254 kernel: with arguments: Feb 13 20:28:54.972264 kernel: /init Feb 13 20:28:54.972274 kernel: with environment: Feb 13 20:28:54.972285 kernel: HOME=/ Feb 13 20:28:54.972295 kernel: TERM=linux Feb 13 20:28:54.972305 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Feb 13 20:28:54.972318 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) Feb 13 20:28:54.972331 systemd[1]: Detected virtualization kvm. Feb 13 20:28:54.972342 systemd[1]: Detected architecture x86-64. Feb 13 20:28:54.972353 systemd[1]: Running in initrd. Feb 13 20:28:54.972367 systemd[1]: No hostname configured, using default hostname. Feb 13 20:28:54.972378 systemd[1]: Hostname set to . Feb 13 20:28:54.972389 systemd[1]: Initializing machine ID from VM UUID. Feb 13 20:28:54.972400 systemd[1]: Queued start job for default target initrd.target. Feb 13 20:28:54.972411 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Feb 13 20:28:54.972422 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Feb 13 20:28:54.972433 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Feb 13 20:28:54.972453 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Feb 13 20:28:54.972466 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Feb 13 20:28:54.973488 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Feb 13 20:28:54.973516 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Feb 13 20:28:54.973527 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Feb 13 20:28:54.973538 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Feb 13 20:28:54.973553 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Feb 13 20:28:54.973563 systemd[1]: Reached target paths.target - Path Units. Feb 13 20:28:54.973574 systemd[1]: Reached target slices.target - Slice Units. Feb 13 20:28:54.973584 systemd[1]: Reached target swap.target - Swaps. Feb 13 20:28:54.973595 systemd[1]: Reached target timers.target - Timer Units. Feb 13 20:28:54.973605 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Feb 13 20:28:54.973616 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Feb 13 20:28:54.973626 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Feb 13 20:28:54.973638 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Feb 13 20:28:54.973649 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Feb 13 20:28:54.973660 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Feb 13 20:28:54.973670 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Feb 13 20:28:54.973681 systemd[1]: Reached target sockets.target - Socket Units. Feb 13 20:28:54.973691 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Feb 13 20:28:54.973702 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Feb 13 20:28:54.973712 systemd[1]: Finished network-cleanup.service - Network Cleanup. Feb 13 20:28:54.973723 systemd[1]: Starting systemd-fsck-usr.service... Feb 13 20:28:54.973735 systemd[1]: Starting systemd-journald.service - Journal Service... Feb 13 20:28:54.973746 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Feb 13 20:28:54.973756 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Feb 13 20:28:54.973766 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Feb 13 20:28:54.973795 systemd-journald[185]: Collecting audit messages is disabled. Feb 13 20:28:54.973823 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Feb 13 20:28:54.973834 systemd[1]: Finished systemd-fsck-usr.service. Feb 13 20:28:54.973848 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Feb 13 20:28:54.973859 systemd-journald[185]: Journal started Feb 13 20:28:54.973882 systemd-journald[185]: Runtime Journal (/run/log/journal/871ef69189ad4fb48c7aea73935d2631) is 8.0M, max 78.3M, 70.3M free. Feb 13 20:28:54.978490 systemd[1]: Started systemd-journald.service - Journal Service. Feb 13 20:28:54.985100 systemd-modules-load[186]: Inserted module 'overlay' Feb 13 20:28:54.987591 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Feb 13 20:28:54.990632 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Feb 13 20:28:55.039407 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Feb 13 20:28:55.039434 kernel: Bridge firewalling registered Feb 13 20:28:55.014381 systemd-modules-load[186]: Inserted module 'br_netfilter' Feb 13 20:28:55.040943 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Feb 13 20:28:55.041592 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Feb 13 20:28:55.042945 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Feb 13 20:28:55.049595 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Feb 13 20:28:55.052595 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Feb 13 20:28:55.054696 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Feb 13 20:28:55.065910 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Feb 13 20:28:55.072614 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Feb 13 20:28:55.074035 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Feb 13 20:28:55.075392 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Feb 13 20:28:55.086646 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Feb 13 20:28:55.097499 dracut-cmdline[220]: dracut-dracut-053 Feb 13 20:28:55.098181 dracut-cmdline[220]: 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,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=openstack flatcar.autologin verity.usrhash=015d1d9e5e601f6a4e226c935072d3d0819e7eb2da20e68715973498f21aa3fe Feb 13 20:28:55.103901 systemd-resolved[216]: Positive Trust Anchors: Feb 13 20:28:55.104550 systemd-resolved[216]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Feb 13 20:28:55.104597 systemd-resolved[216]: 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 Feb 13 20:28:55.110338 systemd-resolved[216]: Defaulting to hostname 'linux'. Feb 13 20:28:55.111207 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Feb 13 20:28:55.112049 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Feb 13 20:28:55.166520 kernel: SCSI subsystem initialized Feb 13 20:28:55.177534 kernel: Loading iSCSI transport class v2.0-870. Feb 13 20:28:55.189604 kernel: iscsi: registered transport (tcp) Feb 13 20:28:55.211907 kernel: iscsi: registered transport (qla4xxx) Feb 13 20:28:55.211976 kernel: QLogic iSCSI HBA Driver Feb 13 20:28:55.266421 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Feb 13 20:28:55.278618 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Feb 13 20:28:55.329396 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Feb 13 20:28:55.329527 kernel: device-mapper: uevent: version 1.0.3 Feb 13 20:28:55.329576 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Feb 13 20:28:55.377567 kernel: raid6: sse2x4 gen() 12606 MB/s Feb 13 20:28:55.395570 kernel: raid6: sse2x2 gen() 14288 MB/s Feb 13 20:28:55.413887 kernel: raid6: sse2x1 gen() 9958 MB/s Feb 13 20:28:55.413952 kernel: raid6: using algorithm sse2x2 gen() 14288 MB/s Feb 13 20:28:55.432905 kernel: raid6: .... xor() 9128 MB/s, rmw enabled Feb 13 20:28:55.432987 kernel: raid6: using ssse3x2 recovery algorithm Feb 13 20:28:55.487969 kernel: xor: measuring software checksum speed Feb 13 20:28:55.488027 kernel: prefetch64-sse : 7416 MB/sec Feb 13 20:28:55.490504 kernel: generic_sse : 6747 MB/sec Feb 13 20:28:55.494274 kernel: xor: using function: prefetch64-sse (7416 MB/sec) Feb 13 20:28:55.679551 kernel: Btrfs loaded, zoned=no, fsverity=no Feb 13 20:28:55.696161 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Feb 13 20:28:55.705604 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Feb 13 20:28:55.748918 systemd-udevd[403]: Using default interface naming scheme 'v255'. Feb 13 20:28:55.759630 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Feb 13 20:28:55.770736 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Feb 13 20:28:55.793768 dracut-pre-trigger[411]: rd.md=0: removing MD RAID activation Feb 13 20:28:55.836623 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Feb 13 20:28:55.845773 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Feb 13 20:28:55.904405 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Feb 13 20:28:55.911614 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Feb 13 20:28:55.948774 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Feb 13 20:28:55.952416 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Feb 13 20:28:55.955213 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Feb 13 20:28:55.956657 systemd[1]: Reached target remote-fs.target - Remote File Systems. Feb 13 20:28:55.962612 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Feb 13 20:28:55.988068 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Feb 13 20:28:56.001496 kernel: virtio_blk virtio2: 2/0/0 default/read/poll queues Feb 13 20:28:56.038579 kernel: libata version 3.00 loaded. Feb 13 20:28:56.038598 kernel: virtio_blk virtio2: [vda] 20971520 512-byte logical blocks (10.7 GB/10.0 GiB) Feb 13 20:28:56.038715 kernel: ata_piix 0000:00:01.1: version 2.13 Feb 13 20:28:56.041935 kernel: scsi host0: ata_piix Feb 13 20:28:56.042055 kernel: scsi host1: ata_piix Feb 13 20:28:56.042175 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Feb 13 20:28:56.042189 kernel: GPT:17805311 != 20971519 Feb 13 20:28:56.042201 kernel: GPT:Alternate GPT header not at the end of the disk. Feb 13 20:28:56.042213 kernel: GPT:17805311 != 20971519 Feb 13 20:28:56.042227 kernel: GPT: Use GNU Parted to correct GPT errors. Feb 13 20:28:56.042238 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Feb 13 20:28:56.042250 kernel: ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 Feb 13 20:28:56.042261 kernel: ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 Feb 13 20:28:56.035399 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Feb 13 20:28:56.035557 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Feb 13 20:28:56.061307 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Feb 13 20:28:56.062971 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Feb 13 20:28:56.063734 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Feb 13 20:28:56.066769 kernel: BTRFS: device fsid c7adc9b8-df7f-4a5f-93bf-204def2767a9 devid 1 transid 39 /dev/vda3 scanned by (udev-worker) (453) Feb 13 20:28:56.065511 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Feb 13 20:28:56.078513 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by (udev-worker) (460) Feb 13 20:28:56.076825 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Feb 13 20:28:56.103705 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Feb 13 20:28:56.136504 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Feb 13 20:28:56.143137 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Feb 13 20:28:56.149389 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Feb 13 20:28:56.154325 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Feb 13 20:28:56.154915 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Feb 13 20:28:56.161648 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Feb 13 20:28:56.164102 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Feb 13 20:28:56.175827 disk-uuid[502]: Primary Header is updated. Feb 13 20:28:56.175827 disk-uuid[502]: Secondary Entries is updated. Feb 13 20:28:56.175827 disk-uuid[502]: Secondary Header is updated. Feb 13 20:28:56.181676 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Feb 13 20:28:56.186521 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Feb 13 20:28:57.206555 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Feb 13 20:28:57.209132 disk-uuid[509]: The operation has completed successfully. Feb 13 20:28:57.282175 systemd[1]: disk-uuid.service: Deactivated successfully. Feb 13 20:28:57.282428 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Feb 13 20:28:57.311592 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Feb 13 20:28:57.319598 sh[523]: Success Feb 13 20:28:57.341503 kernel: device-mapper: verity: sha256 using implementation "sha256-ssse3" Feb 13 20:28:57.418975 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Feb 13 20:28:57.423591 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Feb 13 20:28:57.431860 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Feb 13 20:28:57.454507 kernel: BTRFS info (device dm-0): first mount of filesystem c7adc9b8-df7f-4a5f-93bf-204def2767a9 Feb 13 20:28:57.454554 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Feb 13 20:28:57.454568 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Feb 13 20:28:57.454583 kernel: BTRFS info (device dm-0): disabling log replay at mount time Feb 13 20:28:57.457126 kernel: BTRFS info (device dm-0): using free space tree Feb 13 20:28:57.471196 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Feb 13 20:28:57.473830 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Feb 13 20:28:57.479741 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Feb 13 20:28:57.483182 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Feb 13 20:28:57.502337 kernel: BTRFS info (device vda6): first mount of filesystem 60a376b4-1193-4e0b-af89-a0e6d698bf0f Feb 13 20:28:57.502439 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Feb 13 20:28:57.502511 kernel: BTRFS info (device vda6): using free space tree Feb 13 20:28:57.514544 kernel: BTRFS info (device vda6): auto enabling async discard Feb 13 20:28:57.538816 kernel: BTRFS info (device vda6): last unmount of filesystem 60a376b4-1193-4e0b-af89-a0e6d698bf0f Feb 13 20:28:57.532230 systemd[1]: mnt-oem.mount: Deactivated successfully. Feb 13 20:28:57.557845 systemd[1]: Finished ignition-setup.service - Ignition (setup). Feb 13 20:28:57.566609 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Feb 13 20:28:57.577206 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Feb 13 20:28:57.583591 systemd[1]: Starting systemd-networkd.service - Network Configuration... Feb 13 20:28:57.605320 systemd-networkd[705]: lo: Link UP Feb 13 20:28:57.605331 systemd-networkd[705]: lo: Gained carrier Feb 13 20:28:57.606441 systemd-networkd[705]: Enumeration completed Feb 13 20:28:57.606543 systemd[1]: Started systemd-networkd.service - Network Configuration. Feb 13 20:28:57.606945 systemd-networkd[705]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Feb 13 20:28:57.606949 systemd-networkd[705]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Feb 13 20:28:57.608879 systemd-networkd[705]: eth0: Link UP Feb 13 20:28:57.608883 systemd-networkd[705]: eth0: Gained carrier Feb 13 20:28:57.608891 systemd-networkd[705]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Feb 13 20:28:57.610887 systemd[1]: Reached target network.target - Network. Feb 13 20:28:57.622518 systemd-networkd[705]: eth0: DHCPv4 address 172.24.4.236/24, gateway 172.24.4.1 acquired from 172.24.4.1 Feb 13 20:28:57.702988 ignition[686]: Ignition 2.20.0 Feb 13 20:28:57.703000 ignition[686]: Stage: fetch-offline Feb 13 20:28:57.703032 ignition[686]: no configs at "/usr/lib/ignition/base.d" Feb 13 20:28:57.703041 ignition[686]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Feb 13 20:28:57.705300 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Feb 13 20:28:57.703124 ignition[686]: parsed url from cmdline: "" Feb 13 20:28:57.703128 ignition[686]: no config URL provided Feb 13 20:28:57.703133 ignition[686]: reading system config file "/usr/lib/ignition/user.ign" Feb 13 20:28:57.703140 ignition[686]: no config at "/usr/lib/ignition/user.ign" Feb 13 20:28:57.703145 ignition[686]: failed to fetch config: resource requires networking Feb 13 20:28:57.703334 ignition[686]: Ignition finished successfully Feb 13 20:28:57.715832 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Feb 13 20:28:57.726673 ignition[720]: Ignition 2.20.0 Feb 13 20:28:57.726685 ignition[720]: Stage: fetch Feb 13 20:28:57.726856 ignition[720]: no configs at "/usr/lib/ignition/base.d" Feb 13 20:28:57.726867 ignition[720]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Feb 13 20:28:57.726949 ignition[720]: parsed url from cmdline: "" Feb 13 20:28:57.726953 ignition[720]: no config URL provided Feb 13 20:28:57.726958 ignition[720]: reading system config file "/usr/lib/ignition/user.ign" Feb 13 20:28:57.726965 ignition[720]: no config at "/usr/lib/ignition/user.ign" Feb 13 20:28:57.727039 ignition[720]: GET http://169.254.169.254/openstack/latest/user_data: attempt #1 Feb 13 20:28:57.727122 ignition[720]: config drive ("/dev/disk/by-label/config-2") not found. Waiting... Feb 13 20:28:57.727145 ignition[720]: config drive ("/dev/disk/by-label/CONFIG-2") not found. Waiting... Feb 13 20:28:58.092679 ignition[720]: GET result: OK Feb 13 20:28:58.092827 ignition[720]: parsing config with SHA512: d37a8ade1bd371feb223efba4e48a167fea4e5e8217500499e60be3bdd23d08915851549247b81915125bf1d8a07263df0f2a442030a0c33bdad6e44c704a250 Feb 13 20:28:58.108932 unknown[720]: fetched base config from "system" Feb 13 20:28:58.108957 unknown[720]: fetched base config from "system" Feb 13 20:28:58.109507 ignition[720]: fetch: fetch complete Feb 13 20:28:58.108971 unknown[720]: fetched user config from "openstack" Feb 13 20:28:58.109522 ignition[720]: fetch: fetch passed Feb 13 20:28:58.112898 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Feb 13 20:28:58.109607 ignition[720]: Ignition finished successfully Feb 13 20:28:58.121888 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Feb 13 20:28:58.155242 ignition[727]: Ignition 2.20.0 Feb 13 20:28:58.155266 ignition[727]: Stage: kargs Feb 13 20:28:58.155718 ignition[727]: no configs at "/usr/lib/ignition/base.d" Feb 13 20:28:58.160057 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Feb 13 20:28:58.155744 ignition[727]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Feb 13 20:28:58.157413 ignition[727]: kargs: kargs passed Feb 13 20:28:58.157557 ignition[727]: Ignition finished successfully Feb 13 20:28:58.174308 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Feb 13 20:28:58.200940 ignition[733]: Ignition 2.20.0 Feb 13 20:28:58.200967 ignition[733]: Stage: disks Feb 13 20:28:58.201365 ignition[733]: no configs at "/usr/lib/ignition/base.d" Feb 13 20:28:58.201390 ignition[733]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Feb 13 20:28:58.206103 systemd[1]: Finished ignition-disks.service - Ignition (disks). Feb 13 20:28:58.203073 ignition[733]: disks: disks passed Feb 13 20:28:58.209020 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Feb 13 20:28:58.203166 ignition[733]: Ignition finished successfully Feb 13 20:28:58.210963 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Feb 13 20:28:58.213375 systemd[1]: Reached target local-fs.target - Local File Systems. Feb 13 20:28:58.216231 systemd[1]: Reached target sysinit.target - System Initialization. Feb 13 20:28:58.218867 systemd[1]: Reached target basic.target - Basic System. Feb 13 20:28:58.228776 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Feb 13 20:28:58.274385 systemd-fsck[742]: ROOT: clean, 14/1628000 files, 120691/1617920 blocks Feb 13 20:28:58.286592 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Feb 13 20:28:58.295678 systemd[1]: Mounting sysroot.mount - /sysroot... Feb 13 20:28:58.460783 kernel: EXT4-fs (vda9): mounted filesystem 7d46b70d-4c30-46e6-9935-e1f7fb523560 r/w with ordered data mode. Quota mode: none. Feb 13 20:28:58.461201 systemd[1]: Mounted sysroot.mount - /sysroot. Feb 13 20:28:58.462200 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Feb 13 20:28:58.470700 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Feb 13 20:28:58.473851 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Feb 13 20:28:58.476975 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Feb 13 20:28:58.480660 systemd[1]: Starting flatcar-openstack-hostname.service - Flatcar OpenStack Metadata Hostname Agent... Feb 13 20:28:58.500614 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 scanned by mount (750) Feb 13 20:28:58.500663 kernel: BTRFS info (device vda6): first mount of filesystem 60a376b4-1193-4e0b-af89-a0e6d698bf0f Feb 13 20:28:58.500721 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Feb 13 20:28:58.500752 kernel: BTRFS info (device vda6): using free space tree Feb 13 20:28:58.481793 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Feb 13 20:28:58.505169 kernel: BTRFS info (device vda6): auto enabling async discard Feb 13 20:28:58.481823 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Feb 13 20:28:58.511149 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Feb 13 20:28:58.511896 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Feb 13 20:28:58.536633 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Feb 13 20:28:58.621124 initrd-setup-root[780]: cut: /sysroot/etc/passwd: No such file or directory Feb 13 20:28:58.628998 initrd-setup-root[787]: cut: /sysroot/etc/group: No such file or directory Feb 13 20:28:58.636752 initrd-setup-root[794]: cut: /sysroot/etc/shadow: No such file or directory Feb 13 20:28:58.643920 initrd-setup-root[801]: cut: /sysroot/etc/gshadow: No such file or directory Feb 13 20:28:58.738391 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Feb 13 20:28:58.742548 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Feb 13 20:28:58.745613 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Feb 13 20:28:58.751519 systemd[1]: sysroot-oem.mount: Deactivated successfully. Feb 13 20:28:58.754702 kernel: BTRFS info (device vda6): last unmount of filesystem 60a376b4-1193-4e0b-af89-a0e6d698bf0f Feb 13 20:28:58.774946 ignition[868]: INFO : Ignition 2.20.0 Feb 13 20:28:58.774946 ignition[868]: INFO : Stage: mount Feb 13 20:28:58.776832 ignition[868]: INFO : no configs at "/usr/lib/ignition/base.d" Feb 13 20:28:58.776832 ignition[868]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/openstack" Feb 13 20:28:58.776832 ignition[868]: INFO : mount: mount passed Feb 13 20:28:58.776832 ignition[868]: INFO : Ignition finished successfully Feb 13 20:28:58.778000 systemd[1]: Finished ignition-mount.service - Ignition (mount). Feb 13 20:28:58.786874 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Feb 13 20:28:58.946740 systemd-networkd[705]: eth0: Gained IPv6LL Feb 13 20:29:05.723939 coreos-metadata[752]: Feb 13 20:29:05.723 WARN failed to locate config-drive, using the metadata service API instead Feb 13 20:29:05.764878 coreos-metadata[752]: Feb 13 20:29:05.764 INFO Fetching http://169.254.169.254/latest/meta-data/hostname: Attempt #1 Feb 13 20:29:05.779574 coreos-metadata[752]: Feb 13 20:29:05.779 INFO Fetch successful Feb 13 20:29:05.781001 coreos-metadata[752]: Feb 13 20:29:05.780 INFO wrote hostname ci-4186-1-1-8-cbfa5c72c7.novalocal to /sysroot/etc/hostname Feb 13 20:29:05.783079 systemd[1]: flatcar-openstack-hostname.service: Deactivated successfully. Feb 13 20:29:05.783289 systemd[1]: Finished flatcar-openstack-hostname.service - Flatcar OpenStack Metadata Hostname Agent. Feb 13 20:29:05.793680 systemd[1]: Starting ignition-files.service - Ignition (files)... Feb 13 20:29:05.826835 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Feb 13 20:29:05.846545 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/vda6 scanned by mount (886) Feb 13 20:29:05.853524 kernel: BTRFS info (device vda6): first mount of filesystem 60a376b4-1193-4e0b-af89-a0e6d698bf0f Feb 13 20:29:05.853588 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Feb 13 20:29:05.857847 kernel: BTRFS info (device vda6): using free space tree Feb 13 20:29:05.869606 kernel: BTRFS info (device vda6): auto enabling async discard Feb 13 20:29:05.874618 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Feb 13 20:29:05.920899 ignition[904]: INFO : Ignition 2.20.0 Feb 13 20:29:05.920899 ignition[904]: INFO : Stage: files Feb 13 20:29:05.923765 ignition[904]: INFO : no configs at "/usr/lib/ignition/base.d" Feb 13 20:29:05.923765 ignition[904]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/openstack" Feb 13 20:29:05.923765 ignition[904]: DEBUG : files: compiled without relabeling support, skipping Feb 13 20:29:05.929806 ignition[904]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Feb 13 20:29:05.929806 ignition[904]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Feb 13 20:29:05.934236 ignition[904]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Feb 13 20:29:05.936346 ignition[904]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Feb 13 20:29:05.936346 ignition[904]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Feb 13 20:29:05.935221 unknown[904]: wrote ssh authorized keys file for user: core Feb 13 20:29:05.942070 ignition[904]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Feb 13 20:29:05.942070 ignition[904]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Feb 13 20:29:05.942070 ignition[904]: INFO : files: op(4): [started] processing unit "etcd-member.service" Feb 13 20:29:05.942070 ignition[904]: 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" Feb 13 20:29:05.942070 ignition[904]: 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" Feb 13 20:29:05.942070 ignition[904]: INFO : files: op(4): [finished] processing unit "etcd-member.service" Feb 13 20:29:05.942070 ignition[904]: INFO : files: op(6): [started] processing unit "coreos-metadata.service" Feb 13 20:29:05.942070 ignition[904]: INFO : files: op(6): op(7): [started] writing systemd drop-in "20-clct-provider-override.conf" at "/sysroot/etc/systemd/system/coreos-metadata.service.d/20-clct-provider-override.conf" Feb 13 20:29:05.965703 ignition[904]: INFO : files: op(6): op(7): [finished] writing systemd drop-in "20-clct-provider-override.conf" at "/sysroot/etc/systemd/system/coreos-metadata.service.d/20-clct-provider-override.conf" Feb 13 20:29:05.965703 ignition[904]: INFO : files: op(6): [finished] processing unit "coreos-metadata.service" Feb 13 20:29:05.965703 ignition[904]: INFO : files: op(8): [started] processing unit "coreos-metadata-sshkeys@.service" Feb 13 20:29:05.965703 ignition[904]: INFO : files: op(8): op(9): [started] writing systemd drop-in "20-clct-provider-override.conf" at "/sysroot/etc/systemd/system/coreos-metadata-sshkeys@.service.d/20-clct-provider-override.conf" Feb 13 20:29:05.965703 ignition[904]: INFO : files: op(8): op(9): [finished] writing systemd drop-in "20-clct-provider-override.conf" at "/sysroot/etc/systemd/system/coreos-metadata-sshkeys@.service.d/20-clct-provider-override.conf" Feb 13 20:29:05.965703 ignition[904]: INFO : files: op(8): [finished] processing unit "coreos-metadata-sshkeys@.service" Feb 13 20:29:05.965703 ignition[904]: INFO : files: op(a): [started] setting preset to enabled for "coreos-metadata-sshkeys@.service " Feb 13 20:29:05.965703 ignition[904]: INFO : files: op(a): [finished] setting preset to enabled for "coreos-metadata-sshkeys@.service " Feb 13 20:29:05.965703 ignition[904]: INFO : files: op(b): [started] setting preset to enabled for "etcd-member.service" Feb 13 20:29:05.965703 ignition[904]: INFO : files: op(b): [finished] setting preset to enabled for "etcd-member.service" Feb 13 20:29:05.965703 ignition[904]: INFO : files: createResultFile: createFiles: op(c): [started] writing file "/sysroot/etc/.ignition-result.json" Feb 13 20:29:05.965703 ignition[904]: INFO : files: createResultFile: createFiles: op(c): [finished] writing file "/sysroot/etc/.ignition-result.json" Feb 13 20:29:05.965703 ignition[904]: INFO : files: files passed Feb 13 20:29:05.965703 ignition[904]: INFO : Ignition finished successfully Feb 13 20:29:05.965237 systemd[1]: Finished ignition-files.service - Ignition (files). Feb 13 20:29:05.977709 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Feb 13 20:29:05.982613 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Feb 13 20:29:05.986674 systemd[1]: ignition-quench.service: Deactivated successfully. Feb 13 20:29:05.986758 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Feb 13 20:29:05.995437 initrd-setup-root-after-ignition[933]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Feb 13 20:29:05.995437 initrd-setup-root-after-ignition[933]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Feb 13 20:29:05.997543 initrd-setup-root-after-ignition[937]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Feb 13 20:29:05.999233 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Feb 13 20:29:06.000824 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Feb 13 20:29:06.012627 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Feb 13 20:29:06.030929 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Feb 13 20:29:06.031044 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Feb 13 20:29:06.032642 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Feb 13 20:29:06.033296 systemd[1]: Reached target initrd.target - Initrd Default Target. Feb 13 20:29:06.034384 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Feb 13 20:29:06.041625 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Feb 13 20:29:06.056498 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Feb 13 20:29:06.064631 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Feb 13 20:29:06.080194 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Feb 13 20:29:06.081417 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Feb 13 20:29:06.086884 systemd[1]: Stopped target timers.target - Timer Units. Feb 13 20:29:06.088343 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Feb 13 20:29:06.088591 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Feb 13 20:29:06.090732 systemd[1]: Stopped target initrd.target - Initrd Default Target. Feb 13 20:29:06.092318 systemd[1]: Stopped target basic.target - Basic System. Feb 13 20:29:06.093369 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Feb 13 20:29:06.094788 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Feb 13 20:29:06.096329 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Feb 13 20:29:06.097905 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Feb 13 20:29:06.099398 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Feb 13 20:29:06.100995 systemd[1]: Stopped target sysinit.target - System Initialization. Feb 13 20:29:06.102550 systemd[1]: Stopped target local-fs.target - Local File Systems. Feb 13 20:29:06.103992 systemd[1]: Stopped target swap.target - Swaps. Feb 13 20:29:06.105351 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Feb 13 20:29:06.105649 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Feb 13 20:29:06.107191 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Feb 13 20:29:06.107919 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Feb 13 20:29:06.109017 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Feb 13 20:29:06.110149 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Feb 13 20:29:06.110779 systemd[1]: dracut-initqueue.service: Deactivated successfully. Feb 13 20:29:06.110888 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Feb 13 20:29:06.112334 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Feb 13 20:29:06.112454 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Feb 13 20:29:06.113729 systemd[1]: ignition-files.service: Deactivated successfully. Feb 13 20:29:06.113835 systemd[1]: Stopped ignition-files.service - Ignition (files). Feb 13 20:29:06.124896 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Feb 13 20:29:06.127677 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Feb 13 20:29:06.128198 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Feb 13 20:29:06.128360 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Feb 13 20:29:06.129651 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Feb 13 20:29:06.130602 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Feb 13 20:29:06.139414 systemd[1]: initrd-cleanup.service: Deactivated successfully. Feb 13 20:29:06.141516 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Feb 13 20:29:06.142326 ignition[957]: INFO : Ignition 2.20.0 Feb 13 20:29:06.142326 ignition[957]: INFO : Stage: umount Feb 13 20:29:06.142326 ignition[957]: INFO : no configs at "/usr/lib/ignition/base.d" Feb 13 20:29:06.142326 ignition[957]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/openstack" Feb 13 20:29:06.147055 ignition[957]: INFO : umount: umount passed Feb 13 20:29:06.147055 ignition[957]: INFO : Ignition finished successfully Feb 13 20:29:06.144103 systemd[1]: ignition-mount.service: Deactivated successfully. Feb 13 20:29:06.144180 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Feb 13 20:29:06.145400 systemd[1]: ignition-disks.service: Deactivated successfully. Feb 13 20:29:06.145457 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Feb 13 20:29:06.149682 systemd[1]: ignition-kargs.service: Deactivated successfully. Feb 13 20:29:06.149722 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Feb 13 20:29:06.150824 systemd[1]: ignition-fetch.service: Deactivated successfully. Feb 13 20:29:06.150862 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Feb 13 20:29:06.151931 systemd[1]: Stopped target network.target - Network. Feb 13 20:29:06.153021 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Feb 13 20:29:06.153064 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Feb 13 20:29:06.155573 systemd[1]: Stopped target paths.target - Path Units. Feb 13 20:29:06.156194 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Feb 13 20:29:06.159535 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Feb 13 20:29:06.160092 systemd[1]: Stopped target slices.target - Slice Units. Feb 13 20:29:06.160549 systemd[1]: Stopped target sockets.target - Socket Units. Feb 13 20:29:06.161068 systemd[1]: iscsid.socket: Deactivated successfully. Feb 13 20:29:06.161104 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Feb 13 20:29:06.163724 systemd[1]: iscsiuio.socket: Deactivated successfully. Feb 13 20:29:06.163767 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Feb 13 20:29:06.164465 systemd[1]: ignition-setup.service: Deactivated successfully. Feb 13 20:29:06.164542 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Feb 13 20:29:06.165499 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Feb 13 20:29:06.165541 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Feb 13 20:29:06.166591 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Feb 13 20:29:06.167772 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Feb 13 20:29:06.169627 systemd[1]: sysroot-boot.mount: Deactivated successfully. Feb 13 20:29:06.170159 systemd[1]: sysroot-boot.service: Deactivated successfully. Feb 13 20:29:06.170236 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Feb 13 20:29:06.171143 systemd[1]: initrd-setup-root.service: Deactivated successfully. Feb 13 20:29:06.171209 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Feb 13 20:29:06.171646 systemd-networkd[705]: eth0: DHCPv6 lease lost Feb 13 20:29:06.172916 systemd[1]: systemd-networkd.service: Deactivated successfully. Feb 13 20:29:06.172999 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Feb 13 20:29:06.174346 systemd[1]: systemd-networkd.socket: Deactivated successfully. Feb 13 20:29:06.174388 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Feb 13 20:29:06.182587 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Feb 13 20:29:06.183085 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Feb 13 20:29:06.183135 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Feb 13 20:29:06.183877 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Feb 13 20:29:06.185315 systemd[1]: systemd-resolved.service: Deactivated successfully. Feb 13 20:29:06.185407 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Feb 13 20:29:06.192749 systemd[1]: systemd-udevd.service: Deactivated successfully. Feb 13 20:29:06.192877 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Feb 13 20:29:06.194935 systemd[1]: network-cleanup.service: Deactivated successfully. Feb 13 20:29:06.195078 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Feb 13 20:29:06.197443 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Feb 13 20:29:06.197557 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Feb 13 20:29:06.198690 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Feb 13 20:29:06.198720 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Feb 13 20:29:06.199799 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Feb 13 20:29:06.199841 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Feb 13 20:29:06.205060 systemd[1]: dracut-cmdline.service: Deactivated successfully. Feb 13 20:29:06.205101 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Feb 13 20:29:06.206075 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Feb 13 20:29:06.206114 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Feb 13 20:29:06.215595 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Feb 13 20:29:06.217606 systemd[1]: systemd-sysctl.service: Deactivated successfully. Feb 13 20:29:06.217658 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Feb 13 20:29:06.218158 systemd[1]: systemd-modules-load.service: Deactivated successfully. Feb 13 20:29:06.218197 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Feb 13 20:29:06.218718 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Feb 13 20:29:06.218756 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Feb 13 20:29:06.219280 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Feb 13 20:29:06.219319 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Feb 13 20:29:06.220621 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Feb 13 20:29:06.220684 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Feb 13 20:29:06.221626 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Feb 13 20:29:06.221665 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Feb 13 20:29:06.222778 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Feb 13 20:29:06.222817 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Feb 13 20:29:06.224341 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Feb 13 20:29:06.224421 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Feb 13 20:29:06.225496 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Feb 13 20:29:06.233589 systemd[1]: Starting initrd-switch-root.service - Switch Root... Feb 13 20:29:06.239676 systemd[1]: Switching root. Feb 13 20:29:06.272042 systemd-journald[185]: Journal stopped Feb 13 20:29:07.366892 systemd-journald[185]: Received SIGTERM from PID 1 (systemd). Feb 13 20:29:07.366944 kernel: SELinux: policy capability network_peer_controls=1 Feb 13 20:29:07.366962 kernel: SELinux: policy capability open_perms=1 Feb 13 20:29:07.366974 kernel: SELinux: policy capability extended_socket_class=1 Feb 13 20:29:07.366992 kernel: SELinux: policy capability always_check_network=0 Feb 13 20:29:07.367006 kernel: SELinux: policy capability cgroup_seclabel=1 Feb 13 20:29:07.367020 kernel: SELinux: policy capability nnp_nosuid_transition=1 Feb 13 20:29:07.367031 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Feb 13 20:29:07.367043 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Feb 13 20:29:07.367055 kernel: audit: type=1403 audit(1739478546.435:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Feb 13 20:29:07.367070 systemd[1]: Successfully loaded SELinux policy in 64.244ms. Feb 13 20:29:07.367089 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 10.266ms. Feb 13 20:29:07.367103 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) Feb 13 20:29:07.367118 systemd[1]: Detected virtualization kvm. Feb 13 20:29:07.367131 systemd[1]: Detected architecture x86-64. Feb 13 20:29:07.367144 systemd[1]: Detected first boot. Feb 13 20:29:07.367156 systemd[1]: Hostname set to . Feb 13 20:29:07.367172 systemd[1]: Initializing machine ID from VM UUID. Feb 13 20:29:07.367184 zram_generator::config[1001]: No configuration found. Feb 13 20:29:07.367198 systemd[1]: Populated /etc with preset unit settings. Feb 13 20:29:07.367210 systemd[1]: initrd-switch-root.service: Deactivated successfully. Feb 13 20:29:07.367225 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Feb 13 20:29:07.367238 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Feb 13 20:29:07.367251 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Feb 13 20:29:07.367264 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Feb 13 20:29:07.367277 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Feb 13 20:29:07.367290 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Feb 13 20:29:07.367303 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Feb 13 20:29:07.367315 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Feb 13 20:29:07.367330 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Feb 13 20:29:07.367343 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Feb 13 20:29:07.367355 systemd[1]: Created slice user.slice - User and Session Slice. Feb 13 20:29:07.367369 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Feb 13 20:29:07.367381 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Feb 13 20:29:07.367393 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Feb 13 20:29:07.367406 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Feb 13 20:29:07.367419 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Feb 13 20:29:07.367432 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Feb 13 20:29:07.367446 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Feb 13 20:29:07.367459 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Feb 13 20:29:07.370835 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Feb 13 20:29:07.370857 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Feb 13 20:29:07.370870 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Feb 13 20:29:07.370883 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Feb 13 20:29:07.370900 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Feb 13 20:29:07.370913 systemd[1]: Reached target remote-fs.target - Remote File Systems. Feb 13 20:29:07.370925 systemd[1]: Reached target slices.target - Slice Units. Feb 13 20:29:07.370938 systemd[1]: Reached target swap.target - Swaps. Feb 13 20:29:07.370950 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Feb 13 20:29:07.370963 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Feb 13 20:29:07.370975 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Feb 13 20:29:07.370988 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Feb 13 20:29:07.371000 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Feb 13 20:29:07.371016 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Feb 13 20:29:07.371031 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Feb 13 20:29:07.371044 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Feb 13 20:29:07.371056 systemd[1]: Mounting media.mount - External Media Directory... Feb 13 20:29:07.371069 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Feb 13 20:29:07.371083 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Feb 13 20:29:07.371095 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Feb 13 20:29:07.371108 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Feb 13 20:29:07.371121 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). Feb 13 20:29:07.371135 systemd[1]: Reached target machines.target - Containers. Feb 13 20:29:07.371148 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Feb 13 20:29:07.371160 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Feb 13 20:29:07.371172 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Feb 13 20:29:07.371185 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Feb 13 20:29:07.371197 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Feb 13 20:29:07.371209 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Feb 13 20:29:07.371221 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Feb 13 20:29:07.371233 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Feb 13 20:29:07.371248 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Feb 13 20:29:07.371260 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Feb 13 20:29:07.371273 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Feb 13 20:29:07.371285 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Feb 13 20:29:07.371297 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Feb 13 20:29:07.371309 systemd[1]: Stopped systemd-fsck-usr.service. Feb 13 20:29:07.371322 systemd[1]: Starting systemd-journald.service - Journal Service... Feb 13 20:29:07.371334 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Feb 13 20:29:07.371347 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Feb 13 20:29:07.371362 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Feb 13 20:29:07.371374 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Feb 13 20:29:07.371387 systemd[1]: verity-setup.service: Deactivated successfully. Feb 13 20:29:07.371399 systemd[1]: Stopped verity-setup.service. Feb 13 20:29:07.371411 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Feb 13 20:29:07.371424 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Feb 13 20:29:07.371436 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Feb 13 20:29:07.371449 systemd[1]: Mounted media.mount - External Media Directory. Feb 13 20:29:07.371464 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Feb 13 20:29:07.371501 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Feb 13 20:29:07.373371 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Feb 13 20:29:07.373387 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Feb 13 20:29:07.373399 systemd[1]: modprobe@configfs.service: Deactivated successfully. Feb 13 20:29:07.373416 kernel: fuse: init (API version 7.39) Feb 13 20:29:07.373429 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Feb 13 20:29:07.373441 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Feb 13 20:29:07.373455 kernel: loop: module loaded Feb 13 20:29:07.373467 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Feb 13 20:29:07.373515 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Feb 13 20:29:07.373547 systemd-journald[1087]: Collecting audit messages is disabled. Feb 13 20:29:07.373576 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Feb 13 20:29:07.373589 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Feb 13 20:29:07.373602 systemd-journald[1087]: Journal started Feb 13 20:29:07.373626 systemd-journald[1087]: Runtime Journal (/run/log/journal/871ef69189ad4fb48c7aea73935d2631) is 8.0M, max 78.3M, 70.3M free. Feb 13 20:29:07.039696 systemd[1]: Queued start job for default target multi-user.target. Feb 13 20:29:07.061605 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Feb 13 20:29:07.061968 systemd[1]: systemd-journald.service: Deactivated successfully. Feb 13 20:29:07.376922 systemd[1]: Started systemd-journald.service - Journal Service. Feb 13 20:29:07.378319 systemd[1]: modprobe@fuse.service: Deactivated successfully. Feb 13 20:29:07.378467 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Feb 13 20:29:07.379173 systemd[1]: modprobe@loop.service: Deactivated successfully. Feb 13 20:29:07.379288 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Feb 13 20:29:07.380124 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Feb 13 20:29:07.381090 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Feb 13 20:29:07.381874 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Feb 13 20:29:07.391362 systemd[1]: Reached target network-pre.target - Preparation for Network. Feb 13 20:29:07.401602 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Feb 13 20:29:07.407536 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Feb 13 20:29:07.409572 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Feb 13 20:29:07.409604 systemd[1]: Reached target local-fs.target - Local File Systems. Feb 13 20:29:07.415612 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Feb 13 20:29:07.427496 kernel: ACPI: bus type drm_connector registered Feb 13 20:29:07.431633 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Feb 13 20:29:07.437560 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Feb 13 20:29:07.438163 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Feb 13 20:29:07.445675 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Feb 13 20:29:07.447217 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Feb 13 20:29:07.447817 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Feb 13 20:29:07.451619 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Feb 13 20:29:07.452701 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Feb 13 20:29:07.454615 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Feb 13 20:29:07.456593 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Feb 13 20:29:07.463582 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Feb 13 20:29:07.467333 systemd-journald[1087]: Time spent on flushing to /var/log/journal/871ef69189ad4fb48c7aea73935d2631 is 41.489ms for 933 entries. Feb 13 20:29:07.467333 systemd-journald[1087]: System Journal (/var/log/journal/871ef69189ad4fb48c7aea73935d2631) is 8.0M, max 584.8M, 576.8M free. Feb 13 20:29:07.521454 systemd-journald[1087]: Received client request to flush runtime journal. Feb 13 20:29:07.468341 systemd[1]: modprobe@drm.service: Deactivated successfully. Feb 13 20:29:07.525335 kernel: loop0: detected capacity change from 0 to 138184 Feb 13 20:29:07.468507 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Feb 13 20:29:07.470105 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Feb 13 20:29:07.473680 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Feb 13 20:29:07.474333 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Feb 13 20:29:07.475053 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Feb 13 20:29:07.490690 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Feb 13 20:29:07.527927 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Feb 13 20:29:07.528798 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Feb 13 20:29:07.530226 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Feb 13 20:29:07.536223 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Feb 13 20:29:07.543814 udevadm[1141]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Feb 13 20:29:07.558824 systemd-tmpfiles[1134]: ACLs are not supported, ignoring. Feb 13 20:29:07.558837 systemd-tmpfiles[1134]: ACLs are not supported, ignoring. Feb 13 20:29:07.568599 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Feb 13 20:29:07.574901 systemd[1]: Starting systemd-sysusers.service - Create System Users... Feb 13 20:29:07.585764 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Feb 13 20:29:07.591992 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Feb 13 20:29:07.592610 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Feb 13 20:29:07.618500 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Feb 13 20:29:07.646590 kernel: loop1: detected capacity change from 0 to 141000 Feb 13 20:29:07.656300 systemd[1]: Finished systemd-sysusers.service - Create System Users. Feb 13 20:29:07.662772 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Feb 13 20:29:07.676065 systemd-tmpfiles[1156]: ACLs are not supported, ignoring. Feb 13 20:29:07.676357 systemd-tmpfiles[1156]: ACLs are not supported, ignoring. Feb 13 20:29:07.680554 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Feb 13 20:29:07.714503 kernel: loop2: detected capacity change from 0 to 8 Feb 13 20:29:07.735461 kernel: loop3: detected capacity change from 0 to 138184 Feb 13 20:29:07.804500 kernel: loop4: detected capacity change from 0 to 141000 Feb 13 20:29:07.851523 kernel: loop5: detected capacity change from 0 to 8 Feb 13 20:29:07.851927 (sd-merge)[1161]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-openstack'. Feb 13 20:29:07.852282 (sd-merge)[1161]: Merged extensions into '/usr'. Feb 13 20:29:07.858888 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Feb 13 20:29:07.865638 systemd[1]: Starting ensure-sysext.service... Feb 13 20:29:07.867033 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Feb 13 20:29:07.894938 systemd[1]: Reloading requested from client PID 1163 ('systemctl') (unit ensure-sysext.service)... Feb 13 20:29:07.894957 systemd[1]: Reloading... Feb 13 20:29:07.897384 systemd-tmpfiles[1164]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Feb 13 20:29:07.898096 systemd-tmpfiles[1164]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Feb 13 20:29:07.898918 systemd-tmpfiles[1164]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Feb 13 20:29:07.899203 systemd-tmpfiles[1164]: ACLs are not supported, ignoring. Feb 13 20:29:07.899261 systemd-tmpfiles[1164]: ACLs are not supported, ignoring. Feb 13 20:29:07.915047 systemd-tmpfiles[1164]: Detected autofs mount point /boot during canonicalization of boot. Feb 13 20:29:07.915226 systemd-tmpfiles[1164]: Skipping /boot Feb 13 20:29:07.928304 systemd-tmpfiles[1164]: Detected autofs mount point /boot during canonicalization of boot. Feb 13 20:29:07.928465 systemd-tmpfiles[1164]: Skipping /boot Feb 13 20:29:07.995510 zram_generator::config[1189]: No configuration found. Feb 13 20:29:08.209684 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. Feb 13 20:29:08.266877 systemd[1]: Reloading finished in 371 ms. Feb 13 20:29:08.293348 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Feb 13 20:29:08.303659 systemd[1]: Starting audit-rules.service - Load Audit Rules... Feb 13 20:29:08.308622 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Feb 13 20:29:08.313755 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Feb 13 20:29:08.324872 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Feb 13 20:29:08.327596 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Feb 13 20:29:08.341063 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Feb 13 20:29:08.341260 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Feb 13 20:29:08.349681 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Feb 13 20:29:08.355441 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Feb 13 20:29:08.368247 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Feb 13 20:29:08.369085 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Feb 13 20:29:08.369222 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Feb 13 20:29:08.370311 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Feb 13 20:29:08.371829 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Feb 13 20:29:08.373145 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Feb 13 20:29:08.373358 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Feb 13 20:29:08.376978 systemd[1]: modprobe@loop.service: Deactivated successfully. Feb 13 20:29:08.377140 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Feb 13 20:29:08.388133 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Feb 13 20:29:08.388372 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Feb 13 20:29:08.393703 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Feb 13 20:29:08.398782 ldconfig[1128]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Feb 13 20:29:08.402779 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Feb 13 20:29:08.405742 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Feb 13 20:29:08.407039 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Feb 13 20:29:08.411685 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Feb 13 20:29:08.412518 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Feb 13 20:29:08.415803 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Feb 13 20:29:08.416928 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Feb 13 20:29:08.417873 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Feb 13 20:29:08.418024 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Feb 13 20:29:08.420016 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Feb 13 20:29:08.420135 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Feb 13 20:29:08.425772 systemd[1]: modprobe@loop.service: Deactivated successfully. Feb 13 20:29:08.426404 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Feb 13 20:29:08.439388 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Feb 13 20:29:08.440413 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Feb 13 20:29:08.449749 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Feb 13 20:29:08.452653 augenrules[1289]: No rules Feb 13 20:29:08.458212 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Feb 13 20:29:08.460715 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Feb 13 20:29:08.463718 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Feb 13 20:29:08.464368 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Feb 13 20:29:08.464562 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Feb 13 20:29:08.466609 systemd[1]: audit-rules.service: Deactivated successfully. Feb 13 20:29:08.466790 systemd[1]: Finished audit-rules.service - Load Audit Rules. Feb 13 20:29:08.467688 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Feb 13 20:29:08.469670 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Feb 13 20:29:08.469784 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Feb 13 20:29:08.473325 systemd[1]: Finished ensure-sysext.service. Feb 13 20:29:08.483676 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Feb 13 20:29:08.484559 systemd[1]: modprobe@drm.service: Deactivated successfully. Feb 13 20:29:08.485577 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Feb 13 20:29:08.498723 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Feb 13 20:29:08.510188 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Feb 13 20:29:08.516628 systemd[1]: Starting systemd-update-done.service - Update is Completed... Feb 13 20:29:08.517408 systemd[1]: Started systemd-userdbd.service - User Database Manager. Feb 13 20:29:08.519132 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Feb 13 20:29:08.519295 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Feb 13 20:29:08.520783 systemd[1]: modprobe@loop.service: Deactivated successfully. Feb 13 20:29:08.520910 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Feb 13 20:29:08.524027 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Feb 13 20:29:08.524101 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Feb 13 20:29:08.529195 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Feb 13 20:29:08.529976 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). Feb 13 20:29:08.547120 systemd-udevd[1305]: Using default interface naming scheme 'v255'. Feb 13 20:29:08.556029 systemd[1]: Finished systemd-update-done.service - Update is Completed. Feb 13 20:29:08.587575 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Feb 13 20:29:08.597644 systemd[1]: Starting systemd-networkd.service - Network Configuration... Feb 13 20:29:08.598583 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Feb 13 20:29:08.600243 systemd[1]: Reached target time-set.target - System Time Set. Feb 13 20:29:08.606172 systemd-resolved[1250]: Positive Trust Anchors: Feb 13 20:29:08.606446 systemd-resolved[1250]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Feb 13 20:29:08.606563 systemd-resolved[1250]: 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 Feb 13 20:29:08.611884 systemd-resolved[1250]: Using system hostname 'ci-4186-1-1-8-cbfa5c72c7.novalocal'. Feb 13 20:29:08.615171 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Feb 13 20:29:08.618906 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Feb 13 20:29:08.659664 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Feb 13 20:29:08.674496 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (1319) Feb 13 20:29:08.685203 systemd-networkd[1324]: lo: Link UP Feb 13 20:29:08.685212 systemd-networkd[1324]: lo: Gained carrier Feb 13 20:29:08.686255 systemd-networkd[1324]: Enumeration completed Feb 13 20:29:08.686349 systemd[1]: Started systemd-networkd.service - Network Configuration. Feb 13 20:29:08.687340 systemd[1]: Reached target network.target - Network. Feb 13 20:29:08.692363 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Feb 13 20:29:08.742501 kernel: piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 Feb 13 20:29:08.759891 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 Feb 13 20:29:08.759918 kernel: ACPI: button: Power Button [PWRF] Feb 13 20:29:08.760263 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Feb 13 20:29:08.764281 systemd-networkd[1324]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Feb 13 20:29:08.764291 systemd-networkd[1324]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Feb 13 20:29:08.765618 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Feb 13 20:29:08.768550 systemd-networkd[1324]: eth0: Link UP Feb 13 20:29:08.768567 systemd-networkd[1324]: eth0: Gained carrier Feb 13 20:29:08.768586 systemd-networkd[1324]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Feb 13 20:29:08.783594 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 Feb 13 20:29:08.783737 systemd-networkd[1324]: eth0: DHCPv4 address 172.24.4.236/24, gateway 172.24.4.1 acquired from 172.24.4.1 Feb 13 20:29:08.784840 systemd-timesyncd[1301]: Network configuration changed, trying to establish connection. Feb 13 20:29:08.800350 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Feb 13 20:29:08.816503 kernel: mousedev: PS/2 mouse device common for all mice Feb 13 20:29:08.825197 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Feb 13 20:29:08.828701 kernel: [drm] pci: virtio-vga detected at 0000:00:02.0 Feb 13 20:29:08.828769 kernel: virtio-pci 0000:00:02.0: vgaarb: deactivate vga console Feb 13 20:29:08.834618 kernel: Console: switching to colour dummy device 80x25 Feb 13 20:29:08.838021 kernel: [drm] features: -virgl +edid -resource_blob -host_visible Feb 13 20:29:08.838060 kernel: [drm] features: -context_init Feb 13 20:29:08.838085 kernel: [drm] number of scanouts: 1 Feb 13 20:29:08.838499 kernel: [drm] number of cap sets: 0 Feb 13 20:29:08.842491 kernel: [drm] Initialized virtio_gpu 0.1.0 0 for 0000:00:02.0 on minor 0 Feb 13 20:29:08.844083 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Feb 13 20:29:08.844320 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Feb 13 20:29:08.848503 kernel: fbcon: virtio_gpudrmfb (fb0) is primary device Feb 13 20:29:08.856521 kernel: Console: switching to colour frame buffer device 160x50 Feb 13 20:29:08.857373 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Feb 13 20:29:08.866177 kernel: virtio-pci 0000:00:02.0: [drm] fb0: virtio_gpudrmfb frame buffer device Feb 13 20:29:08.870420 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Feb 13 20:29:08.870844 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Feb 13 20:29:08.878693 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Feb 13 20:29:08.880276 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Feb 13 20:29:08.883168 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Feb 13 20:29:08.901016 lvm[1363]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Feb 13 20:29:08.934211 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Feb 13 20:29:08.934468 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Feb 13 20:29:08.940616 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Feb 13 20:29:08.947541 lvm[1368]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Feb 13 20:29:08.956001 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Feb 13 20:29:08.957459 systemd[1]: Reached target sysinit.target - System Initialization. Feb 13 20:29:08.957659 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Feb 13 20:29:08.957755 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Feb 13 20:29:08.959056 systemd[1]: Started logrotate.timer - Daily rotation of log files. Feb 13 20:29:08.959339 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Feb 13 20:29:08.959415 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Feb 13 20:29:08.960334 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Feb 13 20:29:08.960368 systemd[1]: Reached target paths.target - Path Units. Feb 13 20:29:08.960427 systemd[1]: Reached target timers.target - Timer Units. Feb 13 20:29:08.962931 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Feb 13 20:29:08.964100 systemd[1]: Starting docker.socket - Docker Socket for the API... Feb 13 20:29:08.969722 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Feb 13 20:29:08.970608 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Feb 13 20:29:08.971467 systemd[1]: Listening on docker.socket - Docker Socket for the API. Feb 13 20:29:08.972155 systemd[1]: Reached target sockets.target - Socket Units. Feb 13 20:29:08.973079 systemd[1]: Reached target basic.target - Basic System. Feb 13 20:29:08.974880 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Feb 13 20:29:08.975001 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Feb 13 20:29:08.981603 systemd[1]: Starting containerd.service - containerd container runtime... Feb 13 20:29:08.985884 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Feb 13 20:29:08.992754 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Feb 13 20:29:09.003719 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Feb 13 20:29:09.015603 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Feb 13 20:29:09.020997 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Feb 13 20:29:09.021590 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). Feb 13 20:29:09.026699 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Feb 13 20:29:09.031814 jq[1380]: false Feb 13 20:29:09.037635 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Feb 13 20:29:09.043624 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Feb 13 20:29:09.055639 systemd[1]: Starting systemd-logind.service - User Login Management... Feb 13 20:29:09.060721 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Feb 13 20:29:09.061263 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Feb 13 20:29:09.061346 extend-filesystems[1383]: Found loop3 Feb 13 20:29:09.063594 extend-filesystems[1383]: Found loop4 Feb 13 20:29:09.063594 extend-filesystems[1383]: Found loop5 Feb 13 20:29:09.063594 extend-filesystems[1383]: Found vda Feb 13 20:29:09.063594 extend-filesystems[1383]: Found vda1 Feb 13 20:29:09.063594 extend-filesystems[1383]: Found vda2 Feb 13 20:29:09.063594 extend-filesystems[1383]: Found vda3 Feb 13 20:29:09.063594 extend-filesystems[1383]: Found usr Feb 13 20:29:09.063594 extend-filesystems[1383]: Found vda4 Feb 13 20:29:09.063594 extend-filesystems[1383]: Found vda6 Feb 13 20:29:09.063594 extend-filesystems[1383]: Found vda7 Feb 13 20:29:09.063594 extend-filesystems[1383]: Found vda9 Feb 13 20:29:09.063594 extend-filesystems[1383]: Checking size of /dev/vda9 Feb 13 20:29:09.117467 extend-filesystems[1383]: Resized partition /dev/vda9 Feb 13 20:29:09.172898 kernel: EXT4-fs (vda9): resizing filesystem from 1617920 to 2014203 blocks Feb 13 20:29:09.172941 kernel: EXT4-fs (vda9): resized filesystem to 2014203 Feb 13 20:29:09.172966 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (1323) Feb 13 20:29:09.066189 dbus-daemon[1379]: [system] SELinux support is enabled Feb 13 20:29:09.079643 systemd[1]: Starting update-engine.service - Update Engine... Feb 13 20:29:09.173288 coreos-metadata[1378]: Feb 13 20:29:09.089 INFO Fetching http://169.254.169.254/openstack/2012-08-10/meta_data.json: Attempt #1 Feb 13 20:29:09.173288 coreos-metadata[1378]: Feb 13 20:29:09.140 INFO Fetch successful Feb 13 20:29:09.173288 coreos-metadata[1378]: Feb 13 20:29:09.140 INFO Fetching http://169.254.169.254/latest/meta-data/hostname: Attempt #1 Feb 13 20:29:09.173288 coreos-metadata[1378]: Feb 13 20:29:09.150 INFO Fetch successful Feb 13 20:29:09.173288 coreos-metadata[1378]: Feb 13 20:29:09.150 INFO Fetching http://169.254.169.254/latest/meta-data/instance-id: Attempt #1 Feb 13 20:29:09.173288 coreos-metadata[1378]: Feb 13 20:29:09.167 INFO Fetch successful Feb 13 20:29:09.173288 coreos-metadata[1378]: Feb 13 20:29:09.167 INFO Fetching http://169.254.169.254/latest/meta-data/instance-type: Attempt #1 Feb 13 20:29:09.173676 extend-filesystems[1394]: resize2fs 1.47.1 (20-May-2024) Feb 13 20:29:09.176645 coreos-metadata[1375]: Feb 13 20:29:09.139 INFO Fetching http://169.254.169.254/latest/meta-data/public-keys: Attempt #1 Feb 13 20:29:09.176645 coreos-metadata[1375]: Feb 13 20:29:09.160 INFO Fetch successful Feb 13 20:29:09.176645 coreos-metadata[1375]: Feb 13 20:29:09.160 INFO Fetching http://169.254.169.254/latest/meta-data/public-keys/0/openssh-key: Attempt #1 Feb 13 20:29:09.102710 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Feb 13 20:29:09.104803 systemd[1]: Started dbus.service - D-Bus System Message Bus. Feb 13 20:29:09.177409 update_engine[1390]: I20250213 20:29:09.144892 1390 main.cc:92] Flatcar Update Engine starting Feb 13 20:29:09.177409 update_engine[1390]: I20250213 20:29:09.165827 1390 update_check_scheduler.cc:74] Next update check in 9m29s Feb 13 20:29:09.116911 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Feb 13 20:29:09.178363 extend-filesystems[1394]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Feb 13 20:29:09.178363 extend-filesystems[1394]: old_desc_blocks = 1, new_desc_blocks = 1 Feb 13 20:29:09.178363 extend-filesystems[1394]: The filesystem on /dev/vda9 is now 2014203 (4k) blocks long. Feb 13 20:29:09.117094 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Feb 13 20:29:09.187763 coreos-metadata[1375]: Feb 13 20:29:09.179 INFO Fetch successful Feb 13 20:29:09.187819 extend-filesystems[1383]: Resized filesystem in /dev/vda9 Feb 13 20:29:09.192549 coreos-metadata[1378]: Feb 13 20:29:09.183 INFO Fetch successful Feb 13 20:29:09.192549 coreos-metadata[1378]: Feb 13 20:29:09.183 INFO Fetching http://169.254.169.254/latest/meta-data/local-ipv4: Attempt #1 Feb 13 20:29:09.117365 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Feb 13 20:29:09.118074 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Feb 13 20:29:09.123803 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). Feb 13 20:29:09.196775 jq[1395]: true Feb 13 20:29:09.123828 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Feb 13 20:29:09.142637 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). Feb 13 20:29:09.142660 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Feb 13 20:29:09.167719 systemd[1]: Started update-engine.service - Update Engine. Feb 13 20:29:09.179250 systemd[1]: extend-filesystems.service: Deactivated successfully. Feb 13 20:29:09.182467 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Feb 13 20:29:09.184288 (ntainerd)[1404]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Feb 13 20:29:09.202597 coreos-metadata[1378]: Feb 13 20:29:09.199 INFO Fetch successful Feb 13 20:29:09.202597 coreos-metadata[1378]: Feb 13 20:29:09.200 INFO Fetching http://169.254.169.254/latest/meta-data/public-ipv4: Attempt #1 Feb 13 20:29:09.200585 unknown[1375]: wrote ssh authorized keys file for user: core Feb 13 20:29:09.205774 systemd[1]: Started locksmithd.service - Cluster reboot manager. Feb 13 20:29:09.221438 coreos-metadata[1378]: Feb 13 20:29:09.214 INFO Fetch successful Feb 13 20:29:09.229542 systemd[1]: motdgen.service: Deactivated successfully. Feb 13 20:29:09.230589 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Feb 13 20:29:09.246421 jq[1405]: true Feb 13 20:29:09.259379 systemd-logind[1388]: New seat seat0. Feb 13 20:29:09.270598 systemd-logind[1388]: Watching system buttons on /dev/input/event1 (Power Button) Feb 13 20:29:09.270620 systemd-logind[1388]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Feb 13 20:29:09.270980 systemd[1]: Started systemd-logind.service - User Login Management. Feb 13 20:29:09.276028 update-ssh-keys[1418]: Updated "/home/core/.ssh/authorized_keys" Feb 13 20:29:09.278236 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Feb 13 20:29:09.289542 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Feb 13 20:29:09.290381 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Feb 13 20:29:09.394127 bash[1442]: Updated "/home/core/.ssh/authorized_keys" Feb 13 20:29:09.396739 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Feb 13 20:29:09.405495 locksmithd[1414]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Feb 13 20:29:09.413698 systemd[1]: Starting sshkeys.service... Feb 13 20:29:09.422433 systemd[1]: Finished sshkeys.service. Feb 13 20:29:09.581035 containerd[1404]: time="2025-02-13T20:29:09.580905545Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Feb 13 20:29:09.618601 containerd[1404]: time="2025-02-13T20:29:09.618533165Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Feb 13 20:29:09.623848 containerd[1404]: time="2025-02-13T20:29:09.623814541Z" 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.74-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Feb 13 20:29:09.623848 containerd[1404]: time="2025-02-13T20:29:09.623846310Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Feb 13 20:29:09.623907 containerd[1404]: time="2025-02-13T20:29:09.623863122Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Feb 13 20:29:09.624483 containerd[1404]: time="2025-02-13T20:29:09.624015277Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Feb 13 20:29:09.624483 containerd[1404]: time="2025-02-13T20:29:09.624038771Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Feb 13 20:29:09.624483 containerd[1404]: time="2025-02-13T20:29:09.624101048Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Feb 13 20:29:09.624483 containerd[1404]: time="2025-02-13T20:29:09.624115936Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Feb 13 20:29:09.624483 containerd[1404]: time="2025-02-13T20:29:09.624276767Z" 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 Feb 13 20:29:09.624483 containerd[1404]: time="2025-02-13T20:29:09.624294691Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Feb 13 20:29:09.624483 containerd[1404]: time="2025-02-13T20:29:09.624309108Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Feb 13 20:29:09.624483 containerd[1404]: time="2025-02-13T20:29:09.624319688Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Feb 13 20:29:09.624483 containerd[1404]: time="2025-02-13T20:29:09.624394478Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Feb 13 20:29:09.624700 containerd[1404]: time="2025-02-13T20:29:09.624621825Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Feb 13 20:29:09.624771 containerd[1404]: time="2025-02-13T20:29:09.624746318Z" 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 Feb 13 20:29:09.624771 containerd[1404]: time="2025-02-13T20:29:09.624767889Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Feb 13 20:29:09.624869 containerd[1404]: time="2025-02-13T20:29:09.624849091Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Feb 13 20:29:09.624923 containerd[1404]: time="2025-02-13T20:29:09.624904375Z" level=info msg="metadata content store policy set" policy=shared Feb 13 20:29:09.633989 containerd[1404]: time="2025-02-13T20:29:09.633962413Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Feb 13 20:29:09.634030 containerd[1404]: time="2025-02-13T20:29:09.634016905Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Feb 13 20:29:09.634053 containerd[1404]: time="2025-02-13T20:29:09.634037203Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Feb 13 20:29:09.636484 containerd[1404]: time="2025-02-13T20:29:09.634054776Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Feb 13 20:29:09.636484 containerd[1404]: time="2025-02-13T20:29:09.634119648Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Feb 13 20:29:09.636484 containerd[1404]: time="2025-02-13T20:29:09.634247317Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Feb 13 20:29:09.636484 containerd[1404]: time="2025-02-13T20:29:09.634595520Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Feb 13 20:29:09.636484 containerd[1404]: time="2025-02-13T20:29:09.634692142Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Feb 13 20:29:09.636484 containerd[1404]: time="2025-02-13T20:29:09.634710235Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Feb 13 20:29:09.636484 containerd[1404]: time="2025-02-13T20:29:09.634725204Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Feb 13 20:29:09.636484 containerd[1404]: time="2025-02-13T20:29:09.634739811Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Feb 13 20:29:09.636484 containerd[1404]: time="2025-02-13T20:29:09.634754248Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Feb 13 20:29:09.636484 containerd[1404]: time="2025-02-13T20:29:09.634767343Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Feb 13 20:29:09.636484 containerd[1404]: time="2025-02-13T20:29:09.634781148Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Feb 13 20:29:09.636484 containerd[1404]: time="2025-02-13T20:29:09.634796046Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Feb 13 20:29:09.636484 containerd[1404]: time="2025-02-13T20:29:09.634809121Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Feb 13 20:29:09.636484 containerd[1404]: time="2025-02-13T20:29:09.634822055Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Feb 13 20:29:09.636789 containerd[1404]: time="2025-02-13T20:29:09.634833988Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Feb 13 20:29:09.636789 containerd[1404]: time="2025-02-13T20:29:09.634855207Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Feb 13 20:29:09.636789 containerd[1404]: time="2025-02-13T20:29:09.634871027Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Feb 13 20:29:09.636789 containerd[1404]: time="2025-02-13T20:29:09.634883931Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Feb 13 20:29:09.636789 containerd[1404]: time="2025-02-13T20:29:09.634897226Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Feb 13 20:29:09.636789 containerd[1404]: time="2025-02-13T20:29:09.634911753Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Feb 13 20:29:09.636789 containerd[1404]: time="2025-02-13T20:29:09.634925008Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Feb 13 20:29:09.636789 containerd[1404]: time="2025-02-13T20:29:09.634939375Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Feb 13 20:29:09.636789 containerd[1404]: time="2025-02-13T20:29:09.634953151Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Feb 13 20:29:09.636789 containerd[1404]: time="2025-02-13T20:29:09.634966666Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Feb 13 20:29:09.636789 containerd[1404]: time="2025-02-13T20:29:09.634982306Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Feb 13 20:29:09.636789 containerd[1404]: time="2025-02-13T20:29:09.634995390Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Feb 13 20:29:09.636789 containerd[1404]: time="2025-02-13T20:29:09.635007934Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Feb 13 20:29:09.636789 containerd[1404]: time="2025-02-13T20:29:09.635021108Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Feb 13 20:29:09.636789 containerd[1404]: time="2025-02-13T20:29:09.635036387Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Feb 13 20:29:09.637124 containerd[1404]: time="2025-02-13T20:29:09.635056866Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Feb 13 20:29:09.637124 containerd[1404]: time="2025-02-13T20:29:09.635093194Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Feb 13 20:29:09.637124 containerd[1404]: time="2025-02-13T20:29:09.635106719Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Feb 13 20:29:09.637124 containerd[1404]: time="2025-02-13T20:29:09.635159558Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Feb 13 20:29:09.637124 containerd[1404]: time="2025-02-13T20:29:09.635178464Z" 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 Feb 13 20:29:09.637124 containerd[1404]: time="2025-02-13T20:29:09.635195195Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Feb 13 20:29:09.637124 containerd[1404]: time="2025-02-13T20:29:09.635256770Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Feb 13 20:29:09.637124 containerd[1404]: time="2025-02-13T20:29:09.635271248Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Feb 13 20:29:09.637124 containerd[1404]: time="2025-02-13T20:29:09.635288660Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Feb 13 20:29:09.637124 containerd[1404]: time="2025-02-13T20:29:09.635299420Z" level=info msg="NRI interface is disabled by configuration." Feb 13 20:29:09.637124 containerd[1404]: time="2025-02-13T20:29:09.635309940Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Feb 13 20:29:09.637353 containerd[1404]: time="2025-02-13T20:29:09.635627606Z" 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}" Feb 13 20:29:09.637353 containerd[1404]: time="2025-02-13T20:29:09.635681758Z" level=info msg="Connect containerd service" Feb 13 20:29:09.637353 containerd[1404]: time="2025-02-13T20:29:09.635707917Z" level=info msg="using legacy CRI server" Feb 13 20:29:09.637353 containerd[1404]: time="2025-02-13T20:29:09.635715130Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Feb 13 20:29:09.637353 containerd[1404]: time="2025-02-13T20:29:09.636718722Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Feb 13 20:29:09.638392 containerd[1404]: time="2025-02-13T20:29:09.638341406Z" 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" Feb 13 20:29:09.638457 containerd[1404]: time="2025-02-13T20:29:09.638422768Z" level=info msg="Start subscribing containerd event" Feb 13 20:29:09.640491 containerd[1404]: time="2025-02-13T20:29:09.638654062Z" level=info msg="Start recovering state" Feb 13 20:29:09.640491 containerd[1404]: time="2025-02-13T20:29:09.638719345Z" level=info msg="Start event monitor" Feb 13 20:29:09.640491 containerd[1404]: time="2025-02-13T20:29:09.638735625Z" level=info msg="Start snapshots syncer" Feb 13 20:29:09.640491 containerd[1404]: time="2025-02-13T20:29:09.638744512Z" level=info msg="Start cni network conf syncer for default" Feb 13 20:29:09.640491 containerd[1404]: time="2025-02-13T20:29:09.638751966Z" level=info msg="Start streaming server" Feb 13 20:29:09.640491 containerd[1404]: time="2025-02-13T20:29:09.638979743Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Feb 13 20:29:09.640491 containerd[1404]: time="2025-02-13T20:29:09.639049023Z" level=info msg=serving... address=/run/containerd/containerd.sock Feb 13 20:29:09.640491 containerd[1404]: time="2025-02-13T20:29:09.639131538Z" level=info msg="containerd successfully booted in 0.059191s" Feb 13 20:29:09.639211 systemd[1]: Started containerd.service - containerd container runtime. Feb 13 20:29:09.744040 sshd_keygen[1412]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Feb 13 20:29:09.795141 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Feb 13 20:29:09.804085 systemd[1]: Starting issuegen.service - Generate /run/issue... Feb 13 20:29:09.809335 systemd[1]: issuegen.service: Deactivated successfully. Feb 13 20:29:09.809621 systemd[1]: Finished issuegen.service - Generate /run/issue. Feb 13 20:29:09.823543 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Feb 13 20:29:09.833691 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Feb 13 20:29:09.849163 systemd[1]: Started getty@tty1.service - Getty on tty1. Feb 13 20:29:09.858138 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Feb 13 20:29:09.862302 systemd[1]: Reached target getty.target - Login Prompts. Feb 13 20:29:10.210842 systemd-networkd[1324]: eth0: Gained IPv6LL Feb 13 20:29:10.211983 systemd-timesyncd[1301]: Network configuration changed, trying to establish connection. Feb 13 20:29:10.215729 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Feb 13 20:29:10.218799 systemd[1]: Reached target network-online.target - Network is Online. Feb 13 20:29:10.231074 systemd[1]: Starting etcd-member.service - etcd (System Application Container)... Feb 13 20:29:10.234833 (-wrapper)[1476]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS Feb 13 20:29:10.240941 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Feb 13 20:29:10.295918 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Feb 13 20:29:10.479021 systemd[1]: Starting docker.service - Docker Application Container Engine... Feb 13 20:29:10.483870 (dockerd)[1494]: 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 Feb 13 20:29:11.118057 dockerd[1494]: time="2025-02-13T20:29:11.117956664Z" level=info msg="Starting up" Feb 13 20:29:11.305513 systemd[1]: var-lib-docker-check\x2doverlayfs\x2dsupport922888403-merged.mount: Deactivated successfully. Feb 13 20:29:11.382013 dockerd[1494]: time="2025-02-13T20:29:11.381844686Z" level=info msg="Loading containers: start." Feb 13 20:29:11.644617 kernel: Initializing XFRM netlink socket Feb 13 20:29:11.689624 systemd-timesyncd[1301]: Network configuration changed, trying to establish connection. Feb 13 20:29:11.690094 systemd-timesyncd[1301]: Network configuration changed, trying to establish connection. Feb 13 20:29:11.790262 systemd-networkd[1324]: docker0: Link UP Feb 13 20:29:11.790818 systemd-timesyncd[1301]: Network configuration changed, trying to establish connection. Feb 13 20:29:11.845752 dockerd[1494]: time="2025-02-13T20:29:11.845668247Z" level=info msg="Loading containers: done." Feb 13 20:29:11.882428 dockerd[1494]: time="2025-02-13T20:29:11.882352688Z" 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 Feb 13 20:29:11.882782 dockerd[1494]: time="2025-02-13T20:29:11.882547183Z" level=info msg="Docker daemon" commit=41ca978a0a5400cc24b274137efa9f25517fcc0b containerd-snapshotter=false storage-driver=overlay2 version=27.3.1 Feb 13 20:29:11.882782 dockerd[1494]: time="2025-02-13T20:29:11.882751196Z" level=info msg="Daemon has completed initialization" Feb 13 20:29:11.948522 dockerd[1494]: time="2025-02-13T20:29:11.948117400Z" level=info msg="API listen on /run/docker.sock" Feb 13 20:29:11.948941 systemd[1]: Started docker.service - Docker Application Container Engine. Feb 13 20:29:11.955596 etcd-wrapper[1481]: Error response from daemon: No such container: etcd-member Feb 13 20:29:11.978969 etcd-wrapper[1669]: Error response from daemon: No such container: etcd-member Feb 13 20:29:12.034509 etcd-wrapper[1687]: Unable to find image 'quay.io/coreos/etcd:v3.5.16' locally Feb 13 20:29:12.302995 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck4230719610-merged.mount: Deactivated successfully. Feb 13 20:29:13.463845 etcd-wrapper[1687]: v3.5.16: Pulling from coreos/etcd Feb 13 20:29:13.749794 etcd-wrapper[1687]: 804c8aba2cc6: Pulling fs layer Feb 13 20:29:13.750053 etcd-wrapper[1687]: 2ae710cd8bfe: Pulling fs layer Feb 13 20:29:13.751246 etcd-wrapper[1687]: d462aa345367: Pulling fs layer Feb 13 20:29:13.752104 etcd-wrapper[1687]: 0f8b424aa0b9: Pulling fs layer Feb 13 20:29:13.752104 etcd-wrapper[1687]: d557676654e5: Pulling fs layer Feb 13 20:29:13.752104 etcd-wrapper[1687]: c8022d07192e: Pulling fs layer Feb 13 20:29:13.752104 etcd-wrapper[1687]: d858cbc252ad: Pulling fs layer Feb 13 20:29:13.752104 etcd-wrapper[1687]: 1069fc2daed1: Pulling fs layer Feb 13 20:29:13.752104 etcd-wrapper[1687]: b40161cd83fc: Pulling fs layer Feb 13 20:29:13.752104 etcd-wrapper[1687]: 5318d93a3a65: Pulling fs layer Feb 13 20:29:13.752104 etcd-wrapper[1687]: 307c1adadb60: Pulling fs layer Feb 13 20:29:13.752104 etcd-wrapper[1687]: fbb01d9e9dc9: Pulling fs layer Feb 13 20:29:13.752104 etcd-wrapper[1687]: fbfea02ac3cf: Pulling fs layer Feb 13 20:29:13.753312 etcd-wrapper[1687]: 8c26e4bf18e2: Pulling fs layer Feb 13 20:29:13.753312 etcd-wrapper[1687]: 1e59a65f8816: Pulling fs layer Feb 13 20:29:13.753312 etcd-wrapper[1687]: ffbd4ca5f0bd: Pulling fs layer Feb 13 20:29:13.753312 etcd-wrapper[1687]: b40161cd83fc: Waiting Feb 13 20:29:13.753312 etcd-wrapper[1687]: 5318d93a3a65: Waiting Feb 13 20:29:13.753312 etcd-wrapper[1687]: 307c1adadb60: Waiting Feb 13 20:29:13.753312 etcd-wrapper[1687]: fbb01d9e9dc9: Waiting Feb 13 20:29:13.753312 etcd-wrapper[1687]: fbfea02ac3cf: Waiting Feb 13 20:29:13.753312 etcd-wrapper[1687]: 8c26e4bf18e2: Waiting Feb 13 20:29:13.753312 etcd-wrapper[1687]: 1e59a65f8816: Waiting Feb 13 20:29:13.753312 etcd-wrapper[1687]: ffbd4ca5f0bd: Waiting Feb 13 20:29:13.753312 etcd-wrapper[1687]: 0f8b424aa0b9: Waiting Feb 13 20:29:13.753312 etcd-wrapper[1687]: d557676654e5: Waiting Feb 13 20:29:13.753312 etcd-wrapper[1687]: c8022d07192e: Waiting Feb 13 20:29:13.753312 etcd-wrapper[1687]: d858cbc252ad: Waiting Feb 13 20:29:13.753312 etcd-wrapper[1687]: 1069fc2daed1: Waiting Feb 13 20:29:14.092616 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Feb 13 20:29:14.102017 systemd[1]: Started sshd@0-172.24.4.236:22-172.24.4.1:34322.service - OpenSSH per-connection server daemon (172.24.4.1:34322). Feb 13 20:29:14.237072 etcd-wrapper[1687]: 804c8aba2cc6: Download complete Feb 13 20:29:14.308841 etcd-wrapper[1687]: 804c8aba2cc6: Pull complete Feb 13 20:29:14.323623 etcd-wrapper[1687]: 2ae710cd8bfe: Verifying Checksum Feb 13 20:29:14.323623 etcd-wrapper[1687]: 2ae710cd8bfe: Download complete Feb 13 20:29:14.357445 systemd[1]: var-lib-docker-overlay2-6f65b652eaf35dedb234c5245aa6b83963bc09fb00742581c4b83353aac6bc24-merged.mount: Deactivated successfully. Feb 13 20:29:14.382971 etcd-wrapper[1687]: 2ae710cd8bfe: Pull complete Feb 13 20:29:14.524305 etcd-wrapper[1687]: 0f8b424aa0b9: Verifying Checksum Feb 13 20:29:14.525339 etcd-wrapper[1687]: 0f8b424aa0b9: Download complete Feb 13 20:29:14.679843 etcd-wrapper[1687]: d557676654e5: Verifying Checksum Feb 13 20:29:14.680265 etcd-wrapper[1687]: d557676654e5: Download complete Feb 13 20:29:14.801963 etcd-wrapper[1687]: c8022d07192e: Verifying Checksum Feb 13 20:29:14.801963 etcd-wrapper[1687]: c8022d07192e: Download complete Feb 13 20:29:14.879150 agetty[1474]: failed to open credentials directory Feb 13 20:29:14.879250 agetty[1473]: failed to open credentials directory Feb 13 20:29:14.915910 login[1474]: pam_lastlog(login:session): file /var/log/lastlog is locked/read, retrying Feb 13 20:29:14.917323 login[1473]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Feb 13 20:29:14.940214 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Feb 13 20:29:14.948994 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Feb 13 20:29:14.957842 systemd-logind[1388]: New session 1 of user core. Feb 13 20:29:14.981955 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Feb 13 20:29:15.000010 systemd[1]: Starting user@500.service - User Manager for UID 500... Feb 13 20:29:15.006571 (systemd)[1715]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Feb 13 20:29:15.052764 etcd-wrapper[1687]: d858cbc252ad: Verifying Checksum Feb 13 20:29:15.052764 etcd-wrapper[1687]: d858cbc252ad: Download complete Feb 13 20:29:15.082692 etcd-wrapper[1687]: 1069fc2daed1: Download complete Feb 13 20:29:15.091227 sshd[1696]: Accepted publickey for core from 172.24.4.1 port 34322 ssh2: RSA SHA256:DQx/ha0c4y+zAdiiwFxJIsZYU5g5yAmzkyorgcgXCvo Feb 13 20:29:15.091958 sshd-session[1696]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Feb 13 20:29:15.099879 systemd-logind[1388]: New session 3 of user core. Feb 13 20:29:15.128670 systemd[1715]: Queued start job for default target default.target. Feb 13 20:29:15.139349 systemd[1715]: Created slice app.slice - User Application Slice. Feb 13 20:29:15.139377 systemd[1715]: Reached target paths.target - Paths. Feb 13 20:29:15.139392 systemd[1715]: Reached target timers.target - Timers. Feb 13 20:29:15.140651 systemd[1715]: Starting dbus.socket - D-Bus User Message Bus Socket... Feb 13 20:29:15.161590 systemd[1715]: Listening on dbus.socket - D-Bus User Message Bus Socket. Feb 13 20:29:15.161694 systemd[1715]: Reached target sockets.target - Sockets. Feb 13 20:29:15.161710 systemd[1715]: Reached target basic.target - Basic System. Feb 13 20:29:15.161744 systemd[1715]: Reached target default.target - Main User Target. Feb 13 20:29:15.161769 systemd[1715]: Startup finished in 148ms. Feb 13 20:29:15.162784 systemd[1]: Started user@500.service - User Manager for UID 500. Feb 13 20:29:15.174748 systemd[1]: Started session-1.scope - Session 1 of User core. Feb 13 20:29:15.176615 systemd[1]: Started session-3.scope - Session 3 of User core. Feb 13 20:29:15.268141 etcd-wrapper[1687]: d462aa345367: Verifying Checksum Feb 13 20:29:15.268141 etcd-wrapper[1687]: d462aa345367: Download complete Feb 13 20:29:15.506554 etcd-wrapper[1687]: 5318d93a3a65: Verifying Checksum Feb 13 20:29:15.506716 etcd-wrapper[1687]: 5318d93a3a65: Download complete Feb 13 20:29:15.561553 etcd-wrapper[1687]: b40161cd83fc: Verifying Checksum Feb 13 20:29:15.561553 etcd-wrapper[1687]: b40161cd83fc: Download complete Feb 13 20:29:15.698589 systemd[1]: var-lib-docker-overlay2-99c732a26264452becd0fecf5c30127e6a36318e76ed71df8daa0b85c3f77237-merged.mount: Deactivated successfully. Feb 13 20:29:15.709811 systemd[1]: Started sshd@1-172.24.4.236:22-172.24.4.1:34332.service - OpenSSH per-connection server daemon (172.24.4.1:34332). Feb 13 20:29:15.758936 etcd-wrapper[1687]: d462aa345367: Pull complete Feb 13 20:29:15.788115 systemd[1]: var-lib-docker-overlay2-ddbf56bfa1222aec6f2c31476abeff6970d72ecf4b328d3f85b653607135f9d4-merged.mount: Deactivated successfully. Feb 13 20:29:15.798725 etcd-wrapper[1687]: 0f8b424aa0b9: Pull complete Feb 13 20:29:15.826529 systemd[1]: var-lib-docker-overlay2-6e5631033ca9c191183162f15a7be0fa8c29c0e1cd7f2d2ad8e05b9b5fb030d5-merged.mount: Deactivated successfully. Feb 13 20:29:15.845485 etcd-wrapper[1687]: d557676654e5: Pull complete Feb 13 20:29:15.891120 etcd-wrapper[1687]: c8022d07192e: Pull complete Feb 13 20:29:15.922268 login[1474]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Feb 13 20:29:15.936994 systemd-logind[1388]: New session 2 of user core. Feb 13 20:29:15.945271 etcd-wrapper[1687]: d858cbc252ad: Pull complete Feb 13 20:29:15.950829 systemd[1]: Started session-2.scope - Session 2 of User core. Feb 13 20:29:15.972356 etcd-wrapper[1687]: 307c1adadb60: Verifying Checksum Feb 13 20:29:15.972356 etcd-wrapper[1687]: 307c1adadb60: Download complete Feb 13 20:29:15.995698 etcd-wrapper[1687]: 1069fc2daed1: Pull complete Feb 13 20:29:16.050526 etcd-wrapper[1687]: b40161cd83fc: Pull complete Feb 13 20:29:16.154715 etcd-wrapper[1687]: 5318d93a3a65: Pull complete Feb 13 20:29:16.308211 etcd-wrapper[1687]: 307c1adadb60: Pull complete Feb 13 20:29:16.325646 etcd-wrapper[1687]: fbfea02ac3cf: Verifying Checksum Feb 13 20:29:16.325921 etcd-wrapper[1687]: fbfea02ac3cf: Download complete Feb 13 20:29:16.381290 etcd-wrapper[1687]: fbb01d9e9dc9: Verifying Checksum Feb 13 20:29:16.381290 etcd-wrapper[1687]: fbb01d9e9dc9: Download complete Feb 13 20:29:16.471916 etcd-wrapper[1687]: 8c26e4bf18e2: Verifying Checksum Feb 13 20:29:16.471916 etcd-wrapper[1687]: 8c26e4bf18e2: Download complete Feb 13 20:29:16.725770 etcd-wrapper[1687]: 1e59a65f8816: Verifying Checksum Feb 13 20:29:16.726690 etcd-wrapper[1687]: 1e59a65f8816: Download complete Feb 13 20:29:16.726690 etcd-wrapper[1687]: ffbd4ca5f0bd: Verifying Checksum Feb 13 20:29:16.726690 etcd-wrapper[1687]: ffbd4ca5f0bd: Download complete Feb 13 20:29:16.781872 etcd-wrapper[1687]: fbb01d9e9dc9: Pull complete Feb 13 20:29:17.021335 systemd[1]: var-lib-docker-overlay2-8f962d7506e6d3150062574fe83fa823c4737bff654f56b9cccdb8a61716a2b3-merged.mount: Deactivated successfully. Feb 13 20:29:17.107111 etcd-wrapper[1687]: fbfea02ac3cf: Pull complete Feb 13 20:29:17.317445 systemd[1]: var-lib-docker-overlay2-b001cb7d28de866ffd69ba910f56ee503138dd1eeb71a96432592128ab8e9f33-merged.mount: Deactivated successfully. Feb 13 20:29:17.391598 etcd-wrapper[1687]: 8c26e4bf18e2: Pull complete Feb 13 20:29:17.431107 etcd-wrapper[1687]: 1e59a65f8816: Pull complete Feb 13 20:29:17.475017 etcd-wrapper[1687]: ffbd4ca5f0bd: Pull complete Feb 13 20:29:17.495785 etcd-wrapper[1687]: Digest: sha256:d967d98a12dc220a1a290794711dba7eba04b8ce465e12b02383d1bfbb33e159 Feb 13 20:29:17.504771 etcd-wrapper[1687]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.16 Feb 13 20:29:17.811366 sshd[1744]: Accepted publickey for core from 172.24.4.1 port 34332 ssh2: RSA SHA256:DQx/ha0c4y+zAdiiwFxJIsZYU5g5yAmzkyorgcgXCvo Feb 13 20:29:17.814765 sshd-session[1744]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Feb 13 20:29:17.828938 containerd[1404]: time="2025-02-13T20:29:17.824185364Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Feb 13 20:29:17.828938 containerd[1404]: time="2025-02-13T20:29:17.824300680Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Feb 13 20:29:17.828938 containerd[1404]: time="2025-02-13T20:29:17.824345464Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Feb 13 20:29:17.828938 containerd[1404]: time="2025-02-13T20:29:17.824553895Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Feb 13 20:29:17.828146 systemd-logind[1388]: New session 4 of user core. Feb 13 20:29:17.834777 systemd[1]: Started session-4.scope - Session 4 of User core. Feb 13 20:29:17.870630 systemd[1]: Started docker-4a813bdf52ce5b6f2516c4c3685b695174575dac6332fd75671a3175a8f73810.scope - libcontainer container 4a813bdf52ce5b6f2516c4c3685b695174575dac6332fd75671a3175a8f73810. Feb 13 20:29:17.920074 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:17.919432Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} Feb 13 20:29:17.920074 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:17.919684Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"871ef69189ad4fb48c7aea73935d2631"} Feb 13 20:29:17.920074 etcd-wrapper[1687]: {"level":"warn","ts":"2025-02-13T20:29:17.919727Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} Feb 13 20:29:17.920074 etcd-wrapper[1687]: {"level":"warn","ts":"2025-02-13T20:29:17.919737Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.16"} Feb 13 20:29:17.920074 etcd-wrapper[1687]: {"level":"warn","ts":"2025-02-13T20:29:17.919749Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} Feb 13 20:29:17.920074 etcd-wrapper[1687]: {"level":"warn","ts":"2025-02-13T20:29:17.919754Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} Feb 13 20:29:17.920074 etcd-wrapper[1687]: {"level":"warn","ts":"2025-02-13T20:29:17.919818Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Feb 13 20:29:17.920074 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:17.919864Z","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://172.24.4.236:2379"]} Feb 13 20:29:17.920074 etcd-wrapper[1687]: {"level":"warn","ts":"2025-02-13T20:29:17.919920Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Feb 13 20:29:17.920074 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:17.919936Z","caller":"embed/etcd.go:128","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]} Feb 13 20:29:17.921029 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:17.920335Z","caller":"embed/etcd.go:136","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} Feb 13 20:29:17.921029 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:17.920443Z","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":2,"max-cpu-available":2,"member-initialized":false,"name":"871ef69189ad4fb48c7aea73935d2631","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://172.24.4.236:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"871ef69189ad4fb48c7aea73935d2631=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"} Feb 13 20:29:17.924907 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:17.924723Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.978501ms"} Feb 13 20:29:17.930648 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:17.930249Z","caller":"etcdserver/raft.go:505","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"} Feb 13 20:29:17.930648 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:17.930301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"} Feb 13 20:29:17.930648 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:17.930323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"} Feb 13 20:29:17.930648 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:17.930332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} Feb 13 20:29:17.930648 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:17.930340Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"} Feb 13 20:29:17.930648 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:17.930370Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Feb 13 20:29:17.937059 etcd-wrapper[1687]: {"level":"warn","ts":"2025-02-13T20:29:17.936912Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} Feb 13 20:29:17.938289 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:17.938113Z","caller":"mvcc/kvstore.go:423","msg":"kvstore restored","current-rev":1} Feb 13 20:29:17.939351 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:17.939172Z","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"} Feb 13 20:29:17.940800 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:17.940401Z","caller":"etcdserver/server.go:873","msg":"starting etcd server","local-member-id":"8e9e05c52164694d","local-server-version":"3.5.16","cluster-version":"to_be_decided"} Feb 13 20:29:17.940866 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:17.940627Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Feb 13 20:29:17.941883 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:17.941209Z","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"} Feb 13 20:29:17.941883 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:17.941329Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} Feb 13 20:29:17.941883 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:17.941353Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} Feb 13 20:29:17.941883 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:17.941361Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} Feb 13 20:29:17.941883 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:17.941544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Feb 13 20:29:17.941883 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:17.941605Z","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"]} Feb 13 20:29:17.943895 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:17.943622Z","caller":"embed/etcd.go:600","msg":"serving peer traffic","address":"127.0.0.1:2380"} Feb 13 20:29:17.943949 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:17.943735Z","caller":"embed/etcd.go:572","msg":"cmux::serve","address":"127.0.0.1:2380"} Feb 13 20:29:17.943949 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:17.943704Z","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://172.24.4.236:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} Feb 13 20:29:18.231944 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:18.230891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"} Feb 13 20:29:18.232181 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:18.231070Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"} Feb 13 20:29:18.232181 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:18.231152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"} Feb 13 20:29:18.232181 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:18.231180Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"} Feb 13 20:29:18.232181 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:18.231194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"} Feb 13 20:29:18.232181 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:18.231214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"} Feb 13 20:29:18.232181 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:18.231230Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"} Feb 13 20:29:18.236459 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:18.234828Z","caller":"etcdserver/server.go:2140","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:871ef69189ad4fb48c7aea73935d2631 ClientURLs:[http://172.24.4.236:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"} Feb 13 20:29:18.236459 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:18.235088Z","caller":"etcdserver/server.go:2651","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} Feb 13 20:29:18.237734 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:18.236109Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} Feb 13 20:29:18.237734 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:18.236286Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} Feb 13 20:29:18.237794 systemd[1]: Started etcd-member.service - etcd (System Application Container). Feb 13 20:29:18.238531 systemd[1]: Reached target multi-user.target - Multi-User System. Feb 13 20:29:18.243610 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:18.236564Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} Feb 13 20:29:18.243610 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:18.239121Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"} Feb 13 20:29:18.243610 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:18.239621Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} Feb 13 20:29:18.243610 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:18.240005Z","caller":"etcdserver/server.go:2675","msg":"cluster version is updated","cluster-version":"3.5"} Feb 13 20:29:18.243610 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:18.241377Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Feb 13 20:29:18.240997 systemd[1]: Startup finished in 1.122s (kernel) + 11.710s (initrd) + 11.868s (userspace) = 24.701s. Feb 13 20:29:18.244102 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:29:18.242362Z","caller":"embed/serve.go:187","msg":"serving client traffic insecurely; this is strongly discouraged!","traffic":"grpc+http","address":"[::]:2379"} Feb 13 20:29:18.514391 sshd[1846]: Connection closed by 172.24.4.1 port 34332 Feb 13 20:29:18.514546 sshd-session[1744]: pam_unix(sshd:session): session closed for user core Feb 13 20:29:18.523991 systemd[1]: sshd@1-172.24.4.236:22-172.24.4.1:34332.service: Deactivated successfully. Feb 13 20:29:18.527109 systemd[1]: session-4.scope: Deactivated successfully. Feb 13 20:29:18.530801 systemd-logind[1388]: Session 4 logged out. Waiting for processes to exit. Feb 13 20:29:18.543094 systemd[1]: Started sshd@2-172.24.4.236:22-172.24.4.1:34336.service - OpenSSH per-connection server daemon (172.24.4.1:34336). Feb 13 20:29:18.546962 systemd-logind[1388]: Removed session 4. Feb 13 20:29:19.747887 sshd[1875]: Accepted publickey for core from 172.24.4.1 port 34336 ssh2: RSA SHA256:DQx/ha0c4y+zAdiiwFxJIsZYU5g5yAmzkyorgcgXCvo Feb 13 20:29:19.750541 sshd-session[1875]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Feb 13 20:29:19.759979 systemd-logind[1388]: New session 5 of user core. Feb 13 20:29:19.769786 systemd[1]: Started session-5.scope - Session 5 of User core. Feb 13 20:29:20.392207 sshd[1877]: Connection closed by 172.24.4.1 port 34336 Feb 13 20:29:20.392971 sshd-session[1875]: pam_unix(sshd:session): session closed for user core Feb 13 20:29:20.404421 systemd[1]: sshd@2-172.24.4.236:22-172.24.4.1:34336.service: Deactivated successfully. Feb 13 20:29:20.408837 systemd[1]: session-5.scope: Deactivated successfully. Feb 13 20:29:20.410765 systemd-logind[1388]: Session 5 logged out. Waiting for processes to exit. Feb 13 20:29:20.427459 systemd[1]: Started sshd@3-172.24.4.236:22-172.24.4.1:34348.service - OpenSSH per-connection server daemon (172.24.4.1:34348). Feb 13 20:29:20.430197 systemd-logind[1388]: Removed session 5. Feb 13 20:29:21.546827 sshd[1882]: Accepted publickey for core from 172.24.4.1 port 34348 ssh2: RSA SHA256:DQx/ha0c4y+zAdiiwFxJIsZYU5g5yAmzkyorgcgXCvo Feb 13 20:29:21.549458 sshd-session[1882]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Feb 13 20:29:21.559911 systemd-logind[1388]: New session 6 of user core. Feb 13 20:29:21.568789 systemd[1]: Started session-6.scope - Session 6 of User core. Feb 13 20:29:22.190345 sshd[1884]: Connection closed by 172.24.4.1 port 34348 Feb 13 20:29:22.188971 sshd-session[1882]: pam_unix(sshd:session): session closed for user core Feb 13 20:29:22.202837 systemd[1]: sshd@3-172.24.4.236:22-172.24.4.1:34348.service: Deactivated successfully. Feb 13 20:29:22.206080 systemd[1]: session-6.scope: Deactivated successfully. Feb 13 20:29:22.209246 systemd-logind[1388]: Session 6 logged out. Waiting for processes to exit. Feb 13 20:29:22.215017 systemd[1]: Started sshd@4-172.24.4.236:22-172.24.4.1:34350.service - OpenSSH per-connection server daemon (172.24.4.1:34350). Feb 13 20:29:22.217736 systemd-logind[1388]: Removed session 6. Feb 13 20:29:23.381541 sshd[1889]: Accepted publickey for core from 172.24.4.1 port 34350 ssh2: RSA SHA256:DQx/ha0c4y+zAdiiwFxJIsZYU5g5yAmzkyorgcgXCvo Feb 13 20:29:23.384157 sshd-session[1889]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Feb 13 20:29:23.395880 systemd-logind[1388]: New session 7 of user core. Feb 13 20:29:23.400828 systemd[1]: Started session-7.scope - Session 7 of User core. Feb 13 20:29:23.876838 sudo[1892]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Feb 13 20:29:23.878344 sudo[1892]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Feb 13 20:29:23.899365 sudo[1892]: pam_unix(sudo:session): session closed for user root Feb 13 20:29:24.125436 sshd[1891]: Connection closed by 172.24.4.1 port 34350 Feb 13 20:29:24.125367 sshd-session[1889]: pam_unix(sshd:session): session closed for user core Feb 13 20:29:24.136169 systemd[1]: sshd@4-172.24.4.236:22-172.24.4.1:34350.service: Deactivated successfully. Feb 13 20:29:24.139047 systemd[1]: session-7.scope: Deactivated successfully. Feb 13 20:29:24.142083 systemd-logind[1388]: Session 7 logged out. Waiting for processes to exit. Feb 13 20:29:24.150130 systemd[1]: Started sshd@5-172.24.4.236:22-172.24.4.1:56900.service - OpenSSH per-connection server daemon (172.24.4.1:56900). Feb 13 20:29:24.153163 systemd-logind[1388]: Removed session 7. Feb 13 20:29:25.282041 sshd[1897]: Accepted publickey for core from 172.24.4.1 port 56900 ssh2: RSA SHA256:DQx/ha0c4y+zAdiiwFxJIsZYU5g5yAmzkyorgcgXCvo Feb 13 20:29:25.284856 sshd-session[1897]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Feb 13 20:29:25.295918 systemd-logind[1388]: New session 8 of user core. Feb 13 20:29:25.303756 systemd[1]: Started session-8.scope - Session 8 of User core. Feb 13 20:29:25.756377 sudo[1901]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Feb 13 20:29:25.758191 sudo[1901]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Feb 13 20:29:25.765590 sudo[1901]: pam_unix(sudo:session): session closed for user root Feb 13 20:29:25.776850 sudo[1900]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Feb 13 20:29:25.777526 sudo[1900]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Feb 13 20:29:25.808336 systemd[1]: Starting audit-rules.service - Load Audit Rules... Feb 13 20:29:25.863888 augenrules[1923]: No rules Feb 13 20:29:25.866801 systemd[1]: audit-rules.service: Deactivated successfully. Feb 13 20:29:25.867262 systemd[1]: Finished audit-rules.service - Load Audit Rules. Feb 13 20:29:25.870191 sudo[1900]: pam_unix(sudo:session): session closed for user root Feb 13 20:29:26.024691 sshd[1899]: Connection closed by 172.24.4.1 port 56900 Feb 13 20:29:26.023814 sshd-session[1897]: pam_unix(sshd:session): session closed for user core Feb 13 20:29:26.035297 systemd[1]: sshd@5-172.24.4.236:22-172.24.4.1:56900.service: Deactivated successfully. Feb 13 20:29:26.038597 systemd[1]: session-8.scope: Deactivated successfully. Feb 13 20:29:26.040300 systemd-logind[1388]: Session 8 logged out. Waiting for processes to exit. Feb 13 20:29:26.056068 systemd[1]: Started sshd@6-172.24.4.236:22-172.24.4.1:56908.service - OpenSSH per-connection server daemon (172.24.4.1:56908). Feb 13 20:29:26.058885 systemd-logind[1388]: Removed session 8. Feb 13 20:29:27.219352 sshd[1931]: Accepted publickey for core from 172.24.4.1 port 56908 ssh2: RSA SHA256:DQx/ha0c4y+zAdiiwFxJIsZYU5g5yAmzkyorgcgXCvo Feb 13 20:29:27.222458 sshd-session[1931]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Feb 13 20:29:27.233872 systemd-logind[1388]: New session 9 of user core. Feb 13 20:29:27.241788 systemd[1]: Started session-9.scope - Session 9 of User core. Feb 13 20:29:27.862225 sshd[1933]: Connection closed by 172.24.4.1 port 56908 Feb 13 20:29:27.863094 sshd-session[1931]: pam_unix(sshd:session): session closed for user core Feb 13 20:29:27.874558 systemd[1]: sshd@6-172.24.4.236:22-172.24.4.1:56908.service: Deactivated successfully. Feb 13 20:29:27.877900 systemd[1]: session-9.scope: Deactivated successfully. Feb 13 20:29:27.881754 systemd-logind[1388]: Session 9 logged out. Waiting for processes to exit. Feb 13 20:29:27.889156 systemd[1]: Started sshd@7-172.24.4.236:22-172.24.4.1:56924.service - OpenSSH per-connection server daemon (172.24.4.1:56924). Feb 13 20:29:27.892304 systemd-logind[1388]: Removed session 9. Feb 13 20:29:29.064084 sshd[1940]: Accepted publickey for core from 172.24.4.1 port 56924 ssh2: RSA SHA256:DQx/ha0c4y+zAdiiwFxJIsZYU5g5yAmzkyorgcgXCvo Feb 13 20:29:29.066820 sshd-session[1940]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Feb 13 20:29:29.077596 systemd-logind[1388]: New session 10 of user core. Feb 13 20:29:29.088771 systemd[1]: Started session-10.scope - Session 10 of User core. Feb 13 20:29:29.947214 sshd[1942]: Connection closed by 172.24.4.1 port 56924 Feb 13 20:29:29.946355 sshd-session[1940]: pam_unix(sshd:session): session closed for user core Feb 13 20:29:29.952723 systemd[1]: sshd@7-172.24.4.236:22-172.24.4.1:56924.service: Deactivated successfully. Feb 13 20:29:29.955917 systemd[1]: session-10.scope: Deactivated successfully. Feb 13 20:29:29.957625 systemd-logind[1388]: Session 10 logged out. Waiting for processes to exit. Feb 13 20:29:29.959660 systemd-logind[1388]: Removed session 10. Feb 13 20:29:42.717491 systemd-timesyncd[1301]: Contacted time server 193.52.136.2:123 (2.flatcar.pool.ntp.org). Feb 13 20:29:42.717592 systemd-timesyncd[1301]: Initial clock synchronization to Thu 2025-02-13 20:29:42.717043 UTC. Feb 13 20:29:42.718162 systemd-resolved[1250]: Clock change detected. Flushing caches. Feb 13 20:29:55.196717 update_engine[1390]: I20250213 20:29:55.196586 1390 update_attempter.cc:509] Updating boot flags... Feb 13 20:29:55.251598 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (1959) Feb 13 20:29:55.325438 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (1963) Feb 13 20:29:55.369376 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (1963) Feb 13 20:31:21.589626 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:31:21.585002Z","caller":"traceutil/trace.go:171","msg":"trace[349970050] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"370.441171ms","start":"2025-02-13T20:31:21.214512Z","end":"2025-02-13T20:31:21.584953Z","steps":["trace[349970050] 'process raft request' (duration: 369.54849ms)"],"step_count":1} Feb 13 20:31:21.589626 etcd-wrapper[1687]: {"level":"warn","ts":"2025-02-13T20:31:21.586083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-02-13T20:31:21.214483Z","time spent":"370.646659ms","remote":"172.24.4.192:51674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":903,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Feb 13 20:31:21.635125 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:31:21.633637Z","caller":"traceutil/trace.go:171","msg":"trace[1553678686] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:662; }","duration":"327.107785ms","start":"2025-02-13T20:31:21.306502Z","end":"2025-02-13T20:31:21.633610Z","steps":["trace[1553678686] 'read index received' (duration: 278.358301ms)","trace[1553678686] 'applied index is now lower than readState.Index' (duration: 48.748542ms)"],"step_count":2} Feb 13 20:31:21.635462 etcd-wrapper[1687]: {"level":"warn","ts":"2025-02-13T20:31:21.634184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.661728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Feb 13 20:31:21.635462 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:31:21.634306Z","caller":"traceutil/trace.go:171","msg":"trace[1915110762] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"327.824836ms","start":"2025-02-13T20:31:21.306463Z","end":"2025-02-13T20:31:21.634288Z","steps":["trace[1915110762] 'agreement among raft nodes before linearized reading' (duration: 327.671447ms)"],"step_count":1} Feb 13 20:31:21.635462 etcd-wrapper[1687]: {"level":"warn","ts":"2025-02-13T20:31:21.634354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-02-13T20:31:21.306398Z","time spent":"327.940192ms","remote":"172.24.4.192:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} Feb 13 20:31:21.635462 etcd-wrapper[1687]: {"level":"warn","ts":"2025-02-13T20:31:21.634393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.655117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/calico-system/calico-node-twk9g\" ","response":"range_response_count:1 size:14171"} Feb 13 20:31:21.635462 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:31:21.634483Z","caller":"traceutil/trace.go:171","msg":"trace[774769384] range","detail":"{range_begin:/registry/pods/calico-system/calico-node-twk9g; range_end:; response_count:1; response_revision:648; }","duration":"291.769822ms","start":"2025-02-13T20:31:21.342693Z","end":"2025-02-13T20:31:21.634463Z","steps":["trace[774769384] 'agreement among raft nodes before linearized reading' (duration: 291.563213ms)"],"step_count":1} Feb 13 20:31:21.636034 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:31:21.634573Z","caller":"traceutil/trace.go:171","msg":"trace[780035058] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"346.792587ms","start":"2025-02-13T20:31:21.287761Z","end":"2025-02-13T20:31:21.634554Z","steps":["trace[780035058] 'process raft request' (duration: 314.271638ms)","trace[780035058] 'compare' (duration: 31.468968ms)"],"step_count":2} Feb 13 20:31:21.636034 etcd-wrapper[1687]: {"level":"warn","ts":"2025-02-13T20:31:21.634677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-02-13T20:31:21.287736Z","time spent":"346.876465ms","remote":"172.24.4.192:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Feb 13 20:31:28.868340 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:31:28.867373Z","caller":"traceutil/trace.go:171","msg":"trace[375663658] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"197.187785ms","start":"2025-02-13T20:31:28.670160Z","end":"2025-02-13T20:31:28.867348Z","steps":["trace[375663658] 'process raft request' (duration: 196.985404ms)"],"step_count":1} Feb 13 20:31:29.330999 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:31:29.330298Z","caller":"traceutil/trace.go:171","msg":"trace[1561649782] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"117.940744ms","start":"2025-02-13T20:31:29.212331Z","end":"2025-02-13T20:31:29.330272Z","steps":["trace[1561649782] 'process raft request' (duration: 117.79034ms)"],"step_count":1} Feb 13 20:31:30.255838 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:31:30.255127Z","caller":"traceutil/trace.go:171","msg":"trace[1765723961] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"211.408496ms","start":"2025-02-13T20:31:30.043695Z","end":"2025-02-13T20:31:30.255103Z","steps":["trace[1765723961] 'process raft request' (duration: 210.891894ms)"],"step_count":1} Feb 13 20:31:30.467764 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:31:30.466129Z","caller":"traceutil/trace.go:171","msg":"trace[177485181] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"174.155044ms","start":"2025-02-13T20:31:30.291920Z","end":"2025-02-13T20:31:30.466075Z","steps":["trace[177485181] 'process raft request' (duration: 138.558101ms)","trace[177485181] 'compare' (duration: 35.451199ms)"],"step_count":2} Feb 13 20:31:30.468100 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:31:30.466523Z","caller":"traceutil/trace.go:171","msg":"trace[1190047423] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"172.173666ms","start":"2025-02-13T20:31:30.294333Z","end":"2025-02-13T20:31:30.466507Z","steps":["trace[1190047423] 'process raft request' (duration: 172.033682ms)"],"step_count":1} Feb 13 20:31:30.468100 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:31:30.466719Z","caller":"traceutil/trace.go:171","msg":"trace[1792140377] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"174.661947ms","start":"2025-02-13T20:31:30.292041Z","end":"2025-02-13T20:31:30.466703Z","steps":["trace[1792140377] 'process raft request' (duration: 173.997797ms)"],"step_count":1} Feb 13 20:31:30.468100 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:31:30.466709Z","caller":"traceutil/trace.go:171","msg":"trace[1103596933] linearizableReadLoop","detail":"{readStateIndex:707; appliedIndex:703; }","duration":"150.397212ms","start":"2025-02-13T20:31:30.316285Z","end":"2025-02-13T20:31:30.466682Z","steps":["trace[1103596933] 'read index received' (duration: 114.201633ms)","trace[1103596933] 'applied index is now lower than readState.Index' (duration: 36.194567ms)"],"step_count":2} Feb 13 20:31:30.468100 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:31:30.466910Z","caller":"traceutil/trace.go:171","msg":"trace[1412628456] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"168.933711ms","start":"2025-02-13T20:31:30.297885Z","end":"2025-02-13T20:31:30.466819Z","steps":["trace[1412628456] 'process raft request' (duration: 168.586367ms)"],"step_count":1} Feb 13 20:31:30.468100 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:31:30.467001Z","caller":"traceutil/trace.go:171","msg":"trace[1654092460] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"134.295761ms","start":"2025-02-13T20:31:30.332688Z","end":"2025-02-13T20:31:30.466984Z","steps":["trace[1654092460] 'process raft request' (duration: 133.952686ms)"],"step_count":1} Feb 13 20:31:30.468490 etcd-wrapper[1687]: {"level":"warn","ts":"2025-02-13T20:31:30.467156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.852618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-6f6b679f8f-svtz4\" ","response":"range_response_count:1 size:3839"} Feb 13 20:31:30.468490 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:31:30.467225Z","caller":"traceutil/trace.go:171","msg":"trace[121903996] range","detail":"{range_begin:/registry/pods/kube-system/coredns-6f6b679f8f-svtz4; range_end:; response_count:1; response_revision:690; }","duration":"150.938148ms","start":"2025-02-13T20:31:30.316251Z","end":"2025-02-13T20:31:30.467189Z","steps":["trace[121903996] 'agreement among raft nodes before linearized reading' (duration: 150.527186ms)"],"step_count":1} Feb 13 20:31:30.783578 etcd-wrapper[1687]: {"level":"warn","ts":"2025-02-13T20:31:30.782948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.944474ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Feb 13 20:31:30.783578 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:31:30.783162Z","caller":"traceutil/trace.go:171","msg":"trace[985054006] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:709; }","duration":"225.455961ms","start":"2025-02-13T20:31:30.557687Z","end":"2025-02-13T20:31:30.783143Z","steps":["trace[985054006] 'read index received' (duration: 63.675174ms)","trace[985054006] 'applied index is now lower than readState.Index' (duration: 161.779895ms)"],"step_count":2} Feb 13 20:31:30.785236 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:31:30.783450Z","caller":"traceutil/trace.go:171","msg":"trace[1552568945] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"295.585375ms","start":"2025-02-13T20:31:30.487818Z","end":"2025-02-13T20:31:30.783403Z","steps":["trace[1552568945] 'process raft request' (duration: 133.572442ms)","trace[1552568945] 'compare' (duration: 160.704051ms)"],"step_count":2} Feb 13 20:31:30.785236 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:31:30.783506Z","caller":"traceutil/trace.go:171","msg":"trace[1620833620] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"264.875976ms","start":"2025-02-13T20:31:30.518619Z","end":"2025-02-13T20:31:30.783495Z","steps":["trace[1620833620] 'process raft request' (duration: 264.430488ms)"],"step_count":1} Feb 13 20:31:30.785236 etcd-wrapper[1687]: {"level":"warn","ts":"2025-02-13T20:31:30.783618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.922518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} Feb 13 20:31:30.785236 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:31:30.784387Z","caller":"traceutil/trace.go:171","msg":"trace[1561807811] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:694; }","duration":"226.723204ms","start":"2025-02-13T20:31:30.557638Z","end":"2025-02-13T20:31:30.784362Z","steps":["trace[1561807811] 'agreement among raft nodes before linearized reading' (duration: 225.913361ms)"],"step_count":1} Feb 13 20:31:37.088191 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:31:37.087167Z","caller":"traceutil/trace.go:171","msg":"trace[2047869855] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"124.816946ms","start":"2025-02-13T20:31:36.962324Z","end":"2025-02-13T20:31:37.087141Z","steps":["trace[2047869855] 'process raft request' (duration: 124.746735ms)"],"step_count":1} Feb 13 20:31:37.089521 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:31:37.087215Z","caller":"traceutil/trace.go:171","msg":"trace[1987959927] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"127.437673ms","start":"2025-02-13T20:31:36.959715Z","end":"2025-02-13T20:31:37.087152Z","steps":["trace[1987959927] 'process raft request' (duration: 75.205746ms)","trace[1987959927] 'compare' (duration: 52.012624ms)"],"step_count":2} Feb 13 20:32:18.123464 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:32:18.105756Z","caller":"traceutil/trace.go:171","msg":"trace[2034875948] linearizableReadLoop","detail":"{readStateIndex:1206; appliedIndex:1206; }","duration":"102.30729ms","start":"2025-02-13T20:32:18.003392Z","end":"2025-02-13T20:32:18.105699Z","steps":["trace[2034875948] 'read index received' (duration: 102.288094ms)","trace[2034875948] 'applied index is now lower than readState.Index' (duration: 16.631µs)"],"step_count":2} Feb 13 20:32:18.123464 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:32:18.105343Z","caller":"traceutil/trace.go:171","msg":"trace[1482028156] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"105.455109ms","start":"2025-02-13T20:32:17.999857Z","end":"2025-02-13T20:32:18.105313Z","steps":["trace[1482028156] 'process raft request' (duration: 105.313644ms)"],"step_count":1} Feb 13 20:32:18.123464 etcd-wrapper[1687]: {"level":"warn","ts":"2025-02-13T20:32:18.106242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.794174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/tigera-apiserver-access-tigera-crds\" ","response":"range_response_count:0 size:5"} Feb 13 20:32:18.123464 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:32:18.106307Z","caller":"traceutil/trace.go:171","msg":"trace[469624151] range","detail":"{range_begin:/registry/clusterrolebindings/tigera-apiserver-access-tigera-crds; range_end:; response_count:0; response_revision:1090; }","duration":"102.929428ms","start":"2025-02-13T20:32:18.003359Z","end":"2025-02-13T20:32:18.106288Z","steps":["trace[469624151] 'agreement among raft nodes before linearized reading' (duration: 102.754911ms)"],"step_count":1} Feb 13 20:32:18.308364 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:32:18.307272Z","caller":"traceutil/trace.go:171","msg":"trace[1638393219] linearizableReadLoop","detail":"{readStateIndex:1207; appliedIndex:1206; }","duration":"187.741501ms","start":"2025-02-13T20:32:18.119497Z","end":"2025-02-13T20:32:18.307239Z","steps":["trace[1638393219] 'read index received' (duration: 183.175069ms)","trace[1638393219] 'applied index is now lower than readState.Index' (duration: 4.56528ms)"],"step_count":2} Feb 13 20:32:18.308364 etcd-wrapper[1687]: {"level":"warn","ts":"2025-02-13T20:32:18.307562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.036105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/tigera-operator/calico-apiserver-certs\" ","response":"range_response_count:1 size:4751"} Feb 13 20:32:18.308364 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:32:18.307609Z","caller":"traceutil/trace.go:171","msg":"trace[572157862] range","detail":"{range_begin:/registry/secrets/tigera-operator/calico-apiserver-certs; range_end:; response_count:1; response_revision:1091; }","duration":"188.110464ms","start":"2025-02-13T20:32:18.119484Z","end":"2025-02-13T20:32:18.307595Z","steps":["trace[572157862] 'agreement among raft nodes before linearized reading' (duration: 187.896062ms)"],"step_count":1} Feb 13 20:32:18.308364 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:32:18.307590Z","caller":"traceutil/trace.go:171","msg":"trace[102551936] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"214.025066ms","start":"2025-02-13T20:32:18.093534Z","end":"2025-02-13T20:32:18.307559Z","steps":["trace[102551936] 'process raft request' (duration: 209.208063ms)"],"step_count":1} Feb 13 20:32:50.573474 etcd-wrapper[1687]: {"level":"info","ts":"2025-02-13T20:32:50.567560Z","caller":"traceutil/trace.go:171","msg":"trace[612591765] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"113.986208ms","start":"2025-02-13T20:32:50.453539Z","end":"2025-02-13T20:32:50.567526Z","steps":["trace[612591765] 'process raft request' (duration: 113.696044ms)"],"step_count":1}