Apr 30 13:42:19.096279 kernel: Linux version 6.6.88-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.43 p3) 2.43.1) #1 SMP PREEMPT_DYNAMIC Tue Apr 29 22:26:36 -00 2025 Apr 30 13:42:19.096305 kernel: Command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=openstack flatcar.autologin verity.usrhash=95dd3de5eb34971546a976dc51c66bc73cf59b888896e27767c0cbf245cb98fe Apr 30 13:42:19.096315 kernel: BIOS-provided physical RAM map: Apr 30 13:42:19.096324 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Apr 30 13:42:19.096331 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Apr 30 13:42:19.096341 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Apr 30 13:42:19.096350 kernel: BIOS-e820: [mem 0x0000000000100000-0x00000000bffdcfff] usable Apr 30 13:42:19.096358 kernel: BIOS-e820: [mem 0x00000000bffdd000-0x00000000bfffffff] reserved Apr 30 13:42:19.096366 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Apr 30 13:42:19.096374 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Apr 30 13:42:19.096382 kernel: BIOS-e820: [mem 0x0000000100000000-0x000000013fffffff] usable Apr 30 13:42:19.096392 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Apr 30 13:42:19.096403 kernel: NX (Execute Disable) protection: active Apr 30 13:42:19.096412 kernel: APIC: Static calls initialized Apr 30 13:42:19.096423 kernel: SMBIOS 3.0.0 present. Apr 30 13:42:19.096431 kernel: DMI: OpenStack Foundation OpenStack Nova, BIOS 1.16.3-debian-1.16.3-2 04/01/2014 Apr 30 13:42:19.096439 kernel: Hypervisor detected: KVM Apr 30 13:42:19.096448 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Apr 30 13:42:19.096456 kernel: kvm-clock: using sched offset of 3729368605 cycles Apr 30 13:42:19.096466 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Apr 30 13:42:19.096475 kernel: tsc: Detected 1996.249 MHz processor Apr 30 13:42:19.096484 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Apr 30 13:42:19.096493 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Apr 30 13:42:19.096501 kernel: last_pfn = 0x140000 max_arch_pfn = 0x400000000 Apr 30 13:42:19.096510 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Apr 30 13:42:19.096519 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Apr 30 13:42:19.096528 kernel: last_pfn = 0xbffdd max_arch_pfn = 0x400000000 Apr 30 13:42:19.096536 kernel: ACPI: Early table checksum verification disabled Apr 30 13:42:19.096546 kernel: ACPI: RSDP 0x00000000000F51E0 000014 (v00 BOCHS ) Apr 30 13:42:19.096555 kernel: ACPI: RSDT 0x00000000BFFE1B65 000030 (v01 BOCHS BXPC 00000001 BXPC 00000001) Apr 30 13:42:19.096564 kernel: ACPI: FACP 0x00000000BFFE1A49 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) Apr 30 13:42:19.096572 kernel: ACPI: DSDT 0x00000000BFFE0040 001A09 (v01 BOCHS BXPC 00000001 BXPC 00000001) Apr 30 13:42:19.096581 kernel: ACPI: FACS 0x00000000BFFE0000 000040 Apr 30 13:42:19.096589 kernel: ACPI: APIC 0x00000000BFFE1ABD 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Apr 30 13:42:19.096597 kernel: ACPI: WAET 0x00000000BFFE1B3D 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Apr 30 13:42:19.096606 kernel: ACPI: Reserving FACP table memory at [mem 0xbffe1a49-0xbffe1abc] Apr 30 13:42:19.096614 kernel: ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1a48] Apr 30 13:42:19.096625 kernel: ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] Apr 30 13:42:19.096633 kernel: ACPI: Reserving APIC table memory at [mem 0xbffe1abd-0xbffe1b3c] Apr 30 13:42:19.096642 kernel: ACPI: Reserving WAET table memory at [mem 0xbffe1b3d-0xbffe1b64] Apr 30 13:42:19.096653 kernel: No NUMA configuration found Apr 30 13:42:19.096662 kernel: Faking a node at [mem 0x0000000000000000-0x000000013fffffff] Apr 30 13:42:19.096671 kernel: NODE_DATA(0) allocated [mem 0x13fff7000-0x13fffcfff] Apr 30 13:42:19.096682 kernel: Zone ranges: Apr 30 13:42:19.096690 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Apr 30 13:42:19.096701 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Apr 30 13:42:19.096711 kernel: Normal [mem 0x0000000100000000-0x000000013fffffff] Apr 30 13:42:19.096720 kernel: Movable zone start for each node Apr 30 13:42:19.096729 kernel: Early memory node ranges Apr 30 13:42:19.096738 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Apr 30 13:42:19.096746 kernel: node 0: [mem 0x0000000000100000-0x00000000bffdcfff] Apr 30 13:42:19.096757 kernel: node 0: [mem 0x0000000100000000-0x000000013fffffff] Apr 30 13:42:19.096766 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000013fffffff] Apr 30 13:42:19.096775 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Apr 30 13:42:19.096783 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Apr 30 13:42:19.096792 kernel: On node 0, zone Normal: 35 pages in unavailable ranges Apr 30 13:42:19.096801 kernel: ACPI: PM-Timer IO Port: 0x608 Apr 30 13:42:19.096810 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Apr 30 13:42:19.096819 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Apr 30 13:42:19.096828 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Apr 30 13:42:19.096839 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Apr 30 13:42:19.096848 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Apr 30 13:42:19.096857 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Apr 30 13:42:19.096866 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Apr 30 13:42:19.096890 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Apr 30 13:42:19.096899 kernel: smpboot: Allowing 2 CPUs, 0 hotplug CPUs Apr 30 13:42:19.099705 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Apr 30 13:42:19.099715 kernel: [mem 0xc0000000-0xfeffbfff] available for PCI devices Apr 30 13:42:19.099724 kernel: Booting paravirtualized kernel on KVM Apr 30 13:42:19.099738 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Apr 30 13:42:19.099747 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 Apr 30 13:42:19.099756 kernel: percpu: Embedded 58 pages/cpu s197096 r8192 d32280 u1048576 Apr 30 13:42:19.099765 kernel: pcpu-alloc: s197096 r8192 d32280 u1048576 alloc=1*2097152 Apr 30 13:42:19.099774 kernel: pcpu-alloc: [0] 0 1 Apr 30 13:42:19.099782 kernel: kvm-guest: PV spinlocks disabled, no host support Apr 30 13:42:19.099793 kernel: Kernel command line: rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=openstack flatcar.autologin verity.usrhash=95dd3de5eb34971546a976dc51c66bc73cf59b888896e27767c0cbf245cb98fe Apr 30 13:42:19.099802 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Apr 30 13:42:19.099815 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Apr 30 13:42:19.099826 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Apr 30 13:42:19.099835 kernel: Fallback order for Node 0: 0 Apr 30 13:42:19.099844 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1031901 Apr 30 13:42:19.099852 kernel: Policy zone: Normal Apr 30 13:42:19.099861 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Apr 30 13:42:19.099870 kernel: software IO TLB: area num 2. Apr 30 13:42:19.099909 kernel: Memory: 3964156K/4193772K available (14336K kernel code, 2295K rwdata, 22864K rodata, 43484K init, 1592K bss, 229356K reserved, 0K cma-reserved) Apr 30 13:42:19.099919 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Apr 30 13:42:19.099931 kernel: ftrace: allocating 37918 entries in 149 pages Apr 30 13:42:19.099940 kernel: ftrace: allocated 149 pages with 4 groups Apr 30 13:42:19.099949 kernel: Dynamic Preempt: voluntary Apr 30 13:42:19.099958 kernel: rcu: Preemptible hierarchical RCU implementation. Apr 30 13:42:19.099967 kernel: rcu: RCU event tracing is enabled. Apr 30 13:42:19.099976 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Apr 30 13:42:19.099985 kernel: Trampoline variant of Tasks RCU enabled. Apr 30 13:42:19.099994 kernel: Rude variant of Tasks RCU enabled. Apr 30 13:42:19.100003 kernel: Tracing variant of Tasks RCU enabled. Apr 30 13:42:19.100014 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Apr 30 13:42:19.100023 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Apr 30 13:42:19.100032 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 16 Apr 30 13:42:19.100041 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Apr 30 13:42:19.100049 kernel: Console: colour VGA+ 80x25 Apr 30 13:42:19.100058 kernel: printk: console [tty0] enabled Apr 30 13:42:19.100067 kernel: printk: console [ttyS0] enabled Apr 30 13:42:19.100076 kernel: ACPI: Core revision 20230628 Apr 30 13:42:19.100085 kernel: APIC: Switch to symmetric I/O mode setup Apr 30 13:42:19.100094 kernel: x2apic enabled Apr 30 13:42:19.100105 kernel: APIC: Switched APIC routing to: physical x2apic Apr 30 13:42:19.100114 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Apr 30 13:42:19.100123 kernel: tsc: Marking TSC unstable due to TSCs unsynchronized Apr 30 13:42:19.100132 kernel: Calibrating delay loop (skipped) preset value.. 3992.49 BogoMIPS (lpj=1996249) Apr 30 13:42:19.100141 kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 Apr 30 13:42:19.100150 kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 Apr 30 13:42:19.100159 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Apr 30 13:42:19.100167 kernel: Spectre V2 : Mitigation: Retpolines Apr 30 13:42:19.100176 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Apr 30 13:42:19.100189 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Apr 30 13:42:19.100198 kernel: Speculative Store Bypass: Vulnerable Apr 30 13:42:19.100207 kernel: x86/fpu: x87 FPU will use FXSAVE Apr 30 13:42:19.100215 kernel: Freeing SMP alternatives memory: 32K Apr 30 13:42:19.100232 kernel: pid_max: default: 32768 minimum: 301 Apr 30 13:42:19.100244 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Apr 30 13:42:19.100258 kernel: landlock: Up and running. Apr 30 13:42:19.100267 kernel: SELinux: Initializing. Apr 30 13:42:19.100277 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Apr 30 13:42:19.100286 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Apr 30 13:42:19.100295 kernel: smpboot: CPU0: AMD Intel Core i7 9xx (Nehalem Class Core i7) (family: 0x6, model: 0x1a, stepping: 0x3) Apr 30 13:42:19.100308 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Apr 30 13:42:19.100317 kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Apr 30 13:42:19.100327 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Apr 30 13:42:19.100336 kernel: Performance Events: AMD PMU driver. Apr 30 13:42:19.100345 kernel: ... version: 0 Apr 30 13:42:19.100357 kernel: ... bit width: 48 Apr 30 13:42:19.100366 kernel: ... generic registers: 4 Apr 30 13:42:19.100376 kernel: ... value mask: 0000ffffffffffff Apr 30 13:42:19.100385 kernel: ... max period: 00007fffffffffff Apr 30 13:42:19.100395 kernel: ... fixed-purpose events: 0 Apr 30 13:42:19.100404 kernel: ... event mask: 000000000000000f Apr 30 13:42:19.100414 kernel: signal: max sigframe size: 1440 Apr 30 13:42:19.100424 kernel: rcu: Hierarchical SRCU implementation. Apr 30 13:42:19.100433 kernel: rcu: Max phase no-delay instances is 400. Apr 30 13:42:19.100445 kernel: smp: Bringing up secondary CPUs ... Apr 30 13:42:19.100454 kernel: smpboot: x86: Booting SMP configuration: Apr 30 13:42:19.100463 kernel: .... node #0, CPUs: #1 Apr 30 13:42:19.100473 kernel: smp: Brought up 1 node, 2 CPUs Apr 30 13:42:19.100482 kernel: smpboot: Max logical packages: 2 Apr 30 13:42:19.100491 kernel: smpboot: Total of 2 processors activated (7984.99 BogoMIPS) Apr 30 13:42:19.100500 kernel: devtmpfs: initialized Apr 30 13:42:19.100509 kernel: x86/mm: Memory block size: 128MB Apr 30 13:42:19.100519 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Apr 30 13:42:19.100531 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Apr 30 13:42:19.100540 kernel: pinctrl core: initialized pinctrl subsystem Apr 30 13:42:19.100549 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Apr 30 13:42:19.100559 kernel: audit: initializing netlink subsys (disabled) Apr 30 13:42:19.100568 kernel: audit: type=2000 audit(1746020538.932:1): state=initialized audit_enabled=0 res=1 Apr 30 13:42:19.100577 kernel: thermal_sys: Registered thermal governor 'step_wise' Apr 30 13:42:19.100586 kernel: thermal_sys: Registered thermal governor 'user_space' Apr 30 13:42:19.100598 kernel: cpuidle: using governor menu Apr 30 13:42:19.100609 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Apr 30 13:42:19.100620 kernel: dca service started, version 1.12.1 Apr 30 13:42:19.100629 kernel: PCI: Using configuration type 1 for base access Apr 30 13:42:19.100639 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Apr 30 13:42:19.100648 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Apr 30 13:42:19.100657 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Apr 30 13:42:19.100667 kernel: ACPI: Added _OSI(Module Device) Apr 30 13:42:19.100676 kernel: ACPI: Added _OSI(Processor Device) Apr 30 13:42:19.100685 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Apr 30 13:42:19.100694 kernel: ACPI: Added _OSI(Processor Aggregator Device) Apr 30 13:42:19.100704 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Apr 30 13:42:19.100715 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Apr 30 13:42:19.100724 kernel: ACPI: Interpreter enabled Apr 30 13:42:19.100733 kernel: ACPI: PM: (supports S0 S3 S5) Apr 30 13:42:19.100742 kernel: ACPI: Using IOAPIC for interrupt routing Apr 30 13:42:19.100752 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Apr 30 13:42:19.100761 kernel: PCI: Using E820 reservations for host bridge windows Apr 30 13:42:19.100770 kernel: ACPI: Enabled 2 GPEs in block 00 to 0F Apr 30 13:42:19.100780 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Apr 30 13:42:19.102160 kernel: acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] Apr 30 13:42:19.102271 kernel: acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] Apr 30 13:42:19.102365 kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge Apr 30 13:42:19.102379 kernel: acpiphp: Slot [3] registered Apr 30 13:42:19.102389 kernel: acpiphp: Slot [4] registered Apr 30 13:42:19.102399 kernel: acpiphp: Slot [5] registered Apr 30 13:42:19.102408 kernel: acpiphp: Slot [6] registered Apr 30 13:42:19.102417 kernel: acpiphp: Slot [7] registered Apr 30 13:42:19.102430 kernel: acpiphp: Slot [8] registered Apr 30 13:42:19.102439 kernel: acpiphp: Slot [9] registered Apr 30 13:42:19.102449 kernel: acpiphp: Slot [10] registered Apr 30 13:42:19.102458 kernel: acpiphp: Slot [11] registered Apr 30 13:42:19.102467 kernel: acpiphp: Slot [12] registered Apr 30 13:42:19.102476 kernel: acpiphp: Slot [13] registered Apr 30 13:42:19.102485 kernel: acpiphp: Slot [14] registered Apr 30 13:42:19.102495 kernel: acpiphp: Slot [15] registered Apr 30 13:42:19.102504 kernel: acpiphp: Slot [16] registered Apr 30 13:42:19.102515 kernel: acpiphp: Slot [17] registered Apr 30 13:42:19.102528 kernel: acpiphp: Slot [18] registered Apr 30 13:42:19.102538 kernel: acpiphp: Slot [19] registered Apr 30 13:42:19.102547 kernel: acpiphp: Slot [20] registered Apr 30 13:42:19.102556 kernel: acpiphp: Slot [21] registered Apr 30 13:42:19.102565 kernel: acpiphp: Slot [22] registered Apr 30 13:42:19.102575 kernel: acpiphp: Slot [23] registered Apr 30 13:42:19.102584 kernel: acpiphp: Slot [24] registered Apr 30 13:42:19.102593 kernel: acpiphp: Slot [25] registered Apr 30 13:42:19.102602 kernel: acpiphp: Slot [26] registered Apr 30 13:42:19.102614 kernel: acpiphp: Slot [27] registered Apr 30 13:42:19.102623 kernel: acpiphp: Slot [28] registered Apr 30 13:42:19.102632 kernel: acpiphp: Slot [29] registered Apr 30 13:42:19.102642 kernel: acpiphp: Slot [30] registered Apr 30 13:42:19.102651 kernel: acpiphp: Slot [31] registered Apr 30 13:42:19.102660 kernel: PCI host bridge to bus 0000:00 Apr 30 13:42:19.102759 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Apr 30 13:42:19.102847 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Apr 30 13:42:19.102965 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Apr 30 13:42:19.103051 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] Apr 30 13:42:19.103134 kernel: pci_bus 0000:00: root bus resource [mem 0xc000000000-0xc07fffffff window] Apr 30 13:42:19.103217 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Apr 30 13:42:19.103335 kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 Apr 30 13:42:19.103441 kernel: pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 Apr 30 13:42:19.103551 kernel: pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 Apr 30 13:42:19.103649 kernel: pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] Apr 30 13:42:19.103747 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] Apr 30 13:42:19.103843 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] Apr 30 13:42:19.104555 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] Apr 30 13:42:19.104658 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] Apr 30 13:42:19.104761 kernel: pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 Apr 30 13:42:19.104871 kernel: pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI Apr 30 13:42:19.105005 kernel: pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB Apr 30 13:42:19.105110 kernel: pci 0000:00:02.0: [1af4:1050] type 00 class 0x030000 Apr 30 13:42:19.105210 kernel: pci 0000:00:02.0: reg 0x10: [mem 0xfe000000-0xfe7fffff pref] Apr 30 13:42:19.105304 kernel: pci 0000:00:02.0: reg 0x18: [mem 0xc000000000-0xc000003fff 64bit pref] Apr 30 13:42:19.105399 kernel: pci 0000:00:02.0: reg 0x20: [mem 0xfeb90000-0xfeb90fff] Apr 30 13:42:19.105497 kernel: pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfeb8ffff pref] Apr 30 13:42:19.105602 kernel: pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Apr 30 13:42:19.105767 kernel: pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 Apr 30 13:42:19.105937 kernel: pci 0000:00:03.0: reg 0x10: [io 0xc080-0xc0bf] Apr 30 13:42:19.106039 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xfeb91000-0xfeb91fff] Apr 30 13:42:19.106135 kernel: pci 0000:00:03.0: reg 0x20: [mem 0xc000004000-0xc000007fff 64bit pref] Apr 30 13:42:19.106232 kernel: pci 0000:00:03.0: reg 0x30: [mem 0xfeb00000-0xfeb7ffff pref] Apr 30 13:42:19.106346 kernel: pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 Apr 30 13:42:19.106455 kernel: pci 0000:00:04.0: reg 0x10: [io 0xc000-0xc07f] Apr 30 13:42:19.106556 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xfeb92000-0xfeb92fff] Apr 30 13:42:19.106657 kernel: pci 0000:00:04.0: reg 0x20: [mem 0xc000008000-0xc00000bfff 64bit pref] Apr 30 13:42:19.106758 kernel: pci 0000:00:05.0: [1af4:1002] type 00 class 0x00ff00 Apr 30 13:42:19.106855 kernel: pci 0000:00:05.0: reg 0x10: [io 0xc0c0-0xc0ff] Apr 30 13:42:19.107007 kernel: pci 0000:00:05.0: reg 0x20: [mem 0xc00000c000-0xc00000ffff 64bit pref] Apr 30 13:42:19.107110 kernel: pci 0000:00:06.0: [1af4:1005] type 00 class 0x00ff00 Apr 30 13:42:19.107212 kernel: pci 0000:00:06.0: reg 0x10: [io 0xc100-0xc11f] Apr 30 13:42:19.107310 kernel: pci 0000:00:06.0: reg 0x14: [mem 0xfeb93000-0xfeb93fff] Apr 30 13:42:19.107405 kernel: pci 0000:00:06.0: reg 0x20: [mem 0xc000010000-0xc000013fff 64bit pref] Apr 30 13:42:19.107419 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Apr 30 13:42:19.107429 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Apr 30 13:42:19.107439 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Apr 30 13:42:19.107448 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Apr 30 13:42:19.107461 kernel: ACPI: PCI: Interrupt link LNKS configured for IRQ 9 Apr 30 13:42:19.107471 kernel: iommu: Default domain type: Translated Apr 30 13:42:19.107480 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Apr 30 13:42:19.107490 kernel: PCI: Using ACPI for IRQ routing Apr 30 13:42:19.107499 kernel: PCI: pci_cache_line_size set to 64 bytes Apr 30 13:42:19.107508 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Apr 30 13:42:19.107518 kernel: e820: reserve RAM buffer [mem 0xbffdd000-0xbfffffff] Apr 30 13:42:19.107611 kernel: pci 0000:00:02.0: vgaarb: setting as boot VGA device Apr 30 13:42:19.107704 kernel: pci 0000:00:02.0: vgaarb: bridge control possible Apr 30 13:42:19.107812 kernel: pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Apr 30 13:42:19.107826 kernel: vgaarb: loaded Apr 30 13:42:19.107835 kernel: clocksource: Switched to clocksource kvm-clock Apr 30 13:42:19.107844 kernel: VFS: Disk quotas dquot_6.6.0 Apr 30 13:42:19.107854 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Apr 30 13:42:19.107863 kernel: pnp: PnP ACPI init Apr 30 13:42:19.107995 kernel: pnp 00:03: [dma 2] Apr 30 13:42:19.108012 kernel: pnp: PnP ACPI: found 5 devices Apr 30 13:42:19.108021 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Apr 30 13:42:19.108035 kernel: NET: Registered PF_INET protocol family Apr 30 13:42:19.108044 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Apr 30 13:42:19.108055 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Apr 30 13:42:19.108068 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Apr 30 13:42:19.108078 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Apr 30 13:42:19.108087 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Apr 30 13:42:19.108097 kernel: TCP: Hash tables configured (established 32768 bind 32768) Apr 30 13:42:19.108106 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Apr 30 13:42:19.108118 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Apr 30 13:42:19.108128 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Apr 30 13:42:19.108137 kernel: NET: Registered PF_XDP protocol family Apr 30 13:42:19.108225 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Apr 30 13:42:19.108308 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Apr 30 13:42:19.108390 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Apr 30 13:42:19.108477 kernel: pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] Apr 30 13:42:19.108562 kernel: pci_bus 0000:00: resource 8 [mem 0xc000000000-0xc07fffffff window] Apr 30 13:42:19.108658 kernel: pci 0000:00:01.0: PIIX3: Enabling Passive Release Apr 30 13:42:19.108761 kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers Apr 30 13:42:19.108778 kernel: PCI: CLS 0 bytes, default 64 Apr 30 13:42:19.108789 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Apr 30 13:42:19.108799 kernel: software IO TLB: mapped [mem 0x00000000bbfdd000-0x00000000bffdd000] (64MB) Apr 30 13:42:19.108808 kernel: Initialise system trusted keyrings Apr 30 13:42:19.108818 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Apr 30 13:42:19.108827 kernel: Key type asymmetric registered Apr 30 13:42:19.108836 kernel: Asymmetric key parser 'x509' registered Apr 30 13:42:19.108849 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Apr 30 13:42:19.108858 kernel: io scheduler mq-deadline registered Apr 30 13:42:19.108868 kernel: io scheduler kyber registered Apr 30 13:42:19.108894 kernel: io scheduler bfq registered Apr 30 13:42:19.108904 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Apr 30 13:42:19.108914 kernel: ACPI: \_SB_.LNKB: Enabled at IRQ 10 Apr 30 13:42:19.108923 kernel: ACPI: \_SB_.LNKC: Enabled at IRQ 11 Apr 30 13:42:19.108933 kernel: ACPI: \_SB_.LNKD: Enabled at IRQ 11 Apr 30 13:42:19.108943 kernel: ACPI: \_SB_.LNKA: Enabled at IRQ 10 Apr 30 13:42:19.108956 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Apr 30 13:42:19.108966 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Apr 30 13:42:19.108975 kernel: random: crng init done Apr 30 13:42:19.108984 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Apr 30 13:42:19.108994 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Apr 30 13:42:19.109003 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Apr 30 13:42:19.109102 kernel: rtc_cmos 00:04: RTC can wake from S4 Apr 30 13:42:19.109118 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Apr 30 13:42:19.109201 kernel: rtc_cmos 00:04: registered as rtc0 Apr 30 13:42:19.109305 kernel: rtc_cmos 00:04: setting system clock to 2025-04-30T13:42:18 UTC (1746020538) Apr 30 13:42:19.109393 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram Apr 30 13:42:19.109407 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled Apr 30 13:42:19.109417 kernel: NET: Registered PF_INET6 protocol family Apr 30 13:42:19.109426 kernel: Segment Routing with IPv6 Apr 30 13:42:19.109435 kernel: In-situ OAM (IOAM) with IPv6 Apr 30 13:42:19.109444 kernel: NET: Registered PF_PACKET protocol family Apr 30 13:42:19.109454 kernel: Key type dns_resolver registered Apr 30 13:42:19.109467 kernel: IPI shorthand broadcast: enabled Apr 30 13:42:19.109477 kernel: sched_clock: Marking stable (998009226, 170887347)->(1204848663, -35952090) Apr 30 13:42:19.109486 kernel: registered taskstats version 1 Apr 30 13:42:19.109495 kernel: Loading compiled-in X.509 certificates Apr 30 13:42:19.109505 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.88-flatcar: 10d2d341d26c1df942e743344427c053ef3a2a5f' Apr 30 13:42:19.109514 kernel: Key type .fscrypt registered Apr 30 13:42:19.109523 kernel: Key type fscrypt-provisioning registered Apr 30 13:42:19.109533 kernel: ima: No TPM chip found, activating TPM-bypass! Apr 30 13:42:19.109545 kernel: ima: Allocated hash algorithm: sha1 Apr 30 13:42:19.109554 kernel: ima: No architecture policies found Apr 30 13:42:19.109567 kernel: clk: Disabling unused clocks Apr 30 13:42:19.109577 kernel: Freeing unused kernel image (initmem) memory: 43484K Apr 30 13:42:19.109586 kernel: Write protecting the kernel read-only data: 38912k Apr 30 13:42:19.109596 kernel: Freeing unused kernel image (rodata/data gap) memory: 1712K Apr 30 13:42:19.109605 kernel: Run /init as init process Apr 30 13:42:19.109614 kernel: with arguments: Apr 30 13:42:19.109623 kernel: /init Apr 30 13:42:19.109632 kernel: with environment: Apr 30 13:42:19.109644 kernel: HOME=/ Apr 30 13:42:19.109653 kernel: TERM=linux Apr 30 13:42:19.109662 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Apr 30 13:42:19.109685 systemd[1]: Successfully made /usr/ read-only. Apr 30 13:42:19.109699 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) Apr 30 13:42:19.109710 systemd[1]: Detected virtualization kvm. Apr 30 13:42:19.109720 systemd[1]: Detected architecture x86-64. Apr 30 13:42:19.109733 systemd[1]: Running in initrd. Apr 30 13:42:19.109743 systemd[1]: No hostname configured, using default hostname. Apr 30 13:42:19.109753 systemd[1]: Hostname set to . Apr 30 13:42:19.109763 systemd[1]: Initializing machine ID from VM UUID. Apr 30 13:42:19.109773 systemd[1]: Queued start job for default target initrd.target. Apr 30 13:42:19.109783 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 30 13:42:19.109794 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 30 13:42:19.109816 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Apr 30 13:42:19.109828 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Apr 30 13:42:19.109839 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Apr 30 13:42:19.109850 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Apr 30 13:42:19.109861 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Apr 30 13:42:19.109874 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Apr 30 13:42:19.109900 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 30 13:42:19.109924 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Apr 30 13:42:19.109935 systemd[1]: Reached target paths.target - Path Units. Apr 30 13:42:19.109945 systemd[1]: Reached target slices.target - Slice Units. Apr 30 13:42:19.109956 systemd[1]: Reached target swap.target - Swaps. Apr 30 13:42:19.109966 systemd[1]: Reached target timers.target - Timer Units. Apr 30 13:42:19.109976 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Apr 30 13:42:19.109987 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Apr 30 13:42:19.110000 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Apr 30 13:42:19.110012 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Apr 30 13:42:19.110025 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Apr 30 13:42:19.110036 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Apr 30 13:42:19.110046 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Apr 30 13:42:19.110056 systemd[1]: Reached target sockets.target - Socket Units. Apr 30 13:42:19.110067 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Apr 30 13:42:19.110077 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Apr 30 13:42:19.110087 systemd[1]: Finished network-cleanup.service - Network Cleanup. Apr 30 13:42:19.110099 systemd[1]: Starting systemd-fsck-usr.service... Apr 30 13:42:19.110110 systemd[1]: Starting systemd-journald.service - Journal Service... Apr 30 13:42:19.110120 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Apr 30 13:42:19.110130 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 30 13:42:19.110140 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Apr 30 13:42:19.110150 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Apr 30 13:42:19.110163 systemd[1]: Finished systemd-fsck-usr.service. Apr 30 13:42:19.110197 systemd-journald[185]: Collecting audit messages is disabled. Apr 30 13:42:19.110227 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Apr 30 13:42:19.110238 systemd-journald[185]: Journal started Apr 30 13:42:19.110261 systemd-journald[185]: Runtime Journal (/run/log/journal/fb95a460456a494ea4083b54d96b19b4) is 8M, max 78.3M, 70.3M free. Apr 30 13:42:19.110933 systemd[1]: Started systemd-journald.service - Journal Service. Apr 30 13:42:19.125923 systemd-modules-load[186]: Inserted module 'overlay' Apr 30 13:42:19.166147 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Apr 30 13:42:19.166167 kernel: Bridge firewalling registered Apr 30 13:42:19.153332 systemd-modules-load[186]: Inserted module 'br_netfilter' Apr 30 13:42:19.168053 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Apr 30 13:42:19.168917 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 30 13:42:19.170248 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Apr 30 13:42:19.177049 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 30 13:42:19.179064 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Apr 30 13:42:19.184320 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Apr 30 13:42:19.186200 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Apr 30 13:42:19.190945 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Apr 30 13:42:19.200534 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 30 13:42:19.202705 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 30 13:42:19.208021 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Apr 30 13:42:19.209533 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 30 13:42:19.221750 dracut-cmdline[220]: dracut-dracut-053 Apr 30 13:42:19.224727 dracut-cmdline[220]: Using kernel command line parameters: rd.driver.pre=btrfs rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=openstack flatcar.autologin verity.usrhash=95dd3de5eb34971546a976dc51c66bc73cf59b888896e27767c0cbf245cb98fe Apr 30 13:42:19.226198 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Apr 30 13:42:19.262081 systemd-resolved[226]: Positive Trust Anchors: Apr 30 13:42:19.262095 systemd-resolved[226]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Apr 30 13:42:19.262135 systemd-resolved[226]: 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 Apr 30 13:42:19.265472 systemd-resolved[226]: Defaulting to hostname 'linux'. Apr 30 13:42:19.266405 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Apr 30 13:42:19.267778 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Apr 30 13:42:19.312932 kernel: SCSI subsystem initialized Apr 30 13:42:19.323932 kernel: Loading iSCSI transport class v2.0-870. Apr 30 13:42:19.336222 kernel: iscsi: registered transport (tcp) Apr 30 13:42:19.358161 kernel: iscsi: registered transport (qla4xxx) Apr 30 13:42:19.358233 kernel: QLogic iSCSI HBA Driver Apr 30 13:42:19.413141 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Apr 30 13:42:19.418204 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Apr 30 13:42:19.447403 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Apr 30 13:42:19.447476 kernel: device-mapper: uevent: version 1.0.3 Apr 30 13:42:19.448158 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Apr 30 13:42:19.503961 kernel: raid6: sse2x4 gen() 12985 MB/s Apr 30 13:42:19.521952 kernel: raid6: sse2x2 gen() 14983 MB/s Apr 30 13:42:19.540301 kernel: raid6: sse2x1 gen() 10107 MB/s Apr 30 13:42:19.540364 kernel: raid6: using algorithm sse2x2 gen() 14983 MB/s Apr 30 13:42:19.559336 kernel: raid6: .... xor() 9320 MB/s, rmw enabled Apr 30 13:42:19.559399 kernel: raid6: using ssse3x2 recovery algorithm Apr 30 13:42:19.582373 kernel: xor: measuring software checksum speed Apr 30 13:42:19.582440 kernel: prefetch64-sse : 18497 MB/sec Apr 30 13:42:19.583919 kernel: generic_sse : 15570 MB/sec Apr 30 13:42:19.583993 kernel: xor: using function: prefetch64-sse (18497 MB/sec) Apr 30 13:42:19.763483 kernel: Btrfs loaded, zoned=no, fsverity=no Apr 30 13:42:19.779187 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Apr 30 13:42:19.788138 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 30 13:42:19.831841 systemd-udevd[405]: Using default interface naming scheme 'v255'. Apr 30 13:42:19.844330 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 30 13:42:19.856178 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Apr 30 13:42:19.902235 dracut-pre-trigger[413]: rd.md=0: removing MD RAID activation Apr 30 13:42:19.962230 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Apr 30 13:42:19.969146 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Apr 30 13:42:20.024756 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Apr 30 13:42:20.034249 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Apr 30 13:42:20.082428 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Apr 30 13:42:20.086801 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Apr 30 13:42:20.087934 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 30 13:42:20.090264 systemd[1]: Reached target remote-fs.target - Remote File Systems. Apr 30 13:42:20.096996 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Apr 30 13:42:20.108716 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Apr 30 13:42:20.120817 kernel: virtio_blk virtio2: 2/0/0 default/read/poll queues Apr 30 13:42:20.152388 kernel: virtio_blk virtio2: [vda] 20971520 512-byte logical blocks (10.7 GB/10.0 GiB) Apr 30 13:42:20.152516 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Apr 30 13:42:20.152531 kernel: GPT:17805311 != 20971519 Apr 30 13:42:20.152544 kernel: GPT:Alternate GPT header not at the end of the disk. Apr 30 13:42:20.152564 kernel: GPT:17805311 != 20971519 Apr 30 13:42:20.152576 kernel: GPT: Use GNU Parted to correct GPT errors. Apr 30 13:42:20.152588 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Apr 30 13:42:20.152600 kernel: libata version 3.00 loaded. Apr 30 13:42:20.152612 kernel: ata_piix 0000:00:01.1: version 2.13 Apr 30 13:42:20.158151 kernel: scsi host0: ata_piix Apr 30 13:42:20.158288 kernel: scsi host1: ata_piix Apr 30 13:42:20.158407 kernel: ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 Apr 30 13:42:20.158428 kernel: ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 Apr 30 13:42:20.166029 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Apr 30 13:42:20.166178 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 30 13:42:20.167492 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 30 13:42:20.168241 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Apr 30 13:42:20.168386 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Apr 30 13:42:20.170370 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Apr 30 13:42:20.177661 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 30 13:42:20.190930 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by (udev-worker) (459) Apr 30 13:42:20.211916 kernel: BTRFS: device fsid 0778af4c-f6f8-4118-a0d2-fb24d73f5df4 devid 1 transid 40 /dev/vda3 scanned by (udev-worker) (463) Apr 30 13:42:20.218723 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Apr 30 13:42:20.262339 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Apr 30 13:42:20.263137 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 30 13:42:20.280152 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Apr 30 13:42:20.280742 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Apr 30 13:42:20.292326 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Apr 30 13:42:20.299138 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Apr 30 13:42:20.303074 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 30 13:42:20.310031 disk-uuid[507]: Primary Header is updated. Apr 30 13:42:20.310031 disk-uuid[507]: Secondary Entries is updated. Apr 30 13:42:20.310031 disk-uuid[507]: Secondary Header is updated. Apr 30 13:42:20.321986 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Apr 30 13:42:20.331607 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 30 13:42:21.344067 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Apr 30 13:42:21.346768 disk-uuid[509]: The operation has completed successfully. Apr 30 13:42:21.428662 systemd[1]: disk-uuid.service: Deactivated successfully. Apr 30 13:42:21.428768 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Apr 30 13:42:21.488018 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Apr 30 13:42:21.493487 sh[529]: Success Apr 30 13:42:21.516918 kernel: device-mapper: verity: sha256 using implementation "sha256-ssse3" Apr 30 13:42:21.585096 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Apr 30 13:42:21.587212 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Apr 30 13:42:21.594056 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Apr 30 13:42:21.610938 kernel: BTRFS info (device dm-0): first mount of filesystem 0778af4c-f6f8-4118-a0d2-fb24d73f5df4 Apr 30 13:42:21.611005 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Apr 30 13:42:21.613292 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Apr 30 13:42:21.615241 kernel: BTRFS info (device dm-0): disabling log replay at mount time Apr 30 13:42:21.617742 kernel: BTRFS info (device dm-0): using free space tree Apr 30 13:42:21.631854 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Apr 30 13:42:21.634219 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Apr 30 13:42:21.642154 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Apr 30 13:42:21.647141 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Apr 30 13:42:21.663869 kernel: BTRFS info (device vda6): first mount of filesystem 70902d85-577c-4d48-8616-61ed6d6784d1 Apr 30 13:42:21.663946 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Apr 30 13:42:21.663961 kernel: BTRFS info (device vda6): using free space tree Apr 30 13:42:21.672975 kernel: BTRFS info (device vda6): auto enabling async discard Apr 30 13:42:21.686975 kernel: BTRFS info (device vda6): last unmount of filesystem 70902d85-577c-4d48-8616-61ed6d6784d1 Apr 30 13:42:21.695124 systemd[1]: Finished ignition-setup.service - Ignition (setup). Apr 30 13:42:21.701144 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Apr 30 13:42:21.775866 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Apr 30 13:42:21.783259 systemd[1]: Starting systemd-networkd.service - Network Configuration... Apr 30 13:42:21.813871 systemd-networkd[710]: lo: Link UP Apr 30 13:42:21.814538 systemd-networkd[710]: lo: Gained carrier Apr 30 13:42:21.815721 systemd-networkd[710]: Enumeration completed Apr 30 13:42:21.816933 systemd[1]: Started systemd-networkd.service - Network Configuration. Apr 30 13:42:21.817481 systemd[1]: Reached target network.target - Network. Apr 30 13:42:21.818220 systemd-networkd[710]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 30 13:42:21.818223 systemd-networkd[710]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Apr 30 13:42:21.820121 systemd-networkd[710]: eth0: Link UP Apr 30 13:42:21.820124 systemd-networkd[710]: eth0: Gained carrier Apr 30 13:42:21.820134 systemd-networkd[710]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 30 13:42:21.831944 systemd-networkd[710]: eth0: DHCPv4 address 172.24.4.31/24, gateway 172.24.4.1 acquired from 172.24.4.1 Apr 30 13:42:21.864648 ignition[621]: Ignition 2.20.0 Apr 30 13:42:21.864659 ignition[621]: Stage: fetch-offline Apr 30 13:42:21.864699 ignition[621]: no configs at "/usr/lib/ignition/base.d" Apr 30 13:42:21.864708 ignition[621]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Apr 30 13:42:21.866910 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Apr 30 13:42:21.864810 ignition[621]: parsed url from cmdline: "" Apr 30 13:42:21.864813 ignition[621]: no config URL provided Apr 30 13:42:21.864819 ignition[621]: reading system config file "/usr/lib/ignition/user.ign" Apr 30 13:42:21.864826 ignition[621]: no config at "/usr/lib/ignition/user.ign" Apr 30 13:42:21.864831 ignition[621]: failed to fetch config: resource requires networking Apr 30 13:42:21.865050 ignition[621]: Ignition finished successfully Apr 30 13:42:21.874068 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Apr 30 13:42:21.884957 ignition[722]: Ignition 2.20.0 Apr 30 13:42:21.884969 ignition[722]: Stage: fetch Apr 30 13:42:21.885156 ignition[722]: no configs at "/usr/lib/ignition/base.d" Apr 30 13:42:21.885167 ignition[722]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Apr 30 13:42:21.885255 ignition[722]: parsed url from cmdline: "" Apr 30 13:42:21.885259 ignition[722]: no config URL provided Apr 30 13:42:21.885264 ignition[722]: reading system config file "/usr/lib/ignition/user.ign" Apr 30 13:42:21.885272 ignition[722]: no config at "/usr/lib/ignition/user.ign" Apr 30 13:42:21.885354 ignition[722]: GET http://169.254.169.254/openstack/latest/user_data: attempt #1 Apr 30 13:42:21.885506 ignition[722]: config drive ("/dev/disk/by-label/config-2") not found. Waiting... Apr 30 13:42:21.885525 ignition[722]: config drive ("/dev/disk/by-label/CONFIG-2") not found. Waiting... Apr 30 13:42:22.010947 systemd-resolved[226]: Detected conflict on linux IN A 172.24.4.31 Apr 30 13:42:22.010980 systemd-resolved[226]: Hostname conflict, changing published hostname from 'linux' to 'linux8'. Apr 30 13:42:22.332983 ignition[722]: GET result: OK Apr 30 13:42:22.333126 ignition[722]: parsing config with SHA512: 0ba5df9089e853a01e681771c20eec3cbe75c2110edc5e5cc41a760af49f58db3ce0097159fc007b4a4f38d4120b0ca5aebff8b9c0f9dd0dcd61d69b467707bb Apr 30 13:42:22.355702 unknown[722]: fetched base config from "system" Apr 30 13:42:22.355738 unknown[722]: fetched base config from "system" Apr 30 13:42:22.356555 ignition[722]: fetch: fetch complete Apr 30 13:42:22.355761 unknown[722]: fetched user config from "openstack" Apr 30 13:42:22.356575 ignition[722]: fetch: fetch passed Apr 30 13:42:22.361555 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Apr 30 13:42:22.356696 ignition[722]: Ignition finished successfully Apr 30 13:42:22.375336 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Apr 30 13:42:22.412400 ignition[728]: Ignition 2.20.0 Apr 30 13:42:22.412436 ignition[728]: Stage: kargs Apr 30 13:42:22.412859 ignition[728]: no configs at "/usr/lib/ignition/base.d" Apr 30 13:42:22.412935 ignition[728]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Apr 30 13:42:22.416710 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Apr 30 13:42:22.414827 ignition[728]: kargs: kargs passed Apr 30 13:42:22.414971 ignition[728]: Ignition finished successfully Apr 30 13:42:22.427287 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Apr 30 13:42:22.454499 ignition[734]: Ignition 2.20.0 Apr 30 13:42:22.456141 ignition[734]: Stage: disks Apr 30 13:42:22.456529 ignition[734]: no configs at "/usr/lib/ignition/base.d" Apr 30 13:42:22.456554 ignition[734]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Apr 30 13:42:22.462483 ignition[734]: disks: disks passed Apr 30 13:42:22.462586 ignition[734]: Ignition finished successfully Apr 30 13:42:22.464555 systemd[1]: Finished ignition-disks.service - Ignition (disks). Apr 30 13:42:22.466861 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Apr 30 13:42:22.468705 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Apr 30 13:42:22.471370 systemd[1]: Reached target local-fs.target - Local File Systems. Apr 30 13:42:22.474233 systemd[1]: Reached target sysinit.target - System Initialization. Apr 30 13:42:22.477114 systemd[1]: Reached target basic.target - Basic System. Apr 30 13:42:22.487207 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Apr 30 13:42:22.518491 systemd-fsck[742]: ROOT: clean, 14/1628000 files, 120691/1617920 blocks Apr 30 13:42:22.528686 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Apr 30 13:42:22.539277 systemd[1]: Mounting sysroot.mount - /sysroot... Apr 30 13:42:22.662904 kernel: EXT4-fs (vda9): mounted filesystem 59d16236-967d-47d1-a9bd-4b055a17ab77 r/w with ordered data mode. Quota mode: none. Apr 30 13:42:22.664172 systemd[1]: Mounted sysroot.mount - /sysroot. Apr 30 13:42:22.666342 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Apr 30 13:42:22.676991 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Apr 30 13:42:22.679760 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Apr 30 13:42:22.680723 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Apr 30 13:42:22.683147 systemd[1]: Starting flatcar-openstack-hostname.service - Flatcar OpenStack Metadata Hostname Agent... Apr 30 13:42:22.683826 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Apr 30 13:42:22.683940 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Apr 30 13:42:22.708039 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 scanned by mount (750) Apr 30 13:42:22.708080 kernel: BTRFS info (device vda6): first mount of filesystem 70902d85-577c-4d48-8616-61ed6d6784d1 Apr 30 13:42:22.708104 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Apr 30 13:42:22.708127 kernel: BTRFS info (device vda6): using free space tree Apr 30 13:42:22.708149 kernel: BTRFS info (device vda6): auto enabling async discard Apr 30 13:42:22.699077 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Apr 30 13:42:22.718115 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Apr 30 13:42:22.728465 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Apr 30 13:42:22.815515 initrd-setup-root[778]: cut: /sysroot/etc/passwd: No such file or directory Apr 30 13:42:22.827803 initrd-setup-root[785]: cut: /sysroot/etc/group: No such file or directory Apr 30 13:42:22.833333 initrd-setup-root[792]: cut: /sysroot/etc/shadow: No such file or directory Apr 30 13:42:22.839808 initrd-setup-root[799]: cut: /sysroot/etc/gshadow: No such file or directory Apr 30 13:42:22.980771 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Apr 30 13:42:22.989228 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Apr 30 13:42:22.992052 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Apr 30 13:42:23.004729 systemd[1]: sysroot-oem.mount: Deactivated successfully. Apr 30 13:42:23.009460 kernel: BTRFS info (device vda6): last unmount of filesystem 70902d85-577c-4d48-8616-61ed6d6784d1 Apr 30 13:42:23.030708 ignition[867]: INFO : Ignition 2.20.0 Apr 30 13:42:23.030708 ignition[867]: INFO : Stage: mount Apr 30 13:42:23.030708 ignition[867]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 30 13:42:23.030708 ignition[867]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/openstack" Apr 30 13:42:23.035615 ignition[867]: INFO : mount: mount passed Apr 30 13:42:23.035615 ignition[867]: INFO : Ignition finished successfully Apr 30 13:42:23.032187 systemd[1]: Finished ignition-mount.service - Ignition (mount). Apr 30 13:42:23.047094 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Apr 30 13:42:23.705523 systemd-networkd[710]: eth0: Gained IPv6LL Apr 30 13:42:29.891423 coreos-metadata[752]: Apr 30 13:42:29.891 WARN failed to locate config-drive, using the metadata service API instead Apr 30 13:42:29.936152 coreos-metadata[752]: Apr 30 13:42:29.936 INFO Fetching http://169.254.169.254/latest/meta-data/hostname: Attempt #1 Apr 30 13:42:29.954711 coreos-metadata[752]: Apr 30 13:42:29.954 INFO Fetch successful Apr 30 13:42:29.955424 coreos-metadata[752]: Apr 30 13:42:29.955 INFO wrote hostname ci-4230-1-1-5-9fc3a58b15.novalocal to /sysroot/etc/hostname Apr 30 13:42:29.959603 systemd[1]: flatcar-openstack-hostname.service: Deactivated successfully. Apr 30 13:42:29.959871 systemd[1]: Finished flatcar-openstack-hostname.service - Flatcar OpenStack Metadata Hostname Agent. Apr 30 13:42:29.970028 systemd[1]: Starting ignition-files.service - Ignition (files)... Apr 30 13:42:29.984090 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Apr 30 13:42:29.994975 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/vda6 scanned by mount (884) Apr 30 13:42:30.005976 kernel: BTRFS info (device vda6): first mount of filesystem 70902d85-577c-4d48-8616-61ed6d6784d1 Apr 30 13:42:30.006060 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Apr 30 13:42:30.007450 kernel: BTRFS info (device vda6): using free space tree Apr 30 13:42:30.018959 kernel: BTRFS info (device vda6): auto enabling async discard Apr 30 13:42:30.025044 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Apr 30 13:42:30.073521 ignition[902]: INFO : Ignition 2.20.0 Apr 30 13:42:30.073521 ignition[902]: INFO : Stage: files Apr 30 13:42:30.077779 ignition[902]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 30 13:42:30.077779 ignition[902]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/openstack" Apr 30 13:42:30.077779 ignition[902]: DEBUG : files: compiled without relabeling support, skipping Apr 30 13:42:30.084633 ignition[902]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Apr 30 13:42:30.084633 ignition[902]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Apr 30 13:42:30.090770 ignition[902]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Apr 30 13:42:30.093472 ignition[902]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Apr 30 13:42:30.093472 ignition[902]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Apr 30 13:42:30.092605 unknown[902]: wrote ssh authorized keys file for user: core Apr 30 13:42:30.100454 ignition[902]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Apr 30 13:42:30.100454 ignition[902]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Apr 30 13:42:30.100454 ignition[902]: INFO : files: op(4): [started] processing unit "coreos-metadata.service" Apr 30 13:42:30.108161 ignition[902]: INFO : files: op(4): op(5): [started] writing systemd drop-in "20-clct-provider-override.conf" at "/sysroot/etc/systemd/system/coreos-metadata.service.d/20-clct-provider-override.conf" Apr 30 13:42:30.108161 ignition[902]: INFO : files: op(4): op(5): [finished] writing systemd drop-in "20-clct-provider-override.conf" at "/sysroot/etc/systemd/system/coreos-metadata.service.d/20-clct-provider-override.conf" Apr 30 13:42:30.108161 ignition[902]: INFO : files: op(4): [finished] processing unit "coreos-metadata.service" Apr 30 13:42:30.108161 ignition[902]: INFO : files: op(6): [started] processing unit "coreos-metadata-sshkeys@.service" Apr 30 13:42:30.108161 ignition[902]: INFO : files: op(6): op(7): [started] writing systemd drop-in "20-clct-provider-override.conf" at "/sysroot/etc/systemd/system/coreos-metadata-sshkeys@.service.d/20-clct-provider-override.conf" Apr 30 13:42:30.108161 ignition[902]: INFO : files: op(6): op(7): [finished] writing systemd drop-in "20-clct-provider-override.conf" at "/sysroot/etc/systemd/system/coreos-metadata-sshkeys@.service.d/20-clct-provider-override.conf" Apr 30 13:42:30.108161 ignition[902]: INFO : files: op(6): [finished] processing unit "coreos-metadata-sshkeys@.service" Apr 30 13:42:30.108161 ignition[902]: INFO : files: op(8): [started] processing unit "etcd-member.service" Apr 30 13:42:30.108161 ignition[902]: INFO : files: op(8): op(9): [started] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Apr 30 13:42:30.108161 ignition[902]: INFO : files: op(8): op(9): [finished] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Apr 30 13:42:30.108161 ignition[902]: INFO : files: op(8): [finished] processing unit "etcd-member.service" Apr 30 13:42:30.108161 ignition[902]: INFO : files: op(a): [started] setting preset to enabled for "coreos-metadata-sshkeys@.service " Apr 30 13:42:30.108161 ignition[902]: INFO : files: op(a): [finished] setting preset to enabled for "coreos-metadata-sshkeys@.service " Apr 30 13:42:30.108161 ignition[902]: INFO : files: op(b): [started] setting preset to enabled for "etcd-member.service" Apr 30 13:42:30.108161 ignition[902]: INFO : files: op(b): [finished] setting preset to enabled for "etcd-member.service" Apr 30 13:42:30.125162 ignition[902]: INFO : files: createResultFile: createFiles: op(c): [started] writing file "/sysroot/etc/.ignition-result.json" Apr 30 13:42:30.125162 ignition[902]: INFO : files: createResultFile: createFiles: op(c): [finished] writing file "/sysroot/etc/.ignition-result.json" Apr 30 13:42:30.125162 ignition[902]: INFO : files: files passed Apr 30 13:42:30.125162 ignition[902]: INFO : Ignition finished successfully Apr 30 13:42:30.126731 systemd[1]: Finished ignition-files.service - Ignition (files). Apr 30 13:42:30.135023 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Apr 30 13:42:30.137655 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Apr 30 13:42:30.142134 systemd[1]: ignition-quench.service: Deactivated successfully. Apr 30 13:42:30.142844 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Apr 30 13:42:30.150541 initrd-setup-root-after-ignition[930]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Apr 30 13:42:30.150541 initrd-setup-root-after-ignition[930]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Apr 30 13:42:30.152732 initrd-setup-root-after-ignition[934]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Apr 30 13:42:30.154061 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Apr 30 13:42:30.155124 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Apr 30 13:42:30.162044 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Apr 30 13:42:30.180846 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Apr 30 13:42:30.180971 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Apr 30 13:42:30.182426 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Apr 30 13:42:30.183318 systemd[1]: Reached target initrd.target - Initrd Default Target. Apr 30 13:42:30.184640 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Apr 30 13:42:30.186099 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Apr 30 13:42:30.203181 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Apr 30 13:42:30.209063 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Apr 30 13:42:30.219250 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Apr 30 13:42:30.223932 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 30 13:42:30.224630 systemd[1]: Stopped target timers.target - Timer Units. Apr 30 13:42:30.225960 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Apr 30 13:42:30.226102 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Apr 30 13:42:30.227565 systemd[1]: Stopped target initrd.target - Initrd Default Target. Apr 30 13:42:30.228355 systemd[1]: Stopped target basic.target - Basic System. Apr 30 13:42:30.229373 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Apr 30 13:42:30.230640 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Apr 30 13:42:30.231767 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Apr 30 13:42:30.232835 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Apr 30 13:42:30.234088 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Apr 30 13:42:30.235384 systemd[1]: Stopped target sysinit.target - System Initialization. Apr 30 13:42:30.236637 systemd[1]: Stopped target local-fs.target - Local File Systems. Apr 30 13:42:30.237788 systemd[1]: Stopped target swap.target - Swaps. Apr 30 13:42:30.240327 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Apr 30 13:42:30.240595 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Apr 30 13:42:30.242532 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Apr 30 13:42:30.244148 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 30 13:42:30.245651 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Apr 30 13:42:30.245843 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 30 13:42:30.247080 systemd[1]: dracut-initqueue.service: Deactivated successfully. Apr 30 13:42:30.247246 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Apr 30 13:42:30.248682 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Apr 30 13:42:30.248907 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Apr 30 13:42:30.250359 systemd[1]: ignition-files.service: Deactivated successfully. Apr 30 13:42:30.250531 systemd[1]: Stopped ignition-files.service - Ignition (files). Apr 30 13:42:30.263366 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Apr 30 13:42:30.266143 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Apr 30 13:42:30.266723 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Apr 30 13:42:30.266933 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Apr 30 13:42:30.269129 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Apr 30 13:42:30.269302 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Apr 30 13:42:30.278143 systemd[1]: initrd-cleanup.service: Deactivated successfully. Apr 30 13:42:30.278236 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Apr 30 13:42:30.285251 ignition[954]: INFO : Ignition 2.20.0 Apr 30 13:42:30.286953 ignition[954]: INFO : Stage: umount Apr 30 13:42:30.286953 ignition[954]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 30 13:42:30.286953 ignition[954]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/openstack" Apr 30 13:42:30.290868 ignition[954]: INFO : umount: umount passed Apr 30 13:42:30.290868 ignition[954]: INFO : Ignition finished successfully Apr 30 13:42:30.290200 systemd[1]: ignition-mount.service: Deactivated successfully. Apr 30 13:42:30.290326 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Apr 30 13:42:30.291742 systemd[1]: ignition-disks.service: Deactivated successfully. Apr 30 13:42:30.291814 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Apr 30 13:42:30.293171 systemd[1]: ignition-kargs.service: Deactivated successfully. Apr 30 13:42:30.293213 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Apr 30 13:42:30.295086 systemd[1]: ignition-fetch.service: Deactivated successfully. Apr 30 13:42:30.295127 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Apr 30 13:42:30.295859 systemd[1]: Stopped target network.target - Network. Apr 30 13:42:30.296357 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Apr 30 13:42:30.296403 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Apr 30 13:42:30.296932 systemd[1]: Stopped target paths.target - Path Units. Apr 30 13:42:30.297357 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Apr 30 13:42:30.299173 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 30 13:42:30.299729 systemd[1]: Stopped target slices.target - Slice Units. Apr 30 13:42:30.300298 systemd[1]: Stopped target sockets.target - Socket Units. Apr 30 13:42:30.303440 systemd[1]: iscsid.socket: Deactivated successfully. Apr 30 13:42:30.303485 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Apr 30 13:42:30.303985 systemd[1]: iscsiuio.socket: Deactivated successfully. Apr 30 13:42:30.304018 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Apr 30 13:42:30.305243 systemd[1]: ignition-setup.service: Deactivated successfully. Apr 30 13:42:30.305293 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Apr 30 13:42:30.306268 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Apr 30 13:42:30.306313 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Apr 30 13:42:30.307521 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Apr 30 13:42:30.308757 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Apr 30 13:42:30.311040 systemd[1]: sysroot-boot.mount: Deactivated successfully. Apr 30 13:42:30.311601 systemd[1]: sysroot-boot.service: Deactivated successfully. Apr 30 13:42:30.311680 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Apr 30 13:42:30.312874 systemd[1]: initrd-setup-root.service: Deactivated successfully. Apr 30 13:42:30.312994 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Apr 30 13:42:30.316072 systemd[1]: systemd-networkd.service: Deactivated successfully. Apr 30 13:42:30.316163 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Apr 30 13:42:30.318264 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Apr 30 13:42:30.318462 systemd[1]: systemd-resolved.service: Deactivated successfully. Apr 30 13:42:30.318562 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Apr 30 13:42:30.321604 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Apr 30 13:42:30.322261 systemd[1]: systemd-networkd.socket: Deactivated successfully. Apr 30 13:42:30.322553 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Apr 30 13:42:30.329036 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Apr 30 13:42:30.330682 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Apr 30 13:42:30.330739 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Apr 30 13:42:30.331852 systemd[1]: systemd-sysctl.service: Deactivated successfully. Apr 30 13:42:30.331920 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Apr 30 13:42:30.333474 systemd[1]: systemd-modules-load.service: Deactivated successfully. Apr 30 13:42:30.333519 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Apr 30 13:42:30.334296 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Apr 30 13:42:30.334341 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 30 13:42:30.335742 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 30 13:42:30.337677 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Apr 30 13:42:30.337741 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Apr 30 13:42:30.346422 systemd[1]: network-cleanup.service: Deactivated successfully. Apr 30 13:42:30.346534 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Apr 30 13:42:30.348210 systemd[1]: systemd-udevd.service: Deactivated successfully. Apr 30 13:42:30.348346 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 30 13:42:30.349384 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Apr 30 13:42:30.349441 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Apr 30 13:42:30.350261 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Apr 30 13:42:30.350294 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Apr 30 13:42:30.351320 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Apr 30 13:42:30.351368 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Apr 30 13:42:30.353042 systemd[1]: dracut-cmdline.service: Deactivated successfully. Apr 30 13:42:30.353087 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Apr 30 13:42:30.354272 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Apr 30 13:42:30.354329 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 30 13:42:30.362025 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Apr 30 13:42:30.362804 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Apr 30 13:42:30.362857 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 30 13:42:30.363481 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Apr 30 13:42:30.363523 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Apr 30 13:42:30.364078 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Apr 30 13:42:30.364118 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Apr 30 13:42:30.364631 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Apr 30 13:42:30.364669 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Apr 30 13:42:30.368116 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Apr 30 13:42:30.368225 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Apr 30 13:42:30.369748 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Apr 30 13:42:30.379220 systemd[1]: Starting initrd-switch-root.service - Switch Root... Apr 30 13:42:30.387358 systemd[1]: Switching root. Apr 30 13:42:30.411531 systemd-journald[185]: Journal stopped Apr 30 13:42:31.719974 systemd-journald[185]: Received SIGTERM from PID 1 (systemd). Apr 30 13:42:31.720025 kernel: SELinux: policy capability network_peer_controls=1 Apr 30 13:42:31.720046 kernel: SELinux: policy capability open_perms=1 Apr 30 13:42:31.720058 kernel: SELinux: policy capability extended_socket_class=1 Apr 30 13:42:31.720069 kernel: SELinux: policy capability always_check_network=0 Apr 30 13:42:31.720081 kernel: SELinux: policy capability cgroup_seclabel=1 Apr 30 13:42:31.720093 kernel: SELinux: policy capability nnp_nosuid_transition=1 Apr 30 13:42:31.720104 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Apr 30 13:42:31.720115 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Apr 30 13:42:31.720131 systemd[1]: Successfully loaded SELinux policy in 67.522ms. Apr 30 13:42:31.720147 kernel: audit: type=1403 audit(1746020550.646:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Apr 30 13:42:31.720161 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 11.250ms. Apr 30 13:42:31.720175 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) Apr 30 13:42:31.720189 systemd[1]: Detected virtualization kvm. Apr 30 13:42:31.720201 systemd[1]: Detected architecture x86-64. Apr 30 13:42:31.720213 systemd[1]: Detected first boot. Apr 30 13:42:31.720225 systemd[1]: Hostname set to . Apr 30 13:42:31.720238 systemd[1]: Initializing machine ID from VM UUID. Apr 30 13:42:31.720251 zram_generator::config[998]: No configuration found. Apr 30 13:42:31.720266 kernel: Guest personality initialized and is inactive Apr 30 13:42:31.720277 kernel: VMCI host device registered (name=vmci, major=10, minor=125) Apr 30 13:42:31.720289 kernel: Initialized host personality Apr 30 13:42:31.720299 kernel: NET: Registered PF_VSOCK protocol family Apr 30 13:42:31.720314 systemd[1]: Populated /etc with preset unit settings. Apr 30 13:42:31.720327 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Apr 30 13:42:31.720339 systemd[1]: initrd-switch-root.service: Deactivated successfully. Apr 30 13:42:31.720351 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Apr 30 13:42:31.720365 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Apr 30 13:42:31.720378 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Apr 30 13:42:31.720390 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Apr 30 13:42:31.720402 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Apr 30 13:42:31.720414 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Apr 30 13:42:31.720426 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Apr 30 13:42:31.720438 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Apr 30 13:42:31.720450 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Apr 30 13:42:31.720463 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Apr 30 13:42:31.720477 systemd[1]: Created slice user.slice - User and Session Slice. Apr 30 13:42:31.720489 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 30 13:42:31.720502 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 30 13:42:31.720515 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Apr 30 13:42:31.720527 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Apr 30 13:42:31.720540 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Apr 30 13:42:31.720555 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Apr 30 13:42:31.720567 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Apr 30 13:42:31.720580 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 30 13:42:31.720592 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Apr 30 13:42:31.720604 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Apr 30 13:42:31.720617 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Apr 30 13:42:31.720629 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Apr 30 13:42:31.720642 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 30 13:42:31.720654 systemd[1]: Reached target remote-fs.target - Remote File Systems. Apr 30 13:42:31.720669 systemd[1]: Reached target slices.target - Slice Units. Apr 30 13:42:31.720681 systemd[1]: Reached target swap.target - Swaps. Apr 30 13:42:31.720693 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Apr 30 13:42:31.720705 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Apr 30 13:42:31.720718 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Apr 30 13:42:31.720730 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Apr 30 13:42:31.720742 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Apr 30 13:42:31.720754 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Apr 30 13:42:31.720766 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Apr 30 13:42:31.720782 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Apr 30 13:42:31.720795 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Apr 30 13:42:31.720807 systemd[1]: Mounting media.mount - External Media Directory... Apr 30 13:42:31.720819 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 30 13:42:31.720831 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Apr 30 13:42:31.720844 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Apr 30 13:42:31.720856 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Apr 30 13:42:31.720869 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). Apr 30 13:42:31.724278 systemd[1]: Reached target machines.target - Containers. Apr 30 13:42:31.724303 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Apr 30 13:42:31.724318 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 30 13:42:31.724334 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Apr 30 13:42:31.724350 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Apr 30 13:42:31.724363 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Apr 30 13:42:31.724377 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Apr 30 13:42:31.724390 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Apr 30 13:42:31.724403 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Apr 30 13:42:31.724419 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Apr 30 13:42:31.724432 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Apr 30 13:42:31.724446 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Apr 30 13:42:31.724458 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Apr 30 13:42:31.724471 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Apr 30 13:42:31.724484 systemd[1]: Stopped systemd-fsck-usr.service. Apr 30 13:42:31.724497 kernel: loop: module loaded Apr 30 13:42:31.724511 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). Apr 30 13:42:31.724526 systemd[1]: Starting systemd-journald.service - Journal Service... Apr 30 13:42:31.724539 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Apr 30 13:42:31.724552 kernel: ACPI: bus type drm_connector registered Apr 30 13:42:31.724565 kernel: fuse: init (API version 7.39) Apr 30 13:42:31.724578 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Apr 30 13:42:31.724590 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Apr 30 13:42:31.724602 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Apr 30 13:42:31.724633 systemd-journald[1095]: Collecting audit messages is disabled. Apr 30 13:42:31.724660 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Apr 30 13:42:31.724673 systemd-journald[1095]: Journal started Apr 30 13:42:31.724697 systemd-journald[1095]: Runtime Journal (/run/log/journal/fb95a460456a494ea4083b54d96b19b4) is 8M, max 78.3M, 70.3M free. Apr 30 13:42:31.371241 systemd[1]: Queued start job for default target multi-user.target. Apr 30 13:42:31.381987 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Apr 30 13:42:31.382428 systemd[1]: systemd-journald.service: Deactivated successfully. Apr 30 13:42:31.726903 systemd[1]: verity-setup.service: Deactivated successfully. Apr 30 13:42:31.729904 systemd[1]: Stopped verity-setup.service. Apr 30 13:42:31.735927 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 30 13:42:31.751944 systemd[1]: Started systemd-journald.service - Journal Service. Apr 30 13:42:31.752426 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Apr 30 13:42:31.753230 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Apr 30 13:42:31.754050 systemd[1]: Mounted media.mount - External Media Directory. Apr 30 13:42:31.754721 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Apr 30 13:42:31.755371 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Apr 30 13:42:31.756301 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Apr 30 13:42:31.757053 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Apr 30 13:42:31.759185 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Apr 30 13:42:31.759958 systemd[1]: modprobe@configfs.service: Deactivated successfully. Apr 30 13:42:31.760111 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Apr 30 13:42:31.760828 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 30 13:42:31.761033 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Apr 30 13:42:31.761748 systemd[1]: modprobe@drm.service: Deactivated successfully. Apr 30 13:42:31.762127 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Apr 30 13:42:31.762805 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Apr 30 13:42:31.762988 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Apr 30 13:42:31.764509 systemd[1]: modprobe@fuse.service: Deactivated successfully. Apr 30 13:42:31.764687 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Apr 30 13:42:31.766099 systemd[1]: modprobe@loop.service: Deactivated successfully. Apr 30 13:42:31.766250 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Apr 30 13:42:31.767008 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Apr 30 13:42:31.767715 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Apr 30 13:42:31.769235 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Apr 30 13:42:31.771019 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Apr 30 13:42:31.784271 systemd[1]: Reached target network-pre.target - Preparation for Network. Apr 30 13:42:31.796125 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Apr 30 13:42:31.800433 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Apr 30 13:42:31.801183 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Apr 30 13:42:31.801287 systemd[1]: Reached target local-fs.target - Local File Systems. Apr 30 13:42:31.803291 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Apr 30 13:42:31.814594 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Apr 30 13:42:31.818688 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Apr 30 13:42:31.821087 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 30 13:42:31.830113 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Apr 30 13:42:31.832320 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Apr 30 13:42:31.833435 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Apr 30 13:42:31.842720 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Apr 30 13:42:31.843704 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Apr 30 13:42:31.845392 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Apr 30 13:42:31.851085 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Apr 30 13:42:31.855260 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Apr 30 13:42:31.859655 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Apr 30 13:42:31.860364 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Apr 30 13:42:31.861086 systemd-journald[1095]: Time spent on flushing to /var/log/journal/fb95a460456a494ea4083b54d96b19b4 is 68.189ms for 951 entries. Apr 30 13:42:31.861086 systemd-journald[1095]: System Journal (/var/log/journal/fb95a460456a494ea4083b54d96b19b4) is 8M, max 584.8M, 576.8M free. Apr 30 13:42:31.997976 systemd-journald[1095]: Received client request to flush runtime journal. Apr 30 13:42:31.998558 kernel: loop0: detected capacity change from 0 to 8 Apr 30 13:42:31.998596 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Apr 30 13:42:31.998616 kernel: loop1: detected capacity change from 0 to 147912 Apr 30 13:42:31.862019 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Apr 30 13:42:31.868913 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Apr 30 13:42:31.869735 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Apr 30 13:42:31.874678 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Apr 30 13:42:31.878072 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Apr 30 13:42:31.881243 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Apr 30 13:42:31.947066 udevadm[1146]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Apr 30 13:42:31.981746 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Apr 30 13:42:31.999158 systemd-tmpfiles[1138]: ACLs are not supported, ignoring. Apr 30 13:42:31.999180 systemd-tmpfiles[1138]: ACLs are not supported, ignoring. Apr 30 13:42:32.001169 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Apr 30 13:42:32.008535 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Apr 30 13:42:32.017065 systemd[1]: Starting systemd-sysusers.service - Create System Users... Apr 30 13:42:32.052554 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Apr 30 13:42:32.132117 kernel: loop2: detected capacity change from 0 to 138176 Apr 30 13:42:32.145153 systemd[1]: Finished systemd-sysusers.service - Create System Users. Apr 30 13:42:32.156070 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Apr 30 13:42:32.184026 systemd-tmpfiles[1162]: ACLs are not supported, ignoring. Apr 30 13:42:32.184049 systemd-tmpfiles[1162]: ACLs are not supported, ignoring. Apr 30 13:42:32.188712 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 30 13:42:32.195358 kernel: loop3: detected capacity change from 0 to 8 Apr 30 13:42:32.197907 kernel: loop4: detected capacity change from 0 to 147912 Apr 30 13:42:32.237922 kernel: loop5: detected capacity change from 0 to 138176 Apr 30 13:42:32.283994 (sd-merge)[1166]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-openstack'. Apr 30 13:42:32.284774 (sd-merge)[1166]: Merged extensions into '/usr'. Apr 30 13:42:32.287261 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Apr 30 13:42:32.296236 systemd[1]: Starting ensure-sysext.service... Apr 30 13:42:32.306272 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Apr 30 13:42:32.329097 systemd[1]: Reload requested from client PID 1168 ('systemctl') (unit ensure-sysext.service)... Apr 30 13:42:32.329114 systemd[1]: Reloading... Apr 30 13:42:32.336613 systemd-tmpfiles[1169]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Apr 30 13:42:32.336925 systemd-tmpfiles[1169]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Apr 30 13:42:32.337797 systemd-tmpfiles[1169]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Apr 30 13:42:32.338542 systemd-tmpfiles[1169]: ACLs are not supported, ignoring. Apr 30 13:42:32.338617 systemd-tmpfiles[1169]: ACLs are not supported, ignoring. Apr 30 13:42:32.363397 systemd-tmpfiles[1169]: Detected autofs mount point /boot during canonicalization of boot. Apr 30 13:42:32.363409 systemd-tmpfiles[1169]: Skipping /boot Apr 30 13:42:32.391102 systemd-tmpfiles[1169]: Detected autofs mount point /boot during canonicalization of boot. Apr 30 13:42:32.391114 systemd-tmpfiles[1169]: Skipping /boot Apr 30 13:42:32.432587 zram_generator::config[1195]: No configuration found. Apr 30 13:42:32.684675 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. Apr 30 13:42:32.771538 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Apr 30 13:42:32.771670 systemd[1]: Reloading finished in 442 ms. Apr 30 13:42:32.810991 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 30 13:42:32.825083 systemd[1]: Starting audit-rules.service - Load Audit Rules... Apr 30 13:42:32.834086 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Apr 30 13:42:32.836026 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Apr 30 13:42:32.844115 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Apr 30 13:42:32.860072 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Apr 30 13:42:32.878429 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 30 13:42:32.878703 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 30 13:42:32.885275 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Apr 30 13:42:32.896142 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Apr 30 13:42:32.903479 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Apr 30 13:42:32.904190 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 30 13:42:32.904322 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). Apr 30 13:42:32.912218 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Apr 30 13:42:32.912824 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 30 13:42:32.915773 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Apr 30 13:42:32.916779 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 30 13:42:32.916983 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Apr 30 13:42:32.919982 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Apr 30 13:42:32.920213 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Apr 30 13:42:32.939068 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Apr 30 13:42:32.942170 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 30 13:42:32.942425 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 30 13:42:32.954155 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Apr 30 13:42:32.966989 augenrules[1289]: No rules Apr 30 13:42:32.965714 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Apr 30 13:42:32.966407 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 30 13:42:32.966528 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). Apr 30 13:42:32.966656 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 30 13:42:32.970155 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Apr 30 13:42:32.971395 systemd[1]: audit-rules.service: Deactivated successfully. Apr 30 13:42:32.971577 systemd[1]: Finished audit-rules.service - Load Audit Rules. Apr 30 13:42:32.972728 systemd[1]: modprobe@loop.service: Deactivated successfully. Apr 30 13:42:32.972866 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Apr 30 13:42:32.974430 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 30 13:42:32.974584 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Apr 30 13:42:32.975592 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Apr 30 13:42:32.975730 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Apr 30 13:42:32.983501 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 30 13:42:32.988243 systemd[1]: Starting audit-rules.service - Load Audit Rules... Apr 30 13:42:32.992171 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 30 13:42:32.994258 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Apr 30 13:42:32.998822 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Apr 30 13:42:33.003145 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Apr 30 13:42:33.012139 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Apr 30 13:42:33.012960 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 30 13:42:33.013180 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). Apr 30 13:42:33.016933 ldconfig[1132]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Apr 30 13:42:33.016364 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 30 13:42:33.017266 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 30 13:42:33.018710 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Apr 30 13:42:33.027171 systemd[1]: Finished ensure-sysext.service. Apr 30 13:42:33.028699 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Apr 30 13:42:33.048027 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Apr 30 13:42:33.050625 systemd[1]: Starting systemd-update-done.service - Update is Completed... Apr 30 13:42:33.052442 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). Apr 30 13:42:33.052682 systemd[1]: Started systemd-userdbd.service - User Database Manager. Apr 30 13:42:33.054296 systemd[1]: modprobe@drm.service: Deactivated successfully. Apr 30 13:42:33.054494 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Apr 30 13:42:33.055657 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Apr 30 13:42:33.056117 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Apr 30 13:42:33.060196 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 30 13:42:33.060345 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Apr 30 13:42:33.061163 systemd[1]: modprobe@loop.service: Deactivated successfully. Apr 30 13:42:33.061325 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Apr 30 13:42:33.064725 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Apr 30 13:42:33.065565 augenrules[1300]: /sbin/augenrules: No change Apr 30 13:42:33.064796 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Apr 30 13:42:33.079921 systemd[1]: Finished systemd-update-done.service - Update is Completed. Apr 30 13:42:33.080973 augenrules[1336]: No rules Apr 30 13:42:33.082773 systemd[1]: audit-rules.service: Deactivated successfully. Apr 30 13:42:33.083002 systemd[1]: Finished audit-rules.service - Load Audit Rules. Apr 30 13:42:33.094826 systemd-udevd[1309]: Using default interface naming scheme 'v255'. Apr 30 13:42:33.140934 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Apr 30 13:42:33.141630 systemd[1]: Reached target time-set.target - System Time Set. Apr 30 13:42:33.145237 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 30 13:42:33.156109 systemd[1]: Starting systemd-networkd.service - Network Configuration... Apr 30 13:42:33.170154 systemd-resolved[1259]: Positive Trust Anchors: Apr 30 13:42:33.170171 systemd-resolved[1259]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Apr 30 13:42:33.170213 systemd-resolved[1259]: 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 Apr 30 13:42:33.176368 systemd-resolved[1259]: Using system hostname 'ci-4230-1-1-5-9fc3a58b15.novalocal'. Apr 30 13:42:33.177743 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Apr 30 13:42:33.178457 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Apr 30 13:42:33.247041 systemd-networkd[1354]: lo: Link UP Apr 30 13:42:33.247052 systemd-networkd[1354]: lo: Gained carrier Apr 30 13:42:33.247982 systemd-networkd[1354]: Enumeration completed Apr 30 13:42:33.248072 systemd[1]: Started systemd-networkd.service - Network Configuration. Apr 30 13:42:33.248867 systemd[1]: Reached target network.target - Network. Apr 30 13:42:33.258945 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 40 scanned by (udev-worker) (1350) Apr 30 13:42:33.261098 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Apr 30 13:42:33.265050 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Apr 30 13:42:33.288537 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Apr 30 13:42:33.323570 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Apr 30 13:42:33.345495 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Apr 30 13:42:33.354116 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Apr 30 13:42:33.374299 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Apr 30 13:42:33.377909 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 Apr 30 13:42:33.384252 systemd-networkd[1354]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 30 13:42:33.384264 systemd-networkd[1354]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Apr 30 13:42:33.385830 systemd-networkd[1354]: eth0: Link UP Apr 30 13:42:33.385840 systemd-networkd[1354]: eth0: Gained carrier Apr 30 13:42:33.385859 systemd-networkd[1354]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 30 13:42:33.387904 kernel: piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 Apr 30 13:42:33.403045 kernel: ACPI: button: Power Button [PWRF] Apr 30 13:42:33.398018 systemd-networkd[1354]: eth0: DHCPv4 address 172.24.4.31/24, gateway 172.24.4.1 acquired from 172.24.4.1 Apr 30 13:42:33.400076 systemd-timesyncd[1321]: Network configuration changed, trying to establish connection. Apr 30 13:42:33.417917 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 Apr 30 13:42:33.438919 kernel: mousedev: PS/2 mouse device common for all mice Apr 30 13:42:33.448819 kernel: [drm] pci: virtio-vga detected at 0000:00:02.0 Apr 30 13:42:33.446764 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 30 13:42:33.452363 kernel: virtio-pci 0000:00:02.0: vgaarb: deactivate vga console Apr 30 13:42:33.459250 kernel: Console: switching to colour dummy device 80x25 Apr 30 13:42:33.461040 kernel: [drm] features: -virgl +edid -resource_blob -host_visible Apr 30 13:42:33.461069 kernel: [drm] features: -context_init Apr 30 13:42:33.467101 kernel: [drm] number of scanouts: 1 Apr 30 13:42:33.467166 kernel: [drm] number of cap sets: 0 Apr 30 13:42:33.470899 kernel: [drm] Initialized virtio_gpu 0.1.0 0 for 0000:00:02.0 on minor 0 Apr 30 13:42:33.479836 kernel: fbcon: virtio_gpudrmfb (fb0) is primary device Apr 30 13:42:33.479910 kernel: Console: switching to colour frame buffer device 160x50 Apr 30 13:42:33.490895 kernel: virtio-pci 0000:00:02.0: [drm] fb0: virtio_gpudrmfb frame buffer device Apr 30 13:42:33.491558 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Apr 30 13:42:33.491792 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Apr 30 13:42:33.496510 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Apr 30 13:42:33.496955 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Apr 30 13:42:33.507214 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Apr 30 13:42:33.509022 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 30 13:42:33.529204 lvm[1389]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Apr 30 13:42:33.561394 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Apr 30 13:42:33.561709 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Apr 30 13:42:33.567212 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Apr 30 13:42:33.571086 lvm[1394]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Apr 30 13:42:33.597059 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Apr 30 13:42:33.616993 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 30 13:42:33.618419 systemd[1]: Reached target sysinit.target - System Initialization. Apr 30 13:42:33.618710 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Apr 30 13:42:33.619223 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Apr 30 13:42:33.619820 systemd[1]: Started logrotate.timer - Daily rotation of log files. Apr 30 13:42:33.620359 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Apr 30 13:42:33.620660 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Apr 30 13:42:33.620968 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Apr 30 13:42:33.621019 systemd[1]: Reached target paths.target - Path Units. Apr 30 13:42:33.621360 systemd[1]: Reached target timers.target - Timer Units. Apr 30 13:42:33.624159 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Apr 30 13:42:33.626949 systemd[1]: Starting docker.socket - Docker Socket for the API... Apr 30 13:42:33.634677 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Apr 30 13:42:33.635432 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Apr 30 13:42:33.635636 systemd[1]: Reached target ssh-access.target - SSH Access Available. Apr 30 13:42:33.639773 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Apr 30 13:42:33.642718 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Apr 30 13:42:33.646384 systemd[1]: Listening on docker.socket - Docker Socket for the API. Apr 30 13:42:33.648745 systemd[1]: Reached target sockets.target - Socket Units. Apr 30 13:42:33.650728 systemd[1]: Reached target basic.target - Basic System. Apr 30 13:42:33.652744 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Apr 30 13:42:33.652934 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Apr 30 13:42:33.666800 systemd[1]: Starting containerd.service - containerd container runtime... Apr 30 13:42:33.674679 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Apr 30 13:42:33.687580 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Apr 30 13:42:33.697165 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Apr 30 13:42:33.713009 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Apr 30 13:42:33.727235 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Apr 30 13:42:33.727857 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). Apr 30 13:42:33.745653 jq[1407]: false Apr 30 13:42:33.736492 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Apr 30 13:42:33.741814 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Apr 30 13:42:33.752119 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Apr 30 13:42:33.761691 coreos-metadata[1402]: Apr 30 13:42:33.748 INFO Fetching http://169.254.169.254/latest/meta-data/public-keys: Attempt #1 Apr 30 13:42:33.762118 coreos-metadata[1402]: Apr 30 13:42:33.761 INFO Fetch successful Apr 30 13:42:33.762118 coreos-metadata[1402]: Apr 30 13:42:33.762 INFO Fetching http://169.254.169.254/latest/meta-data/public-keys/0/openssh-key: Attempt #1 Apr 30 13:42:33.768107 systemd[1]: Starting systemd-logind.service - User Login Management... Apr 30 13:42:33.769723 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Apr 30 13:42:33.775511 coreos-metadata[1402]: Apr 30 13:42:33.774 INFO Fetch successful Apr 30 13:42:33.772700 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Apr 30 13:42:33.783191 systemd[1]: Starting update-engine.service - Update Engine... Apr 30 13:42:33.784788 unknown[1402]: wrote ssh authorized keys file for user: core Apr 30 13:42:33.789114 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Apr 30 13:42:33.801269 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Apr 30 13:42:33.801797 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Apr 30 13:42:33.803203 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Apr 30 13:42:33.805109 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Apr 30 13:42:33.817897 coreos-metadata[1403]: Apr 30 13:42:33.817 INFO Fetching http://169.254.169.254/openstack/2012-08-10/meta_data.json: Attempt #1 Apr 30 13:42:33.822744 update_engine[1416]: I20250430 13:42:33.822407 1416 main.cc:92] Flatcar Update Engine starting Apr 30 13:42:33.826345 dbus-daemon[1406]: [system] SELinux support is enabled Apr 30 13:42:33.828161 jq[1417]: true Apr 30 13:42:33.823841 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Apr 30 13:42:33.828975 systemd[1]: Started dbus.service - D-Bus System Message Bus. Apr 30 13:42:33.843045 update_engine[1416]: I20250430 13:42:33.835714 1416 update_check_scheduler.cc:74] Next update check in 6m50s Apr 30 13:42:33.843082 extend-filesystems[1410]: Found loop3 Apr 30 13:42:33.843082 extend-filesystems[1410]: Found loop4 Apr 30 13:42:33.843082 extend-filesystems[1410]: Found loop5 Apr 30 13:42:33.843082 extend-filesystems[1410]: Found vda Apr 30 13:42:33.843082 extend-filesystems[1410]: Found vda1 Apr 30 13:42:33.843082 extend-filesystems[1410]: Found vda2 Apr 30 13:42:33.843082 extend-filesystems[1410]: Found vda3 Apr 30 13:42:33.843082 extend-filesystems[1410]: Found usr Apr 30 13:42:33.843082 extend-filesystems[1410]: Found vda4 Apr 30 13:42:33.843082 extend-filesystems[1410]: Found vda6 Apr 30 13:42:33.843082 extend-filesystems[1410]: Found vda7 Apr 30 13:42:33.843082 extend-filesystems[1410]: Found vda9 Apr 30 13:42:33.843082 extend-filesystems[1410]: Checking size of /dev/vda9 Apr 30 13:42:33.911312 coreos-metadata[1403]: Apr 30 13:42:33.834 INFO Fetch successful Apr 30 13:42:33.911312 coreos-metadata[1403]: Apr 30 13:42:33.834 INFO Fetching http://169.254.169.254/latest/meta-data/hostname: Attempt #1 Apr 30 13:42:33.911312 coreos-metadata[1403]: Apr 30 13:42:33.846 INFO Fetch successful Apr 30 13:42:33.911312 coreos-metadata[1403]: Apr 30 13:42:33.847 INFO Fetching http://169.254.169.254/latest/meta-data/instance-id: Attempt #1 Apr 30 13:42:33.911312 coreos-metadata[1403]: Apr 30 13:42:33.864 INFO Fetch successful Apr 30 13:42:33.911312 coreos-metadata[1403]: Apr 30 13:42:33.864 INFO Fetching http://169.254.169.254/latest/meta-data/instance-type: Attempt #1 Apr 30 13:42:33.911312 coreos-metadata[1403]: Apr 30 13:42:33.873 INFO Fetch successful Apr 30 13:42:33.911312 coreos-metadata[1403]: Apr 30 13:42:33.873 INFO Fetching http://169.254.169.254/latest/meta-data/local-ipv4: Attempt #1 Apr 30 13:42:33.911312 coreos-metadata[1403]: Apr 30 13:42:33.891 INFO Fetch successful Apr 30 13:42:33.911312 coreos-metadata[1403]: Apr 30 13:42:33.891 INFO Fetching http://169.254.169.254/latest/meta-data/public-ipv4: Attempt #1 Apr 30 13:42:33.911577 update-ssh-keys[1421]: Updated "/home/core/.ssh/authorized_keys" Apr 30 13:42:33.852447 systemd[1]: motdgen.service: Deactivated successfully. Apr 30 13:42:33.852640 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Apr 30 13:42:33.911904 jq[1435]: true Apr 30 13:42:33.873214 (ntainerd)[1436]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Apr 30 13:42:33.873312 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). Apr 30 13:42:33.873345 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Apr 30 13:42:33.881417 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). Apr 30 13:42:33.881439 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Apr 30 13:42:33.882028 systemd[1]: Started update-engine.service - Update Engine. Apr 30 13:42:33.896950 systemd[1]: Started locksmithd.service - Cluster reboot manager. Apr 30 13:42:33.908095 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Apr 30 13:42:33.914040 coreos-metadata[1403]: Apr 30 13:42:33.913 INFO Fetch successful Apr 30 13:42:33.935725 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Apr 30 13:42:33.940110 extend-filesystems[1410]: Resized partition /dev/vda9 Apr 30 13:42:33.940078 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Apr 30 13:42:33.948471 extend-filesystems[1456]: resize2fs 1.47.1 (20-May-2024) Apr 30 13:42:33.974121 kernel: EXT4-fs (vda9): resizing filesystem from 1617920 to 2014203 blocks Apr 30 13:42:33.988452 kernel: EXT4-fs (vda9): resized filesystem to 2014203 Apr 30 13:42:33.991285 systemd-logind[1414]: New seat seat0. Apr 30 13:42:34.082815 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 40 scanned by (udev-worker) (1366) Apr 30 13:42:34.083420 systemd-logind[1414]: Watching system buttons on /dev/input/event1 (Power Button) Apr 30 13:42:34.085004 systemd-logind[1414]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Apr 30 13:42:34.085334 systemd[1]: Started systemd-logind.service - User Login Management. Apr 30 13:42:34.090209 extend-filesystems[1456]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Apr 30 13:42:34.090209 extend-filesystems[1456]: old_desc_blocks = 1, new_desc_blocks = 1 Apr 30 13:42:34.090209 extend-filesystems[1456]: The filesystem on /dev/vda9 is now 2014203 (4k) blocks long. Apr 30 13:42:34.106470 extend-filesystems[1410]: Resized filesystem in /dev/vda9 Apr 30 13:42:34.093161 systemd[1]: extend-filesystems.service: Deactivated successfully. Apr 30 13:42:34.093384 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Apr 30 13:42:34.097438 locksmithd[1442]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Apr 30 13:42:34.122045 bash[1466]: Updated "/home/core/.ssh/authorized_keys" Apr 30 13:42:34.122959 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Apr 30 13:42:34.159572 systemd[1]: Starting sshkeys.service... Apr 30 13:42:34.186061 systemd[1]: Finished sshkeys.service. Apr 30 13:42:34.319577 containerd[1436]: time="2025-04-30T13:42:34.319476785Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Apr 30 13:42:34.352944 containerd[1436]: time="2025-04-30T13:42:34.350624312Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Apr 30 13:42:34.352944 containerd[1436]: time="2025-04-30T13:42:34.352024628Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.88-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Apr 30 13:42:34.352944 containerd[1436]: time="2025-04-30T13:42:34.352051388Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Apr 30 13:42:34.352944 containerd[1436]: time="2025-04-30T13:42:34.352070013Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Apr 30 13:42:34.352944 containerd[1436]: time="2025-04-30T13:42:34.352237177Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Apr 30 13:42:34.352944 containerd[1436]: time="2025-04-30T13:42:34.352256733Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Apr 30 13:42:34.352944 containerd[1436]: time="2025-04-30T13:42:34.352321906Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Apr 30 13:42:34.352944 containerd[1436]: time="2025-04-30T13:42:34.352338697Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Apr 30 13:42:34.352944 containerd[1436]: time="2025-04-30T13:42:34.352533452Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Apr 30 13:42:34.352944 containerd[1436]: time="2025-04-30T13:42:34.352551606Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Apr 30 13:42:34.352944 containerd[1436]: time="2025-04-30T13:42:34.352567666Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Apr 30 13:42:34.353253 containerd[1436]: time="2025-04-30T13:42:34.352579529Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Apr 30 13:42:34.353253 containerd[1436]: time="2025-04-30T13:42:34.352660360Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Apr 30 13:42:34.353253 containerd[1436]: time="2025-04-30T13:42:34.352861357Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Apr 30 13:42:34.353253 containerd[1436]: time="2025-04-30T13:42:34.353024994Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Apr 30 13:42:34.353253 containerd[1436]: time="2025-04-30T13:42:34.353043850Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Apr 30 13:42:34.353253 containerd[1436]: time="2025-04-30T13:42:34.353133077Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Apr 30 13:42:34.353253 containerd[1436]: time="2025-04-30T13:42:34.353186868Z" level=info msg="metadata content store policy set" policy=shared Apr 30 13:42:34.363985 containerd[1436]: time="2025-04-30T13:42:34.363958350Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Apr 30 13:42:34.364038 containerd[1436]: time="2025-04-30T13:42:34.364012371Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Apr 30 13:42:34.364038 containerd[1436]: time="2025-04-30T13:42:34.364032088Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Apr 30 13:42:34.364120 containerd[1436]: time="2025-04-30T13:42:34.364049761Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Apr 30 13:42:34.364156 containerd[1436]: time="2025-04-30T13:42:34.364123970Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Apr 30 13:42:34.364279 containerd[1436]: time="2025-04-30T13:42:34.364255778Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Apr 30 13:42:34.364595 containerd[1436]: time="2025-04-30T13:42:34.364574535Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Apr 30 13:42:34.364701 containerd[1436]: time="2025-04-30T13:42:34.364678641Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Apr 30 13:42:34.364728 containerd[1436]: time="2025-04-30T13:42:34.364703427Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Apr 30 13:42:34.364728 containerd[1436]: time="2025-04-30T13:42:34.364720259Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Apr 30 13:42:34.364772 containerd[1436]: time="2025-04-30T13:42:34.364735788Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Apr 30 13:42:34.364772 containerd[1436]: time="2025-04-30T13:42:34.364750726Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Apr 30 13:42:34.364772 containerd[1436]: time="2025-04-30T13:42:34.364766225Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Apr 30 13:42:34.364859 containerd[1436]: time="2025-04-30T13:42:34.364781794Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Apr 30 13:42:34.364859 containerd[1436]: time="2025-04-30T13:42:34.364797984Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Apr 30 13:42:34.364859 containerd[1436]: time="2025-04-30T13:42:34.364812932Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Apr 30 13:42:34.364859 containerd[1436]: time="2025-04-30T13:42:34.364827169Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Apr 30 13:42:34.364859 containerd[1436]: time="2025-04-30T13:42:34.364839893Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Apr 30 13:42:34.365010 containerd[1436]: time="2025-04-30T13:42:34.364861844Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Apr 30 13:42:34.365010 containerd[1436]: time="2025-04-30T13:42:34.364901789Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Apr 30 13:42:34.365010 containerd[1436]: time="2025-04-30T13:42:34.364919753Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Apr 30 13:42:34.365010 containerd[1436]: time="2025-04-30T13:42:34.364940402Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Apr 30 13:42:34.365010 containerd[1436]: time="2025-04-30T13:42:34.364954698Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Apr 30 13:42:34.365010 containerd[1436]: time="2025-04-30T13:42:34.364969676Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Apr 30 13:42:34.365010 containerd[1436]: time="2025-04-30T13:42:34.364983172Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Apr 30 13:42:34.365010 containerd[1436]: time="2025-04-30T13:42:34.364997449Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Apr 30 13:42:34.365010 containerd[1436]: time="2025-04-30T13:42:34.365011685Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Apr 30 13:42:34.365251 containerd[1436]: time="2025-04-30T13:42:34.365029649Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Apr 30 13:42:34.365251 containerd[1436]: time="2025-04-30T13:42:34.365044166Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Apr 30 13:42:34.365251 containerd[1436]: time="2025-04-30T13:42:34.365057481Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Apr 30 13:42:34.365251 containerd[1436]: time="2025-04-30T13:42:34.365071287Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Apr 30 13:42:34.365251 containerd[1436]: time="2025-04-30T13:42:34.365087908Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Apr 30 13:42:34.365251 containerd[1436]: time="2025-04-30T13:42:34.365110100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Apr 30 13:42:34.365251 containerd[1436]: time="2025-04-30T13:42:34.365124928Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Apr 30 13:42:34.365251 containerd[1436]: time="2025-04-30T13:42:34.365137331Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Apr 30 13:42:34.365251 containerd[1436]: time="2025-04-30T13:42:34.365192925Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Apr 30 13:42:34.365251 containerd[1436]: time="2025-04-30T13:42:34.365212452Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Apr 30 13:42:34.365251 containerd[1436]: time="2025-04-30T13:42:34.365224695Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Apr 30 13:42:34.365518 containerd[1436]: time="2025-04-30T13:42:34.365289546Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Apr 30 13:42:34.365518 containerd[1436]: time="2025-04-30T13:42:34.365305657Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Apr 30 13:42:34.365518 containerd[1436]: time="2025-04-30T13:42:34.365320765Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Apr 30 13:42:34.365518 containerd[1436]: time="2025-04-30T13:42:34.365331655Z" level=info msg="NRI interface is disabled by configuration." Apr 30 13:42:34.365518 containerd[1436]: time="2025-04-30T13:42:34.365343197Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Apr 30 13:42:34.365734 containerd[1436]: time="2025-04-30T13:42:34.365672555Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Apr 30 13:42:34.365872 containerd[1436]: time="2025-04-30T13:42:34.365736635Z" level=info msg="Connect containerd service" Apr 30 13:42:34.365872 containerd[1436]: time="2025-04-30T13:42:34.365767373Z" level=info msg="using legacy CRI server" Apr 30 13:42:34.365872 containerd[1436]: time="2025-04-30T13:42:34.365775939Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Apr 30 13:42:34.365958 containerd[1436]: time="2025-04-30T13:42:34.365903648Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Apr 30 13:42:34.366546 containerd[1436]: time="2025-04-30T13:42:34.366517710Z" 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" Apr 30 13:42:34.366749 containerd[1436]: time="2025-04-30T13:42:34.366714689Z" level=info msg="Start subscribing containerd event" Apr 30 13:42:34.366779 containerd[1436]: time="2025-04-30T13:42:34.366761036Z" level=info msg="Start recovering state" Apr 30 13:42:34.366833 containerd[1436]: time="2025-04-30T13:42:34.366813985Z" level=info msg="Start event monitor" Apr 30 13:42:34.366861 containerd[1436]: time="2025-04-30T13:42:34.366831538Z" level=info msg="Start snapshots syncer" Apr 30 13:42:34.366861 containerd[1436]: time="2025-04-30T13:42:34.366841016Z" level=info msg="Start cni network conf syncer for default" Apr 30 13:42:34.366861 containerd[1436]: time="2025-04-30T13:42:34.366849983Z" level=info msg="Start streaming server" Apr 30 13:42:34.369639 containerd[1436]: time="2025-04-30T13:42:34.366988633Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Apr 30 13:42:34.369639 containerd[1436]: time="2025-04-30T13:42:34.367042564Z" level=info msg=serving... address=/run/containerd/containerd.sock Apr 30 13:42:34.369639 containerd[1436]: time="2025-04-30T13:42:34.367099291Z" level=info msg="containerd successfully booted in 0.049261s" Apr 30 13:42:34.367984 systemd[1]: Started containerd.service - containerd container runtime. Apr 30 13:42:34.539938 sshd_keygen[1433]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Apr 30 13:42:34.575732 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Apr 30 13:42:34.589021 systemd[1]: Starting issuegen.service - Generate /run/issue... Apr 30 13:42:34.594267 systemd[1]: Started sshd@0-172.24.4.31:22-172.24.4.1:42658.service - OpenSSH per-connection server daemon (172.24.4.1:42658). Apr 30 13:42:34.605136 systemd[1]: issuegen.service: Deactivated successfully. Apr 30 13:42:34.605731 systemd[1]: Finished issuegen.service - Generate /run/issue. Apr 30 13:42:34.620427 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Apr 30 13:42:34.640120 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Apr 30 13:42:34.652377 systemd[1]: Started getty@tty1.service - Getty on tty1. Apr 30 13:42:34.661211 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Apr 30 13:42:34.661975 systemd[1]: Reached target getty.target - Login Prompts. Apr 30 13:42:35.161233 systemd-networkd[1354]: eth0: Gained IPv6LL Apr 30 13:42:35.162342 systemd-timesyncd[1321]: Network configuration changed, trying to establish connection. Apr 30 13:42:35.168050 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Apr 30 13:42:35.171221 systemd[1]: Reached target network-online.target - Network is Online. Apr 30 13:42:35.180418 systemd[1]: Starting etcd-member.service - etcd (System Application Container)... Apr 30 13:42:35.186955 (-wrapper)[1504]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS Apr 30 13:42:35.189448 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Apr 30 13:42:35.274136 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Apr 30 13:42:35.456414 systemd[1]: Starting docker.service - Docker Application Container Engine... Apr 30 13:42:35.459564 (dockerd)[1523]: 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 Apr 30 13:42:36.129568 sshd[1494]: Accepted publickey for core from 172.24.4.1 port 42658 ssh2: RSA SHA256:zUB7DYTw0Tgn/bgRMQaWs8gBzY+wFpwfqzYhsTkYoFg Apr 30 13:42:36.155764 sshd-session[1494]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 13:42:36.182481 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Apr 30 13:42:36.201569 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Apr 30 13:42:36.222667 systemd-logind[1414]: New session 1 of user core. Apr 30 13:42:36.232959 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Apr 30 13:42:36.244210 systemd[1]: Starting user@500.service - User Manager for UID 500... Apr 30 13:42:36.249793 (systemd)[1530]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Apr 30 13:42:36.252678 systemd-logind[1414]: New session c1 of user core. Apr 30 13:42:36.563744 dockerd[1523]: time="2025-04-30T13:42:36.563306406Z" level=info msg="Starting up" Apr 30 13:42:36.726951 systemd[1530]: Queued start job for default target default.target. Apr 30 13:42:36.732596 systemd[1530]: Created slice app.slice - User Application Slice. Apr 30 13:42:36.732714 systemd[1530]: Reached target paths.target - Paths. Apr 30 13:42:36.732836 systemd[1530]: Reached target timers.target - Timers. Apr 30 13:42:36.734238 systemd[1530]: Starting dbus.socket - D-Bus User Message Bus Socket... Apr 30 13:42:36.745304 systemd[1530]: Listening on dbus.socket - D-Bus User Message Bus Socket. Apr 30 13:42:36.745423 systemd[1530]: Reached target sockets.target - Sockets. Apr 30 13:42:36.745464 systemd[1530]: Reached target basic.target - Basic System. Apr 30 13:42:36.745502 systemd[1530]: Reached target default.target - Main User Target. Apr 30 13:42:36.745530 systemd[1530]: Startup finished in 486ms. Apr 30 13:42:36.745571 systemd[1]: Started user@500.service - User Manager for UID 500. Apr 30 13:42:36.753036 systemd[1]: Started session-1.scope - Session 1 of User core. Apr 30 13:42:36.809576 dockerd[1523]: time="2025-04-30T13:42:36.809536200Z" level=info msg="Loading containers: start." Apr 30 13:42:37.056939 kernel: Initializing XFRM netlink socket Apr 30 13:42:37.105914 systemd-timesyncd[1321]: Network configuration changed, trying to establish connection. Apr 30 13:42:37.107305 systemd-timesyncd[1321]: Network configuration changed, trying to establish connection. Apr 30 13:42:37.124587 systemd-timesyncd[1321]: Network configuration changed, trying to establish connection. Apr 30 13:42:37.146208 systemd[1]: Started sshd@1-172.24.4.31:22-172.24.4.1:42670.service - OpenSSH per-connection server daemon (172.24.4.1:42670). Apr 30 13:42:37.176702 systemd-networkd[1354]: docker0: Link UP Apr 30 13:42:37.177276 systemd-timesyncd[1321]: Network configuration changed, trying to establish connection. Apr 30 13:42:37.215037 dockerd[1523]: time="2025-04-30T13:42:37.214984609Z" level=info msg="Loading containers: done." Apr 30 13:42:37.247608 dockerd[1523]: time="2025-04-30T13:42:37.247222471Z" 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 Apr 30 13:42:37.247608 dockerd[1523]: time="2025-04-30T13:42:37.247320304Z" level=info msg="Docker daemon" commit=41ca978a0a5400cc24b274137efa9f25517fcc0b containerd-snapshotter=false storage-driver=overlay2 version=27.3.1 Apr 30 13:42:37.247608 dockerd[1523]: time="2025-04-30T13:42:37.247418849Z" level=info msg="Daemon has completed initialization" Apr 30 13:42:37.309631 dockerd[1523]: time="2025-04-30T13:42:37.308792265Z" level=info msg="API listen on /run/docker.sock" Apr 30 13:42:37.308967 systemd[1]: Started docker.service - Docker Application Container Engine. Apr 30 13:42:37.312369 etcd-wrapper[1509]: Error response from daemon: No such container: etcd-member Apr 30 13:42:37.328684 etcd-wrapper[1712]: Error response from daemon: No such container: etcd-member Apr 30 13:42:37.391933 etcd-wrapper[1730]: Unable to find image 'quay.io/coreos/etcd:v3.5.16' locally Apr 30 13:42:38.562609 sshd[1658]: Accepted publickey for core from 172.24.4.1 port 42670 ssh2: RSA SHA256:zUB7DYTw0Tgn/bgRMQaWs8gBzY+wFpwfqzYhsTkYoFg Apr 30 13:42:38.565807 sshd-session[1658]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 13:42:38.577487 systemd-logind[1414]: New session 2 of user core. Apr 30 13:42:38.586198 systemd[1]: Started session-2.scope - Session 2 of User core. Apr 30 13:42:39.148927 sshd[1739]: Connection closed by 172.24.4.1 port 42670 Apr 30 13:42:39.149824 sshd-session[1658]: pam_unix(sshd:session): session closed for user core Apr 30 13:42:39.165746 systemd[1]: sshd@1-172.24.4.31:22-172.24.4.1:42670.service: Deactivated successfully. Apr 30 13:42:39.168970 systemd[1]: session-2.scope: Deactivated successfully. Apr 30 13:42:39.172364 systemd-logind[1414]: Session 2 logged out. Waiting for processes to exit. Apr 30 13:42:39.180479 systemd[1]: Started sshd@2-172.24.4.31:22-172.24.4.1:42672.service - OpenSSH per-connection server daemon (172.24.4.1:42672). Apr 30 13:42:39.189294 systemd-logind[1414]: Removed session 2. Apr 30 13:42:39.722344 login[1500]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Apr 30 13:42:39.731424 login[1501]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Apr 30 13:42:39.735784 systemd-logind[1414]: New session 3 of user core. Apr 30 13:42:39.741244 systemd[1]: Started session-3.scope - Session 3 of User core. Apr 30 13:42:39.749009 systemd-logind[1414]: New session 4 of user core. Apr 30 13:42:39.760214 systemd[1]: Started session-4.scope - Session 4 of User core. Apr 30 13:42:40.484126 sshd[1744]: Accepted publickey for core from 172.24.4.1 port 42672 ssh2: RSA SHA256:zUB7DYTw0Tgn/bgRMQaWs8gBzY+wFpwfqzYhsTkYoFg Apr 30 13:42:40.486374 sshd-session[1744]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 13:42:40.495624 systemd-logind[1414]: New session 5 of user core. Apr 30 13:42:40.505108 systemd[1]: Started session-5.scope - Session 5 of User core. Apr 30 13:42:40.762020 etcd-wrapper[1730]: v3.5.16: Pulling from coreos/etcd Apr 30 13:42:41.076258 sshd[1773]: Connection closed by 172.24.4.1 port 42672 Apr 30 13:42:41.076794 sshd-session[1744]: pam_unix(sshd:session): session closed for user core Apr 30 13:42:41.082219 systemd[1]: sshd@2-172.24.4.31:22-172.24.4.1:42672.service: Deactivated successfully. Apr 30 13:42:41.091602 etcd-wrapper[1730]: 804c8aba2cc6: Pulling fs layer Apr 30 13:42:41.091602 etcd-wrapper[1730]: 2ae710cd8bfe: Pulling fs layer Apr 30 13:42:41.091602 etcd-wrapper[1730]: d462aa345367: Pulling fs layer Apr 30 13:42:41.091602 etcd-wrapper[1730]: 0f8b424aa0b9: Pulling fs layer Apr 30 13:42:41.091602 etcd-wrapper[1730]: d557676654e5: Pulling fs layer Apr 30 13:42:41.091602 etcd-wrapper[1730]: c8022d07192e: Pulling fs layer Apr 30 13:42:41.091602 etcd-wrapper[1730]: d858cbc252ad: Pulling fs layer Apr 30 13:42:41.091927 etcd-wrapper[1730]: 1069fc2daed1: Pulling fs layer Apr 30 13:42:41.091927 etcd-wrapper[1730]: b40161cd83fc: Pulling fs layer Apr 30 13:42:41.091927 etcd-wrapper[1730]: 5318d93a3a65: Pulling fs layer Apr 30 13:42:41.091927 etcd-wrapper[1730]: 307c1adadb60: Pulling fs layer Apr 30 13:42:41.091927 etcd-wrapper[1730]: fbb01d9e9dc9: Pulling fs layer Apr 30 13:42:41.091927 etcd-wrapper[1730]: fbfea02ac3cf: Pulling fs layer Apr 30 13:42:41.091927 etcd-wrapper[1730]: 8c26e4bf18e2: Pulling fs layer Apr 30 13:42:41.091927 etcd-wrapper[1730]: 1e59a65f8816: Pulling fs layer Apr 30 13:42:41.091927 etcd-wrapper[1730]: ffbd4ca5f0bd: Pulling fs layer Apr 30 13:42:41.091927 etcd-wrapper[1730]: 0f8b424aa0b9: Waiting Apr 30 13:42:41.091927 etcd-wrapper[1730]: d557676654e5: Waiting Apr 30 13:42:41.093572 systemd[1]: session-5.scope: Deactivated successfully. Apr 30 13:42:41.095741 etcd-wrapper[1730]: c8022d07192e: Waiting Apr 30 13:42:41.095741 etcd-wrapper[1730]: d858cbc252ad: Waiting Apr 30 13:42:41.095741 etcd-wrapper[1730]: 1069fc2daed1: Waiting Apr 30 13:42:41.095741 etcd-wrapper[1730]: b40161cd83fc: Waiting Apr 30 13:42:41.095741 etcd-wrapper[1730]: 5318d93a3a65: Waiting Apr 30 13:42:41.095741 etcd-wrapper[1730]: 307c1adadb60: Waiting Apr 30 13:42:41.095741 etcd-wrapper[1730]: fbb01d9e9dc9: Waiting Apr 30 13:42:41.095741 etcd-wrapper[1730]: fbfea02ac3cf: Waiting Apr 30 13:42:41.095741 etcd-wrapper[1730]: 8c26e4bf18e2: Waiting Apr 30 13:42:41.095741 etcd-wrapper[1730]: 1e59a65f8816: Waiting Apr 30 13:42:41.095741 etcd-wrapper[1730]: ffbd4ca5f0bd: Waiting Apr 30 13:42:41.096362 systemd-logind[1414]: Session 5 logged out. Waiting for processes to exit. Apr 30 13:42:41.098309 systemd-logind[1414]: Removed session 5. Apr 30 13:42:41.419184 etcd-wrapper[1730]: d462aa345367: Verifying Checksum Apr 30 13:42:41.419184 etcd-wrapper[1730]: d462aa345367: Download complete Apr 30 13:42:41.419184 etcd-wrapper[1730]: 2ae710cd8bfe: Download complete Apr 30 13:42:41.668183 etcd-wrapper[1730]: 804c8aba2cc6: Download complete Apr 30 13:42:41.703265 etcd-wrapper[1730]: 0f8b424aa0b9: Verifying Checksum Apr 30 13:42:41.703265 etcd-wrapper[1730]: 0f8b424aa0b9: Download complete Apr 30 13:42:41.727856 etcd-wrapper[1730]: d557676654e5: Verifying Checksum Apr 30 13:42:41.727856 etcd-wrapper[1730]: d557676654e5: Download complete Apr 30 13:42:41.741933 etcd-wrapper[1730]: 804c8aba2cc6: Pull complete Apr 30 13:42:41.798589 systemd[1]: var-lib-docker-overlay2-736b07356747a2f0adf17454076c92a0a7b5042b7195e905f6cd5319331f4ec8-merged.mount: Deactivated successfully. Apr 30 13:42:41.834215 etcd-wrapper[1730]: 2ae710cd8bfe: Pull complete Apr 30 13:42:41.993039 etcd-wrapper[1730]: d858cbc252ad: Verifying Checksum Apr 30 13:42:41.993039 etcd-wrapper[1730]: d858cbc252ad: Download complete Apr 30 13:42:42.197679 etcd-wrapper[1730]: c8022d07192e: Verifying Checksum Apr 30 13:42:42.197679 etcd-wrapper[1730]: c8022d07192e: Download complete Apr 30 13:42:42.285081 etcd-wrapper[1730]: b40161cd83fc: Verifying Checksum Apr 30 13:42:42.285304 etcd-wrapper[1730]: b40161cd83fc: Download complete Apr 30 13:42:42.290848 systemd[1]: var-lib-docker-overlay2-262e9d485c555335e3463b3a862d3cfb3eeecfa86343ad8223ee7b200c7dc54f-merged.mount: Deactivated successfully. Apr 30 13:42:42.357015 etcd-wrapper[1730]: d462aa345367: Pull complete Apr 30 13:42:42.399386 etcd-wrapper[1730]: 0f8b424aa0b9: Pull complete Apr 30 13:42:42.457136 etcd-wrapper[1730]: d557676654e5: Pull complete Apr 30 13:42:42.505536 etcd-wrapper[1730]: c8022d07192e: Pull complete Apr 30 13:42:42.548972 etcd-wrapper[1730]: d858cbc252ad: Pull complete Apr 30 13:42:42.603769 etcd-wrapper[1730]: 307c1adadb60: Download complete Apr 30 13:42:42.768830 systemd[1]: var-lib-docker-overlay2-830d662e2026143bab9d7c3c36307e512dd4c5316f7171b3b825fa9a4229e515-merged.mount: Deactivated successfully. Apr 30 13:42:42.835860 etcd-wrapper[1730]: 5318d93a3a65: Verifying Checksum Apr 30 13:42:42.835860 etcd-wrapper[1730]: 5318d93a3a65: Download complete Apr 30 13:42:43.400021 etcd-wrapper[1730]: fbb01d9e9dc9: Verifying Checksum Apr 30 13:42:43.400021 etcd-wrapper[1730]: fbb01d9e9dc9: Download complete Apr 30 13:42:43.756076 etcd-wrapper[1730]: fbfea02ac3cf: Verifying Checksum Apr 30 13:42:43.756076 etcd-wrapper[1730]: fbfea02ac3cf: Download complete Apr 30 13:42:43.842263 etcd-wrapper[1730]: 8c26e4bf18e2: Verifying Checksum Apr 30 13:42:43.842263 etcd-wrapper[1730]: 8c26e4bf18e2: Download complete Apr 30 13:42:43.991037 etcd-wrapper[1730]: 1069fc2daed1: Verifying Checksum Apr 30 13:42:43.991037 etcd-wrapper[1730]: 1069fc2daed1: Download complete Apr 30 13:42:44.022389 systemd[1]: var-lib-docker-overlay2-9f5902c361478f8f76e503f4bf4b9bd374ba542f3451f271fd113510f5747adb-merged.mount: Deactivated successfully. Apr 30 13:42:44.116595 etcd-wrapper[1730]: 1e59a65f8816: Verifying Checksum Apr 30 13:42:44.116595 etcd-wrapper[1730]: 1e59a65f8816: Download complete Apr 30 13:42:44.151823 etcd-wrapper[1730]: ffbd4ca5f0bd: Verifying Checksum Apr 30 13:42:44.152072 etcd-wrapper[1730]: ffbd4ca5f0bd: Download complete Apr 30 13:42:44.164132 etcd-wrapper[1730]: 1069fc2daed1: Pull complete Apr 30 13:42:44.205437 systemd[1]: var-lib-docker-overlay2-10fe88eb7f05b395b3a07256b93b58992e72ae08fe1ced2cc286c76d889412c5-merged.mount: Deactivated successfully. Apr 30 13:42:44.226274 etcd-wrapper[1730]: b40161cd83fc: Pull complete Apr 30 13:42:44.296210 etcd-wrapper[1730]: 5318d93a3a65: Pull complete Apr 30 13:42:44.362819 etcd-wrapper[1730]: 307c1adadb60: Pull complete Apr 30 13:42:44.798435 etcd-wrapper[1730]: fbb01d9e9dc9: Pull complete Apr 30 13:42:45.010333 systemd[1]: var-lib-docker-overlay2-a1eb2670240fa6491027521b4024bf0e0a23290db34bd387045a082ebe0454c5-merged.mount: Deactivated successfully. Apr 30 13:42:45.041525 systemd[1]: var-lib-docker-overlay2-aaa048745a60a3bb7816b3c4b872f0c43f190c14305cc3f6535de01d6f5a59b9-merged.mount: Deactivated successfully. Apr 30 13:42:45.155716 etcd-wrapper[1730]: fbfea02ac3cf: Pull complete Apr 30 13:42:45.367082 systemd[1]: var-lib-docker-overlay2-9ccfaff869780d910747825cf76ac857f70b9867bc5714ca8990ec588d38360f-merged.mount: Deactivated successfully. Apr 30 13:42:45.473318 etcd-wrapper[1730]: 8c26e4bf18e2: Pull complete Apr 30 13:42:45.536253 etcd-wrapper[1730]: 1e59a65f8816: Pull complete Apr 30 13:42:45.591040 etcd-wrapper[1730]: ffbd4ca5f0bd: Pull complete Apr 30 13:42:45.611185 etcd-wrapper[1730]: Digest: sha256:d967d98a12dc220a1a290794711dba7eba04b8ce465e12b02383d1bfbb33e159 Apr 30 13:42:45.623766 etcd-wrapper[1730]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.16 Apr 30 13:42:45.961151 containerd[1436]: time="2025-04-30T13:42:45.960986814Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 30 13:42:45.961151 containerd[1436]: time="2025-04-30T13:42:45.961048539Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 30 13:42:45.961151 containerd[1436]: time="2025-04-30T13:42:45.961069048Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 13:42:45.962660 containerd[1436]: time="2025-04-30T13:42:45.961152254Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 13:42:45.987202 systemd[1]: Started docker-04bb385b77ea318cdd41b25c7d96d8aeca58d91373fc59ed014a11d6f443deeb.scope - libcontainer container 04bb385b77ea318cdd41b25c7d96d8aeca58d91373fc59ed014a11d6f443deeb. Apr 30 13:42:46.017750 systemd[1]: var-lib-docker-overlay2-e8d1029e14200bac0de39910f492774a38d6c4ca4db4f688473f6ad86e214aed-merged.mount: Deactivated successfully. Apr 30 13:42:46.050753 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.049910Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} Apr 30 13:42:46.050753 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.050025Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"fb95a460456a494ea4083b54d96b19b4"} Apr 30 13:42:46.050753 etcd-wrapper[1730]: {"level":"warn","ts":"2025-04-30T13:42:46.050055Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} Apr 30 13:42:46.050753 etcd-wrapper[1730]: {"level":"warn","ts":"2025-04-30T13:42:46.050063Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.16"} Apr 30 13:42:46.050753 etcd-wrapper[1730]: {"level":"warn","ts":"2025-04-30T13:42:46.050070Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} Apr 30 13:42:46.050753 etcd-wrapper[1730]: {"level":"warn","ts":"2025-04-30T13:42:46.050076Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} Apr 30 13:42:46.050753 etcd-wrapper[1730]: {"level":"warn","ts":"2025-04-30T13:42:46.050124Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Apr 30 13:42:46.050753 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.050137Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["/usr/local/bin/etcd","--listen-client-urls=http://0.0.0.0:2379","--advertise-client-urls=http://172.24.4.31:2379"]} Apr 30 13:42:46.050753 etcd-wrapper[1730]: {"level":"warn","ts":"2025-04-30T13:42:46.050189Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Apr 30 13:42:46.050753 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.050200Z","caller":"embed/etcd.go:128","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]} Apr 30 13:42:46.050753 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.050631Z","caller":"embed/etcd.go:136","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} Apr 30 13:42:46.051326 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.051179Z","caller":"embed/etcd.go:311","msg":"starting an etcd server","etcd-version":"3.5.16","git-sha":"f20bbad","go-version":"go1.22.7","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"fb95a460456a494ea4083b54d96b19b4","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":100000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["http://localhost:2380"],"listen-peer-urls":["http://localhost:2380"],"advertise-client-urls":["http://172.24.4.31:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"fb95a460456a494ea4083b54d96b19b4=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"} Apr 30 13:42:46.055122 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.054980Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.378135ms"} Apr 30 13:42:46.062244 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.061967Z","caller":"etcdserver/raft.go:505","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"} Apr 30 13:42:46.062244 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.062021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"} Apr 30 13:42:46.062244 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.062044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"} Apr 30 13:42:46.062244 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.062056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} Apr 30 13:42:46.062244 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.062062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"} Apr 30 13:42:46.062244 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.062093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Apr 30 13:42:46.068621 etcd-wrapper[1730]: {"level":"warn","ts":"2025-04-30T13:42:46.068497Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} Apr 30 13:42:46.069868 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.069726Z","caller":"mvcc/kvstore.go:423","msg":"kvstore restored","current-rev":1} Apr 30 13:42:46.071357 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.071136Z","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"} Apr 30 13:42:46.072855 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.072248Z","caller":"etcdserver/server.go:873","msg":"starting etcd server","local-member-id":"8e9e05c52164694d","local-server-version":"3.5.16","cluster-version":"to_be_decided"} Apr 30 13:42:46.072855 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.072435Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Apr 30 13:42:46.075970 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.074157Z","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"} Apr 30 13:42:46.076153 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.074628Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} Apr 30 13:42:46.076554 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.076194Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} Apr 30 13:42:46.076554 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.075679Z","caller":"embed/etcd.go:280","msg":"now serving peer/client/metrics","local-member-id":"8e9e05c52164694d","initial-advertise-peer-urls":["http://localhost:2380"],"listen-peer-urls":["http://localhost:2380"],"advertise-client-urls":["http://172.24.4.31:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} Apr 30 13:42:46.076554 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.075727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Apr 30 13:42:46.076554 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.076294Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} Apr 30 13:42:46.076554 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.076376Z","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"]} Apr 30 13:42:46.076554 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.075729Z","caller":"embed/etcd.go:600","msg":"serving peer traffic","address":"127.0.0.1:2380"} Apr 30 13:42:46.076554 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.076407Z","caller":"embed/etcd.go:572","msg":"cmux::serve","address":"127.0.0.1:2380"} Apr 30 13:42:46.963594 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.962745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"} Apr 30 13:42:46.963594 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.962812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"} Apr 30 13:42:46.963594 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.962839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"} Apr 30 13:42:46.963594 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.962932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"} Apr 30 13:42:46.963594 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.962952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"} Apr 30 13:42:46.963594 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.962971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"} Apr 30 13:42:46.963594 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.962994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"} Apr 30 13:42:46.966193 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.964684Z","caller":"etcdserver/server.go:2651","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} Apr 30 13:42:46.967309 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.966970Z","caller":"etcdserver/server.go:2140","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:fb95a460456a494ea4083b54d96b19b4 ClientURLs:[http://172.24.4.31:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"} Apr 30 13:42:46.967309 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.967108Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} Apr 30 13:42:46.968128 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.967441Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"} Apr 30 13:42:46.968128 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.967650Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} Apr 30 13:42:46.968128 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.967701Z","caller":"etcdserver/server.go:2675","msg":"cluster version is updated","cluster-version":"3.5"} Apr 30 13:42:46.970313 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.968211Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} Apr 30 13:42:46.970313 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.968478Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} Apr 30 13:42:46.969077 systemd[1]: Started etcd-member.service - etcd (System Application Container). Apr 30 13:42:46.969831 systemd[1]: Reached target multi-user.target - Multi-User System. Apr 30 13:42:46.971530 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.969666Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Apr 30 13:42:46.972172 systemd[1]: Startup finished in 1.231s (kernel) + 11.822s (initrd) + 16.392s (userspace) = 29.446s. Apr 30 13:42:46.973181 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:42:46.972928Z","caller":"embed/serve.go:187","msg":"serving client traffic insecurely; this is strongly discouraged!","traffic":"grpc+http","address":"[::]:2379"} Apr 30 13:42:51.100651 systemd[1]: Started sshd@3-172.24.4.31:22-172.24.4.1:51964.service - OpenSSH per-connection server daemon (172.24.4.1:51964). Apr 30 13:42:52.622247 sshd[1908]: Accepted publickey for core from 172.24.4.1 port 51964 ssh2: RSA SHA256:zUB7DYTw0Tgn/bgRMQaWs8gBzY+wFpwfqzYhsTkYoFg Apr 30 13:42:52.624793 sshd-session[1908]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 13:42:52.634794 systemd-logind[1414]: New session 6 of user core. Apr 30 13:42:52.649335 systemd[1]: Started session-6.scope - Session 6 of User core. Apr 30 13:42:53.304712 sshd[1910]: Connection closed by 172.24.4.1 port 51964 Apr 30 13:42:53.305682 sshd-session[1908]: pam_unix(sshd:session): session closed for user core Apr 30 13:42:53.320070 systemd[1]: sshd@3-172.24.4.31:22-172.24.4.1:51964.service: Deactivated successfully. Apr 30 13:42:53.323055 systemd[1]: session-6.scope: Deactivated successfully. Apr 30 13:42:53.324604 systemd-logind[1414]: Session 6 logged out. Waiting for processes to exit. Apr 30 13:42:53.332435 systemd[1]: Started sshd@4-172.24.4.31:22-172.24.4.1:38182.service - OpenSSH per-connection server daemon (172.24.4.1:38182). Apr 30 13:42:53.335805 systemd-logind[1414]: Removed session 6. Apr 30 13:42:54.775202 sshd[1915]: Accepted publickey for core from 172.24.4.1 port 38182 ssh2: RSA SHA256:zUB7DYTw0Tgn/bgRMQaWs8gBzY+wFpwfqzYhsTkYoFg Apr 30 13:42:54.777868 sshd-session[1915]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 13:42:54.789374 systemd-logind[1414]: New session 7 of user core. Apr 30 13:42:54.798182 systemd[1]: Started session-7.scope - Session 7 of User core. Apr 30 13:42:55.557165 sshd[1918]: Connection closed by 172.24.4.1 port 38182 Apr 30 13:42:55.557811 sshd-session[1915]: pam_unix(sshd:session): session closed for user core Apr 30 13:42:55.572705 systemd[1]: sshd@4-172.24.4.31:22-172.24.4.1:38182.service: Deactivated successfully. Apr 30 13:42:55.575785 systemd[1]: session-7.scope: Deactivated successfully. Apr 30 13:42:55.579186 systemd-logind[1414]: Session 7 logged out. Waiting for processes to exit. Apr 30 13:42:55.586440 systemd[1]: Started sshd@5-172.24.4.31:22-172.24.4.1:38196.service - OpenSSH per-connection server daemon (172.24.4.1:38196). Apr 30 13:42:55.589641 systemd-logind[1414]: Removed session 7. Apr 30 13:42:56.892673 sshd[1923]: Accepted publickey for core from 172.24.4.1 port 38196 ssh2: RSA SHA256:zUB7DYTw0Tgn/bgRMQaWs8gBzY+wFpwfqzYhsTkYoFg Apr 30 13:42:56.896573 sshd-session[1923]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 13:42:56.913132 systemd-logind[1414]: New session 8 of user core. Apr 30 13:42:56.924275 systemd[1]: Started session-8.scope - Session 8 of User core. Apr 30 13:42:57.644926 sshd[1926]: Connection closed by 172.24.4.1 port 38196 Apr 30 13:42:57.645916 sshd-session[1923]: pam_unix(sshd:session): session closed for user core Apr 30 13:42:57.662587 systemd[1]: sshd@5-172.24.4.31:22-172.24.4.1:38196.service: Deactivated successfully. Apr 30 13:42:57.665769 systemd[1]: session-8.scope: Deactivated successfully. Apr 30 13:42:57.669158 systemd-logind[1414]: Session 8 logged out. Waiting for processes to exit. Apr 30 13:42:57.679467 systemd[1]: Started sshd@6-172.24.4.31:22-172.24.4.1:38206.service - OpenSSH per-connection server daemon (172.24.4.1:38206). Apr 30 13:42:57.684127 systemd-logind[1414]: Removed session 8. Apr 30 13:42:58.824950 sshd[1931]: Accepted publickey for core from 172.24.4.1 port 38206 ssh2: RSA SHA256:zUB7DYTw0Tgn/bgRMQaWs8gBzY+wFpwfqzYhsTkYoFg Apr 30 13:42:58.827482 sshd-session[1931]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 13:42:58.837792 systemd-logind[1414]: New session 9 of user core. Apr 30 13:42:58.847195 systemd[1]: Started session-9.scope - Session 9 of User core. Apr 30 13:42:59.211168 sudo[1935]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Apr 30 13:42:59.212175 sudo[1935]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Apr 30 13:42:59.229437 sudo[1935]: pam_unix(sudo:session): session closed for user root Apr 30 13:42:59.429673 sshd[1934]: Connection closed by 172.24.4.1 port 38206 Apr 30 13:42:59.430085 sshd-session[1931]: pam_unix(sshd:session): session closed for user core Apr 30 13:42:59.445850 systemd[1]: sshd@6-172.24.4.31:22-172.24.4.1:38206.service: Deactivated successfully. Apr 30 13:42:59.449107 systemd[1]: session-9.scope: Deactivated successfully. Apr 30 13:42:59.452569 systemd-logind[1414]: Session 9 logged out. Waiting for processes to exit. Apr 30 13:42:59.459457 systemd[1]: Started sshd@7-172.24.4.31:22-172.24.4.1:38222.service - OpenSSH per-connection server daemon (172.24.4.1:38222). Apr 30 13:42:59.462872 systemd-logind[1414]: Removed session 9. Apr 30 13:43:00.718050 sshd[1940]: Accepted publickey for core from 172.24.4.1 port 38222 ssh2: RSA SHA256:zUB7DYTw0Tgn/bgRMQaWs8gBzY+wFpwfqzYhsTkYoFg Apr 30 13:43:00.720673 sshd-session[1940]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 13:43:00.733012 systemd-logind[1414]: New session 10 of user core. Apr 30 13:43:00.743203 systemd[1]: Started session-10.scope - Session 10 of User core. Apr 30 13:43:01.162853 sudo[1945]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Apr 30 13:43:01.163949 sudo[1945]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Apr 30 13:43:01.172200 sudo[1945]: pam_unix(sudo:session): session closed for user root Apr 30 13:43:01.184863 sudo[1944]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Apr 30 13:43:01.185845 sudo[1944]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Apr 30 13:43:01.216830 systemd[1]: Starting audit-rules.service - Load Audit Rules... Apr 30 13:43:01.285307 augenrules[1967]: No rules Apr 30 13:43:01.286700 systemd[1]: audit-rules.service: Deactivated successfully. Apr 30 13:43:01.287533 systemd[1]: Finished audit-rules.service - Load Audit Rules. Apr 30 13:43:01.290538 sudo[1944]: pam_unix(sudo:session): session closed for user root Apr 30 13:43:01.560291 sshd[1943]: Connection closed by 172.24.4.1 port 38222 Apr 30 13:43:01.561588 sshd-session[1940]: pam_unix(sshd:session): session closed for user core Apr 30 13:43:01.585758 systemd[1]: sshd@7-172.24.4.31:22-172.24.4.1:38222.service: Deactivated successfully. Apr 30 13:43:01.589742 systemd[1]: session-10.scope: Deactivated successfully. Apr 30 13:43:01.594261 systemd-logind[1414]: Session 10 logged out. Waiting for processes to exit. Apr 30 13:43:01.601762 systemd[1]: Started sshd@8-172.24.4.31:22-172.24.4.1:38228.service - OpenSSH per-connection server daemon (172.24.4.1:38228). Apr 30 13:43:01.606453 systemd-logind[1414]: Removed session 10. Apr 30 13:43:02.985653 sshd[1975]: Accepted publickey for core from 172.24.4.1 port 38228 ssh2: RSA SHA256:zUB7DYTw0Tgn/bgRMQaWs8gBzY+wFpwfqzYhsTkYoFg Apr 30 13:43:02.987416 sshd-session[1975]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 13:43:02.994512 systemd-logind[1414]: New session 11 of user core. Apr 30 13:43:03.000039 systemd[1]: Started session-11.scope - Session 11 of User core. Apr 30 13:43:03.569065 sshd[1978]: Connection closed by 172.24.4.1 port 38228 Apr 30 13:43:03.572064 sshd-session[1975]: pam_unix(sshd:session): session closed for user core Apr 30 13:43:03.591785 systemd[1]: sshd@8-172.24.4.31:22-172.24.4.1:38228.service: Deactivated successfully. Apr 30 13:43:03.595756 systemd[1]: session-11.scope: Deactivated successfully. Apr 30 13:43:03.598042 systemd-logind[1414]: Session 11 logged out. Waiting for processes to exit. Apr 30 13:43:03.605702 systemd[1]: Started sshd@9-172.24.4.31:22-172.24.4.1:48582.service - OpenSSH per-connection server daemon (172.24.4.1:48582). Apr 30 13:43:03.610922 systemd-logind[1414]: Removed session 11. Apr 30 13:43:05.062914 sshd[1985]: Accepted publickey for core from 172.24.4.1 port 48582 ssh2: RSA SHA256:zUB7DYTw0Tgn/bgRMQaWs8gBzY+wFpwfqzYhsTkYoFg Apr 30 13:43:05.065601 sshd-session[1985]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 13:43:05.078519 systemd-logind[1414]: New session 12 of user core. Apr 30 13:43:05.092273 systemd[1]: Started session-12.scope - Session 12 of User core. Apr 30 13:43:05.875134 sshd[1988]: Connection closed by 172.24.4.1 port 48582 Apr 30 13:43:05.876125 sshd-session[1985]: pam_unix(sshd:session): session closed for user core Apr 30 13:43:05.882351 systemd-logind[1414]: Session 12 logged out. Waiting for processes to exit. Apr 30 13:43:05.883875 systemd[1]: sshd@9-172.24.4.31:22-172.24.4.1:48582.service: Deactivated successfully. Apr 30 13:43:05.887039 systemd[1]: session-12.scope: Deactivated successfully. Apr 30 13:43:05.889745 systemd-logind[1414]: Removed session 12. Apr 30 13:43:08.711602 systemd-timesyncd[1321]: Contacted time server 50.205.57.38:123 (2.flatcar.pool.ntp.org). Apr 30 13:43:08.711737 systemd-timesyncd[1321]: Initial clock synchronization to Wed 2025-04-30 13:43:08.711110 UTC. Apr 30 13:43:08.713030 systemd-resolved[1259]: Clock change detected. Flushing caches. Apr 30 13:43:20.891967 update_engine[1416]: I20250430 13:43:20.891744 1416 update_attempter.cc:509] Updating boot flags... Apr 30 13:43:20.957463 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 40 scanned by (udev-worker) (2007) Apr 30 13:43:21.042725 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 40 scanned by (udev-worker) (2008) Apr 30 13:43:21.102324 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 40 scanned by (udev-worker) (2008) Apr 30 13:47:09.899267 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:47:09.893790Z","caller":"traceutil/trace.go:171","msg":"trace[227420195] linearizableReadLoop","detail":"{readStateIndex:279; appliedIndex:278; }","duration":"134.524037ms","start":"2025-04-30T13:47:09.759160Z","end":"2025-04-30T13:47:09.893684Z","steps":["trace[227420195] 'read index received' (duration: 134.358203ms)","trace[227420195] 'applied index is now lower than readState.Index' (duration: 164.882µs)"],"step_count":2} Apr 30 13:47:09.899267 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:47:09.893937Z","caller":"traceutil/trace.go:171","msg":"trace[771348016] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"136.970556ms","start":"2025-04-30T13:47:09.756901Z","end":"2025-04-30T13:47:09.893872Z","steps":["trace[771348016] 'process raft request' (duration: 136.667393ms)"],"step_count":1} Apr 30 13:47:09.899267 etcd-wrapper[1730]: {"level":"warn","ts":"2025-04-30T13:47:09.894540Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.114586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" limit:1 ","response":"range_response_count:0 size:5"} Apr 30 13:47:09.899267 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:47:09.894721Z","caller":"traceutil/trace.go:171","msg":"trace[1757054680] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:0; response_revision:272; }","duration":"135.5608ms","start":"2025-04-30T13:47:09.759131Z","end":"2025-04-30T13:47:09.894692Z","steps":["trace[1757054680] 'agreement among raft nodes before linearized reading' (duration: 135.088667ms)"],"step_count":1} Apr 30 13:47:28.734615 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:47:28.732426Z","caller":"traceutil/trace.go:171","msg":"trace[1247392619] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"256.22382ms","start":"2025-04-30T13:47:28.476169Z","end":"2025-04-30T13:47:28.732393Z","steps":["trace[1247392619] 'process raft request' (duration: 255.852418ms)"],"step_count":1} Apr 30 13:47:38.006717 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:47:38.001494Z","caller":"traceutil/trace.go:171","msg":"trace[684847447] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"142.100133ms","start":"2025-04-30T13:47:37.859142Z","end":"2025-04-30T13:47:38.001243Z","steps":["trace[684847447] 'process raft request' (duration: 81.487227ms)","trace[684847447] 'compare' (duration: 58.056371ms)"],"step_count":2} Apr 30 13:47:38.136461 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:47:38.135474Z","caller":"traceutil/trace.go:171","msg":"trace[506796246] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"114.149658ms","start":"2025-04-30T13:47:38.021289Z","end":"2025-04-30T13:47:38.135439Z","steps":["trace[506796246] 'process raft request' (duration: 113.332915ms)"],"step_count":1} Apr 30 13:47:38.480417 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:47:38.479272Z","caller":"traceutil/trace.go:171","msg":"trace[1574789809] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"258.431805ms","start":"2025-04-30T13:47:38.220810Z","end":"2025-04-30T13:47:38.479242Z","steps":["trace[1574789809] 'process raft request' (duration: 258.380948ms)"],"step_count":1} Apr 30 13:47:38.480417 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:47:38.479603Z","caller":"traceutil/trace.go:171","msg":"trace[501174413] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"329.2173ms","start":"2025-04-30T13:47:38.150279Z","end":"2025-04-30T13:47:38.479496Z","steps":["trace[501174413] 'process raft request' (duration: 269.57549ms)","trace[501174413] 'compare' (duration: 59.037046ms)"],"step_count":2} Apr 30 13:47:38.481699 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:47:38.480548Z","caller":"traceutil/trace.go:171","msg":"trace[1686764678] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"327.957307ms","start":"2025-04-30T13:47:38.152564Z","end":"2025-04-30T13:47:38.480521Z","steps":["trace[1686764678] 'process raft request' (duration: 326.527101ms)"],"step_count":1} Apr 30 13:47:38.483381 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:47:38.481010Z","caller":"traceutil/trace.go:171","msg":"trace[143040047] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"329.044343ms","start":"2025-04-30T13:47:38.151948Z","end":"2025-04-30T13:47:38.480992Z","steps":["trace[143040047] 'process raft request' (duration: 327.085739ms)"],"step_count":1} Apr 30 13:47:38.483381 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:47:38.481917Z","caller":"traceutil/trace.go:171","msg":"trace[1515440049] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"321.520223ms","start":"2025-04-30T13:47:38.160377Z","end":"2025-04-30T13:47:38.481897Z","steps":["trace[1515440049] 'process raft request' (duration: 318.757848ms)"],"step_count":1} Apr 30 13:47:38.483381 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:47:38.482465Z","caller":"traceutil/trace.go:171","msg":"trace[1047320314] linearizableReadLoop","detail":"{readStateIndex:723; appliedIndex:719; }","duration":"267.818337ms","start":"2025-04-30T13:47:38.214560Z","end":"2025-04-30T13:47:38.482378Z","steps":["trace[1047320314] 'read index received' (duration: 205.310718ms)","trace[1047320314] 'applied index is now lower than readState.Index' (duration: 62.506237ms)"],"step_count":2} Apr 30 13:47:38.483381 etcd-wrapper[1730]: {"level":"warn","ts":"2025-04-30T13:47:38.482918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.338812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/calico-apiserver/calico-apiserver-846855bb6b-92j4s\" limit:1 ","response":"range_response_count:1 size:4183"} Apr 30 13:47:38.484270 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:47:38.483442Z","caller":"traceutil/trace.go:171","msg":"trace[785685019] range","detail":"{range_begin:/registry/pods/calico-apiserver/calico-apiserver-846855bb6b-92j4s; range_end:; response_count:1; response_revision:705; }","duration":"268.895515ms","start":"2025-04-30T13:47:38.214507Z","end":"2025-04-30T13:47:38.483402Z","steps":["trace[785685019] 'agreement among raft nodes before linearized reading' (duration: 268.243111ms)"],"step_count":1} Apr 30 13:47:38.484270 etcd-wrapper[1730]: {"level":"warn","ts":"2025-04-30T13:47:38.483170Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-04-30T13:47:38.150258Z","time spent":"329.410776ms","remote":"172.24.4.158:60730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3824,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Apr 30 13:47:38.485756 etcd-wrapper[1730]: {"level":"warn","ts":"2025-04-30T13:47:38.484780Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.069471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/172.24.4.158\" limit:1 ","response":"range_response_count:1 size:131"} Apr 30 13:47:38.485756 etcd-wrapper[1730]: {"level":"warn","ts":"2025-04-30T13:47:38.484148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-04-30T13:47:38.152539Z","time spent":"328.427836ms","remote":"172.24.4.158:60730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3824,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Apr 30 13:47:38.485756 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:47:38.484962Z","caller":"traceutil/trace.go:171","msg":"trace[468216722] range","detail":"{range_begin:/registry/masterleases/172.24.4.158; range_end:; response_count:1; response_revision:705; }","duration":"101.300667ms","start":"2025-04-30T13:47:38.383642Z","end":"2025-04-30T13:47:38.484943Z","steps":["trace[468216722] 'agreement among raft nodes before linearized reading' (duration: 100.962858ms)"],"step_count":1} Apr 30 13:47:38.486799 etcd-wrapper[1730]: {"level":"warn","ts":"2025-04-30T13:47:38.485510Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-04-30T13:47:38.151920Z","time spent":"329.576999ms","remote":"172.24.4.158:60730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4794,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Apr 30 13:47:38.486799 etcd-wrapper[1730]: {"level":"warn","ts":"2025-04-30T13:47:38.486190Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-04-30T13:47:38.160349Z","time spent":"321.999189ms","remote":"172.24.4.158:60730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4168,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Apr 30 13:47:38.719190 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:47:38.718251Z","caller":"traceutil/trace.go:171","msg":"trace[1078135857] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"208.818162ms","start":"2025-04-30T13:47:38.509398Z","end":"2025-04-30T13:47:38.718216Z","steps":["trace[1078135857] 'process raft request' (duration: 206.008979ms)"],"step_count":1} Apr 30 13:47:38.719190 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:47:38.718279Z","caller":"traceutil/trace.go:171","msg":"trace[699273459] linearizableReadLoop","detail":"{readStateIndex:728; appliedIndex:725; }","duration":"107.311675ms","start":"2025-04-30T13:47:38.610949Z","end":"2025-04-30T13:47:38.718261Z","steps":["trace[699273459] 'read index received' (duration: 104.466725ms)","trace[699273459] 'applied index is now lower than readState.Index' (duration: 2.843608ms)"],"step_count":2} Apr 30 13:47:38.721209 etcd-wrapper[1730]: {"level":"warn","ts":"2025-04-30T13:47:38.719143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.088555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" limit:1 ","response":"range_response_count:1 size:179"} Apr 30 13:47:38.721209 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:47:38.719575Z","caller":"traceutil/trace.go:171","msg":"trace[1390444135] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:708; }","duration":"108.357043ms","start":"2025-04-30T13:47:38.610896Z","end":"2025-04-30T13:47:38.719253Z","steps":["trace[1390444135] 'agreement among raft nodes before linearized reading' (duration: 107.487618ms)"],"step_count":1} Apr 30 13:47:38.721209 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:47:38.720465Z","caller":"traceutil/trace.go:171","msg":"trace[1927410604] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"186.813475ms","start":"2025-04-30T13:47:38.533585Z","end":"2025-04-30T13:47:38.720398Z","steps":["trace[1927410604] 'process raft request' (duration: 184.459423ms)"],"step_count":1} Apr 30 13:47:38.721209 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:47:38.720600Z","caller":"traceutil/trace.go:171","msg":"trace[1581661442] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"180.659506ms","start":"2025-04-30T13:47:38.539920Z","end":"2025-04-30T13:47:38.720579Z","steps":["trace[1581661442] 'process raft request' (duration: 178.2349ms)"],"step_count":1} Apr 30 13:47:58.741233 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:47:58.738489Z","caller":"traceutil/trace.go:171","msg":"trace[934205652] transaction","detail":"{read_only:false; number_of_response:0; response_revision:962; }","duration":"100.399769ms","start":"2025-04-30T13:47:58.638042Z","end":"2025-04-30T13:47:58.738442Z","steps":["trace[934205652] 'process raft request' (duration: 100.333854ms)"],"step_count":1} Apr 30 13:49:25.969603 update_engine[1416]: I20250430 13:49:25.968513 1416 prefs.cc:52] certificate-report-to-send-update not present in /var/lib/update_engine/prefs Apr 30 13:49:25.969603 update_engine[1416]: I20250430 13:49:25.968878 1416 prefs.cc:52] certificate-report-to-send-download not present in /var/lib/update_engine/prefs Apr 30 13:49:25.971218 update_engine[1416]: I20250430 13:49:25.971145 1416 prefs.cc:52] aleph-version not present in /var/lib/update_engine/prefs Apr 30 13:49:25.973834 update_engine[1416]: I20250430 13:49:25.973748 1416 omaha_request_params.cc:62] Current group set to beta Apr 30 13:49:25.974899 update_engine[1416]: I20250430 13:49:25.974831 1416 update_attempter.cc:499] Already updated boot flags. Skipping. Apr 30 13:49:25.974899 update_engine[1416]: I20250430 13:49:25.974875 1416 update_attempter.cc:643] Scheduling an action processor start. Apr 30 13:49:25.975170 update_engine[1416]: I20250430 13:49:25.974970 1416 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Apr 30 13:49:25.975268 update_engine[1416]: I20250430 13:49:25.975183 1416 prefs.cc:52] previous-version not present in /var/lib/update_engine/prefs Apr 30 13:49:25.976384 update_engine[1416]: I20250430 13:49:25.975423 1416 omaha_request_action.cc:271] Posting an Omaha request to disabled Apr 30 13:49:25.976384 update_engine[1416]: I20250430 13:49:25.975462 1416 omaha_request_action.cc:272] Request: Apr 30 13:49:25.976384 update_engine[1416]: Apr 30 13:49:25.976384 update_engine[1416]: Apr 30 13:49:25.976384 update_engine[1416]: Apr 30 13:49:25.976384 update_engine[1416]: Apr 30 13:49:25.976384 update_engine[1416]: Apr 30 13:49:25.976384 update_engine[1416]: Apr 30 13:49:25.976384 update_engine[1416]: Apr 30 13:49:25.976384 update_engine[1416]: Apr 30 13:49:25.976384 update_engine[1416]: I20250430 13:49:25.975486 1416 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Apr 30 13:49:25.981928 update_engine[1416]: I20250430 13:49:25.981685 1416 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Apr 30 13:49:25.983207 update_engine[1416]: I20250430 13:49:25.983115 1416 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Apr 30 13:49:25.985839 locksmithd[1442]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_CHECKING_FOR_UPDATE" NewVersion=0.0.0 NewSize=0 Apr 30 13:49:25.990627 update_engine[1416]: E20250430 13:49:25.990545 1416 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Apr 30 13:49:25.990762 update_engine[1416]: I20250430 13:49:25.990733 1416 libcurl_http_fetcher.cc:283] No HTTP response, retry 1 Apr 30 13:49:35.890250 update_engine[1416]: I20250430 13:49:35.888769 1416 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Apr 30 13:49:35.895450 update_engine[1416]: I20250430 13:49:35.891638 1416 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Apr 30 13:49:35.895450 update_engine[1416]: I20250430 13:49:35.893541 1416 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Apr 30 13:49:35.899074 update_engine[1416]: E20250430 13:49:35.898958 1416 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Apr 30 13:49:35.899298 update_engine[1416]: I20250430 13:49:35.899262 1416 libcurl_http_fetcher.cc:283] No HTTP response, retry 2 Apr 30 13:49:45.885403 update_engine[1416]: I20250430 13:49:45.884198 1416 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Apr 30 13:49:45.888898 update_engine[1416]: I20250430 13:49:45.886421 1416 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Apr 30 13:49:45.888898 update_engine[1416]: I20250430 13:49:45.887614 1416 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Apr 30 13:49:45.892973 update_engine[1416]: E20250430 13:49:45.892897 1416 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Apr 30 13:49:45.893176 update_engine[1416]: I20250430 13:49:45.893107 1416 libcurl_http_fetcher.cc:283] No HTTP response, retry 3 Apr 30 13:49:55.879924 update_engine[1416]: I20250430 13:49:55.879635 1416 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Apr 30 13:49:55.882131 update_engine[1416]: I20250430 13:49:55.880858 1416 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Apr 30 13:49:55.882508 update_engine[1416]: I20250430 13:49:55.882407 1416 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Apr 30 13:49:55.887948 update_engine[1416]: E20250430 13:49:55.887843 1416 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Apr 30 13:49:55.888223 update_engine[1416]: I20250430 13:49:55.888036 1416 libcurl_http_fetcher.cc:297] Transfer resulted in an error (0), 0 bytes downloaded Apr 30 13:49:55.888223 update_engine[1416]: I20250430 13:49:55.888109 1416 omaha_request_action.cc:617] Omaha request response: Apr 30 13:49:55.888921 update_engine[1416]: E20250430 13:49:55.888792 1416 omaha_request_action.cc:636] Omaha request network transfer failed. Apr 30 13:49:55.889613 update_engine[1416]: I20250430 13:49:55.889511 1416 action_processor.cc:68] ActionProcessor::ActionComplete: OmahaRequestAction action failed. Aborting processing. Apr 30 13:49:55.889613 update_engine[1416]: I20250430 13:49:55.889556 1416 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Apr 30 13:49:55.889939 update_engine[1416]: I20250430 13:49:55.889591 1416 update_attempter.cc:306] Processing Done. Apr 30 13:49:55.889939 update_engine[1416]: E20250430 13:49:55.889730 1416 update_attempter.cc:619] Update failed. Apr 30 13:49:55.889939 update_engine[1416]: I20250430 13:49:55.889782 1416 utils.cc:600] Converting error code 2000 to kActionCodeOmahaErrorInHTTPResponse Apr 30 13:49:55.889939 update_engine[1416]: I20250430 13:49:55.889803 1416 payload_state.cc:97] Updating payload state for error code: 37 (kActionCodeOmahaErrorInHTTPResponse) Apr 30 13:49:55.889939 update_engine[1416]: I20250430 13:49:55.889824 1416 payload_state.cc:103] Ignoring failures until we get a valid Omaha response. Apr 30 13:49:55.892377 update_engine[1416]: I20250430 13:49:55.890750 1416 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Apr 30 13:49:55.892377 update_engine[1416]: I20250430 13:49:55.890949 1416 omaha_request_action.cc:271] Posting an Omaha request to disabled Apr 30 13:49:55.892377 update_engine[1416]: I20250430 13:49:55.890995 1416 omaha_request_action.cc:272] Request: Apr 30 13:49:55.892377 update_engine[1416]: Apr 30 13:49:55.892377 update_engine[1416]: Apr 30 13:49:55.892377 update_engine[1416]: Apr 30 13:49:55.892377 update_engine[1416]: Apr 30 13:49:55.892377 update_engine[1416]: Apr 30 13:49:55.892377 update_engine[1416]: Apr 30 13:49:55.892377 update_engine[1416]: I20250430 13:49:55.891041 1416 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Apr 30 13:49:55.892377 update_engine[1416]: I20250430 13:49:55.891593 1416 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Apr 30 13:49:55.892377 update_engine[1416]: I20250430 13:49:55.892251 1416 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Apr 30 13:49:55.897217 locksmithd[1442]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_REPORTING_ERROR_EVENT" NewVersion=0.0.0 NewSize=0 Apr 30 13:49:55.898789 update_engine[1416]: E20250430 13:49:55.897629 1416 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Apr 30 13:49:55.898789 update_engine[1416]: I20250430 13:49:55.897768 1416 libcurl_http_fetcher.cc:297] Transfer resulted in an error (0), 0 bytes downloaded Apr 30 13:49:55.898789 update_engine[1416]: I20250430 13:49:55.897802 1416 omaha_request_action.cc:617] Omaha request response: Apr 30 13:49:55.898789 update_engine[1416]: I20250430 13:49:55.897825 1416 action_processor.cc:65] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Apr 30 13:49:55.898789 update_engine[1416]: I20250430 13:49:55.897845 1416 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Apr 30 13:49:55.898789 update_engine[1416]: I20250430 13:49:55.897863 1416 update_attempter.cc:306] Processing Done. Apr 30 13:49:55.898789 update_engine[1416]: I20250430 13:49:55.897883 1416 update_attempter.cc:310] Error event sent. Apr 30 13:49:55.898789 update_engine[1416]: I20250430 13:49:55.897987 1416 update_check_scheduler.cc:74] Next update check in 47m25s Apr 30 13:49:55.900124 locksmithd[1442]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_IDLE" NewVersion=0.0.0 NewSize=0 Apr 30 13:50:44.901157 etcd-wrapper[1730]: {"level":"info","ts":"2025-04-30T13:50:44.890716Z","caller":"traceutil/trace.go:171","msg":"trace[708191493] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1405; }","duration":"201.909533ms","start":"2025-04-30T13:50:44.688698Z","end":"2025-04-30T13:50:44.890607Z","steps":["trace[708191493] 'process raft request' (duration: 194.319672ms)"],"step_count":1}