Mar 25 01:23:44.937820 kernel: Linux version 6.6.83-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.44 p1) 2.44.0) #1 SMP PREEMPT_DYNAMIC Mon Mar 24 23:38:35 -00 2025 Mar 25 01:23:44.937844 kernel: Command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected verity.usrhash=e7a00b7ee8d97e8d255663e9d3fa92277da8316702fb7f6d664fd7b137c307e9 Mar 25 01:23:44.937853 kernel: BIOS-provided physical RAM map: Mar 25 01:23:44.937860 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000002ffff] usable Mar 25 01:23:44.937866 kernel: BIOS-e820: [mem 0x0000000000030000-0x000000000004ffff] reserved Mar 25 01:23:44.937875 kernel: BIOS-e820: [mem 0x0000000000050000-0x000000000009efff] usable Mar 25 01:23:44.937883 kernel: BIOS-e820: [mem 0x000000000009f000-0x000000000009ffff] reserved Mar 25 01:23:44.937890 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000009b8ecfff] usable Mar 25 01:23:44.937896 kernel: BIOS-e820: [mem 0x000000009b8ed000-0x000000009bb6cfff] reserved Mar 25 01:23:44.937914 kernel: BIOS-e820: [mem 0x000000009bb6d000-0x000000009bb7efff] ACPI data Mar 25 01:23:44.937921 kernel: BIOS-e820: [mem 0x000000009bb7f000-0x000000009bbfefff] ACPI NVS Mar 25 01:23:44.937927 kernel: BIOS-e820: [mem 0x000000009bbff000-0x000000009bfb0fff] usable Mar 25 01:23:44.937934 kernel: BIOS-e820: [mem 0x000000009bfb1000-0x000000009bfb4fff] reserved Mar 25 01:23:44.937941 kernel: BIOS-e820: [mem 0x000000009bfb5000-0x000000009bfb6fff] ACPI NVS Mar 25 01:23:44.937951 kernel: BIOS-e820: [mem 0x000000009bfb7000-0x000000009bffffff] usable Mar 25 01:23:44.937958 kernel: BIOS-e820: [mem 0x000000009c000000-0x000000009cffffff] reserved Mar 25 01:23:44.937966 kernel: BIOS-e820: [mem 0x00000000e0000000-0x00000000efffffff] reserved Mar 25 01:23:44.937973 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Mar 25 01:23:44.937980 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Mar 25 01:23:44.937989 kernel: NX (Execute Disable) protection: active Mar 25 01:23:44.937996 kernel: APIC: Static calls initialized Mar 25 01:23:44.938004 kernel: e820: update [mem 0x9a187018-0x9a190c57] usable ==> usable Mar 25 01:23:44.938011 kernel: e820: update [mem 0x9a187018-0x9a190c57] usable ==> usable Mar 25 01:23:44.938018 kernel: e820: update [mem 0x9a14a018-0x9a186e57] usable ==> usable Mar 25 01:23:44.938033 kernel: e820: update [mem 0x9a14a018-0x9a186e57] usable ==> usable Mar 25 01:23:44.938040 kernel: extended physical RAM map: Mar 25 01:23:44.938047 kernel: reserve setup_data: [mem 0x0000000000000000-0x000000000002ffff] usable Mar 25 01:23:44.938054 kernel: reserve setup_data: [mem 0x0000000000030000-0x000000000004ffff] reserved Mar 25 01:23:44.938062 kernel: reserve setup_data: [mem 0x0000000000050000-0x000000000009efff] usable Mar 25 01:23:44.938069 kernel: reserve setup_data: [mem 0x000000000009f000-0x000000000009ffff] reserved Mar 25 01:23:44.938079 kernel: reserve setup_data: [mem 0x0000000000100000-0x000000009a14a017] usable Mar 25 01:23:44.938086 kernel: reserve setup_data: [mem 0x000000009a14a018-0x000000009a186e57] usable Mar 25 01:23:44.938093 kernel: reserve setup_data: [mem 0x000000009a186e58-0x000000009a187017] usable Mar 25 01:23:44.938100 kernel: reserve setup_data: [mem 0x000000009a187018-0x000000009a190c57] usable Mar 25 01:23:44.938107 kernel: reserve setup_data: [mem 0x000000009a190c58-0x000000009b8ecfff] usable Mar 25 01:23:44.938115 kernel: reserve setup_data: [mem 0x000000009b8ed000-0x000000009bb6cfff] reserved Mar 25 01:23:44.938122 kernel: reserve setup_data: [mem 0x000000009bb6d000-0x000000009bb7efff] ACPI data Mar 25 01:23:44.938129 kernel: reserve setup_data: [mem 0x000000009bb7f000-0x000000009bbfefff] ACPI NVS Mar 25 01:23:44.938136 kernel: reserve setup_data: [mem 0x000000009bbff000-0x000000009bfb0fff] usable Mar 25 01:23:44.938143 kernel: reserve setup_data: [mem 0x000000009bfb1000-0x000000009bfb4fff] reserved Mar 25 01:23:44.938156 kernel: reserve setup_data: [mem 0x000000009bfb5000-0x000000009bfb6fff] ACPI NVS Mar 25 01:23:44.938163 kernel: reserve setup_data: [mem 0x000000009bfb7000-0x000000009bffffff] usable Mar 25 01:23:44.938171 kernel: reserve setup_data: [mem 0x000000009c000000-0x000000009cffffff] reserved Mar 25 01:23:44.938178 kernel: reserve setup_data: [mem 0x00000000e0000000-0x00000000efffffff] reserved Mar 25 01:23:44.938185 kernel: reserve setup_data: [mem 0x00000000feffc000-0x00000000feffffff] reserved Mar 25 01:23:44.938193 kernel: reserve setup_data: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Mar 25 01:23:44.938202 kernel: efi: EFI v2.7 by EDK II Mar 25 01:23:44.938210 kernel: efi: SMBIOS=0x9b9d5000 ACPI=0x9bb7e000 ACPI 2.0=0x9bb7e014 MEMATTR=0x9a1f7018 RNG=0x9bb73018 Mar 25 01:23:44.938217 kernel: random: crng init done Mar 25 01:23:44.938225 kernel: Kernel is locked down from EFI Secure Boot; see man kernel_lockdown.7 Mar 25 01:23:44.938232 kernel: secureboot: Secure boot enabled Mar 25 01:23:44.938239 kernel: SMBIOS 2.8 present. Mar 25 01:23:44.938247 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS unknown 02/02/2022 Mar 25 01:23:44.938254 kernel: Hypervisor detected: KVM Mar 25 01:23:44.938262 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Mar 25 01:23:44.938269 kernel: kvm-clock: using sched offset of 4132922542 cycles Mar 25 01:23:44.938277 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Mar 25 01:23:44.938287 kernel: tsc: Detected 2794.748 MHz processor Mar 25 01:23:44.938295 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Mar 25 01:23:44.938302 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Mar 25 01:23:44.938310 kernel: last_pfn = 0x9c000 max_arch_pfn = 0x400000000 Mar 25 01:23:44.938318 kernel: MTRR map: 4 entries (2 fixed + 2 variable; max 18), built from 8 variable MTRRs Mar 25 01:23:44.938325 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Mar 25 01:23:44.938333 kernel: Using GB pages for direct mapping Mar 25 01:23:44.938340 kernel: ACPI: Early table checksum verification disabled Mar 25 01:23:44.938348 kernel: ACPI: RSDP 0x000000009BB7E014 000024 (v02 BOCHS ) Mar 25 01:23:44.938358 kernel: ACPI: XSDT 0x000000009BB7D0E8 000054 (v01 BOCHS BXPC 00000001 01000013) Mar 25 01:23:44.938365 kernel: ACPI: FACP 0x000000009BB79000 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) Mar 25 01:23:44.938373 kernel: ACPI: DSDT 0x000000009BB7A000 002225 (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 25 01:23:44.938381 kernel: ACPI: FACS 0x000000009BBDD000 000040 Mar 25 01:23:44.938388 kernel: ACPI: APIC 0x000000009BB78000 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 25 01:23:44.938396 kernel: ACPI: HPET 0x000000009BB77000 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 25 01:23:44.938403 kernel: ACPI: MCFG 0x000000009BB76000 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 25 01:23:44.938411 kernel: ACPI: WAET 0x000000009BB75000 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 25 01:23:44.938418 kernel: ACPI: BGRT 0x000000009BB74000 000038 (v01 INTEL EDK2 00000002 01000013) Mar 25 01:23:44.938428 kernel: ACPI: Reserving FACP table memory at [mem 0x9bb79000-0x9bb790f3] Mar 25 01:23:44.938436 kernel: ACPI: Reserving DSDT table memory at [mem 0x9bb7a000-0x9bb7c224] Mar 25 01:23:44.938443 kernel: ACPI: Reserving FACS table memory at [mem 0x9bbdd000-0x9bbdd03f] Mar 25 01:23:44.938451 kernel: ACPI: Reserving APIC table memory at [mem 0x9bb78000-0x9bb7808f] Mar 25 01:23:44.938458 kernel: ACPI: Reserving HPET table memory at [mem 0x9bb77000-0x9bb77037] Mar 25 01:23:44.938466 kernel: ACPI: Reserving MCFG table memory at [mem 0x9bb76000-0x9bb7603b] Mar 25 01:23:44.938473 kernel: ACPI: Reserving WAET table memory at [mem 0x9bb75000-0x9bb75027] Mar 25 01:23:44.938480 kernel: ACPI: Reserving BGRT table memory at [mem 0x9bb74000-0x9bb74037] Mar 25 01:23:44.938488 kernel: No NUMA configuration found Mar 25 01:23:44.938498 kernel: Faking a node at [mem 0x0000000000000000-0x000000009bffffff] Mar 25 01:23:44.938505 kernel: NODE_DATA(0) allocated [mem 0x9bf59000-0x9bf5efff] Mar 25 01:23:44.938513 kernel: Zone ranges: Mar 25 01:23:44.938520 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Mar 25 01:23:44.938528 kernel: DMA32 [mem 0x0000000001000000-0x000000009bffffff] Mar 25 01:23:44.938535 kernel: Normal empty Mar 25 01:23:44.938543 kernel: Movable zone start for each node Mar 25 01:23:44.938550 kernel: Early memory node ranges Mar 25 01:23:44.938558 kernel: node 0: [mem 0x0000000000001000-0x000000000002ffff] Mar 25 01:23:44.938568 kernel: node 0: [mem 0x0000000000050000-0x000000000009efff] Mar 25 01:23:44.938575 kernel: node 0: [mem 0x0000000000100000-0x000000009b8ecfff] Mar 25 01:23:44.938583 kernel: node 0: [mem 0x000000009bbff000-0x000000009bfb0fff] Mar 25 01:23:44.938590 kernel: node 0: [mem 0x000000009bfb7000-0x000000009bffffff] Mar 25 01:23:44.938598 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009bffffff] Mar 25 01:23:44.938605 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Mar 25 01:23:44.938613 kernel: On node 0, zone DMA: 32 pages in unavailable ranges Mar 25 01:23:44.938620 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Mar 25 01:23:44.938628 kernel: On node 0, zone DMA32: 786 pages in unavailable ranges Mar 25 01:23:44.938637 kernel: On node 0, zone DMA32: 6 pages in unavailable ranges Mar 25 01:23:44.938645 kernel: On node 0, zone DMA32: 16384 pages in unavailable ranges Mar 25 01:23:44.938652 kernel: ACPI: PM-Timer IO Port: 0x608 Mar 25 01:23:44.938660 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Mar 25 01:23:44.938668 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Mar 25 01:23:44.938675 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Mar 25 01:23:44.938683 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Mar 25 01:23:44.938690 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Mar 25 01:23:44.938698 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Mar 25 01:23:44.938705 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Mar 25 01:23:44.938715 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Mar 25 01:23:44.938723 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Mar 25 01:23:44.938730 kernel: TSC deadline timer available Mar 25 01:23:44.938738 kernel: smpboot: Allowing 4 CPUs, 0 hotplug CPUs Mar 25 01:23:44.938745 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Mar 25 01:23:44.938753 kernel: kvm-guest: KVM setup pv remote TLB flush Mar 25 01:23:44.938768 kernel: kvm-guest: setup PV sched yield Mar 25 01:23:44.938777 kernel: [mem 0x9d000000-0xdfffffff] available for PCI devices Mar 25 01:23:44.938785 kernel: Booting paravirtualized kernel on KVM Mar 25 01:23:44.938793 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Mar 25 01:23:44.938801 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 Mar 25 01:23:44.938811 kernel: percpu: Embedded 58 pages/cpu s197032 r8192 d32344 u524288 Mar 25 01:23:44.938819 kernel: pcpu-alloc: s197032 r8192 d32344 u524288 alloc=1*2097152 Mar 25 01:23:44.938827 kernel: pcpu-alloc: [0] 0 1 2 3 Mar 25 01:23:44.938834 kernel: kvm-guest: PV spinlocks enabled Mar 25 01:23:44.938842 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Mar 25 01:23:44.938851 kernel: Kernel command line: rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected verity.usrhash=e7a00b7ee8d97e8d255663e9d3fa92277da8316702fb7f6d664fd7b137c307e9 Mar 25 01:23:44.938859 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Mar 25 01:23:44.938867 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Mar 25 01:23:44.938875 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Mar 25 01:23:44.938886 kernel: Fallback order for Node 0: 0 Mar 25 01:23:44.938894 kernel: Built 1 zonelists, mobility grouping on. Total pages: 625927 Mar 25 01:23:44.938933 kernel: Policy zone: DMA32 Mar 25 01:23:44.938941 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Mar 25 01:23:44.938949 kernel: Memory: 2368304K/2552216K available (14336K kernel code, 2304K rwdata, 25060K rodata, 43592K init, 1472K bss, 183656K reserved, 0K cma-reserved) Mar 25 01:23:44.938960 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Mar 25 01:23:44.938968 kernel: ftrace: allocating 37985 entries in 149 pages Mar 25 01:23:44.938975 kernel: ftrace: allocated 149 pages with 4 groups Mar 25 01:23:44.938983 kernel: Dynamic Preempt: voluntary Mar 25 01:23:44.938991 kernel: rcu: Preemptible hierarchical RCU implementation. Mar 25 01:23:44.938999 kernel: rcu: RCU event tracing is enabled. Mar 25 01:23:44.939008 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Mar 25 01:23:44.939016 kernel: Trampoline variant of Tasks RCU enabled. Mar 25 01:23:44.939024 kernel: Rude variant of Tasks RCU enabled. Mar 25 01:23:44.939040 kernel: Tracing variant of Tasks RCU enabled. Mar 25 01:23:44.939047 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Mar 25 01:23:44.939056 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Mar 25 01:23:44.939064 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 Mar 25 01:23:44.939072 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Mar 25 01:23:44.939080 kernel: Console: colour dummy device 80x25 Mar 25 01:23:44.939088 kernel: printk: console [ttyS0] enabled Mar 25 01:23:44.939095 kernel: ACPI: Core revision 20230628 Mar 25 01:23:44.939103 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Mar 25 01:23:44.939114 kernel: APIC: Switch to symmetric I/O mode setup Mar 25 01:23:44.939121 kernel: x2apic enabled Mar 25 01:23:44.939129 kernel: APIC: Switched APIC routing to: physical x2apic Mar 25 01:23:44.939137 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() Mar 25 01:23:44.939145 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() Mar 25 01:23:44.939153 kernel: kvm-guest: setup PV IPIs Mar 25 01:23:44.939161 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Mar 25 01:23:44.939169 kernel: tsc: Marking TSC unstable due to TSCs unsynchronized Mar 25 01:23:44.939176 kernel: Calibrating delay loop (skipped) preset value.. 5589.49 BogoMIPS (lpj=2794748) Mar 25 01:23:44.939187 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Mar 25 01:23:44.939194 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 Mar 25 01:23:44.939202 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 Mar 25 01:23:44.939210 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Mar 25 01:23:44.939218 kernel: Spectre V2 : Mitigation: Retpolines Mar 25 01:23:44.939226 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Mar 25 01:23:44.939234 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Mar 25 01:23:44.939242 kernel: Spectre V2 : Enabling Speculation Barrier for firmware calls Mar 25 01:23:44.939250 kernel: RETBleed: Mitigation: untrained return thunk Mar 25 01:23:44.939260 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Mar 25 01:23:44.939268 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Mar 25 01:23:44.939276 kernel: Speculative Return Stack Overflow: IBPB-extending microcode not applied! Mar 25 01:23:44.939284 kernel: Speculative Return Stack Overflow: WARNING: See https://kernel.org/doc/html/latest/admin-guide/hw-vuln/srso.html for mitigation options. Mar 25 01:23:44.939292 kernel: Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode Mar 25 01:23:44.939300 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Mar 25 01:23:44.939308 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Mar 25 01:23:44.939316 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Mar 25 01:23:44.939326 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Mar 25 01:23:44.939334 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format. Mar 25 01:23:44.939342 kernel: Freeing SMP alternatives memory: 32K Mar 25 01:23:44.939350 kernel: pid_max: default: 32768 minimum: 301 Mar 25 01:23:44.939358 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Mar 25 01:23:44.939366 kernel: landlock: Up and running. Mar 25 01:23:44.939373 kernel: SELinux: Initializing. Mar 25 01:23:44.939381 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Mar 25 01:23:44.939389 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Mar 25 01:23:44.939399 kernel: smpboot: CPU0: AMD EPYC 7402P 24-Core Processor (family: 0x17, model: 0x31, stepping: 0x0) Mar 25 01:23:44.939407 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Mar 25 01:23:44.939415 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Mar 25 01:23:44.939423 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Mar 25 01:23:44.939431 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver. Mar 25 01:23:44.939439 kernel: ... version: 0 Mar 25 01:23:44.939447 kernel: ... bit width: 48 Mar 25 01:23:44.939455 kernel: ... generic registers: 6 Mar 25 01:23:44.939462 kernel: ... value mask: 0000ffffffffffff Mar 25 01:23:44.939473 kernel: ... max period: 00007fffffffffff Mar 25 01:23:44.939480 kernel: ... fixed-purpose events: 0 Mar 25 01:23:44.939488 kernel: ... event mask: 000000000000003f Mar 25 01:23:44.939496 kernel: signal: max sigframe size: 1776 Mar 25 01:23:44.939504 kernel: rcu: Hierarchical SRCU implementation. Mar 25 01:23:44.939512 kernel: rcu: Max phase no-delay instances is 400. Mar 25 01:23:44.939520 kernel: smp: Bringing up secondary CPUs ... Mar 25 01:23:44.939527 kernel: smpboot: x86: Booting SMP configuration: Mar 25 01:23:44.939535 kernel: .... node #0, CPUs: #1 #2 #3 Mar 25 01:23:44.939545 kernel: smp: Brought up 1 node, 4 CPUs Mar 25 01:23:44.939553 kernel: smpboot: Max logical packages: 1 Mar 25 01:23:44.939561 kernel: smpboot: Total of 4 processors activated (22357.98 BogoMIPS) Mar 25 01:23:44.939569 kernel: devtmpfs: initialized Mar 25 01:23:44.939577 kernel: x86/mm: Memory block size: 128MB Mar 25 01:23:44.939584 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9bb7f000-0x9bbfefff] (524288 bytes) Mar 25 01:23:44.939592 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9bfb5000-0x9bfb6fff] (8192 bytes) Mar 25 01:23:44.939600 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Mar 25 01:23:44.939608 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Mar 25 01:23:44.939618 kernel: pinctrl core: initialized pinctrl subsystem Mar 25 01:23:44.939626 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Mar 25 01:23:44.939634 kernel: audit: initializing netlink subsys (disabled) Mar 25 01:23:44.939642 kernel: audit: type=2000 audit(1742865825.092:1): state=initialized audit_enabled=0 res=1 Mar 25 01:23:44.939650 kernel: thermal_sys: Registered thermal governor 'step_wise' Mar 25 01:23:44.939658 kernel: thermal_sys: Registered thermal governor 'user_space' Mar 25 01:23:44.939666 kernel: cpuidle: using governor menu Mar 25 01:23:44.939673 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Mar 25 01:23:44.939681 kernel: dca service started, version 1.12.1 Mar 25 01:23:44.939691 kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xe0000000-0xefffffff] (base 0xe0000000) Mar 25 01:23:44.939699 kernel: PCI: Using configuration type 1 for base access Mar 25 01:23:44.939707 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Mar 25 01:23:44.939715 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Mar 25 01:23:44.939723 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Mar 25 01:23:44.939731 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Mar 25 01:23:44.939738 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Mar 25 01:23:44.939746 kernel: ACPI: Added _OSI(Module Device) Mar 25 01:23:44.939754 kernel: ACPI: Added _OSI(Processor Device) Mar 25 01:23:44.939764 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Mar 25 01:23:44.939772 kernel: ACPI: Added _OSI(Processor Aggregator Device) Mar 25 01:23:44.939780 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Mar 25 01:23:44.939788 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Mar 25 01:23:44.939796 kernel: ACPI: Interpreter enabled Mar 25 01:23:44.939804 kernel: ACPI: PM: (supports S0 S5) Mar 25 01:23:44.939811 kernel: ACPI: Using IOAPIC for interrupt routing Mar 25 01:23:44.939820 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Mar 25 01:23:44.939827 kernel: PCI: Using E820 reservations for host bridge windows Mar 25 01:23:44.939838 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F Mar 25 01:23:44.939846 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Mar 25 01:23:44.940050 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Mar 25 01:23:44.940184 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] Mar 25 01:23:44.940307 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] Mar 25 01:23:44.940318 kernel: PCI host bridge to bus 0000:00 Mar 25 01:23:44.940442 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Mar 25 01:23:44.940563 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Mar 25 01:23:44.940677 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Mar 25 01:23:44.940789 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xdfffffff window] Mar 25 01:23:44.940928 kernel: pci_bus 0000:00: root bus resource [mem 0xf0000000-0xfebfffff window] Mar 25 01:23:44.941054 kernel: pci_bus 0000:00: root bus resource [mem 0x380000000000-0x3807ffffffff window] Mar 25 01:23:44.941169 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Mar 25 01:23:44.941311 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 Mar 25 01:23:44.941455 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 Mar 25 01:23:44.941581 kernel: pci 0000:00:01.0: reg 0x10: [mem 0xc0000000-0xc0ffffff pref] Mar 25 01:23:44.941708 kernel: pci 0000:00:01.0: reg 0x18: [mem 0xc1044000-0xc1044fff] Mar 25 01:23:44.941832 kernel: pci 0000:00:01.0: reg 0x30: [mem 0xffff0000-0xffffffff pref] Mar 25 01:23:44.941971 kernel: pci 0000:00:01.0: BAR 0: assigned to efifb Mar 25 01:23:44.942106 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Mar 25 01:23:44.942244 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 Mar 25 01:23:44.942369 kernel: pci 0000:00:02.0: reg 0x10: [io 0x6100-0x611f] Mar 25 01:23:44.942494 kernel: pci 0000:00:02.0: reg 0x14: [mem 0xc1043000-0xc1043fff] Mar 25 01:23:44.942625 kernel: pci 0000:00:02.0: reg 0x20: [mem 0x380000000000-0x380000003fff 64bit pref] Mar 25 01:23:44.942761 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 Mar 25 01:23:44.942886 kernel: pci 0000:00:03.0: reg 0x10: [io 0x6000-0x607f] Mar 25 01:23:44.943040 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xc1042000-0xc1042fff] Mar 25 01:23:44.943172 kernel: pci 0000:00:03.0: reg 0x20: [mem 0x380000004000-0x380000007fff 64bit pref] Mar 25 01:23:44.943304 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 Mar 25 01:23:44.943430 kernel: pci 0000:00:04.0: reg 0x10: [io 0x60e0-0x60ff] Mar 25 01:23:44.943553 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xc1041000-0xc1041fff] Mar 25 01:23:44.943677 kernel: pci 0000:00:04.0: reg 0x20: [mem 0x380000008000-0x38000000bfff 64bit pref] Mar 25 01:23:44.943804 kernel: pci 0000:00:04.0: reg 0x30: [mem 0xfffc0000-0xffffffff pref] Mar 25 01:23:44.943952 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 Mar 25 01:23:44.944095 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO Mar 25 01:23:44.944230 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 Mar 25 01:23:44.944363 kernel: pci 0000:00:1f.2: reg 0x20: [io 0x60c0-0x60df] Mar 25 01:23:44.944490 kernel: pci 0000:00:1f.2: reg 0x24: [mem 0xc1040000-0xc1040fff] Mar 25 01:23:44.944626 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 Mar 25 01:23:44.944754 kernel: pci 0000:00:1f.3: reg 0x20: [io 0x6080-0x60bf] Mar 25 01:23:44.944769 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Mar 25 01:23:44.944777 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Mar 25 01:23:44.944785 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Mar 25 01:23:44.944793 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Mar 25 01:23:44.944801 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 Mar 25 01:23:44.944809 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 Mar 25 01:23:44.944817 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 Mar 25 01:23:44.944825 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 Mar 25 01:23:44.944833 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 Mar 25 01:23:44.944843 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 Mar 25 01:23:44.944851 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 Mar 25 01:23:44.944859 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 Mar 25 01:23:44.944867 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 Mar 25 01:23:44.944875 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 Mar 25 01:23:44.944883 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 Mar 25 01:23:44.944891 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 Mar 25 01:23:44.944912 kernel: iommu: Default domain type: Translated Mar 25 01:23:44.944920 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Mar 25 01:23:44.944931 kernel: efivars: Registered efivars operations Mar 25 01:23:44.944939 kernel: PCI: Using ACPI for IRQ routing Mar 25 01:23:44.944947 kernel: PCI: pci_cache_line_size set to 64 bytes Mar 25 01:23:44.944955 kernel: e820: reserve RAM buffer [mem 0x0009f000-0x0009ffff] Mar 25 01:23:44.944963 kernel: e820: reserve RAM buffer [mem 0x9a14a018-0x9bffffff] Mar 25 01:23:44.944971 kernel: e820: reserve RAM buffer [mem 0x9a187018-0x9bffffff] Mar 25 01:23:44.944979 kernel: e820: reserve RAM buffer [mem 0x9b8ed000-0x9bffffff] Mar 25 01:23:44.944986 kernel: e820: reserve RAM buffer [mem 0x9bfb1000-0x9bffffff] Mar 25 01:23:44.945122 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device Mar 25 01:23:44.945254 kernel: pci 0000:00:01.0: vgaarb: bridge control possible Mar 25 01:23:44.945381 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Mar 25 01:23:44.945392 kernel: vgaarb: loaded Mar 25 01:23:44.945400 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Mar 25 01:23:44.945408 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Mar 25 01:23:44.945416 kernel: clocksource: Switched to clocksource kvm-clock Mar 25 01:23:44.945424 kernel: VFS: Disk quotas dquot_6.6.0 Mar 25 01:23:44.945436 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Mar 25 01:23:44.945447 kernel: pnp: PnP ACPI init Mar 25 01:23:44.945593 kernel: system 00:05: [mem 0xe0000000-0xefffffff window] has been reserved Mar 25 01:23:44.945606 kernel: pnp: PnP ACPI: found 6 devices Mar 25 01:23:44.945617 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Mar 25 01:23:44.945625 kernel: NET: Registered PF_INET protocol family Mar 25 01:23:44.945633 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Mar 25 01:23:44.945641 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Mar 25 01:23:44.945649 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Mar 25 01:23:44.945657 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Mar 25 01:23:44.945668 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Mar 25 01:23:44.945676 kernel: TCP: Hash tables configured (established 32768 bind 32768) Mar 25 01:23:44.945684 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Mar 25 01:23:44.945692 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Mar 25 01:23:44.945700 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Mar 25 01:23:44.945708 kernel: NET: Registered PF_XDP protocol family Mar 25 01:23:44.945836 kernel: pci 0000:00:04.0: can't claim BAR 6 [mem 0xfffc0000-0xffffffff pref]: no compatible bridge window Mar 25 01:23:44.945975 kernel: pci 0000:00:04.0: BAR 6: assigned [mem 0x9d000000-0x9d03ffff pref] Mar 25 01:23:44.946104 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Mar 25 01:23:44.946218 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Mar 25 01:23:44.946332 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Mar 25 01:23:44.946445 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xdfffffff window] Mar 25 01:23:44.946559 kernel: pci_bus 0000:00: resource 8 [mem 0xf0000000-0xfebfffff window] Mar 25 01:23:44.946671 kernel: pci_bus 0000:00: resource 9 [mem 0x380000000000-0x3807ffffffff window] Mar 25 01:23:44.946681 kernel: PCI: CLS 0 bytes, default 64 Mar 25 01:23:44.946689 kernel: Initialise system trusted keyrings Mar 25 01:23:44.946701 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Mar 25 01:23:44.946709 kernel: Key type asymmetric registered Mar 25 01:23:44.946716 kernel: Asymmetric key parser 'x509' registered Mar 25 01:23:44.946724 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Mar 25 01:23:44.946732 kernel: io scheduler mq-deadline registered Mar 25 01:23:44.946740 kernel: io scheduler kyber registered Mar 25 01:23:44.946748 kernel: io scheduler bfq registered Mar 25 01:23:44.946756 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Mar 25 01:23:44.946780 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 Mar 25 01:23:44.946793 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 Mar 25 01:23:44.946801 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 Mar 25 01:23:44.946809 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Mar 25 01:23:44.946818 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Mar 25 01:23:44.946827 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Mar 25 01:23:44.946835 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Mar 25 01:23:44.946843 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Mar 25 01:23:44.947057 kernel: rtc_cmos 00:04: RTC can wake from S4 Mar 25 01:23:44.947208 kernel: rtc_cmos 00:04: registered as rtc0 Mar 25 01:23:44.947239 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Mar 25 01:23:44.947399 kernel: rtc_cmos 00:04: setting system clock to 2025-03-25T01:23:44 UTC (1742865824) Mar 25 01:23:44.947516 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram Mar 25 01:23:44.947526 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled Mar 25 01:23:44.947535 kernel: efifb: probing for efifb Mar 25 01:23:44.947543 kernel: efifb: framebuffer at 0xc0000000, using 4000k, total 4000k Mar 25 01:23:44.947551 kernel: efifb: mode is 1280x800x32, linelength=5120, pages=1 Mar 25 01:23:44.947560 kernel: efifb: scrolling: redraw Mar 25 01:23:44.947572 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Mar 25 01:23:44.947580 kernel: Console: switching to colour frame buffer device 160x50 Mar 25 01:23:44.947588 kernel: fb0: EFI VGA frame buffer device Mar 25 01:23:44.947597 kernel: pstore: Using crash dump compression: deflate Mar 25 01:23:44.947605 kernel: pstore: Registered efi_pstore as persistent store backend Mar 25 01:23:44.947613 kernel: NET: Registered PF_INET6 protocol family Mar 25 01:23:44.947622 kernel: Segment Routing with IPv6 Mar 25 01:23:44.947630 kernel: In-situ OAM (IOAM) with IPv6 Mar 25 01:23:44.947638 kernel: NET: Registered PF_PACKET protocol family Mar 25 01:23:44.947649 kernel: Key type dns_resolver registered Mar 25 01:23:44.947659 kernel: IPI shorthand broadcast: enabled Mar 25 01:23:44.947667 kernel: sched_clock: Marking stable (659002959, 153866484)->(835520317, -22650874) Mar 25 01:23:44.947675 kernel: registered taskstats version 1 Mar 25 01:23:44.947684 kernel: Loading compiled-in X.509 certificates Mar 25 01:23:44.947693 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.83-flatcar: eff01054e94a599f8e404b9a9482f4e2220f5386' Mar 25 01:23:44.947703 kernel: Key type .fscrypt registered Mar 25 01:23:44.947711 kernel: Key type fscrypt-provisioning registered Mar 25 01:23:44.947720 kernel: ima: No TPM chip found, activating TPM-bypass! Mar 25 01:23:44.947728 kernel: ima: Allocated hash algorithm: sha1 Mar 25 01:23:44.947736 kernel: ima: No architecture policies found Mar 25 01:23:44.947744 kernel: clk: Disabling unused clocks Mar 25 01:23:44.947753 kernel: Freeing unused kernel image (initmem) memory: 43592K Mar 25 01:23:44.947761 kernel: Write protecting the kernel read-only data: 40960k Mar 25 01:23:44.947769 kernel: Freeing unused kernel image (rodata/data gap) memory: 1564K Mar 25 01:23:44.947780 kernel: Run /init as init process Mar 25 01:23:44.947788 kernel: with arguments: Mar 25 01:23:44.947796 kernel: /init Mar 25 01:23:44.947804 kernel: with environment: Mar 25 01:23:44.947812 kernel: HOME=/ Mar 25 01:23:44.947820 kernel: TERM=linux Mar 25 01:23:44.947828 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Mar 25 01:23:44.947838 systemd[1]: Successfully made /usr/ read-only. Mar 25 01:23:44.947851 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Mar 25 01:23:44.947861 systemd[1]: Detected virtualization kvm. Mar 25 01:23:44.947869 systemd[1]: Detected architecture x86-64. Mar 25 01:23:44.947878 systemd[1]: Running in initrd. Mar 25 01:23:44.947886 systemd[1]: No hostname configured, using default hostname. Mar 25 01:23:44.947895 systemd[1]: Hostname set to . Mar 25 01:23:44.947916 systemd[1]: Initializing machine ID from VM UUID. Mar 25 01:23:44.947925 systemd[1]: Queued start job for default target initrd.target. Mar 25 01:23:44.947937 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 25 01:23:44.947946 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 25 01:23:44.947955 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Mar 25 01:23:44.947964 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Mar 25 01:23:44.947973 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Mar 25 01:23:44.947982 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Mar 25 01:23:44.947995 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Mar 25 01:23:44.948004 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Mar 25 01:23:44.948013 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 25 01:23:44.948021 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Mar 25 01:23:44.948040 systemd[1]: Reached target paths.target - Path Units. Mar 25 01:23:44.948048 systemd[1]: Reached target slices.target - Slice Units. Mar 25 01:23:44.948057 systemd[1]: Reached target swap.target - Swaps. Mar 25 01:23:44.948066 systemd[1]: Reached target timers.target - Timer Units. Mar 25 01:23:44.948075 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Mar 25 01:23:44.948086 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Mar 25 01:23:44.948095 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Mar 25 01:23:44.948104 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Mar 25 01:23:44.948112 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Mar 25 01:23:44.948121 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Mar 25 01:23:44.948130 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Mar 25 01:23:44.948139 systemd[1]: Reached target sockets.target - Socket Units. Mar 25 01:23:44.948148 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Mar 25 01:23:44.948156 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Mar 25 01:23:44.948167 systemd[1]: Finished network-cleanup.service - Network Cleanup. Mar 25 01:23:44.948176 systemd[1]: Starting systemd-fsck-usr.service... Mar 25 01:23:44.948185 systemd[1]: Starting systemd-journald.service - Journal Service... Mar 25 01:23:44.948193 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Mar 25 01:23:44.948202 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 25 01:23:44.948211 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Mar 25 01:23:44.948220 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Mar 25 01:23:44.948231 systemd[1]: Finished systemd-fsck-usr.service. Mar 25 01:23:44.948265 systemd-journald[191]: Collecting audit messages is disabled. Mar 25 01:23:44.948289 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Mar 25 01:23:44.948298 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 25 01:23:44.948307 systemd-journald[191]: Journal started Mar 25 01:23:44.948327 systemd-journald[191]: Runtime Journal (/run/log/journal/e595107b9aad499d872dc7db14dac4ce) is 6M, max 47.9M, 41.9M free. Mar 25 01:23:44.949395 systemd-modules-load[193]: Inserted module 'overlay' Mar 25 01:23:44.953940 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 25 01:23:44.956924 systemd[1]: Started systemd-journald.service - Journal Service. Mar 25 01:23:44.957143 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Mar 25 01:23:44.963059 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Mar 25 01:23:44.966642 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Mar 25 01:23:44.975208 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 25 01:23:44.978484 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 25 01:23:44.982778 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Mar 25 01:23:44.996926 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Mar 25 01:23:44.998198 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 25 01:23:45.001535 systemd-modules-load[193]: Inserted module 'br_netfilter' Mar 25 01:23:45.002472 kernel: Bridge firewalling registered Mar 25 01:23:45.003601 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Mar 25 01:23:45.005838 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Mar 25 01:23:45.017467 dracut-cmdline[224]: dracut-dracut-053 Mar 25 01:23:45.022325 dracut-cmdline[224]: Using kernel command line parameters: rd.driver.pre=btrfs rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected verity.usrhash=e7a00b7ee8d97e8d255663e9d3fa92277da8316702fb7f6d664fd7b137c307e9 Mar 25 01:23:45.033307 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Mar 25 01:23:45.037647 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Mar 25 01:23:45.087570 systemd-resolved[255]: Positive Trust Anchors: Mar 25 01:23:45.087584 systemd-resolved[255]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 25 01:23:45.087614 systemd-resolved[255]: 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 Mar 25 01:23:45.090121 systemd-resolved[255]: Defaulting to hostname 'linux'. Mar 25 01:23:45.091174 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Mar 25 01:23:45.097667 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Mar 25 01:23:45.114931 kernel: SCSI subsystem initialized Mar 25 01:23:45.123919 kernel: Loading iSCSI transport class v2.0-870. Mar 25 01:23:45.134932 kernel: iscsi: registered transport (tcp) Mar 25 01:23:45.155927 kernel: iscsi: registered transport (qla4xxx) Mar 25 01:23:45.155960 kernel: QLogic iSCSI HBA Driver Mar 25 01:23:45.204446 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Mar 25 01:23:45.207301 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Mar 25 01:23:45.245634 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Mar 25 01:23:45.245714 kernel: device-mapper: uevent: version 1.0.3 Mar 25 01:23:45.245727 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Mar 25 01:23:45.285938 kernel: raid6: avx2x4 gen() 30672 MB/s Mar 25 01:23:45.302929 kernel: raid6: avx2x2 gen() 31324 MB/s Mar 25 01:23:45.320043 kernel: raid6: avx2x1 gen() 25475 MB/s Mar 25 01:23:45.320073 kernel: raid6: using algorithm avx2x2 gen() 31324 MB/s Mar 25 01:23:45.338084 kernel: raid6: .... xor() 19515 MB/s, rmw enabled Mar 25 01:23:45.338127 kernel: raid6: using avx2x2 recovery algorithm Mar 25 01:23:45.358930 kernel: xor: automatically using best checksumming function avx Mar 25 01:23:45.516935 kernel: Btrfs loaded, zoned=no, fsverity=no Mar 25 01:23:45.530412 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Mar 25 01:23:45.534694 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 25 01:23:45.571069 systemd-udevd[416]: Using default interface naming scheme 'v255'. Mar 25 01:23:45.577162 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 25 01:23:45.580325 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Mar 25 01:23:45.603852 dracut-pre-trigger[420]: rd.md=0: removing MD RAID activation Mar 25 01:23:45.638511 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Mar 25 01:23:45.642538 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Mar 25 01:23:45.722230 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Mar 25 01:23:45.727096 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Mar 25 01:23:45.750613 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Mar 25 01:23:45.752939 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues Mar 25 01:23:45.759815 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Mar 25 01:23:45.760314 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 25 01:23:45.756312 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Mar 25 01:23:45.758924 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 25 01:23:45.762963 systemd[1]: Reached target remote-fs.target - Remote File Systems. Mar 25 01:23:45.766188 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Mar 25 01:23:45.782504 kernel: libata version 3.00 loaded. Mar 25 01:23:45.782555 kernel: cryptd: max_cpu_qlen set to 1000 Mar 25 01:23:45.789182 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Mar 25 01:23:45.795121 kernel: ahci 0000:00:1f.2: version 3.0 Mar 25 01:23:45.835615 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 Mar 25 01:23:45.835637 kernel: ahci 0000:00:1f.2: AHCI 0001.0000 32 slots 6 ports 1.5 Gbps 0x3f impl SATA mode Mar 25 01:23:45.835821 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only Mar 25 01:23:45.836089 kernel: AVX2 version of gcm_enc/dec engaged. Mar 25 01:23:45.836104 kernel: AES CTR mode by8 optimization enabled Mar 25 01:23:45.836119 kernel: scsi host0: ahci Mar 25 01:23:45.836308 kernel: scsi host1: ahci Mar 25 01:23:45.836497 kernel: scsi host2: ahci Mar 25 01:23:45.836672 kernel: scsi host3: ahci Mar 25 01:23:45.836857 kernel: scsi host4: ahci Mar 25 01:23:45.837068 kernel: BTRFS: device fsid 6d9424cd-1432-492b-b006-b311869817e2 devid 1 transid 39 /dev/vda3 scanned by (udev-worker) (464) Mar 25 01:23:45.837083 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 scanned by (udev-worker) (476) Mar 25 01:23:45.837097 kernel: scsi host5: ahci Mar 25 01:23:45.837290 kernel: ata1: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040100 irq 34 Mar 25 01:23:45.837307 kernel: ata2: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040180 irq 34 Mar 25 01:23:45.837322 kernel: ata3: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040200 irq 34 Mar 25 01:23:45.837336 kernel: ata4: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040280 irq 34 Mar 25 01:23:45.837350 kernel: ata5: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040300 irq 34 Mar 25 01:23:45.837364 kernel: ata6: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040380 irq 34 Mar 25 01:23:45.806885 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Mar 25 01:23:45.807122 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 25 01:23:45.808630 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 25 01:23:45.809846 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 25 01:23:45.810105 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 25 01:23:45.815357 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Mar 25 01:23:45.819857 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 25 01:23:45.856830 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Mar 25 01:23:45.859915 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 25 01:23:45.890008 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Mar 25 01:23:45.900865 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Mar 25 01:23:45.902535 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Mar 25 01:23:45.911300 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Mar 25 01:23:45.913540 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Mar 25 01:23:45.915539 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 25 01:23:45.939187 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 25 01:23:45.944931 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 25 01:23:45.947090 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 25 01:23:46.141628 kernel: ata2: SATA link down (SStatus 0 SControl 300) Mar 25 01:23:46.141716 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Mar 25 01:23:46.141733 kernel: ata1: SATA link down (SStatus 0 SControl 300) Mar 25 01:23:46.143185 kernel: ata4: SATA link down (SStatus 0 SControl 300) Mar 25 01:23:46.143269 kernel: ata5: SATA link down (SStatus 0 SControl 300) Mar 25 01:23:46.143922 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 Mar 25 01:23:46.145306 kernel: ata3.00: applying bridge limits Mar 25 01:23:46.145400 kernel: ata3.00: configured for UDMA/100 Mar 25 01:23:46.146931 kernel: scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 Mar 25 01:23:46.151942 kernel: ata6: SATA link down (SStatus 0 SControl 300) Mar 25 01:23:46.197961 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray Mar 25 01:23:46.212031 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Mar 25 01:23:46.212056 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 Mar 25 01:23:46.950934 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 25 01:23:46.951572 disk-uuid[560]: The operation has completed successfully. Mar 25 01:23:46.984155 systemd[1]: disk-uuid.service: Deactivated successfully. Mar 25 01:23:46.984282 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Mar 25 01:23:47.017224 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Mar 25 01:23:47.041178 sh[594]: Success Mar 25 01:23:47.053961 kernel: device-mapper: verity: sha256 using implementation "sha256-ni" Mar 25 01:23:47.097450 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Mar 25 01:23:47.102580 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Mar 25 01:23:47.116403 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Mar 25 01:23:47.128670 kernel: BTRFS info (device dm-0): first mount of filesystem 6d9424cd-1432-492b-b006-b311869817e2 Mar 25 01:23:47.128707 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Mar 25 01:23:47.128719 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Mar 25 01:23:47.129878 kernel: BTRFS info (device dm-0): disabling log replay at mount time Mar 25 01:23:47.130891 kernel: BTRFS info (device dm-0): using free space tree Mar 25 01:23:47.137312 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Mar 25 01:23:47.140065 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Mar 25 01:23:47.143182 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Mar 25 01:23:47.146235 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Mar 25 01:23:47.167588 kernel: BTRFS info (device vda6): first mount of filesystem a72930ba-1354-475c-94df-b83a66efea67 Mar 25 01:23:47.167642 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Mar 25 01:23:47.167660 kernel: BTRFS info (device vda6): using free space tree Mar 25 01:23:47.171956 kernel: BTRFS info (device vda6): auto enabling async discard Mar 25 01:23:47.177935 kernel: BTRFS info (device vda6): last unmount of filesystem a72930ba-1354-475c-94df-b83a66efea67 Mar 25 01:23:47.186047 systemd[1]: Finished ignition-setup.service - Ignition (setup). Mar 25 01:23:47.187156 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Mar 25 01:23:47.274226 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Mar 25 01:23:47.280473 systemd[1]: Starting systemd-networkd.service - Network Configuration... Mar 25 01:23:47.302599 ignition[687]: Ignition 2.20.0 Mar 25 01:23:47.302611 ignition[687]: Stage: fetch-offline Mar 25 01:23:47.302657 ignition[687]: no configs at "/usr/lib/ignition/base.d" Mar 25 01:23:47.302668 ignition[687]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 25 01:23:47.302770 ignition[687]: parsed url from cmdline: "" Mar 25 01:23:47.302775 ignition[687]: no config URL provided Mar 25 01:23:47.302780 ignition[687]: reading system config file "/usr/lib/ignition/user.ign" Mar 25 01:23:47.302789 ignition[687]: no config at "/usr/lib/ignition/user.ign" Mar 25 01:23:47.302817 ignition[687]: op(1): [started] loading QEMU firmware config module Mar 25 01:23:47.302822 ignition[687]: op(1): executing: "modprobe" "qemu_fw_cfg" Mar 25 01:23:47.313066 ignition[687]: op(1): [finished] loading QEMU firmware config module Mar 25 01:23:47.314479 ignition[687]: parsing config with SHA512: 0bee497f70c6ed454e15017cc86137d97b9abea9ce1364b625736caa7b7cd69fadec5dabb9bbf4fbaddd1be754741c4592516c8c1c0670e6025ef0907092d715 Mar 25 01:23:47.321165 unknown[687]: fetched base config from "system" Mar 25 01:23:47.321183 unknown[687]: fetched user config from "qemu" Mar 25 01:23:47.321426 ignition[687]: fetch-offline: fetch-offline passed Mar 25 01:23:47.321528 ignition[687]: Ignition finished successfully Mar 25 01:23:47.324988 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Mar 25 01:23:47.340993 systemd-networkd[777]: lo: Link UP Mar 25 01:23:47.341007 systemd-networkd[777]: lo: Gained carrier Mar 25 01:23:47.343189 systemd-networkd[777]: Enumeration completed Mar 25 01:23:47.343324 systemd[1]: Started systemd-networkd.service - Network Configuration. Mar 25 01:23:47.343644 systemd-networkd[777]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 25 01:23:47.343650 systemd-networkd[777]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 25 01:23:47.344702 systemd-networkd[777]: eth0: Link UP Mar 25 01:23:47.344707 systemd-networkd[777]: eth0: Gained carrier Mar 25 01:23:47.344715 systemd-networkd[777]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 25 01:23:47.347232 systemd[1]: Reached target network.target - Network. Mar 25 01:23:47.351137 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Mar 25 01:23:47.352298 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Mar 25 01:23:47.371034 systemd-networkd[777]: eth0: DHCPv4 address 10.0.0.15/16, gateway 10.0.0.1 acquired from 10.0.0.1 Mar 25 01:23:47.398585 ignition[785]: Ignition 2.20.0 Mar 25 01:23:47.398598 ignition[785]: Stage: kargs Mar 25 01:23:47.398801 ignition[785]: no configs at "/usr/lib/ignition/base.d" Mar 25 01:23:47.398815 ignition[785]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 25 01:23:47.399591 ignition[785]: kargs: kargs passed Mar 25 01:23:47.403413 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Mar 25 01:23:47.399647 ignition[785]: Ignition finished successfully Mar 25 01:23:47.406816 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Mar 25 01:23:47.434916 ignition[795]: Ignition 2.20.0 Mar 25 01:23:47.434931 ignition[795]: Stage: disks Mar 25 01:23:47.435136 ignition[795]: no configs at "/usr/lib/ignition/base.d" Mar 25 01:23:47.435151 ignition[795]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 25 01:23:47.438041 systemd[1]: Finished ignition-disks.service - Ignition (disks). Mar 25 01:23:47.435795 ignition[795]: disks: disks passed Mar 25 01:23:47.440637 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Mar 25 01:23:47.435840 ignition[795]: Ignition finished successfully Mar 25 01:23:47.443286 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Mar 25 01:23:47.444725 systemd[1]: Reached target local-fs.target - Local File Systems. Mar 25 01:23:47.445972 systemd[1]: Reached target sysinit.target - System Initialization. Mar 25 01:23:47.447234 systemd[1]: Reached target basic.target - Basic System. Mar 25 01:23:47.450132 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Mar 25 01:23:47.475515 systemd-resolved[255]: Detected conflict on linux IN A 10.0.0.15 Mar 25 01:23:47.475536 systemd-resolved[255]: Hostname conflict, changing published hostname from 'linux' to 'linux3'. Mar 25 01:23:47.479729 systemd-fsck[805]: ROOT: clean, 14/553520 files, 52654/553472 blocks Mar 25 01:23:47.487112 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Mar 25 01:23:47.488541 systemd[1]: Mounting sysroot.mount - /sysroot... Mar 25 01:23:47.587933 kernel: EXT4-fs (vda9): mounted filesystem 4e6dca82-2e50-453c-be25-61f944b72008 r/w with ordered data mode. Quota mode: none. Mar 25 01:23:47.588683 systemd[1]: Mounted sysroot.mount - /sysroot. Mar 25 01:23:47.589298 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Mar 25 01:23:47.590658 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Mar 25 01:23:47.591711 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Mar 25 01:23:47.593725 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Mar 25 01:23:47.593774 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Mar 25 01:23:47.593801 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Mar 25 01:23:47.608583 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Mar 25 01:23:47.611703 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Mar 25 01:23:47.618912 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/vda6 scanned by mount (813) Mar 25 01:23:47.618941 kernel: BTRFS info (device vda6): first mount of filesystem a72930ba-1354-475c-94df-b83a66efea67 Mar 25 01:23:47.618968 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Mar 25 01:23:47.618982 kernel: BTRFS info (device vda6): using free space tree Mar 25 01:23:47.618996 kernel: BTRFS info (device vda6): auto enabling async discard Mar 25 01:23:47.620945 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Mar 25 01:23:47.681312 initrd-setup-root[837]: cut: /sysroot/etc/passwd: No such file or directory Mar 25 01:23:47.687195 initrd-setup-root[844]: cut: /sysroot/etc/group: No such file or directory Mar 25 01:23:47.692581 initrd-setup-root[851]: cut: /sysroot/etc/shadow: No such file or directory Mar 25 01:23:47.697345 initrd-setup-root[858]: cut: /sysroot/etc/gshadow: No such file or directory Mar 25 01:23:47.814776 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Mar 25 01:23:47.818658 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Mar 25 01:23:47.820765 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Mar 25 01:23:47.847946 kernel: BTRFS info (device vda6): last unmount of filesystem a72930ba-1354-475c-94df-b83a66efea67 Mar 25 01:23:47.868032 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Mar 25 01:23:47.889302 ignition[927]: INFO : Ignition 2.20.0 Mar 25 01:23:47.889302 ignition[927]: INFO : Stage: mount Mar 25 01:23:47.891595 ignition[927]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 25 01:23:47.891595 ignition[927]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 25 01:23:47.891595 ignition[927]: INFO : mount: mount passed Mar 25 01:23:47.891595 ignition[927]: INFO : Ignition finished successfully Mar 25 01:23:47.892719 systemd[1]: Finished ignition-mount.service - Ignition (mount). Mar 25 01:23:47.895524 systemd[1]: Starting ignition-files.service - Ignition (files)... Mar 25 01:23:48.123275 systemd[1]: sysroot-oem.mount: Deactivated successfully. Mar 25 01:23:48.125360 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Mar 25 01:23:48.147240 kernel: BTRFS: device label OEM devid 1 transid 17 /dev/vda6 scanned by mount (939) Mar 25 01:23:48.147287 kernel: BTRFS info (device vda6): first mount of filesystem a72930ba-1354-475c-94df-b83a66efea67 Mar 25 01:23:48.147299 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Mar 25 01:23:48.148929 kernel: BTRFS info (device vda6): using free space tree Mar 25 01:23:48.151937 kernel: BTRFS info (device vda6): auto enabling async discard Mar 25 01:23:48.153805 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Mar 25 01:23:48.186017 ignition[956]: INFO : Ignition 2.20.0 Mar 25 01:23:48.186017 ignition[956]: INFO : Stage: files Mar 25 01:23:48.188198 ignition[956]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 25 01:23:48.188198 ignition[956]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 25 01:23:48.188198 ignition[956]: DEBUG : files: compiled without relabeling support, skipping Mar 25 01:23:48.188198 ignition[956]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Mar 25 01:23:48.188198 ignition[956]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Mar 25 01:23:48.195362 ignition[956]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Mar 25 01:23:48.195362 ignition[956]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Mar 25 01:23:48.195362 ignition[956]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Mar 25 01:23:48.195362 ignition[956]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Mar 25 01:23:48.195362 ignition[956]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Mar 25 01:23:48.195362 ignition[956]: INFO : files: op(4): [started] processing unit "etcd-member.service" Mar 25 01:23:48.195362 ignition[956]: INFO : files: op(4): op(5): [started] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Mar 25 01:23:48.195362 ignition[956]: INFO : files: op(4): op(5): [finished] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Mar 25 01:23:48.195362 ignition[956]: INFO : files: op(4): [finished] processing unit "etcd-member.service" Mar 25 01:23:48.195362 ignition[956]: INFO : files: op(6): [started] processing unit "coreos-metadata.service" Mar 25 01:23:48.195362 ignition[956]: INFO : files: op(6): op(7): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Mar 25 01:23:48.195362 ignition[956]: INFO : files: op(6): op(7): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Mar 25 01:23:48.195362 ignition[956]: INFO : files: op(6): [finished] processing unit "coreos-metadata.service" Mar 25 01:23:48.195362 ignition[956]: INFO : files: op(8): [started] setting preset to disabled for "coreos-metadata.service" Mar 25 01:23:48.191221 unknown[956]: wrote ssh authorized keys file for user: core Mar 25 01:23:48.224456 ignition[956]: INFO : files: op(8): op(9): [started] removing enablement symlink(s) for "coreos-metadata.service" Mar 25 01:23:48.226084 ignition[956]: INFO : files: op(8): op(9): [finished] removing enablement symlink(s) for "coreos-metadata.service" Mar 25 01:23:48.226084 ignition[956]: INFO : files: op(8): [finished] setting preset to disabled for "coreos-metadata.service" Mar 25 01:23:48.226084 ignition[956]: INFO : files: op(a): [started] setting preset to enabled for "etcd-member.service" Mar 25 01:23:48.226084 ignition[956]: INFO : files: op(a): [finished] setting preset to enabled for "etcd-member.service" Mar 25 01:23:48.226084 ignition[956]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Mar 25 01:23:48.226084 ignition[956]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Mar 25 01:23:48.226084 ignition[956]: INFO : files: files passed Mar 25 01:23:48.226084 ignition[956]: INFO : Ignition finished successfully Mar 25 01:23:48.228503 systemd[1]: Finished ignition-files.service - Ignition (files). Mar 25 01:23:48.232199 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Mar 25 01:23:48.234856 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Mar 25 01:23:48.257323 systemd[1]: ignition-quench.service: Deactivated successfully. Mar 25 01:23:48.257485 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Mar 25 01:23:48.261425 initrd-setup-root-after-ignition[986]: grep: /sysroot/oem/oem-release: No such file or directory Mar 25 01:23:48.266239 initrd-setup-root-after-ignition[988]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Mar 25 01:23:48.266239 initrd-setup-root-after-ignition[988]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Mar 25 01:23:48.270137 initrd-setup-root-after-ignition[992]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Mar 25 01:23:48.273580 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Mar 25 01:23:48.274081 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Mar 25 01:23:48.278425 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Mar 25 01:23:48.350358 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Mar 25 01:23:48.350527 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Mar 25 01:23:48.352328 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Mar 25 01:23:48.356657 systemd[1]: Reached target initrd.target - Initrd Default Target. Mar 25 01:23:48.357017 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Mar 25 01:23:48.360337 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Mar 25 01:23:48.386880 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Mar 25 01:23:48.388888 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Mar 25 01:23:48.414373 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Mar 25 01:23:48.415972 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 25 01:23:48.416346 systemd[1]: Stopped target timers.target - Timer Units. Mar 25 01:23:48.416714 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Mar 25 01:23:48.416843 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Mar 25 01:23:48.427300 systemd[1]: Stopped target initrd.target - Initrd Default Target. Mar 25 01:23:48.427486 systemd[1]: Stopped target basic.target - Basic System. Mar 25 01:23:48.430423 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Mar 25 01:23:48.433761 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Mar 25 01:23:48.435122 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Mar 25 01:23:48.437719 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Mar 25 01:23:48.438727 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Mar 25 01:23:48.439345 systemd[1]: Stopped target sysinit.target - System Initialization. Mar 25 01:23:48.439678 systemd[1]: Stopped target local-fs.target - Local File Systems. Mar 25 01:23:48.440024 systemd[1]: Stopped target swap.target - Swaps. Mar 25 01:23:48.440488 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Mar 25 01:23:48.440665 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Mar 25 01:23:48.451585 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Mar 25 01:23:48.451766 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 25 01:23:48.452336 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Mar 25 01:23:48.458990 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 25 01:23:48.462794 systemd[1]: dracut-initqueue.service: Deactivated successfully. Mar 25 01:23:48.463006 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Mar 25 01:23:48.466292 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Mar 25 01:23:48.466471 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Mar 25 01:23:48.467734 systemd[1]: Stopped target paths.target - Path Units. Mar 25 01:23:48.471104 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Mar 25 01:23:48.471987 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 25 01:23:48.475944 systemd[1]: Stopped target slices.target - Slice Units. Mar 25 01:23:48.477825 systemd[1]: Stopped target sockets.target - Socket Units. Mar 25 01:23:48.479802 systemd[1]: iscsid.socket: Deactivated successfully. Mar 25 01:23:48.480724 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Mar 25 01:23:48.482784 systemd[1]: iscsiuio.socket: Deactivated successfully. Mar 25 01:23:48.483718 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Mar 25 01:23:48.485818 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Mar 25 01:23:48.487040 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Mar 25 01:23:48.489943 systemd[1]: ignition-files.service: Deactivated successfully. Mar 25 01:23:48.490104 systemd[1]: Stopped ignition-files.service - Ignition (files). Mar 25 01:23:48.494675 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Mar 25 01:23:48.497771 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Mar 25 01:23:48.499942 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Mar 25 01:23:48.501155 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Mar 25 01:23:48.504021 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Mar 25 01:23:48.505267 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Mar 25 01:23:48.512232 systemd[1]: initrd-cleanup.service: Deactivated successfully. Mar 25 01:23:48.513297 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Mar 25 01:23:48.524519 ignition[1012]: INFO : Ignition 2.20.0 Mar 25 01:23:48.524519 ignition[1012]: INFO : Stage: umount Mar 25 01:23:48.526580 ignition[1012]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 25 01:23:48.526580 ignition[1012]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 25 01:23:48.526580 ignition[1012]: INFO : umount: umount passed Mar 25 01:23:48.526580 ignition[1012]: INFO : Ignition finished successfully Mar 25 01:23:48.527681 systemd[1]: sysroot-boot.mount: Deactivated successfully. Mar 25 01:23:48.533045 systemd[1]: sysroot-boot.service: Deactivated successfully. Mar 25 01:23:48.533187 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Mar 25 01:23:48.537127 systemd[1]: ignition-mount.service: Deactivated successfully. Mar 25 01:23:48.537276 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Mar 25 01:23:48.538378 systemd[1]: Stopped target network.target - Network. Mar 25 01:23:48.540927 systemd[1]: ignition-disks.service: Deactivated successfully. Mar 25 01:23:48.540998 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Mar 25 01:23:48.542801 systemd[1]: ignition-kargs.service: Deactivated successfully. Mar 25 01:23:48.542859 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Mar 25 01:23:48.543848 systemd[1]: ignition-setup.service: Deactivated successfully. Mar 25 01:23:48.543926 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Mar 25 01:23:48.544360 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Mar 25 01:23:48.544417 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Mar 25 01:23:48.544688 systemd[1]: initrd-setup-root.service: Deactivated successfully. Mar 25 01:23:48.544742 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Mar 25 01:23:48.545498 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Mar 25 01:23:48.552128 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Mar 25 01:23:48.563138 systemd[1]: systemd-resolved.service: Deactivated successfully. Mar 25 01:23:48.563291 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Mar 25 01:23:48.568084 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Mar 25 01:23:48.568355 systemd[1]: systemd-networkd.service: Deactivated successfully. Mar 25 01:23:48.568510 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Mar 25 01:23:48.572826 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Mar 25 01:23:48.573761 systemd[1]: systemd-networkd.socket: Deactivated successfully. Mar 25 01:23:48.573835 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Mar 25 01:23:48.575278 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Mar 25 01:23:48.576455 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Mar 25 01:23:48.576521 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Mar 25 01:23:48.576868 systemd[1]: systemd-sysctl.service: Deactivated successfully. Mar 25 01:23:48.576940 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Mar 25 01:23:48.582771 systemd[1]: systemd-modules-load.service: Deactivated successfully. Mar 25 01:23:48.582823 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Mar 25 01:23:48.585373 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Mar 25 01:23:48.585425 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 25 01:23:48.589653 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 25 01:23:48.598611 systemd[1]: network-cleanup.service: Deactivated successfully. Mar 25 01:23:48.598772 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Mar 25 01:23:48.614964 systemd[1]: systemd-udevd.service: Deactivated successfully. Mar 25 01:23:48.615171 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 25 01:23:48.617738 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Mar 25 01:23:48.617804 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Mar 25 01:23:48.619887 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Mar 25 01:23:48.619968 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Mar 25 01:23:48.620954 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Mar 25 01:23:48.621025 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Mar 25 01:23:48.625133 systemd[1]: dracut-cmdline.service: Deactivated successfully. Mar 25 01:23:48.625203 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Mar 25 01:23:48.629118 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Mar 25 01:23:48.629195 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 25 01:23:48.635591 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Mar 25 01:23:48.635670 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Mar 25 01:23:48.635730 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 25 01:23:48.640399 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Mar 25 01:23:48.640476 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Mar 25 01:23:48.641782 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Mar 25 01:23:48.641847 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Mar 25 01:23:48.642162 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 25 01:23:48.642222 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 25 01:23:48.670872 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Mar 25 01:23:48.671057 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Mar 25 01:23:48.673445 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Mar 25 01:23:48.676395 systemd[1]: Starting initrd-switch-root.service - Switch Root... Mar 25 01:23:48.699516 systemd[1]: Switching root. Mar 25 01:23:48.725516 systemd-journald[191]: Journal stopped Mar 25 01:23:49.733799 systemd-journald[191]: Received SIGTERM from PID 1 (systemd). Mar 25 01:23:49.733884 kernel: SELinux: policy capability network_peer_controls=1 Mar 25 01:23:49.733976 kernel: SELinux: policy capability open_perms=1 Mar 25 01:23:49.733992 kernel: SELinux: policy capability extended_socket_class=1 Mar 25 01:23:49.734007 kernel: SELinux: policy capability always_check_network=0 Mar 25 01:23:49.734087 kernel: SELinux: policy capability cgroup_seclabel=1 Mar 25 01:23:49.734110 kernel: SELinux: policy capability nnp_nosuid_transition=1 Mar 25 01:23:49.734124 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Mar 25 01:23:49.734138 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Mar 25 01:23:49.734158 kernel: audit: type=1403 audit(1742865828.821:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Mar 25 01:23:49.735977 systemd[1]: Successfully loaded SELinux policy in 47.872ms. Mar 25 01:23:49.736014 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 18.332ms. Mar 25 01:23:49.736036 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Mar 25 01:23:49.736053 systemd[1]: Detected virtualization kvm. Mar 25 01:23:49.736069 systemd[1]: Detected architecture x86-64. Mar 25 01:23:49.736109 systemd[1]: Detected first boot. Mar 25 01:23:49.736126 systemd[1]: Initializing machine ID from VM UUID. Mar 25 01:23:49.736148 zram_generator::config[1061]: No configuration found. Mar 25 01:23:49.736166 kernel: Guest personality initialized and is inactive Mar 25 01:23:49.736199 kernel: VMCI host device registered (name=vmci, major=10, minor=125) Mar 25 01:23:49.736215 kernel: Initialized host personality Mar 25 01:23:49.736230 kernel: NET: Registered PF_VSOCK protocol family Mar 25 01:23:49.736245 systemd[1]: Populated /etc with preset unit settings. Mar 25 01:23:49.736263 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Mar 25 01:23:49.736280 systemd[1]: initrd-switch-root.service: Deactivated successfully. Mar 25 01:23:49.736297 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Mar 25 01:23:49.736318 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Mar 25 01:23:49.736335 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Mar 25 01:23:49.736351 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Mar 25 01:23:49.736375 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Mar 25 01:23:49.736393 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Mar 25 01:23:49.736413 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Mar 25 01:23:49.736430 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Mar 25 01:23:49.736447 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Mar 25 01:23:49.736463 systemd[1]: Created slice user.slice - User and Session Slice. Mar 25 01:23:49.736479 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 25 01:23:49.736510 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 25 01:23:49.736531 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Mar 25 01:23:49.736548 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Mar 25 01:23:49.736564 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Mar 25 01:23:49.736591 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Mar 25 01:23:49.736607 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Mar 25 01:23:49.736623 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 25 01:23:49.736639 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Mar 25 01:23:49.736656 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Mar 25 01:23:49.736672 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Mar 25 01:23:49.736688 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Mar 25 01:23:49.736708 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 25 01:23:49.736725 systemd[1]: Reached target remote-fs.target - Remote File Systems. Mar 25 01:23:49.736741 systemd[1]: Reached target slices.target - Slice Units. Mar 25 01:23:49.736757 systemd[1]: Reached target swap.target - Swaps. Mar 25 01:23:49.736773 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Mar 25 01:23:49.736790 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Mar 25 01:23:49.736806 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Mar 25 01:23:49.736823 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Mar 25 01:23:49.736839 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Mar 25 01:23:49.736855 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Mar 25 01:23:49.736888 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Mar 25 01:23:49.736930 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Mar 25 01:23:49.736948 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Mar 25 01:23:49.736964 systemd[1]: Mounting media.mount - External Media Directory... Mar 25 01:23:49.736980 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 25 01:23:49.736996 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Mar 25 01:23:49.737013 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Mar 25 01:23:49.737029 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Mar 25 01:23:49.737050 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). Mar 25 01:23:49.737068 systemd[1]: Reached target machines.target - Containers. Mar 25 01:23:49.737084 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Mar 25 01:23:49.737101 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 25 01:23:49.737117 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Mar 25 01:23:49.737133 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Mar 25 01:23:49.737150 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 25 01:23:49.737167 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Mar 25 01:23:49.737183 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 25 01:23:49.737204 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Mar 25 01:23:49.737221 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 25 01:23:49.737237 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Mar 25 01:23:49.737254 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Mar 25 01:23:49.737284 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Mar 25 01:23:49.737300 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Mar 25 01:23:49.737316 systemd[1]: Stopped systemd-fsck-usr.service. Mar 25 01:23:49.737331 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Mar 25 01:23:49.737351 systemd[1]: Starting systemd-journald.service - Journal Service... Mar 25 01:23:49.737366 kernel: fuse: init (API version 7.39) Mar 25 01:23:49.737380 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Mar 25 01:23:49.737395 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Mar 25 01:23:49.737410 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Mar 25 01:23:49.737425 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Mar 25 01:23:49.737442 kernel: loop: module loaded Mar 25 01:23:49.737492 systemd-journald[1138]: Collecting audit messages is disabled. Mar 25 01:23:49.737522 kernel: ACPI: bus type drm_connector registered Mar 25 01:23:49.737537 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Mar 25 01:23:49.737552 systemd[1]: verity-setup.service: Deactivated successfully. Mar 25 01:23:49.737566 systemd[1]: Stopped verity-setup.service. Mar 25 01:23:49.737584 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 25 01:23:49.737599 systemd-journald[1138]: Journal started Mar 25 01:23:49.737626 systemd-journald[1138]: Runtime Journal (/run/log/journal/e595107b9aad499d872dc7db14dac4ce) is 6M, max 47.9M, 41.9M free. Mar 25 01:23:49.482311 systemd[1]: Queued start job for default target multi-user.target. Mar 25 01:23:49.497270 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Mar 25 01:23:49.497791 systemd[1]: systemd-journald.service: Deactivated successfully. Mar 25 01:23:49.743610 systemd[1]: Started systemd-journald.service - Journal Service. Mar 25 01:23:49.744481 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Mar 25 01:23:49.745788 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Mar 25 01:23:49.747137 systemd[1]: Mounted media.mount - External Media Directory. Mar 25 01:23:49.748329 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Mar 25 01:23:49.749626 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Mar 25 01:23:49.750995 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Mar 25 01:23:49.752419 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Mar 25 01:23:49.754056 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Mar 25 01:23:49.755694 systemd[1]: modprobe@configfs.service: Deactivated successfully. Mar 25 01:23:49.755930 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Mar 25 01:23:49.757614 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 25 01:23:49.757850 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 25 01:23:49.759453 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 25 01:23:49.759684 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Mar 25 01:23:49.761231 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 25 01:23:49.761468 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 25 01:23:49.763186 systemd[1]: modprobe@fuse.service: Deactivated successfully. Mar 25 01:23:49.763420 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Mar 25 01:23:49.764889 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 25 01:23:49.765108 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 25 01:23:49.766655 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Mar 25 01:23:49.768213 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Mar 25 01:23:49.769812 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Mar 25 01:23:49.771611 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Mar 25 01:23:49.785337 systemd[1]: Reached target network-pre.target - Preparation for Network. Mar 25 01:23:49.788415 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Mar 25 01:23:49.791198 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Mar 25 01:23:49.792696 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Mar 25 01:23:49.792733 systemd[1]: Reached target local-fs.target - Local File Systems. Mar 25 01:23:49.795474 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Mar 25 01:23:49.800969 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Mar 25 01:23:49.803624 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Mar 25 01:23:49.805146 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 25 01:23:49.806867 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Mar 25 01:23:49.810437 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Mar 25 01:23:49.811871 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 25 01:23:49.814718 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Mar 25 01:23:49.816043 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Mar 25 01:23:49.818485 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Mar 25 01:23:49.824233 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Mar 25 01:23:49.827129 systemd-journald[1138]: Time spent on flushing to /var/log/journal/e595107b9aad499d872dc7db14dac4ce is 24.445ms for 996 entries. Mar 25 01:23:49.827129 systemd-journald[1138]: System Journal (/var/log/journal/e595107b9aad499d872dc7db14dac4ce) is 8M, max 195.6M, 187.6M free. Mar 25 01:23:49.862956 systemd-journald[1138]: Received client request to flush runtime journal. Mar 25 01:23:49.863007 kernel: loop0: detected capacity change from 0 to 109808 Mar 25 01:23:49.829193 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Mar 25 01:23:49.838516 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Mar 25 01:23:49.840925 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Mar 25 01:23:49.842961 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Mar 25 01:23:49.858025 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Mar 25 01:23:49.859664 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Mar 25 01:23:49.865067 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Mar 25 01:23:49.867786 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Mar 25 01:23:49.873975 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Mar 25 01:23:49.891773 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Mar 25 01:23:49.896396 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Mar 25 01:23:49.893978 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Mar 25 01:23:49.904718 systemd-tmpfiles[1182]: ACLs are not supported, ignoring. Mar 25 01:23:49.904742 systemd-tmpfiles[1182]: ACLs are not supported, ignoring. Mar 25 01:23:49.912743 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Mar 25 01:23:49.917061 systemd[1]: Starting systemd-sysusers.service - Create System Users... Mar 25 01:23:49.922276 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Mar 25 01:23:49.925137 udevadm[1197]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Mar 25 01:23:49.937998 kernel: loop1: detected capacity change from 0 to 151640 Mar 25 01:23:49.959175 systemd[1]: Finished systemd-sysusers.service - Create System Users. Mar 25 01:23:49.962505 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Mar 25 01:23:49.974594 kernel: loop2: detected capacity change from 0 to 109808 Mar 25 01:23:49.994957 kernel: loop3: detected capacity change from 0 to 151640 Mar 25 01:23:49.996599 systemd-tmpfiles[1205]: ACLs are not supported, ignoring. Mar 25 01:23:49.996625 systemd-tmpfiles[1205]: ACLs are not supported, ignoring. Mar 25 01:23:50.004656 (sd-merge)[1206]: Using extensions 'containerd-flatcar', 'docker-flatcar'. Mar 25 01:23:50.004709 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 25 01:23:50.005371 (sd-merge)[1206]: Merged extensions into '/usr'. Mar 25 01:23:50.011100 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Mar 25 01:23:50.014598 systemd[1]: Starting ensure-sysext.service... Mar 25 01:23:50.017664 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Mar 25 01:23:50.034568 systemd[1]: Reload requested from client PID 1210 ('systemctl') (unit ensure-sysext.service)... Mar 25 01:23:50.034720 systemd[1]: Reloading... Mar 25 01:23:50.045569 systemd-tmpfiles[1211]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Mar 25 01:23:50.046631 systemd-tmpfiles[1211]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Mar 25 01:23:50.047876 systemd-tmpfiles[1211]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Mar 25 01:23:50.048292 systemd-tmpfiles[1211]: ACLs are not supported, ignoring. Mar 25 01:23:50.048386 systemd-tmpfiles[1211]: ACLs are not supported, ignoring. Mar 25 01:23:50.054329 systemd-tmpfiles[1211]: Detected autofs mount point /boot during canonicalization of boot. Mar 25 01:23:50.054347 systemd-tmpfiles[1211]: Skipping /boot Mar 25 01:23:50.070981 systemd-tmpfiles[1211]: Detected autofs mount point /boot during canonicalization of boot. Mar 25 01:23:50.070997 systemd-tmpfiles[1211]: Skipping /boot Mar 25 01:23:50.121924 zram_generator::config[1241]: No configuration found. Mar 25 01:23:50.244189 ldconfig[1176]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Mar 25 01:23:50.274106 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. Mar 25 01:23:50.346290 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Mar 25 01:23:50.347208 systemd[1]: Reloading finished in 312 ms. Mar 25 01:23:50.368377 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Mar 25 01:23:50.396014 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 25 01:23:50.410519 systemd[1]: Starting audit-rules.service - Load Audit Rules... Mar 25 01:23:50.414458 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Mar 25 01:23:50.423447 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Mar 25 01:23:50.429996 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Mar 25 01:23:50.436181 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Mar 25 01:23:50.441430 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 25 01:23:50.445179 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 25 01:23:50.451649 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 25 01:23:50.470648 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 25 01:23:50.472039 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 25 01:23:50.472207 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Mar 25 01:23:50.475622 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Mar 25 01:23:50.484359 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Mar 25 01:23:50.487414 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 25 01:23:50.487895 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 25 01:23:50.490289 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 25 01:23:50.490707 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 25 01:23:50.493334 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 25 01:23:50.493666 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 25 01:23:50.502795 augenrules[1308]: No rules Mar 25 01:23:50.504629 systemd[1]: audit-rules.service: Deactivated successfully. Mar 25 01:23:50.505055 systemd[1]: Finished audit-rules.service - Load Audit Rules. Mar 25 01:23:50.514832 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Mar 25 01:23:50.521813 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 25 01:23:50.524106 systemd[1]: Starting audit-rules.service - Load Audit Rules... Mar 25 01:23:50.546257 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 25 01:23:50.548414 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 25 01:23:50.553239 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Mar 25 01:23:50.563489 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 25 01:23:50.572192 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 25 01:23:50.574008 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 25 01:23:50.574172 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Mar 25 01:23:50.574356 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 25 01:23:50.576219 systemd[1]: Started systemd-userdbd.service - User Database Manager. Mar 25 01:23:50.578389 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 25 01:23:50.578750 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 25 01:23:50.581445 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 25 01:23:50.582217 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Mar 25 01:23:50.592714 systemd[1]: Finished ensure-sysext.service. Mar 25 01:23:50.594242 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 25 01:23:50.594519 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 25 01:23:50.598082 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 25 01:23:50.598339 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 25 01:23:50.601226 augenrules[1319]: /sbin/augenrules: No change Mar 25 01:23:50.601306 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Mar 25 01:23:50.610911 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 25 01:23:50.611038 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Mar 25 01:23:50.613314 augenrules[1351]: No rules Mar 25 01:23:50.614422 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Mar 25 01:23:50.615986 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). Mar 25 01:23:50.616525 systemd[1]: audit-rules.service: Deactivated successfully. Mar 25 01:23:50.616866 systemd[1]: Finished audit-rules.service - Load Audit Rules. Mar 25 01:23:50.630538 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Mar 25 01:23:50.635670 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 25 01:23:50.639985 systemd[1]: Starting systemd-update-done.service - Update is Completed... Mar 25 01:23:50.662686 systemd[1]: Finished systemd-update-done.service - Update is Completed. Mar 25 01:23:50.683813 systemd-udevd[1359]: Using default interface naming scheme 'v255'. Mar 25 01:23:50.683973 systemd-resolved[1284]: Positive Trust Anchors: Mar 25 01:23:50.683984 systemd-resolved[1284]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 25 01:23:50.684016 systemd-resolved[1284]: 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 Mar 25 01:23:50.687726 systemd-resolved[1284]: Defaulting to hostname 'linux'. Mar 25 01:23:50.689633 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Mar 25 01:23:50.690916 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Mar 25 01:23:50.710071 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 25 01:23:50.716194 systemd[1]: Starting systemd-networkd.service - Network Configuration... Mar 25 01:23:50.717605 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Mar 25 01:23:50.720171 systemd[1]: Reached target time-set.target - System Time Set. Mar 25 01:23:50.767945 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (1363) Mar 25 01:23:50.801261 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Mar 25 01:23:50.823766 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Mar 25 01:23:50.828926 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Mar 25 01:23:50.831122 systemd-networkd[1368]: lo: Link UP Mar 25 01:23:50.831135 systemd-networkd[1368]: lo: Gained carrier Mar 25 01:23:50.833773 systemd-networkd[1368]: Enumeration completed Mar 25 01:23:50.833880 systemd[1]: Started systemd-networkd.service - Network Configuration. Mar 25 01:23:50.835297 systemd[1]: Reached target network.target - Network. Mar 25 01:23:50.836214 systemd-networkd[1368]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 25 01:23:50.836227 systemd-networkd[1368]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 25 01:23:50.838517 systemd-networkd[1368]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 25 01:23:50.838557 systemd-networkd[1368]: eth0: Link UP Mar 25 01:23:50.838563 systemd-networkd[1368]: eth0: Gained carrier Mar 25 01:23:50.838577 systemd-networkd[1368]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 25 01:23:50.839687 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Mar 25 01:23:50.842497 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Mar 25 01:23:50.854000 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input2 Mar 25 01:23:50.854075 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 Mar 25 01:23:50.855165 systemd-networkd[1368]: eth0: DHCPv4 address 10.0.0.15/16, gateway 10.0.0.1 acquired from 10.0.0.1 Mar 25 01:23:50.860274 kernel: i801_smbus 0000:00:1f.3: Enabling SMBus device Mar 25 01:23:52.148725 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Mar 25 01:23:52.148947 kernel: i2c i2c-0: 1/1 memory slots populated (from DMI) Mar 25 01:23:52.149853 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Mar 25 01:23:52.151958 kernel: ACPI: button: Power Button [PWRF] Mar 25 01:23:50.856234 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Mar 25 01:23:50.861166 systemd-timesyncd[1356]: Network configuration changed, trying to establish connection. Mar 25 01:23:52.148459 systemd-timesyncd[1356]: Contacted time server 10.0.0.1:123 (10.0.0.1). Mar 25 01:23:52.148515 systemd-timesyncd[1356]: Initial clock synchronization to Tue 2025-03-25 01:23:52.148329 UTC. Mar 25 01:23:52.148570 systemd-resolved[1284]: Clock change detected. Flushing caches. Mar 25 01:23:52.158895 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Mar 25 01:23:52.215727 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 25 01:23:52.276440 kernel: mousedev: PS/2 mouse device common for all mice Mar 25 01:23:52.276785 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 25 01:23:52.277080 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 25 01:23:52.280160 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 25 01:23:52.287467 kernel: kvm_amd: TSC scaling supported Mar 25 01:23:52.287533 kernel: kvm_amd: Nested Virtualization enabled Mar 25 01:23:52.287551 kernel: kvm_amd: Nested Paging enabled Mar 25 01:23:52.287565 kernel: kvm_amd: LBR virtualization supported Mar 25 01:23:52.287601 kernel: kvm_amd: Virtual VMLOAD VMSAVE supported Mar 25 01:23:52.287625 kernel: kvm_amd: Virtual GIF supported Mar 25 01:23:52.312534 kernel: EDAC MC: Ver: 3.0.0 Mar 25 01:23:52.345213 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Mar 25 01:23:52.347170 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Mar 25 01:23:52.350469 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 25 01:23:52.370698 lvm[1410]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 25 01:23:52.408268 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Mar 25 01:23:52.410023 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Mar 25 01:23:52.411209 systemd[1]: Reached target sysinit.target - System Initialization. Mar 25 01:23:52.412449 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Mar 25 01:23:52.413753 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Mar 25 01:23:52.415280 systemd[1]: Started logrotate.timer - Daily rotation of log files. Mar 25 01:23:52.416569 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Mar 25 01:23:52.417892 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Mar 25 01:23:52.419246 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Mar 25 01:23:52.419282 systemd[1]: Reached target paths.target - Path Units. Mar 25 01:23:52.421584 systemd[1]: Reached target timers.target - Timer Units. Mar 25 01:23:52.423458 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Mar 25 01:23:52.426298 systemd[1]: Starting docker.socket - Docker Socket for the API... Mar 25 01:23:52.429855 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Mar 25 01:23:52.431388 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Mar 25 01:23:52.432781 systemd[1]: Reached target ssh-access.target - SSH Access Available. Mar 25 01:23:52.437047 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Mar 25 01:23:52.438982 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Mar 25 01:23:52.441783 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Mar 25 01:23:52.443707 systemd[1]: Listening on docker.socket - Docker Socket for the API. Mar 25 01:23:52.445095 systemd[1]: Reached target sockets.target - Socket Units. Mar 25 01:23:52.446133 systemd[1]: Reached target basic.target - Basic System. Mar 25 01:23:52.447253 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Mar 25 01:23:52.447286 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Mar 25 01:23:52.448557 systemd[1]: Starting containerd.service - containerd container runtime... Mar 25 01:23:52.451280 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Mar 25 01:23:52.453535 lvm[1416]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 25 01:23:52.455749 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Mar 25 01:23:52.459581 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Mar 25 01:23:52.460940 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). Mar 25 01:23:52.462704 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Mar 25 01:23:52.465925 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Mar 25 01:23:52.471302 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Mar 25 01:23:52.473636 jq[1419]: false Mar 25 01:23:52.480800 dbus-daemon[1418]: [system] SELinux support is enabled Mar 25 01:23:52.488646 extend-filesystems[1420]: Found loop2 Mar 25 01:23:52.488646 extend-filesystems[1420]: Found loop3 Mar 25 01:23:52.488646 extend-filesystems[1420]: Found sr0 Mar 25 01:23:52.488646 extend-filesystems[1420]: Found vda Mar 25 01:23:52.488646 extend-filesystems[1420]: Found vda1 Mar 25 01:23:52.488646 extend-filesystems[1420]: Found vda2 Mar 25 01:23:52.488646 extend-filesystems[1420]: Found vda3 Mar 25 01:23:52.488646 extend-filesystems[1420]: Found usr Mar 25 01:23:52.488646 extend-filesystems[1420]: Found vda4 Mar 25 01:23:52.488646 extend-filesystems[1420]: Found vda6 Mar 25 01:23:52.488646 extend-filesystems[1420]: Found vda7 Mar 25 01:23:52.488646 extend-filesystems[1420]: Found vda9 Mar 25 01:23:52.488646 extend-filesystems[1420]: Checking size of /dev/vda9 Mar 25 01:23:52.485249 systemd[1]: Starting systemd-logind.service - User Login Management... Mar 25 01:23:52.502706 extend-filesystems[1420]: Old size kept for /dev/vda9 Mar 25 01:23:52.488999 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Mar 25 01:23:52.489629 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Mar 25 01:23:52.492840 systemd[1]: Starting update-engine.service - Update Engine... Mar 25 01:23:52.501898 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Mar 25 01:23:52.506964 systemd[1]: Started dbus.service - D-Bus System Message Bus. Mar 25 01:23:52.510827 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Mar 25 01:23:52.515177 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Mar 25 01:23:52.515716 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Mar 25 01:23:52.516129 systemd[1]: extend-filesystems.service: Deactivated successfully. Mar 25 01:23:52.516455 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Mar 25 01:23:52.518598 systemd[1]: motdgen.service: Deactivated successfully. Mar 25 01:23:52.519076 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Mar 25 01:23:52.520138 jq[1436]: true Mar 25 01:23:52.520931 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Mar 25 01:23:52.521281 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Mar 25 01:23:52.536037 update_engine[1430]: I20250325 01:23:52.534427 1430 main.cc:92] Flatcar Update Engine starting Mar 25 01:23:52.544114 update_engine[1430]: I20250325 01:23:52.543864 1430 update_check_scheduler.cc:74] Next update check in 7m27s Mar 25 01:23:52.551141 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (1374) Mar 25 01:23:52.547127 (ntainerd)[1446]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Mar 25 01:23:52.553640 jq[1441]: true Mar 25 01:23:52.585563 systemd-logind[1426]: Watching system buttons on /dev/input/event2 (Power Button) Mar 25 01:23:52.585595 systemd-logind[1426]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Mar 25 01:23:52.586003 systemd-logind[1426]: New seat seat0. Mar 25 01:23:52.590115 systemd[1]: Started systemd-logind.service - User Login Management. Mar 25 01:23:52.596849 systemd[1]: Started update-engine.service - Update Engine. Mar 25 01:23:52.601786 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). Mar 25 01:23:52.602063 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Mar 25 01:23:52.603811 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). Mar 25 01:23:52.603964 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Mar 25 01:23:52.608635 systemd[1]: Started locksmithd.service - Cluster reboot manager. Mar 25 01:23:52.637671 bash[1468]: Updated "/home/core/.ssh/authorized_keys" Mar 25 01:23:52.640608 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Mar 25 01:23:52.644541 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Mar 25 01:23:52.652871 locksmithd[1464]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Mar 25 01:23:52.765991 containerd[1446]: time="2025-03-25T01:23:52Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Mar 25 01:23:52.767224 containerd[1446]: time="2025-03-25T01:23:52.767187401Z" level=info msg="starting containerd" revision=88aa2f531d6c2922003cc7929e51daf1c14caa0a version=v2.0.1 Mar 25 01:23:52.776980 containerd[1446]: time="2025-03-25T01:23:52.776914055Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="7.544µs" Mar 25 01:23:52.776980 containerd[1446]: time="2025-03-25T01:23:52.776961113Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Mar 25 01:23:52.777144 containerd[1446]: time="2025-03-25T01:23:52.776999866Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Mar 25 01:23:52.777303 containerd[1446]: time="2025-03-25T01:23:52.777275784Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Mar 25 01:23:52.777303 containerd[1446]: time="2025-03-25T01:23:52.777299628Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Mar 25 01:23:52.777347 containerd[1446]: time="2025-03-25T01:23:52.777328402Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Mar 25 01:23:52.777439 containerd[1446]: time="2025-03-25T01:23:52.777426637Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Mar 25 01:23:52.777463 containerd[1446]: time="2025-03-25T01:23:52.777441715Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Mar 25 01:23:52.777830 containerd[1446]: time="2025-03-25T01:23:52.777792082Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Mar 25 01:23:52.777861 containerd[1446]: time="2025-03-25T01:23:52.777824323Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Mar 25 01:23:52.777861 containerd[1446]: time="2025-03-25T01:23:52.777842527Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Mar 25 01:23:52.777861 containerd[1446]: time="2025-03-25T01:23:52.777851774Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Mar 25 01:23:52.777976 containerd[1446]: time="2025-03-25T01:23:52.777953305Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Mar 25 01:23:52.778223 containerd[1446]: time="2025-03-25T01:23:52.778198835Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Mar 25 01:23:52.778245 containerd[1446]: time="2025-03-25T01:23:52.778235053Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Mar 25 01:23:52.778272 containerd[1446]: time="2025-03-25T01:23:52.778245122Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Mar 25 01:23:52.778293 containerd[1446]: time="2025-03-25T01:23:52.778281480Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Mar 25 01:23:52.778566 containerd[1446]: time="2025-03-25T01:23:52.778520799Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Mar 25 01:23:52.778605 containerd[1446]: time="2025-03-25T01:23:52.778590279Z" level=info msg="metadata content store policy set" policy=shared Mar 25 01:23:52.785438 containerd[1446]: time="2025-03-25T01:23:52.784226481Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Mar 25 01:23:52.785438 containerd[1446]: time="2025-03-25T01:23:52.784284380Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Mar 25 01:23:52.785438 containerd[1446]: time="2025-03-25T01:23:52.784301071Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Mar 25 01:23:52.785438 containerd[1446]: time="2025-03-25T01:23:52.784318263Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Mar 25 01:23:52.785438 containerd[1446]: time="2025-03-25T01:23:52.784331448Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Mar 25 01:23:52.785438 containerd[1446]: time="2025-03-25T01:23:52.784342469Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Mar 25 01:23:52.785438 containerd[1446]: time="2025-03-25T01:23:52.784359170Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Mar 25 01:23:52.785438 containerd[1446]: time="2025-03-25T01:23:52.784383446Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Mar 25 01:23:52.785438 containerd[1446]: time="2025-03-25T01:23:52.784394937Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Mar 25 01:23:52.785438 containerd[1446]: time="2025-03-25T01:23:52.784429081Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Mar 25 01:23:52.785438 containerd[1446]: time="2025-03-25T01:23:52.784439220Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Mar 25 01:23:52.785438 containerd[1446]: time="2025-03-25T01:23:52.784452225Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Mar 25 01:23:52.785438 containerd[1446]: time="2025-03-25T01:23:52.784594542Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Mar 25 01:23:52.785438 containerd[1446]: time="2025-03-25T01:23:52.784614128Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Mar 25 01:23:52.785863 containerd[1446]: time="2025-03-25T01:23:52.784626772Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Mar 25 01:23:52.785863 containerd[1446]: time="2025-03-25T01:23:52.784638865Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Mar 25 01:23:52.785863 containerd[1446]: time="2025-03-25T01:23:52.784649905Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Mar 25 01:23:52.785863 containerd[1446]: time="2025-03-25T01:23:52.784662990Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Mar 25 01:23:52.785863 containerd[1446]: time="2025-03-25T01:23:52.784674812Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Mar 25 01:23:52.785863 containerd[1446]: time="2025-03-25T01:23:52.784685813Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Mar 25 01:23:52.785863 containerd[1446]: time="2025-03-25T01:23:52.784703797Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Mar 25 01:23:52.785863 containerd[1446]: time="2025-03-25T01:23:52.784715138Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Mar 25 01:23:52.785863 containerd[1446]: time="2025-03-25T01:23:52.784725818Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Mar 25 01:23:52.785863 containerd[1446]: time="2025-03-25T01:23:52.784786121Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Mar 25 01:23:52.785863 containerd[1446]: time="2025-03-25T01:23:52.784798234Z" level=info msg="Start snapshots syncer" Mar 25 01:23:52.785863 containerd[1446]: time="2025-03-25T01:23:52.784817971Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Mar 25 01:23:52.786517 containerd[1446]: time="2025-03-25T01:23:52.785046018Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Mar 25 01:23:52.786517 containerd[1446]: time="2025-03-25T01:23:52.785089350Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Mar 25 01:23:52.786669 containerd[1446]: time="2025-03-25T01:23:52.785149533Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Mar 25 01:23:52.786669 containerd[1446]: time="2025-03-25T01:23:52.785242327Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Mar 25 01:23:52.786669 containerd[1446]: time="2025-03-25T01:23:52.785261022Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Mar 25 01:23:52.786669 containerd[1446]: time="2025-03-25T01:23:52.785272213Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Mar 25 01:23:52.786669 containerd[1446]: time="2025-03-25T01:23:52.785289545Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Mar 25 01:23:52.786669 containerd[1446]: time="2025-03-25T01:23:52.785301908Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Mar 25 01:23:52.786669 containerd[1446]: time="2025-03-25T01:23:52.785313851Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Mar 25 01:23:52.786669 containerd[1446]: time="2025-03-25T01:23:52.785323860Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Mar 25 01:23:52.786669 containerd[1446]: time="2025-03-25T01:23:52.785345901Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Mar 25 01:23:52.786669 containerd[1446]: time="2025-03-25T01:23:52.785358024Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Mar 25 01:23:52.786669 containerd[1446]: time="2025-03-25T01:23:52.785379544Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Mar 25 01:23:52.786669 containerd[1446]: time="2025-03-25T01:23:52.785430249Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Mar 25 01:23:52.786669 containerd[1446]: time="2025-03-25T01:23:52.785444326Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Mar 25 01:23:52.786669 containerd[1446]: time="2025-03-25T01:23:52.785454404Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Mar 25 01:23:52.787020 containerd[1446]: time="2025-03-25T01:23:52.785463852Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Mar 25 01:23:52.787020 containerd[1446]: time="2025-03-25T01:23:52.785472919Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Mar 25 01:23:52.787020 containerd[1446]: time="2025-03-25T01:23:52.785494019Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Mar 25 01:23:52.787020 containerd[1446]: time="2025-03-25T01:23:52.785507013Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Mar 25 01:23:52.787020 containerd[1446]: time="2025-03-25T01:23:52.785524486Z" level=info msg="runtime interface created" Mar 25 01:23:52.787020 containerd[1446]: time="2025-03-25T01:23:52.785530247Z" level=info msg="created NRI interface" Mar 25 01:23:52.787020 containerd[1446]: time="2025-03-25T01:23:52.785538853Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Mar 25 01:23:52.787020 containerd[1446]: time="2025-03-25T01:23:52.785548781Z" level=info msg="Connect containerd service" Mar 25 01:23:52.787020 containerd[1446]: time="2025-03-25T01:23:52.785581042Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Mar 25 01:23:52.787020 containerd[1446]: time="2025-03-25T01:23:52.786991708Z" 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" Mar 25 01:23:52.873094 containerd[1446]: time="2025-03-25T01:23:52.872541966Z" level=info msg="Start subscribing containerd event" Mar 25 01:23:52.873094 containerd[1446]: time="2025-03-25T01:23:52.872593983Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Mar 25 01:23:52.873094 containerd[1446]: time="2025-03-25T01:23:52.872607138Z" level=info msg="Start recovering state" Mar 25 01:23:52.873094 containerd[1446]: time="2025-03-25T01:23:52.872649808Z" level=info msg=serving... address=/run/containerd/containerd.sock Mar 25 01:23:52.873094 containerd[1446]: time="2025-03-25T01:23:52.872720481Z" level=info msg="Start event monitor" Mar 25 01:23:52.873094 containerd[1446]: time="2025-03-25T01:23:52.872742001Z" level=info msg="Start cni network conf syncer for default" Mar 25 01:23:52.873094 containerd[1446]: time="2025-03-25T01:23:52.872751389Z" level=info msg="Start streaming server" Mar 25 01:23:52.873094 containerd[1446]: time="2025-03-25T01:23:52.872765655Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Mar 25 01:23:52.873094 containerd[1446]: time="2025-03-25T01:23:52.872776406Z" level=info msg="runtime interface starting up..." Mar 25 01:23:52.873094 containerd[1446]: time="2025-03-25T01:23:52.872784511Z" level=info msg="starting plugins..." Mar 25 01:23:52.873094 containerd[1446]: time="2025-03-25T01:23:52.872804037Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Mar 25 01:23:52.873094 containerd[1446]: time="2025-03-25T01:23:52.872955131Z" level=info msg="containerd successfully booted in 0.107615s" Mar 25 01:23:52.873534 systemd[1]: Started containerd.service - containerd container runtime. Mar 25 01:23:52.886701 sshd_keygen[1439]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Mar 25 01:23:52.912319 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Mar 25 01:23:52.916310 systemd[1]: Starting issuegen.service - Generate /run/issue... Mar 25 01:23:52.941023 systemd[1]: issuegen.service: Deactivated successfully. Mar 25 01:23:52.941298 systemd[1]: Finished issuegen.service - Generate /run/issue. Mar 25 01:23:52.945014 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Mar 25 01:23:52.970543 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Mar 25 01:23:52.974193 systemd[1]: Started getty@tty1.service - Getty on tty1. Mar 25 01:23:52.976735 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Mar 25 01:23:52.978222 systemd[1]: Reached target getty.target - Login Prompts. Mar 25 01:23:53.410677 systemd-networkd[1368]: eth0: Gained IPv6LL Mar 25 01:23:53.414380 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Mar 25 01:23:53.416750 systemd[1]: Reached target network-online.target - Network is Online. Mar 25 01:23:53.420583 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Mar 25 01:23:53.423476 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Mar 25 01:23:53.470940 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Mar 25 01:23:53.473069 systemd[1]: coreos-metadata.service: Deactivated successfully. Mar 25 01:23:53.473398 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Mar 25 01:23:53.477706 systemd[1]: Starting etcd-member.service - etcd (System Application Container)... Mar 25 01:23:53.479027 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Mar 25 01:23:53.491919 (-wrapper)[1527]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS Mar 25 01:23:53.640817 systemd[1]: Starting docker.service - Docker Application Container Engine... Mar 25 01:23:53.657721 (dockerd)[1539]: 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 Mar 25 01:23:54.002515 dockerd[1539]: time="2025-03-25T01:23:54.002443178Z" level=info msg="Starting up" Mar 25 01:23:54.004441 dockerd[1539]: time="2025-03-25T01:23:54.004372536Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Mar 25 01:23:54.082117 dockerd[1539]: time="2025-03-25T01:23:54.082061108Z" level=info msg="Loading containers: start." Mar 25 01:23:54.265466 kernel: Initializing XFRM netlink socket Mar 25 01:23:54.347031 systemd-networkd[1368]: docker0: Link UP Mar 25 01:23:54.415820 dockerd[1539]: time="2025-03-25T01:23:54.415769960Z" level=info msg="Loading containers: done." Mar 25 01:23:54.434254 dockerd[1539]: time="2025-03-25T01:23:54.434201813Z" 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 Mar 25 01:23:54.434447 dockerd[1539]: time="2025-03-25T01:23:54.434285931Z" level=info msg="Docker daemon" commit=c710b88579fcb5e0d53f96dcae976d79323b9166 containerd-snapshotter=false storage-driver=overlay2 version=27.4.1 Mar 25 01:23:54.434447 dockerd[1539]: time="2025-03-25T01:23:54.434396338Z" level=info msg="Daemon has completed initialization" Mar 25 01:23:54.471390 dockerd[1539]: time="2025-03-25T01:23:54.471284370Z" level=info msg="API listen on /run/docker.sock" Mar 25 01:23:54.471479 systemd[1]: Started docker.service - Docker Application Container Engine. Mar 25 01:23:54.473123 etcd-wrapper[1531]: Error response from daemon: No such container: etcd-member Mar 25 01:23:54.487938 etcd-wrapper[1726]: Error response from daemon: No such container: etcd-member Mar 25 01:23:54.516859 etcd-wrapper[1747]: Unable to find image 'quay.io/coreos/etcd:v3.5.16' locally Mar 25 01:23:55.571868 etcd-wrapper[1747]: v3.5.16: Pulling from coreos/etcd Mar 25 01:23:55.923368 etcd-wrapper[1747]: 804c8aba2cc6: Pulling fs layer Mar 25 01:23:55.923368 etcd-wrapper[1747]: 2ae710cd8bfe: Pulling fs layer Mar 25 01:23:55.923368 etcd-wrapper[1747]: d462aa345367: Pulling fs layer Mar 25 01:23:55.923368 etcd-wrapper[1747]: 0f8b424aa0b9: Pulling fs layer Mar 25 01:23:55.923368 etcd-wrapper[1747]: d557676654e5: Pulling fs layer Mar 25 01:23:55.923368 etcd-wrapper[1747]: c8022d07192e: Pulling fs layer Mar 25 01:23:55.923368 etcd-wrapper[1747]: d858cbc252ad: Pulling fs layer Mar 25 01:23:55.923368 etcd-wrapper[1747]: 1069fc2daed1: Pulling fs layer Mar 25 01:23:55.923368 etcd-wrapper[1747]: b40161cd83fc: Pulling fs layer Mar 25 01:23:55.923368 etcd-wrapper[1747]: 5318d93a3a65: Pulling fs layer Mar 25 01:23:55.923368 etcd-wrapper[1747]: 307c1adadb60: Pulling fs layer Mar 25 01:23:55.923368 etcd-wrapper[1747]: fbb01d9e9dc9: Pulling fs layer Mar 25 01:23:55.923368 etcd-wrapper[1747]: fbfea02ac3cf: Pulling fs layer Mar 25 01:23:55.923368 etcd-wrapper[1747]: 8c26e4bf18e2: Pulling fs layer Mar 25 01:23:55.923368 etcd-wrapper[1747]: 1e59a65f8816: Pulling fs layer Mar 25 01:23:55.923368 etcd-wrapper[1747]: ffbd4ca5f0bd: Pulling fs layer Mar 25 01:23:55.923912 etcd-wrapper[1747]: 0f8b424aa0b9: Waiting Mar 25 01:23:55.923912 etcd-wrapper[1747]: d557676654e5: Waiting Mar 25 01:23:55.923912 etcd-wrapper[1747]: c8022d07192e: Waiting Mar 25 01:23:55.923912 etcd-wrapper[1747]: d858cbc252ad: Waiting Mar 25 01:23:55.923912 etcd-wrapper[1747]: 1069fc2daed1: Waiting Mar 25 01:23:55.923912 etcd-wrapper[1747]: b40161cd83fc: Waiting Mar 25 01:23:55.923912 etcd-wrapper[1747]: 5318d93a3a65: Waiting Mar 25 01:23:55.923912 etcd-wrapper[1747]: 1e59a65f8816: Waiting Mar 25 01:23:55.923912 etcd-wrapper[1747]: ffbd4ca5f0bd: Waiting Mar 25 01:23:55.923912 etcd-wrapper[1747]: 307c1adadb60: Waiting Mar 25 01:23:55.923912 etcd-wrapper[1747]: fbfea02ac3cf: Waiting Mar 25 01:23:55.923912 etcd-wrapper[1747]: fbb01d9e9dc9: Waiting Mar 25 01:23:55.923912 etcd-wrapper[1747]: 8c26e4bf18e2: Waiting Mar 25 01:23:56.533354 etcd-wrapper[1747]: 2ae710cd8bfe: Download complete Mar 25 01:23:56.600642 etcd-wrapper[1747]: 804c8aba2cc6: Verifying Checksum Mar 25 01:23:56.630785 etcd-wrapper[1747]: 804c8aba2cc6: Pull complete Mar 25 01:23:56.647313 systemd[1]: var-lib-docker-overlay2-40c2e5093f7e777d9659289da15e97af3341cd3779f99f7cd6509e35fb5a762d-merged.mount: Deactivated successfully. Mar 25 01:23:56.662717 etcd-wrapper[1747]: 2ae710cd8bfe: Pull complete Mar 25 01:23:57.002133 etcd-wrapper[1747]: d462aa345367: Verifying Checksum Mar 25 01:23:57.002133 etcd-wrapper[1747]: d462aa345367: Download complete Mar 25 01:23:57.166301 etcd-wrapper[1747]: 0f8b424aa0b9: Download complete Mar 25 01:23:57.238899 etcd-wrapper[1747]: d557676654e5: Verifying Checksum Mar 25 01:23:57.238899 etcd-wrapper[1747]: d557676654e5: Download complete Mar 25 01:23:57.421480 systemd[1]: var-lib-docker-overlay2-1b66066df0ae316ad890d735d4023afc7c787dd4833b6d378cf8ea586dd39ca2-merged.mount: Deactivated successfully. Mar 25 01:23:57.470866 etcd-wrapper[1747]: d462aa345367: Pull complete Mar 25 01:23:57.503140 etcd-wrapper[1747]: 0f8b424aa0b9: Pull complete Mar 25 01:23:57.525684 etcd-wrapper[1747]: d557676654e5: Pull complete Mar 25 01:23:57.567936 etcd-wrapper[1747]: c8022d07192e: Verifying Checksum Mar 25 01:23:57.567936 etcd-wrapper[1747]: c8022d07192e: Download complete Mar 25 01:23:57.588989 etcd-wrapper[1747]: c8022d07192e: Pull complete Mar 25 01:23:57.642861 systemd[1]: var-lib-docker-overlay2-8d30e2624a6adc00cc29230895a92381c0576dff730f7864f6c9ab9cc6a03095-merged.mount: Deactivated successfully. Mar 25 01:23:57.763691 etcd-wrapper[1747]: d858cbc252ad: Verifying Checksum Mar 25 01:23:57.763691 etcd-wrapper[1747]: d858cbc252ad: Download complete Mar 25 01:23:57.775299 systemd[1]: var-lib-docker-overlay2-15171dfcab4c5072e1579c4b484be5ed128dfa6ab6d0130b626061d0d86a26f6-merged.mount: Deactivated successfully. Mar 25 01:23:57.785955 etcd-wrapper[1747]: d858cbc252ad: Pull complete Mar 25 01:23:57.819604 etcd-wrapper[1747]: 1069fc2daed1: Verifying Checksum Mar 25 01:23:57.819604 etcd-wrapper[1747]: 1069fc2daed1: Download complete Mar 25 01:23:57.831224 systemd[1]: var-lib-docker-overlay2-54ed3463d1625a0fbc392008543b078eb310786a876d664dc33e9193671bff8d-merged.mount: Deactivated successfully. Mar 25 01:23:57.838647 etcd-wrapper[1747]: 1069fc2daed1: Pull complete Mar 25 01:23:58.123600 etcd-wrapper[1747]: b40161cd83fc: Verifying Checksum Mar 25 01:23:58.145888 etcd-wrapper[1747]: b40161cd83fc: Pull complete Mar 25 01:23:58.390746 etcd-wrapper[1747]: 5318d93a3a65: Verifying Checksum Mar 25 01:23:58.390746 etcd-wrapper[1747]: 5318d93a3a65: Download complete Mar 25 01:23:58.412806 etcd-wrapper[1747]: 5318d93a3a65: Pull complete Mar 25 01:23:58.745194 etcd-wrapper[1747]: 307c1adadb60: Verifying Checksum Mar 25 01:23:58.757972 systemd[1]: var-lib-docker-overlay2-4825b323ea24850dfc129e0551074ef52f9caa149bb2bbdef0184f94e41e80be-merged.mount: Deactivated successfully. Mar 25 01:23:58.775986 etcd-wrapper[1747]: 307c1adadb60: Pull complete Mar 25 01:23:59.005104 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Mar 25 01:23:59.006402 systemd[1]: Started sshd@0-10.0.0.15:22-10.0.0.1:50304.service - OpenSSH per-connection server daemon (10.0.0.1:50304). Mar 25 01:23:59.080176 sshd[1819]: Accepted publickey for core from 10.0.0.1 port 50304 ssh2: RSA SHA256:4f8HJIPOZgNv5AQupi3isO02sy+ZIziCurPc4FU7/A0 Mar 25 01:23:59.082214 sshd-session[1819]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:23:59.093151 systemd-logind[1426]: New session 1 of user core. Mar 25 01:23:59.094494 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Mar 25 01:23:59.095742 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Mar 25 01:23:59.125711 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Mar 25 01:23:59.127680 systemd[1]: Starting user@500.service - User Manager for UID 500... Mar 25 01:23:59.145153 (systemd)[1823]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Mar 25 01:23:59.147925 systemd-logind[1426]: New session c1 of user core. Mar 25 01:23:59.294523 systemd[1823]: Queued start job for default target default.target. Mar 25 01:23:59.305807 systemd[1823]: Created slice app.slice - User Application Slice. Mar 25 01:23:59.305837 systemd[1823]: Reached target paths.target - Paths. Mar 25 01:23:59.305884 systemd[1823]: Reached target timers.target - Timers. Mar 25 01:23:59.307512 systemd[1823]: Starting dbus.socket - D-Bus User Message Bus Socket... Mar 25 01:23:59.319887 systemd[1823]: Listening on dbus.socket - D-Bus User Message Bus Socket. Mar 25 01:23:59.320025 systemd[1823]: Reached target sockets.target - Sockets. Mar 25 01:23:59.320066 systemd[1823]: Reached target basic.target - Basic System. Mar 25 01:23:59.320109 systemd[1823]: Reached target default.target - Main User Target. Mar 25 01:23:59.320143 systemd[1823]: Startup finished in 165ms. Mar 25 01:23:59.320828 systemd[1]: Started user@500.service - User Manager for UID 500. Mar 25 01:23:59.323048 systemd[1]: Started session-1.scope - Session 1 of User core. Mar 25 01:23:59.387766 systemd[1]: Started sshd@1-10.0.0.15:22-10.0.0.1:50316.service - OpenSSH per-connection server daemon (10.0.0.1:50316). Mar 25 01:23:59.457974 sshd[1834]: Accepted publickey for core from 10.0.0.1 port 50316 ssh2: RSA SHA256:4f8HJIPOZgNv5AQupi3isO02sy+ZIziCurPc4FU7/A0 Mar 25 01:23:59.459801 sshd-session[1834]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:23:59.464456 systemd-logind[1426]: New session 2 of user core. Mar 25 01:23:59.474615 systemd[1]: Started session-2.scope - Session 2 of User core. Mar 25 01:23:59.529538 sshd[1836]: Connection closed by 10.0.0.1 port 50316 Mar 25 01:23:59.529845 sshd-session[1834]: pam_unix(sshd:session): session closed for user core Mar 25 01:23:59.547906 systemd[1]: sshd@1-10.0.0.15:22-10.0.0.1:50316.service: Deactivated successfully. Mar 25 01:23:59.549989 systemd[1]: session-2.scope: Deactivated successfully. Mar 25 01:23:59.551767 systemd-logind[1426]: Session 2 logged out. Waiting for processes to exit. Mar 25 01:23:59.553129 systemd[1]: Started sshd@2-10.0.0.15:22-10.0.0.1:50332.service - OpenSSH per-connection server daemon (10.0.0.1:50332). Mar 25 01:23:59.554124 systemd-logind[1426]: Removed session 2. Mar 25 01:23:59.606094 sshd[1841]: Accepted publickey for core from 10.0.0.1 port 50332 ssh2: RSA SHA256:4f8HJIPOZgNv5AQupi3isO02sy+ZIziCurPc4FU7/A0 Mar 25 01:23:59.607856 sshd-session[1841]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:23:59.612851 systemd-logind[1426]: New session 3 of user core. Mar 25 01:23:59.622587 systemd[1]: Started session-3.scope - Session 3 of User core. Mar 25 01:23:59.676010 sshd[1844]: Connection closed by 10.0.0.1 port 50332 Mar 25 01:23:59.676349 sshd-session[1841]: pam_unix(sshd:session): session closed for user core Mar 25 01:23:59.680870 systemd[1]: sshd@2-10.0.0.15:22-10.0.0.1:50332.service: Deactivated successfully. Mar 25 01:23:59.683046 systemd[1]: session-3.scope: Deactivated successfully. Mar 25 01:23:59.683892 systemd-logind[1426]: Session 3 logged out. Waiting for processes to exit. Mar 25 01:23:59.685036 systemd-logind[1426]: Removed session 3. Mar 25 01:24:00.667481 etcd-wrapper[1747]: fbfea02ac3cf: Verifying Checksum Mar 25 01:24:00.667481 etcd-wrapper[1747]: fbfea02ac3cf: Download complete Mar 25 01:24:00.949168 etcd-wrapper[1747]: 8c26e4bf18e2: Verifying Checksum Mar 25 01:24:00.949168 etcd-wrapper[1747]: 8c26e4bf18e2: Download complete Mar 25 01:24:02.021098 etcd-wrapper[1747]: fbb01d9e9dc9: Verifying Checksum Mar 25 01:24:02.021098 etcd-wrapper[1747]: fbb01d9e9dc9: Download complete Mar 25 01:24:02.277597 etcd-wrapper[1747]: 1e59a65f8816: Download complete Mar 25 01:24:02.314327 systemd[1]: var-lib-docker-overlay2-16121014fb92d0bccd81d77e2940429a23d5e058dad92007e4792a859a3f3dd6-merged.mount: Deactivated successfully. Mar 25 01:24:02.550548 etcd-wrapper[1747]: ffbd4ca5f0bd: Verifying Checksum Mar 25 01:24:02.550548 etcd-wrapper[1747]: ffbd4ca5f0bd: Download complete Mar 25 01:24:02.992566 etcd-wrapper[1747]: fbb01d9e9dc9: Pull complete Mar 25 01:24:03.098028 systemd[1]: var-lib-docker-overlay2-4d5d03d351b91d5f2af2f7d1872094bd60c4fdb2d5c20ac9db4afb5dc269bbbc-merged.mount: Deactivated successfully. Mar 25 01:24:03.167803 etcd-wrapper[1747]: fbfea02ac3cf: Pull complete Mar 25 01:24:03.251478 systemd[1]: var-lib-docker-overlay2-f8f6c8a58446501707e28233b9a253db3bd75ff2bf8d195a7a864d2ddceaf749-merged.mount: Deactivated successfully. Mar 25 01:24:03.309618 etcd-wrapper[1747]: 8c26e4bf18e2: Pull complete Mar 25 01:24:03.326155 systemd[1]: var-lib-docker-overlay2-a1c32ba6274f3163107cb3f4b22800ad657a126a66537026fa2bbc931b1b2192-merged.mount: Deactivated successfully. Mar 25 01:24:03.370281 etcd-wrapper[1747]: 1e59a65f8816: Pull complete Mar 25 01:24:03.387660 systemd[1]: var-lib-docker-overlay2-71953eeae17e98796bdc1519975dfcb6631c3bf2c580c541642d4aa3e1466784-merged.mount: Deactivated successfully. Mar 25 01:24:03.404159 etcd-wrapper[1747]: ffbd4ca5f0bd: Pull complete Mar 25 01:24:03.414957 etcd-wrapper[1747]: Digest: sha256:d967d98a12dc220a1a290794711dba7eba04b8ce465e12b02383d1bfbb33e159 Mar 25 01:24:03.418354 etcd-wrapper[1747]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.16 Mar 25 01:24:03.500730 containerd[1446]: time="2025-03-25T01:24:03.500655128Z" level=info msg="connecting to shim 91a58ae84cecbf48cdec9450f571d33ec5c407e2d862e368cc346489df0a8443" address="unix:///run/containerd/s/4ed9ceea96f61756ed73a770efb4e1a9f6337acd84e6e0f33532b5b4786ecdd2" namespace=moby protocol=ttrpc version=3 Mar 25 01:24:03.529735 systemd[1]: Started docker-91a58ae84cecbf48cdec9450f571d33ec5c407e2d862e368cc346489df0a8443.scope - libcontainer container 91a58ae84cecbf48cdec9450f571d33ec5c407e2d862e368cc346489df0a8443. Mar 25 01:24:03.546739 systemd-resolved[1284]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Mar 25 01:24:03.593143 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.592746Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} Mar 25 01:24:03.593143 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.592860Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"e595107b9aad499d872dc7db14dac4ce"} Mar 25 01:24:03.593143 etcd-wrapper[1747]: {"level":"warn","ts":"2025-03-25T01:24:03.592884Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} Mar 25 01:24:03.593143 etcd-wrapper[1747]: {"level":"warn","ts":"2025-03-25T01:24:03.592891Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.16"} Mar 25 01:24:03.593143 etcd-wrapper[1747]: {"level":"warn","ts":"2025-03-25T01:24:03.592896Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} Mar 25 01:24:03.593143 etcd-wrapper[1747]: {"level":"warn","ts":"2025-03-25T01:24:03.592901Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} Mar 25 01:24:03.593143 etcd-wrapper[1747]: {"level":"warn","ts":"2025-03-25T01:24:03.592938Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Mar 25 01:24:03.593143 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.592966Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["/usr/local/bin/etcd","--listen-client-urls=http://0.0.0.0:2379","--advertise-client-urls=http://10.0.0.15:2379"]} Mar 25 01:24:03.593598 etcd-wrapper[1747]: {"level":"warn","ts":"2025-03-25T01:24:03.593025Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Mar 25 01:24:03.593598 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.593035Z","caller":"embed/etcd.go:128","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]} Mar 25 01:24:03.593687 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.593559Z","caller":"embed/etcd.go:136","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} Mar 25 01:24:03.593861 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.593683Z","caller":"embed/etcd.go:311","msg":"starting an etcd server","etcd-version":"3.5.16","git-sha":"f20bbad","go-version":"go1.22.7","go-os":"linux","go-arch":"amd64","max-cpu-set":4,"max-cpu-available":4,"member-initialized":false,"name":"e595107b9aad499d872dc7db14dac4ce","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":100000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["http://localhost:2380"],"listen-peer-urls":["http://localhost:2380"],"advertise-client-urls":["http://10.0.0.15:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"e595107b9aad499d872dc7db14dac4ce=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"} Mar 25 01:24:03.596488 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.596321Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.306907ms"} Mar 25 01:24:03.600353 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.600119Z","caller":"etcdserver/raft.go:505","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"} Mar 25 01:24:03.600353 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.600169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"} Mar 25 01:24:03.600353 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.600198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"} Mar 25 01:24:03.600353 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.600205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} Mar 25 01:24:03.600353 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.600211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"} Mar 25 01:24:03.600353 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.600236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Mar 25 01:24:03.604706 etcd-wrapper[1747]: {"level":"warn","ts":"2025-03-25T01:24:03.604518Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} Mar 25 01:24:03.605621 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.605427Z","caller":"mvcc/kvstore.go:423","msg":"kvstore restored","current-rev":1} Mar 25 01:24:03.606573 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.606364Z","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"} Mar 25 01:24:03.607499 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.607146Z","caller":"etcdserver/server.go:873","msg":"starting etcd server","local-member-id":"8e9e05c52164694d","local-server-version":"3.5.16","cluster-version":"to_be_decided"} Mar 25 01:24:03.607645 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.607284Z","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"} Mar 25 01:24:03.607645 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.607362Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Mar 25 01:24:03.607723 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.607402Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} Mar 25 01:24:03.607955 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.607731Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} Mar 25 01:24:03.607955 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.607853Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} Mar 25 01:24:03.608704 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.608246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Mar 25 01:24:03.608704 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.608453Z","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"]} Mar 25 01:24:03.610093 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.609810Z","caller":"embed/etcd.go:600","msg":"serving peer traffic","address":"127.0.0.1:2380"} Mar 25 01:24:03.610093 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.609828Z","caller":"embed/etcd.go:572","msg":"cmux::serve","address":"127.0.0.1:2380"} Mar 25 01:24:03.610093 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.609845Z","caller":"embed/etcd.go:280","msg":"now serving peer/client/metrics","local-member-id":"8e9e05c52164694d","initial-advertise-peer-urls":["http://localhost:2380"],"listen-peer-urls":["http://localhost:2380"],"advertise-client-urls":["http://10.0.0.15:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} Mar 25 01:24:03.701105 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.700762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"} Mar 25 01:24:03.701105 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.700798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"} Mar 25 01:24:03.701105 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.700825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"} Mar 25 01:24:03.701105 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.700838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"} Mar 25 01:24:03.701105 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.700844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"} Mar 25 01:24:03.701105 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.700855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"} Mar 25 01:24:03.701105 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.700866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"} Mar 25 01:24:03.701805 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.701392Z","caller":"etcdserver/server.go:2140","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:e595107b9aad499d872dc7db14dac4ce ClientURLs:[http://10.0.0.15:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"} Mar 25 01:24:03.701805 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.701435Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} Mar 25 01:24:03.701805 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.701494Z","caller":"etcdserver/server.go:2651","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} Mar 25 01:24:03.701805 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.701571Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} Mar 25 01:24:03.702006 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.701745Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} Mar 25 01:24:03.701944 systemd[1]: Started etcd-member.service - etcd (System Application Container). Mar 25 01:24:03.702405 systemd[1]: Reached target multi-user.target - Multi-User System. Mar 25 01:24:03.702619 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.701992Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Mar 25 01:24:03.702619 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.702297Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"} Mar 25 01:24:03.702619 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.702397Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} Mar 25 01:24:03.702619 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.702390Z","caller":"embed/serve.go:187","msg":"serving client traffic insecurely; this is strongly discouraged!","traffic":"grpc+http","address":"[::]:2379"} Mar 25 01:24:03.702649 systemd[1]: Startup finished in 791ms (kernel) + 4.109s (initrd) + 13.642s (userspace) = 18.544s. Mar 25 01:24:03.702862 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:24:03.702642Z","caller":"etcdserver/server.go:2675","msg":"cluster version is updated","cluster-version":"3.5"} Mar 25 01:24:09.692599 systemd[1]: Started sshd@3-10.0.0.15:22-10.0.0.1:45968.service - OpenSSH per-connection server daemon (10.0.0.1:45968). Mar 25 01:24:09.753335 sshd[1930]: Accepted publickey for core from 10.0.0.1 port 45968 ssh2: RSA SHA256:4f8HJIPOZgNv5AQupi3isO02sy+ZIziCurPc4FU7/A0 Mar 25 01:24:09.755386 sshd-session[1930]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:24:09.760567 systemd-logind[1426]: New session 4 of user core. Mar 25 01:24:09.770643 systemd[1]: Started session-4.scope - Session 4 of User core. Mar 25 01:24:09.827145 sshd[1932]: Connection closed by 10.0.0.1 port 45968 Mar 25 01:24:09.827540 sshd-session[1930]: pam_unix(sshd:session): session closed for user core Mar 25 01:24:09.846749 systemd[1]: sshd@3-10.0.0.15:22-10.0.0.1:45968.service: Deactivated successfully. Mar 25 01:24:09.848686 systemd[1]: session-4.scope: Deactivated successfully. Mar 25 01:24:09.850375 systemd-logind[1426]: Session 4 logged out. Waiting for processes to exit. Mar 25 01:24:09.851733 systemd[1]: Started sshd@4-10.0.0.15:22-10.0.0.1:45970.service - OpenSSH per-connection server daemon (10.0.0.1:45970). Mar 25 01:24:09.852474 systemd-logind[1426]: Removed session 4. Mar 25 01:24:09.912150 sshd[1937]: Accepted publickey for core from 10.0.0.1 port 45970 ssh2: RSA SHA256:4f8HJIPOZgNv5AQupi3isO02sy+ZIziCurPc4FU7/A0 Mar 25 01:24:09.913934 sshd-session[1937]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:24:09.919366 systemd-logind[1426]: New session 5 of user core. Mar 25 01:24:09.929635 systemd[1]: Started session-5.scope - Session 5 of User core. Mar 25 01:24:09.980383 sshd[1940]: Connection closed by 10.0.0.1 port 45970 Mar 25 01:24:09.980608 sshd-session[1937]: pam_unix(sshd:session): session closed for user core Mar 25 01:24:09.994208 systemd[1]: sshd@4-10.0.0.15:22-10.0.0.1:45970.service: Deactivated successfully. Mar 25 01:24:09.996097 systemd[1]: session-5.scope: Deactivated successfully. Mar 25 01:24:09.997506 systemd-logind[1426]: Session 5 logged out. Waiting for processes to exit. Mar 25 01:24:09.998937 systemd[1]: Started sshd@5-10.0.0.15:22-10.0.0.1:45978.service - OpenSSH per-connection server daemon (10.0.0.1:45978). Mar 25 01:24:09.999760 systemd-logind[1426]: Removed session 5. Mar 25 01:24:10.058750 sshd[1945]: Accepted publickey for core from 10.0.0.1 port 45978 ssh2: RSA SHA256:4f8HJIPOZgNv5AQupi3isO02sy+ZIziCurPc4FU7/A0 Mar 25 01:24:10.060676 sshd-session[1945]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:24:10.065767 systemd-logind[1426]: New session 6 of user core. Mar 25 01:24:10.076675 systemd[1]: Started session-6.scope - Session 6 of User core. Mar 25 01:24:10.132321 sshd[1948]: Connection closed by 10.0.0.1 port 45978 Mar 25 01:24:10.132720 sshd-session[1945]: pam_unix(sshd:session): session closed for user core Mar 25 01:24:10.151471 systemd[1]: sshd@5-10.0.0.15:22-10.0.0.1:45978.service: Deactivated successfully. Mar 25 01:24:10.153365 systemd[1]: session-6.scope: Deactivated successfully. Mar 25 01:24:10.155052 systemd-logind[1426]: Session 6 logged out. Waiting for processes to exit. Mar 25 01:24:10.156345 systemd[1]: Started sshd@6-10.0.0.15:22-10.0.0.1:45988.service - OpenSSH per-connection server daemon (10.0.0.1:45988). Mar 25 01:24:10.157171 systemd-logind[1426]: Removed session 6. Mar 25 01:24:10.210131 sshd[1953]: Accepted publickey for core from 10.0.0.1 port 45988 ssh2: RSA SHA256:4f8HJIPOZgNv5AQupi3isO02sy+ZIziCurPc4FU7/A0 Mar 25 01:24:10.211582 sshd-session[1953]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:24:10.216165 systemd-logind[1426]: New session 7 of user core. Mar 25 01:24:10.234667 systemd[1]: Started session-7.scope - Session 7 of User core. Mar 25 01:24:10.287014 sshd[1956]: Connection closed by 10.0.0.1 port 45988 Mar 25 01:24:10.287350 sshd-session[1953]: pam_unix(sshd:session): session closed for user core Mar 25 01:24:10.296522 systemd[1]: sshd@6-10.0.0.15:22-10.0.0.1:45988.service: Deactivated successfully. Mar 25 01:24:10.298354 systemd[1]: session-7.scope: Deactivated successfully. Mar 25 01:24:10.300317 systemd-logind[1426]: Session 7 logged out. Waiting for processes to exit. Mar 25 01:24:10.301822 systemd[1]: Started sshd@7-10.0.0.15:22-10.0.0.1:45992.service - OpenSSH per-connection server daemon (10.0.0.1:45992). Mar 25 01:24:10.302671 systemd-logind[1426]: Removed session 7. Mar 25 01:24:10.369846 sshd[1963]: Accepted publickey for core from 10.0.0.1 port 45992 ssh2: RSA SHA256:4f8HJIPOZgNv5AQupi3isO02sy+ZIziCurPc4FU7/A0 Mar 25 01:24:10.371857 sshd-session[1963]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:24:10.378525 systemd-logind[1426]: New session 8 of user core. Mar 25 01:24:10.389756 systemd[1]: Started session-8.scope - Session 8 of User core. Mar 25 01:24:10.545350 sshd[1966]: Connection closed by 10.0.0.1 port 45992 Mar 25 01:24:10.545621 sshd-session[1963]: pam_unix(sshd:session): session closed for user core Mar 25 01:24:10.549766 systemd[1]: sshd@7-10.0.0.15:22-10.0.0.1:45992.service: Deactivated successfully. Mar 25 01:24:10.551686 systemd[1]: session-8.scope: Deactivated successfully. Mar 25 01:24:10.552342 systemd-logind[1426]: Session 8 logged out. Waiting for processes to exit. Mar 25 01:24:10.553539 systemd-logind[1426]: Removed session 8. Mar 25 01:24:38.186237 update_engine[1430]: I20250325 01:24:38.186105 1430 update_attempter.cc:509] Updating boot flags... Mar 25 01:24:38.217463 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (1984) Mar 25 01:24:38.256561 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (1983) Mar 25 01:24:38.309446 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (1983) Mar 25 01:25:05.563526 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:25:05.563003Z","caller":"traceutil/trace.go:171","msg":"trace[2014889476] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"114.232359ms","start":"2025-03-25T01:25:05.448754Z","end":"2025-03-25T01:25:05.562986Z","steps":["trace[2014889476] 'process raft request' (duration: 114.191172ms)"],"step_count":1} Mar 25 01:25:05.563526 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:25:05.563120Z","caller":"traceutil/trace.go:171","msg":"trace[811241851] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"168.256416ms","start":"2025-03-25T01:25:05.394850Z","end":"2025-03-25T01:25:05.563106Z","steps":["trace[811241851] 'process raft request' (duration: 75.464987ms)","trace[811241851] 'compare' (duration: 92.541679ms)"],"step_count":2} Mar 25 01:25:05.772078 etcd-wrapper[1747]: {"level":"warn","ts":"2025-03-25T01:25:05.771731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.342965ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Mar 25 01:25:05.772078 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:25:05.771826Z","caller":"traceutil/trace.go:171","msg":"trace[1844272324] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"201.440473ms","start":"2025-03-25T01:25:05.570373Z","end":"2025-03-25T01:25:05.771813Z","steps":["trace[1844272324] 'process raft request' (duration: 98.624438ms)","trace[1844272324] 'compare' (duration: 102.258077ms)"],"step_count":2} Mar 25 01:25:24.133237 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:25:24.132872Z","caller":"traceutil/trace.go:171","msg":"trace[453771953] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"134.114197ms","start":"2025-03-25T01:25:23.998739Z","end":"2025-03-25T01:25:24.132853Z","steps":["trace[453771953] 'process raft request' (duration: 96.317282ms)","trace[453771953] 'compare' (duration: 37.635543ms)"],"step_count":2} Mar 25 01:25:34.557500 etcd-wrapper[1747]: {"level":"warn","ts":"2025-03-25T01:25:34.557158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.18843ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Mar 25 01:25:34.557500 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:25:34.557241Z","caller":"traceutil/trace.go:171","msg":"trace[1283512269] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"325.110039ms","start":"2025-03-25T01:25:34.232119Z","end":"2025-03-25T01:25:34.557229Z","steps":["trace[1283512269] 'process raft request' (duration: 92.80321ms)","trace[1283512269] 'compare' (duration: 232.105992ms)"],"step_count":2} Mar 25 01:25:34.557500 etcd-wrapper[1747]: {"level":"warn","ts":"2025-03-25T01:25:34.557281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-03-25T01:25:34.232107Z","time spent":"325.156961ms","remote":"10.0.0.25:45206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} Mar 25 01:25:34.591117 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:25:34.590754Z","caller":"traceutil/trace.go:171","msg":"trace[200623027] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"301.077665ms","start":"2025-03-25T01:25:34.289655Z","end":"2025-03-25T01:25:34.590733Z","steps":["trace[200623027] 'process raft request' (duration: 300.886666ms)"],"step_count":1} Mar 25 01:25:34.591117 etcd-wrapper[1747]: {"level":"warn","ts":"2025-03-25T01:25:34.590864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-03-25T01:25:34.289638Z","time spent":"301.175022ms","remote":"10.0.0.25:45312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7680,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Mar 25 01:25:49.876705 etcd-wrapper[1747]: {"level":"warn","ts":"2025-03-25T01:25:49.876249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.309841ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Mar 25 01:25:49.876705 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:25:49.876397Z","caller":"traceutil/trace.go:171","msg":"trace[1246603268] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"144.566508ms","start":"2025-03-25T01:25:49.731802Z","end":"2025-03-25T01:25:49.876368Z","steps":["trace[1246603268] 'process raft request' (duration: 36.093615ms)","trace[1246603268] 'compare' (duration: 108.222915ms)"],"step_count":2} Mar 25 01:25:50.189244 etcd-wrapper[1747]: {"level":"warn","ts":"2025-03-25T01:25:50.188858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.801256ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Mar 25 01:25:50.189244 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:25:50.188933Z","caller":"traceutil/trace.go:171","msg":"trace[1872144842] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"266.567209ms","start":"2025-03-25T01:25:49.922352Z","end":"2025-03-25T01:25:50.188920Z","steps":["trace[1872144842] 'process raft request' (duration: 75.64996ms)","trace[1872144842] 'compare' (duration: 190.731183ms)"],"step_count":2} Mar 25 01:25:50.360755 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:25:50.360333Z","caller":"traceutil/trace.go:171","msg":"trace[389360695] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"123.594607ms","start":"2025-03-25T01:25:50.236722Z","end":"2025-03-25T01:25:50.360317Z","steps":["trace[389360695] 'process raft request' (duration: 123.574909ms)"],"step_count":1} Mar 25 01:25:50.360944 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:25:50.360496Z","caller":"traceutil/trace.go:171","msg":"trace[172543244] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"125.817465ms","start":"2025-03-25T01:25:50.234673Z","end":"2025-03-25T01:25:50.360490Z","steps":["trace[172543244] 'process raft request' (duration: 125.530717ms)"],"step_count":1} Mar 25 01:25:50.360944 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:25:50.360586Z","caller":"traceutil/trace.go:171","msg":"trace[854006881] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"124.4437ms","start":"2025-03-25T01:25:50.236118Z","end":"2025-03-25T01:25:50.360561Z","steps":["trace[854006881] 'process raft request' (duration: 124.158274ms)"],"step_count":1} Mar 25 01:25:50.729201 etcd-wrapper[1747]: {"level":"warn","ts":"2025-03-25T01:25:50.728805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.972142ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Mar 25 01:25:50.729201 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:25:50.728932Z","caller":"traceutil/trace.go:171","msg":"trace[350518302] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"343.737193ms","start":"2025-03-25T01:25:50.385185Z","end":"2025-03-25T01:25:50.728922Z","steps":["trace[350518302] 'process raft request' (duration: 343.672911ms)"],"step_count":1} Mar 25 01:25:50.729201 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:25:50.728984Z","caller":"traceutil/trace.go:171","msg":"trace[1842244889] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"357.975667ms","start":"2025-03-25T01:25:50.370999Z","end":"2025-03-25T01:25:50.728975Z","steps":["trace[1842244889] 'process raft request' (duration: 241.790227ms)","trace[1842244889] 'compare' (duration: 115.868365ms)"],"step_count":2} Mar 25 01:25:50.729201 etcd-wrapper[1747]: {"level":"warn","ts":"2025-03-25T01:25:50.729027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-03-25T01:25:50.370970Z","time spent":"358.036724ms","remote":"10.0.0.25:45614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2890,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} Mar 25 01:25:50.729645 etcd-wrapper[1747]: {"level":"warn","ts":"2025-03-25T01:25:50.728989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-03-25T01:25:50.385166Z","time spent":"343.800746ms","remote":"10.0.0.25:45388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":465,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Mar 25 01:25:51.136610 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:25:51.136248Z","caller":"traceutil/trace.go:171","msg":"trace[1629822922] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"346.430039ms","start":"2025-03-25T01:25:50.789800Z","end":"2025-03-25T01:25:51.136231Z","steps":["trace[1629822922] 'read index received' (duration: 248.925589ms)","trace[1629822922] 'applied index is now lower than readState.Index' (duration: 97.503999ms)"],"step_count":2} Mar 25 01:25:51.137151 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:25:51.136306Z","caller":"traceutil/trace.go:171","msg":"trace[1239961751] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"403.112971ms","start":"2025-03-25T01:25:50.733170Z","end":"2025-03-25T01:25:51.136283Z","steps":["trace[1239961751] 'process raft request' (duration: 305.275183ms)","trace[1239961751] 'compare' (duration: 97.707108ms)"],"step_count":2} Mar 25 01:25:51.137151 etcd-wrapper[1747]: {"level":"warn","ts":"2025-03-25T01:25:51.136445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-03-25T01:25:50.733162Z","time spent":"403.192081ms","remote":"10.0.0.25:45312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4430,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} Mar 25 01:25:51.137151 etcd-wrapper[1747]: {"level":"warn","ts":"2025-03-25T01:25:51.136409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.574726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-dynamic-volume-claim\" limit:1 ","response":"range_response_count:0 size:5"} Mar 25 01:25:51.137151 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:25:51.136510Z","caller":"traceutil/trace.go:171","msg":"trace[911950785] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-dynamic-volume-claim; range_end:; response_count:0; response_revision:610; }","duration":"346.715926ms","start":"2025-03-25T01:25:50.789776Z","end":"2025-03-25T01:25:51.136492Z","steps":["trace[911950785] 'agreement among raft nodes before linearized reading' (duration: 346.533487ms)"],"step_count":1} Mar 25 01:25:51.137335 etcd-wrapper[1747]: {"level":"warn","ts":"2025-03-25T01:25:51.136557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-03-25T01:25:50.789761Z","time spent":"346.784717ms","remote":"10.0.0.25:45286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/default/test-dynamic-volume-claim\" limit:1 "} Mar 25 01:25:51.163939 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:25:51.163523Z","caller":"traceutil/trace.go:171","msg":"trace[1578313399] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"372.32432ms","start":"2025-03-25T01:25:50.791182Z","end":"2025-03-25T01:25:51.163507Z","steps":["trace[1578313399] 'process raft request' (duration: 372.274103ms)"],"step_count":1} Mar 25 01:25:51.163939 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:25:51.163569Z","caller":"traceutil/trace.go:171","msg":"trace[1029608618] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"373.457134ms","start":"2025-03-25T01:25:50.790093Z","end":"2025-03-25T01:25:51.163550Z","steps":["trace[1029608618] 'process raft request' (duration: 373.26698ms)"],"step_count":1} Mar 25 01:25:51.163939 etcd-wrapper[1747]: {"level":"warn","ts":"2025-03-25T01:25:51.163627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-03-25T01:25:50.791175Z","time spent":"372.399814ms","remote":"10.0.0.25:45312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2335,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Mar 25 01:25:51.163939 etcd-wrapper[1747]: {"level":"warn","ts":"2025-03-25T01:25:51.163660Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-03-25T01:25:50.790084Z","time spent":"373.529233ms","remote":"10.0.0.25:45206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":770,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} Mar 25 01:25:51.271135 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:25:51.270738Z","caller":"traceutil/trace.go:171","msg":"trace[327465164] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"103.89194ms","start":"2025-03-25T01:25:51.166830Z","end":"2025-03-25T01:25:51.270722Z","steps":["trace[327465164] 'process raft request' (duration: 96.275244ms)"],"step_count":1} Mar 25 01:25:58.091873 etcd-wrapper[1747]: {"level":"warn","ts":"2025-03-25T01:25:58.091481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.255499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} Mar 25 01:25:58.091873 etcd-wrapper[1747]: {"level":"info","ts":"2025-03-25T01:25:58.091544Z","caller":"traceutil/trace.go:171","msg":"trace[1414126692] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:640; }","duration":"108.358324ms","start":"2025-03-25T01:25:57.983175Z","end":"2025-03-25T01:25:58.091533Z","steps":["trace[1414126692] 'count revisions from in-memory index tree' (duration: 108.19825ms)"],"step_count":1}