May 15 17:53:03.857442 kernel: Linux version 5.15.182-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 11.3.1_p20221209 p3) 11.3.1 20221209, GNU ld (Gentoo 2.39 p5) 2.39.0) #1 SMP Thu May 15 09:06:41 -00 2025 May 15 17:53:03.857465 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 verity.usrhash=f8c1bc5ff10765e781843bfc97fc5357002a3f8a120201a0e954fce1d2ba48f0 May 15 17:53:03.857476 kernel: BIOS-provided physical RAM map: May 15 17:53:03.857487 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable May 15 17:53:03.857494 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved May 15 17:53:03.857501 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved May 15 17:53:03.857510 kernel: BIOS-e820: [mem 0x0000000000100000-0x00000000bffdcfff] usable May 15 17:53:03.857517 kernel: BIOS-e820: [mem 0x00000000bffdd000-0x00000000bfffffff] reserved May 15 17:53:03.857524 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved May 15 17:53:03.857531 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved May 15 17:53:03.857539 kernel: BIOS-e820: [mem 0x0000000100000000-0x000000013fffffff] usable May 15 17:53:03.857546 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved May 15 17:53:03.857555 kernel: NX (Execute Disable) protection: active May 15 17:53:03.857562 kernel: SMBIOS 3.0.0 present. May 15 17:53:03.857571 kernel: DMI: OpenStack Foundation OpenStack Nova, BIOS 1.16.3-debian-1.16.3-2 04/01/2014 May 15 17:53:03.857578 kernel: Hypervisor detected: KVM May 15 17:53:03.857586 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 May 15 17:53:03.857594 kernel: kvm-clock: cpu 0, msr ab19a001, primary cpu clock May 15 17:53:03.857603 kernel: kvm-clock: using sched offset of 5077782576 cycles May 15 17:53:03.857611 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns May 15 17:53:03.857619 kernel: tsc: Detected 1996.249 MHz processor May 15 17:53:03.857628 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved May 15 17:53:03.857636 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable May 15 17:53:03.857644 kernel: last_pfn = 0x140000 max_arch_pfn = 0x400000000 May 15 17:53:03.857652 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT May 15 17:53:03.857660 kernel: last_pfn = 0xbffdd max_arch_pfn = 0x400000000 May 15 17:53:03.857668 kernel: ACPI: Early table checksum verification disabled May 15 17:53:03.857678 kernel: ACPI: RSDP 0x00000000000F51E0 000014 (v00 BOCHS ) May 15 17:53:03.857686 kernel: ACPI: RSDT 0x00000000BFFE1B65 000030 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 15 17:53:03.857694 kernel: ACPI: FACP 0x00000000BFFE1A49 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 15 17:53:03.857702 kernel: ACPI: DSDT 0x00000000BFFE0040 001A09 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 15 17:53:03.857710 kernel: ACPI: FACS 0x00000000BFFE0000 000040 May 15 17:53:03.857718 kernel: ACPI: APIC 0x00000000BFFE1ABD 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) May 15 17:53:03.857726 kernel: ACPI: WAET 0x00000000BFFE1B3D 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 15 17:53:03.857734 kernel: ACPI: Reserving FACP table memory at [mem 0xbffe1a49-0xbffe1abc] May 15 17:53:03.857743 kernel: ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1a48] May 15 17:53:03.857751 kernel: ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] May 15 17:53:03.857759 kernel: ACPI: Reserving APIC table memory at [mem 0xbffe1abd-0xbffe1b3c] May 15 17:53:03.857767 kernel: ACPI: Reserving WAET table memory at [mem 0xbffe1b3d-0xbffe1b64] May 15 17:53:03.857774 kernel: No NUMA configuration found May 15 17:53:03.857786 kernel: Faking a node at [mem 0x0000000000000000-0x000000013fffffff] May 15 17:53:03.857794 kernel: NODE_DATA(0) allocated [mem 0x13fff7000-0x13fffcfff] May 15 17:53:03.857804 kernel: Zone ranges: May 15 17:53:03.857812 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] May 15 17:53:03.857820 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] May 15 17:53:03.857828 kernel: Normal [mem 0x0000000100000000-0x000000013fffffff] May 15 17:53:03.857837 kernel: Movable zone start for each node May 15 17:53:03.857845 kernel: Early memory node ranges May 15 17:53:03.857853 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] May 15 17:53:03.857861 kernel: node 0: [mem 0x0000000000100000-0x00000000bffdcfff] May 15 17:53:03.857871 kernel: node 0: [mem 0x0000000100000000-0x000000013fffffff] May 15 17:53:03.857879 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000013fffffff] May 15 17:53:03.857887 kernel: On node 0, zone DMA: 1 pages in unavailable ranges May 15 17:53:03.857896 kernel: On node 0, zone DMA: 97 pages in unavailable ranges May 15 17:53:03.857904 kernel: On node 0, zone Normal: 35 pages in unavailable ranges May 15 17:53:03.857912 kernel: ACPI: PM-Timer IO Port: 0x608 May 15 17:53:03.857920 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) May 15 17:53:03.857929 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 May 15 17:53:03.857937 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) May 15 17:53:03.857947 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) May 15 17:53:03.857955 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) May 15 17:53:03.857964 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) May 15 17:53:03.857972 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) May 15 17:53:03.857980 kernel: ACPI: Using ACPI (MADT) for SMP configuration information May 15 17:53:03.857988 kernel: smpboot: Allowing 2 CPUs, 0 hotplug CPUs May 15 17:53:03.857997 kernel: [mem 0xc0000000-0xfeffbfff] available for PCI devices May 15 17:53:03.858005 kernel: Booting paravirtualized kernel on KVM May 15 17:53:03.858013 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns May 15 17:53:03.858023 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:512 nr_cpu_ids:2 nr_node_ids:1 May 15 17:53:03.858032 kernel: percpu: Embedded 56 pages/cpu s188696 r8192 d32488 u1048576 May 15 17:53:03.858040 kernel: pcpu-alloc: s188696 r8192 d32488 u1048576 alloc=1*2097152 May 15 17:53:03.858048 kernel: pcpu-alloc: [0] 0 1 May 15 17:53:03.858056 kernel: kvm-guest: stealtime: cpu 0, msr 13bc1c0c0 May 15 17:53:03.858064 kernel: kvm-guest: PV spinlocks disabled, no host support May 15 17:53:03.858072 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1031901 May 15 17:53:03.858080 kernel: Policy zone: Normal May 15 17:53:03.858090 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 verity.usrhash=f8c1bc5ff10765e781843bfc97fc5357002a3f8a120201a0e954fce1d2ba48f0 May 15 17:53:03.858100 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. May 15 17:53:03.858108 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) May 15 17:53:03.858117 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) May 15 17:53:03.858126 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off May 15 17:53:03.858135 kernel: Memory: 3968276K/4193772K available (12294K kernel code, 2276K rwdata, 13724K rodata, 47472K init, 4108K bss, 225236K reserved, 0K cma-reserved) May 15 17:53:03.858143 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 May 15 17:53:03.858151 kernel: ftrace: allocating 34585 entries in 136 pages May 15 17:53:03.858158 kernel: ftrace: allocated 136 pages with 2 groups May 15 17:53:03.858168 kernel: rcu: Hierarchical RCU implementation. May 15 17:53:03.858176 kernel: rcu: RCU event tracing is enabled. May 15 17:53:03.858184 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. May 15 17:53:03.858192 kernel: Rude variant of Tasks RCU enabled. May 15 17:53:03.858200 kernel: Tracing variant of Tasks RCU enabled. May 15 17:53:03.858207 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. May 15 17:53:03.858215 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 May 15 17:53:03.858223 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 16 May 15 17:53:03.858230 kernel: Console: colour VGA+ 80x25 May 15 17:53:03.858239 kernel: printk: console [tty0] enabled May 15 17:53:03.858247 kernel: printk: console [ttyS0] enabled May 15 17:53:03.858267 kernel: ACPI: Core revision 20210730 May 15 17:53:03.859292 kernel: APIC: Switch to symmetric I/O mode setup May 15 17:53:03.859303 kernel: x2apic enabled May 15 17:53:03.859312 kernel: Switched APIC routing to physical x2apic. May 15 17:53:03.859320 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 May 15 17:53:03.859328 kernel: tsc: Marking TSC unstable due to TSCs unsynchronized May 15 17:53:03.859336 kernel: Calibrating delay loop (skipped) preset value.. 3992.49 BogoMIPS (lpj=1996249) May 15 17:53:03.859347 kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 May 15 17:53:03.859354 kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 May 15 17:53:03.859362 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization May 15 17:53:03.859370 kernel: Spectre V2 : Mitigation: Retpolines May 15 17:53:03.859378 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT May 15 17:53:03.859385 kernel: Speculative Store Bypass: Vulnerable May 15 17:53:03.859393 kernel: x86/fpu: x87 FPU will use FXSAVE May 15 17:53:03.859401 kernel: Freeing SMP alternatives memory: 32K May 15 17:53:03.859408 kernel: pid_max: default: 32768 minimum: 301 May 15 17:53:03.859418 kernel: LSM: Security Framework initializing May 15 17:53:03.859425 kernel: SELinux: Initializing. May 15 17:53:03.859433 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 15 17:53:03.859441 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 15 17:53:03.859449 kernel: smpboot: CPU0: AMD Intel Core i7 9xx (Nehalem Class Core i7) (family: 0x6, model: 0x1a, stepping: 0x3) May 15 17:53:03.859457 kernel: Performance Events: AMD PMU driver. May 15 17:53:03.859470 kernel: ... version: 0 May 15 17:53:03.859479 kernel: ... bit width: 48 May 15 17:53:03.859487 kernel: ... generic registers: 4 May 15 17:53:03.859495 kernel: ... value mask: 0000ffffffffffff May 15 17:53:03.859503 kernel: ... max period: 00007fffffffffff May 15 17:53:03.859511 kernel: ... fixed-purpose events: 0 May 15 17:53:03.859521 kernel: ... event mask: 000000000000000f May 15 17:53:03.859529 kernel: signal: max sigframe size: 1440 May 15 17:53:03.859537 kernel: rcu: Hierarchical SRCU implementation. May 15 17:53:03.859545 kernel: smp: Bringing up secondary CPUs ... May 15 17:53:03.859553 kernel: x86: Booting SMP configuration: May 15 17:53:03.859562 kernel: .... node #0, CPUs: #1 May 15 17:53:03.859570 kernel: kvm-clock: cpu 1, msr ab19a041, secondary cpu clock May 15 17:53:03.859578 kernel: kvm-guest: stealtime: cpu 1, msr 13bd1c0c0 May 15 17:53:03.859586 kernel: smp: Brought up 1 node, 2 CPUs May 15 17:53:03.859594 kernel: smpboot: Max logical packages: 2 May 15 17:53:03.859602 kernel: smpboot: Total of 2 processors activated (7984.99 BogoMIPS) May 15 17:53:03.859610 kernel: devtmpfs: initialized May 15 17:53:03.859618 kernel: x86/mm: Memory block size: 128MB May 15 17:53:03.859627 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns May 15 17:53:03.859637 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) May 15 17:53:03.859645 kernel: pinctrl core: initialized pinctrl subsystem May 15 17:53:03.859653 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 15 17:53:03.859661 kernel: audit: initializing netlink subsys (disabled) May 15 17:53:03.859669 kernel: audit: type=2000 audit(1747331582.782:1): state=initialized audit_enabled=0 res=1 May 15 17:53:03.859677 kernel: thermal_sys: Registered thermal governor 'step_wise' May 15 17:53:03.859685 kernel: thermal_sys: Registered thermal governor 'user_space' May 15 17:53:03.859693 kernel: cpuidle: using governor menu May 15 17:53:03.859701 kernel: ACPI: bus type PCI registered May 15 17:53:03.859711 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 15 17:53:03.859719 kernel: dca service started, version 1.12.1 May 15 17:53:03.859737 kernel: PCI: Using configuration type 1 for base access May 15 17:53:03.859746 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. May 15 17:53:03.859754 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages May 15 17:53:03.859762 kernel: ACPI: Added _OSI(Module Device) May 15 17:53:03.859770 kernel: ACPI: Added _OSI(Processor Device) May 15 17:53:03.859778 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 15 17:53:03.859786 kernel: ACPI: Added _OSI(Processor Aggregator Device) May 15 17:53:03.859796 kernel: ACPI: Added _OSI(Linux-Dell-Video) May 15 17:53:03.859804 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) May 15 17:53:03.859812 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) May 15 17:53:03.859820 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded May 15 17:53:03.859828 kernel: ACPI: Interpreter enabled May 15 17:53:03.859836 kernel: ACPI: PM: (supports S0 S3 S5) May 15 17:53:03.859844 kernel: ACPI: Using IOAPIC for interrupt routing May 15 17:53:03.859852 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug May 15 17:53:03.859860 kernel: ACPI: Enabled 2 GPEs in block 00 to 0F May 15 17:53:03.859870 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) May 15 17:53:03.859998 kernel: acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] May 15 17:53:03.860083 kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. May 15 17:53:03.860096 kernel: acpiphp: Slot [3] registered May 15 17:53:03.860105 kernel: acpiphp: Slot [4] registered May 15 17:53:03.860113 kernel: acpiphp: Slot [5] registered May 15 17:53:03.860121 kernel: acpiphp: Slot [6] registered May 15 17:53:03.860129 kernel: acpiphp: Slot [7] registered May 15 17:53:03.860140 kernel: acpiphp: Slot [8] registered May 15 17:53:03.860148 kernel: acpiphp: Slot [9] registered May 15 17:53:03.860156 kernel: acpiphp: Slot [10] registered May 15 17:53:03.860164 kernel: acpiphp: Slot [11] registered May 15 17:53:03.860172 kernel: acpiphp: Slot [12] registered May 15 17:53:03.860180 kernel: acpiphp: Slot [13] registered May 15 17:53:03.860188 kernel: acpiphp: Slot [14] registered May 15 17:53:03.860196 kernel: acpiphp: Slot [15] registered May 15 17:53:03.860204 kernel: acpiphp: Slot [16] registered May 15 17:53:03.860213 kernel: acpiphp: Slot [17] registered May 15 17:53:03.860221 kernel: acpiphp: Slot [18] registered May 15 17:53:03.860229 kernel: acpiphp: Slot [19] registered May 15 17:53:03.860237 kernel: acpiphp: Slot [20] registered May 15 17:53:03.860245 kernel: acpiphp: Slot [21] registered May 15 17:53:03.860272 kernel: acpiphp: Slot [22] registered May 15 17:53:03.860280 kernel: acpiphp: Slot [23] registered May 15 17:53:03.860288 kernel: acpiphp: Slot [24] registered May 15 17:53:03.860296 kernel: acpiphp: Slot [25] registered May 15 17:53:03.860304 kernel: acpiphp: Slot [26] registered May 15 17:53:03.860314 kernel: acpiphp: Slot [27] registered May 15 17:53:03.860322 kernel: acpiphp: Slot [28] registered May 15 17:53:03.860330 kernel: acpiphp: Slot [29] registered May 15 17:53:03.860338 kernel: acpiphp: Slot [30] registered May 15 17:53:03.860346 kernel: acpiphp: Slot [31] registered May 15 17:53:03.860354 kernel: PCI host bridge to bus 0000:00 May 15 17:53:03.860441 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] May 15 17:53:03.860516 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] May 15 17:53:03.860592 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] May 15 17:53:03.860665 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] May 15 17:53:03.860738 kernel: pci_bus 0000:00: root bus resource [mem 0xc000000000-0xc07fffffff window] May 15 17:53:03.860808 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] May 15 17:53:03.860904 kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 May 15 17:53:03.861001 kernel: pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 May 15 17:53:03.861097 kernel: pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 May 15 17:53:03.861181 kernel: pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] May 15 17:53:03.863635 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] May 15 17:53:03.863759 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] May 15 17:53:03.863853 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] May 15 17:53:03.863943 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] May 15 17:53:03.864041 kernel: pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 May 15 17:53:03.864136 kernel: pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI May 15 17:53:03.864221 kernel: pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB May 15 17:53:03.864331 kernel: pci 0000:00:02.0: [1af4:1050] type 00 class 0x030000 May 15 17:53:03.864417 kernel: pci 0000:00:02.0: reg 0x10: [mem 0xfe000000-0xfe7fffff pref] May 15 17:53:03.864502 kernel: pci 0000:00:02.0: reg 0x18: [mem 0xc000000000-0xc000003fff 64bit pref] May 15 17:53:03.864587 kernel: pci 0000:00:02.0: reg 0x20: [mem 0xfeb90000-0xfeb90fff] May 15 17:53:03.864669 kernel: pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfeb8ffff pref] May 15 17:53:03.864756 kernel: pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] May 15 17:53:03.864850 kernel: pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 May 15 17:53:03.864933 kernel: pci 0000:00:03.0: reg 0x10: [io 0xc080-0xc0bf] May 15 17:53:03.865015 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xfeb91000-0xfeb91fff] May 15 17:53:03.865097 kernel: pci 0000:00:03.0: reg 0x20: [mem 0xc000004000-0xc000007fff 64bit pref] May 15 17:53:03.865179 kernel: pci 0000:00:03.0: reg 0x30: [mem 0xfeb00000-0xfeb7ffff pref] May 15 17:53:03.867793 kernel: pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 May 15 17:53:03.867903 kernel: pci 0000:00:04.0: reg 0x10: [io 0xc000-0xc07f] May 15 17:53:03.867995 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xfeb92000-0xfeb92fff] May 15 17:53:03.868085 kernel: pci 0000:00:04.0: reg 0x20: [mem 0xc000008000-0xc00000bfff 64bit pref] May 15 17:53:03.868183 kernel: pci 0000:00:05.0: [1af4:1002] type 00 class 0x00ff00 May 15 17:53:03.868293 kernel: pci 0000:00:05.0: reg 0x10: [io 0xc0c0-0xc0ff] May 15 17:53:03.868385 kernel: pci 0000:00:05.0: reg 0x20: [mem 0xc00000c000-0xc00000ffff 64bit pref] May 15 17:53:03.868481 kernel: pci 0000:00:06.0: [1af4:1005] type 00 class 0x00ff00 May 15 17:53:03.868575 kernel: pci 0000:00:06.0: reg 0x10: [io 0xc100-0xc11f] May 15 17:53:03.868663 kernel: pci 0000:00:06.0: reg 0x14: [mem 0xfeb93000-0xfeb93fff] May 15 17:53:03.868752 kernel: pci 0000:00:06.0: reg 0x20: [mem 0xc000010000-0xc000013fff 64bit pref] May 15 17:53:03.868765 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 May 15 17:53:03.868774 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 May 15 17:53:03.868783 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 May 15 17:53:03.868792 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 May 15 17:53:03.868804 kernel: ACPI: PCI: Interrupt link LNKS configured for IRQ 9 May 15 17:53:03.868813 kernel: iommu: Default domain type: Translated May 15 17:53:03.868822 kernel: iommu: DMA domain TLB invalidation policy: lazy mode May 15 17:53:03.868909 kernel: pci 0000:00:02.0: vgaarb: setting as boot VGA device May 15 17:53:03.868998 kernel: pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none May 15 17:53:03.869086 kernel: pci 0000:00:02.0: vgaarb: bridge control possible May 15 17:53:03.869100 kernel: vgaarb: loaded May 15 17:53:03.869109 kernel: pps_core: LinuxPPS API ver. 1 registered May 15 17:53:03.869118 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti May 15 17:53:03.869130 kernel: PTP clock support registered May 15 17:53:03.869139 kernel: PCI: Using ACPI for IRQ routing May 15 17:53:03.869148 kernel: PCI: pci_cache_line_size set to 64 bytes May 15 17:53:03.869157 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] May 15 17:53:03.869167 kernel: e820: reserve RAM buffer [mem 0xbffdd000-0xbfffffff] May 15 17:53:03.869176 kernel: clocksource: Switched to clocksource kvm-clock May 15 17:53:03.869184 kernel: VFS: Disk quotas dquot_6.6.0 May 15 17:53:03.869192 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 15 17:53:03.869200 kernel: pnp: PnP ACPI init May 15 17:53:03.872384 kernel: pnp 00:03: [dma 2] May 15 17:53:03.872402 kernel: pnp: PnP ACPI: found 5 devices May 15 17:53:03.872412 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns May 15 17:53:03.872422 kernel: NET: Registered PF_INET protocol family May 15 17:53:03.872431 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) May 15 17:53:03.872441 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) May 15 17:53:03.872450 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 15 17:53:03.872459 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) May 15 17:53:03.872471 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) May 15 17:53:03.872481 kernel: TCP: Hash tables configured (established 32768 bind 32768) May 15 17:53:03.872490 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) May 15 17:53:03.872498 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) May 15 17:53:03.872507 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 15 17:53:03.872516 kernel: NET: Registered PF_XDP protocol family May 15 17:53:03.872601 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] May 15 17:53:03.872680 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] May 15 17:53:03.872759 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] May 15 17:53:03.872838 kernel: pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] May 15 17:53:03.872915 kernel: pci_bus 0000:00: resource 8 [mem 0xc000000000-0xc07fffffff window] May 15 17:53:03.873005 kernel: pci 0000:00:01.0: PIIX3: Enabling Passive Release May 15 17:53:03.873096 kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers May 15 17:53:03.873182 kernel: pci 0000:00:01.0: Activating ISA DMA hang workarounds May 15 17:53:03.873195 kernel: PCI: CLS 0 bytes, default 64 May 15 17:53:03.873205 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) May 15 17:53:03.873214 kernel: software IO TLB: mapped [mem 0x00000000bbfdd000-0x00000000bffdd000] (64MB) May 15 17:53:03.873226 kernel: Initialise system trusted keyrings May 15 17:53:03.873235 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 May 15 17:53:03.873244 kernel: Key type asymmetric registered May 15 17:53:03.873269 kernel: Asymmetric key parser 'x509' registered May 15 17:53:03.873278 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) May 15 17:53:03.873287 kernel: io scheduler mq-deadline registered May 15 17:53:03.873295 kernel: io scheduler kyber registered May 15 17:53:03.873304 kernel: io scheduler bfq registered May 15 17:53:03.873313 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 May 15 17:53:03.873324 kernel: ACPI: \_SB_.LNKB: Enabled at IRQ 10 May 15 17:53:03.873333 kernel: ACPI: \_SB_.LNKC: Enabled at IRQ 11 May 15 17:53:03.873342 kernel: ACPI: \_SB_.LNKD: Enabled at IRQ 11 May 15 17:53:03.873351 kernel: ACPI: \_SB_.LNKA: Enabled at IRQ 10 May 15 17:53:03.873360 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled May 15 17:53:03.873369 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A May 15 17:53:03.873377 kernel: random: crng init done May 15 17:53:03.873386 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 May 15 17:53:03.873395 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 May 15 17:53:03.873405 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 May 15 17:53:03.873414 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 May 15 17:53:03.873504 kernel: rtc_cmos 00:04: RTC can wake from S4 May 15 17:53:03.873586 kernel: rtc_cmos 00:04: registered as rtc0 May 15 17:53:03.873664 kernel: rtc_cmos 00:04: setting system clock to 2025-05-15T17:53:03 UTC (1747331583) May 15 17:53:03.873744 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram May 15 17:53:03.873756 kernel: NET: Registered PF_INET6 protocol family May 15 17:53:03.873765 kernel: Segment Routing with IPv6 May 15 17:53:03.873777 kernel: In-situ OAM (IOAM) with IPv6 May 15 17:53:03.873785 kernel: NET: Registered PF_PACKET protocol family May 15 17:53:03.873794 kernel: Key type dns_resolver registered May 15 17:53:03.873803 kernel: IPI shorthand broadcast: enabled May 15 17:53:03.873811 kernel: sched_clock: Marking stable (781874055, 163923085)->(1014759040, -68961900) May 15 17:53:03.873820 kernel: registered taskstats version 1 May 15 17:53:03.873829 kernel: Loading compiled-in X.509 certificates May 15 17:53:03.873838 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.15.182-flatcar: 04007c306af6b7696d09b3c2eafc1297036fd28e' May 15 17:53:03.873847 kernel: Key type .fscrypt registered May 15 17:53:03.873857 kernel: Key type fscrypt-provisioning registered May 15 17:53:03.873866 kernel: ima: No TPM chip found, activating TPM-bypass! May 15 17:53:03.873875 kernel: ima: Allocated hash algorithm: sha1 May 15 17:53:03.873883 kernel: ima: No architecture policies found May 15 17:53:03.873892 kernel: clk: Disabling unused clocks May 15 17:53:03.873901 kernel: Freeing unused kernel image (initmem) memory: 47472K May 15 17:53:03.873910 kernel: Write protecting the kernel read-only data: 28672k May 15 17:53:03.873918 kernel: Freeing unused kernel image (text/rodata gap) memory: 2040K May 15 17:53:03.873927 kernel: Freeing unused kernel image (rodata/data gap) memory: 612K May 15 17:53:03.873938 kernel: Run /init as init process May 15 17:53:03.873946 kernel: with arguments: May 15 17:53:03.873955 kernel: /init May 15 17:53:03.873963 kernel: with environment: May 15 17:53:03.873972 kernel: HOME=/ May 15 17:53:03.873980 kernel: TERM=linux May 15 17:53:03.873989 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a May 15 17:53:03.874001 systemd[1]: systemd 252 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE -TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) May 15 17:53:03.874014 systemd[1]: Detected virtualization kvm. May 15 17:53:03.874024 systemd[1]: Detected architecture x86-64. May 15 17:53:03.874033 systemd[1]: Running in initrd. May 15 17:53:03.874042 systemd[1]: No hostname configured, using default hostname. May 15 17:53:03.874051 systemd[1]: Hostname set to . May 15 17:53:03.874061 systemd[1]: Initializing machine ID from VM UUID. May 15 17:53:03.874071 systemd[1]: Queued start job for default target initrd.target. May 15 17:53:03.874082 systemd[1]: Started systemd-ask-password-console.path. May 15 17:53:03.874091 systemd[1]: Reached target cryptsetup.target. May 15 17:53:03.874100 systemd[1]: Reached target paths.target. May 15 17:53:03.874109 systemd[1]: Reached target slices.target. May 15 17:53:03.874118 systemd[1]: Reached target swap.target. May 15 17:53:03.874127 systemd[1]: Reached target timers.target. May 15 17:53:03.874137 systemd[1]: Listening on iscsid.socket. May 15 17:53:03.874146 systemd[1]: Listening on iscsiuio.socket. May 15 17:53:03.874158 systemd[1]: Listening on systemd-journald-audit.socket. May 15 17:53:03.874175 systemd[1]: Listening on systemd-journald-dev-log.socket. May 15 17:53:03.874185 systemd[1]: Listening on systemd-journald.socket. May 15 17:53:03.874194 systemd[1]: Listening on systemd-networkd.socket. May 15 17:53:03.874203 systemd[1]: Listening on systemd-udevd-control.socket. May 15 17:53:03.874212 systemd[1]: Listening on systemd-udevd-kernel.socket. May 15 17:53:03.874222 systemd[1]: Reached target sockets.target. May 15 17:53:03.874231 systemd[1]: Starting kmod-static-nodes.service... May 15 17:53:03.874241 systemd[1]: Finished network-cleanup.service. May 15 17:53:03.874262 systemd[1]: Starting systemd-fsck-usr.service... May 15 17:53:03.874272 systemd[1]: Starting systemd-journald.service... May 15 17:53:03.874281 systemd[1]: Starting systemd-modules-load.service... May 15 17:53:03.874290 systemd[1]: Starting systemd-resolved.service... May 15 17:53:03.874299 systemd[1]: Starting systemd-vconsole-setup.service... May 15 17:53:03.874308 systemd[1]: Finished kmod-static-nodes.service. May 15 17:53:03.874321 systemd-journald[186]: Journal started May 15 17:53:03.874365 systemd-journald[186]: Runtime Journal (/run/log/journal/115541c0e1ef4b8e8735142ada7d9521) is 8.0M, max 78.4M, 70.4M free. May 15 17:53:03.858614 systemd-modules-load[187]: Inserted module 'overlay' May 15 17:53:03.933832 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. May 15 17:53:03.933857 kernel: Bridge firewalling registered May 15 17:53:03.933868 systemd[1]: Started systemd-journald.service. May 15 17:53:03.933882 kernel: audit: type=1130 audit(1747331583.926:2): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:03.926000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:03.906304 systemd-modules-load[187]: Inserted module 'br_netfilter' May 15 17:53:03.940775 kernel: audit: type=1130 audit(1747331583.934:3): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:03.940797 kernel: SCSI subsystem initialized May 15 17:53:03.934000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:03.907378 systemd-resolved[188]: Positive Trust Anchors: May 15 17:53:03.907390 systemd-resolved[188]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 15 17:53:03.907428 systemd-resolved[188]: 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 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test May 15 17:53:03.910352 systemd-resolved[188]: Defaulting to hostname 'linux'. May 15 17:53:03.950317 kernel: audit: type=1130 audit(1747331583.944:4): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:03.944000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:03.934400 systemd[1]: Started systemd-resolved.service. May 15 17:53:03.955941 kernel: audit: type=1130 audit(1747331583.950:5): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-usr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:03.950000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-usr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:03.945531 systemd[1]: Finished systemd-fsck-usr.service. May 15 17:53:03.967512 kernel: audit: type=1130 audit(1747331583.956:6): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:03.967529 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. May 15 17:53:03.967540 kernel: device-mapper: uevent: version 1.0.3 May 15 17:53:03.967551 kernel: device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com May 15 17:53:03.956000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:03.951036 systemd[1]: Finished systemd-vconsole-setup.service. May 15 17:53:03.956579 systemd[1]: Reached target nss-lookup.target. May 15 17:53:03.968803 systemd[1]: Starting dracut-cmdline-ask.service... May 15 17:53:03.969091 systemd-modules-load[187]: Inserted module 'dm_multipath' May 15 17:53:03.970981 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... May 15 17:53:03.984429 kernel: audit: type=1130 audit(1747331583.973:7): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:03.973000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:03.972981 systemd[1]: Finished systemd-modules-load.service. May 15 17:53:03.997338 kernel: audit: type=1130 audit(1747331583.984:8): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:03.997359 kernel: audit: type=1130 audit(1747331583.991:9): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:03.984000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:03.991000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:03.974221 systemd[1]: Starting systemd-sysctl.service... May 15 17:53:03.983997 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. May 15 17:53:04.003756 kernel: audit: type=1130 audit(1747331583.997:10): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:03.997000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:03.986160 systemd[1]: Finished systemd-sysctl.service. May 15 17:53:03.992071 systemd[1]: Finished dracut-cmdline-ask.service. May 15 17:53:03.998827 systemd[1]: Starting dracut-cmdline.service... May 15 17:53:04.013364 dracut-cmdline[208]: dracut-dracut-053 May 15 17:53:04.015316 dracut-cmdline[208]: 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 verity.usrhash=f8c1bc5ff10765e781843bfc97fc5357002a3f8a120201a0e954fce1d2ba48f0 May 15 17:53:04.082328 kernel: Loading iSCSI transport class v2.0-870. May 15 17:53:04.103317 kernel: iscsi: registered transport (tcp) May 15 17:53:04.131355 kernel: iscsi: registered transport (qla4xxx) May 15 17:53:04.131431 kernel: QLogic iSCSI HBA Driver May 15 17:53:04.188285 systemd[1]: Finished dracut-cmdline.service. May 15 17:53:04.188000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:04.190439 systemd[1]: Starting dracut-pre-udev.service... May 15 17:53:04.269362 kernel: raid6: sse2x4 gen() 8285 MB/s May 15 17:53:04.287444 kernel: raid6: sse2x4 xor() 4896 MB/s May 15 17:53:04.305476 kernel: raid6: sse2x2 gen() 13334 MB/s May 15 17:53:04.323478 kernel: raid6: sse2x2 xor() 8272 MB/s May 15 17:53:04.341354 kernel: raid6: sse2x1 gen() 10571 MB/s May 15 17:53:04.363632 kernel: raid6: sse2x1 xor() 6665 MB/s May 15 17:53:04.363691 kernel: raid6: using algorithm sse2x2 gen() 13334 MB/s May 15 17:53:04.363719 kernel: raid6: .... xor() 8272 MB/s, rmw enabled May 15 17:53:04.364910 kernel: raid6: using ssse3x2 recovery algorithm May 15 17:53:04.381354 kernel: xor: measuring software checksum speed May 15 17:53:04.381412 kernel: prefetch64-sse : 15889 MB/sec May 15 17:53:04.383850 kernel: generic_sse : 15626 MB/sec May 15 17:53:04.383918 kernel: xor: using function: prefetch64-sse (15889 MB/sec) May 15 17:53:04.512345 kernel: Btrfs loaded, crc32c=crc32c-intel, zoned=no, fsverity=no May 15 17:53:04.528005 systemd[1]: Finished dracut-pre-udev.service. May 15 17:53:04.528000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:04.528000 audit: BPF prog-id=7 op=LOAD May 15 17:53:04.529000 audit: BPF prog-id=8 op=LOAD May 15 17:53:04.529768 systemd[1]: Starting systemd-udevd.service... May 15 17:53:04.544070 systemd-udevd[385]: Using default interface naming scheme 'v252'. May 15 17:53:04.549000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:04.548981 systemd[1]: Started systemd-udevd.service. May 15 17:53:04.553895 systemd[1]: Starting dracut-pre-trigger.service... May 15 17:53:04.578902 dracut-pre-trigger[404]: rd.md=0: removing MD RAID activation May 15 17:53:04.624677 systemd[1]: Finished dracut-pre-trigger.service. May 15 17:53:04.625000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:04.627679 systemd[1]: Starting systemd-udev-trigger.service... May 15 17:53:04.671606 systemd[1]: Finished systemd-udev-trigger.service. May 15 17:53:04.673000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:04.732274 kernel: virtio_blk virtio2: [vda] 20971520 512-byte logical blocks (10.7 GB/10.0 GiB) May 15 17:53:04.757881 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. May 15 17:53:04.757904 kernel: GPT:17805311 != 20971519 May 15 17:53:04.757916 kernel: GPT:Alternate GPT header not at the end of the disk. May 15 17:53:04.757928 kernel: GPT:17805311 != 20971519 May 15 17:53:04.757939 kernel: GPT: Use GNU Parted to correct GPT errors. May 15 17:53:04.757955 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 15 17:53:04.776281 kernel: libata version 3.00 loaded. May 15 17:53:04.782957 kernel: ata_piix 0000:00:01.1: version 2.13 May 15 17:53:04.791499 kernel: scsi host0: ata_piix May 15 17:53:04.791636 kernel: scsi host1: ata_piix May 15 17:53:04.791769 kernel: ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 May 15 17:53:04.791790 kernel: ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 May 15 17:53:04.791802 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by (udev-worker) (443) May 15 17:53:04.801756 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device. May 15 17:53:04.854724 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device. May 15 17:53:04.859473 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device. May 15 17:53:04.860161 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device. May 15 17:53:04.865873 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. May 15 17:53:04.867590 systemd[1]: Starting disk-uuid.service... May 15 17:53:04.906293 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 15 17:53:04.906479 disk-uuid[471]: Primary Header is updated. May 15 17:53:04.906479 disk-uuid[471]: Secondary Entries is updated. May 15 17:53:04.906479 disk-uuid[471]: Secondary Header is updated. May 15 17:53:05.933084 disk-uuid[473]: The operation has completed successfully. May 15 17:53:05.934931 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 15 17:53:05.998077 systemd[1]: disk-uuid.service: Deactivated successfully. May 15 17:53:05.999577 systemd[1]: Finished disk-uuid.service. May 15 17:53:06.000000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=disk-uuid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:06.000000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=disk-uuid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:06.023906 systemd[1]: Starting verity-setup.service... May 15 17:53:06.053333 kernel: device-mapper: verity: sha256 using implementation "sha256-ssse3" May 15 17:53:06.210498 systemd[1]: Found device dev-mapper-usr.device. May 15 17:53:06.214079 systemd[1]: Mounting sysusr-usr.mount... May 15 17:53:06.223486 systemd[1]: Finished verity-setup.service. May 15 17:53:06.224000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=verity-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:06.385295 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: norecovery. Quota mode: none. May 15 17:53:06.386565 systemd[1]: Mounted sysusr-usr.mount. May 15 17:53:06.387857 systemd[1]: afterburn-network-kargs.service was skipped because no trigger condition checks were met. May 15 17:53:06.389188 systemd[1]: Starting ignition-setup.service... May 15 17:53:06.391512 systemd[1]: Starting parse-ip-for-networkd.service... May 15 17:53:06.414521 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm May 15 17:53:06.414566 kernel: BTRFS info (device vda6): using free space tree May 15 17:53:06.414579 kernel: BTRFS info (device vda6): has skinny extents May 15 17:53:06.454177 systemd[1]: mnt-oem.mount: Deactivated successfully. May 15 17:53:06.484311 systemd[1]: Finished ignition-setup.service. May 15 17:53:06.485000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:06.487678 systemd[1]: Starting ignition-fetch-offline.service... May 15 17:53:06.504565 systemd[1]: Finished parse-ip-for-networkd.service. May 15 17:53:06.505000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=parse-ip-for-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:06.507000 audit: BPF prog-id=9 op=LOAD May 15 17:53:06.508543 systemd[1]: Starting systemd-networkd.service... May 15 17:53:06.553952 systemd-networkd[643]: lo: Link UP May 15 17:53:06.553967 systemd-networkd[643]: lo: Gained carrier May 15 17:53:06.554469 systemd-networkd[643]: Enumeration completed May 15 17:53:06.557000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:06.554690 systemd-networkd[643]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 15 17:53:06.556488 systemd-networkd[643]: eth0: Link UP May 15 17:53:06.556492 systemd-networkd[643]: eth0: Gained carrier May 15 17:53:06.556771 systemd[1]: Started systemd-networkd.service. May 15 17:53:06.557884 systemd[1]: Reached target network.target. May 15 17:53:06.559989 systemd[1]: Starting iscsiuio.service... May 15 17:53:06.568201 systemd[1]: Started iscsiuio.service. May 15 17:53:06.568000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:06.569888 systemd[1]: Starting iscsid.service... May 15 17:53:06.572845 iscsid[648]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi May 15 17:53:06.572845 iscsid[648]: iscsid: Warning: InitiatorName file /etc/iscsi/initiatorname.iscsi does not exist or does not contain a properly formatted InitiatorName. If using software iscsi (iscsi_tcp or ib_iser) or partial offload (bnx2i or cxgbi iscsi), you may not be able to log into or discover targets. Please create a file /etc/iscsi/initiatorname.iscsi that contains a sting with the format: InitiatorName=iqn.yyyy-mm.[:identifier]. May 15 17:53:06.572845 iscsid[648]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. May 15 17:53:06.572845 iscsid[648]: If using hardware iscsi like qla4xxx this message can be ignored. May 15 17:53:06.572845 iscsid[648]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi May 15 17:53:06.574000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:06.581703 iscsid[648]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf May 15 17:53:06.574344 systemd[1]: Started iscsid.service. May 15 17:53:06.575660 systemd[1]: Starting dracut-initqueue.service... May 15 17:53:06.576366 systemd-networkd[643]: eth0: DHCPv4 address 172.24.4.161/24, gateway 172.24.4.1 acquired from 172.24.4.1 May 15 17:53:06.588633 systemd[1]: Finished dracut-initqueue.service. May 15 17:53:06.592000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:06.592872 systemd[1]: Reached target remote-fs-pre.target. May 15 17:53:06.594368 systemd[1]: Reached target remote-cryptsetup.target. May 15 17:53:06.596065 systemd[1]: Reached target remote-fs.target. May 15 17:53:06.598133 systemd[1]: Starting dracut-pre-mount.service... May 15 17:53:06.610950 systemd[1]: Finished dracut-pre-mount.service. May 15 17:53:06.611000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:06.857297 ignition[627]: Ignition 2.14.0 May 15 17:53:06.857324 ignition[627]: Stage: fetch-offline May 15 17:53:06.857434 ignition[627]: reading system config file "/usr/lib/ignition/base.d/base.ign" May 15 17:53:06.862028 systemd[1]: Finished ignition-fetch-offline.service. May 15 17:53:06.857481 ignition[627]: parsing config with SHA512: ce918cf8568bff1426dda9ea05b778568a1626fcf4c1bded9ebe13fee104bc1b92fac5f7093a3bfc7d99777c3793d01249c863845c2ca48413d9477d40af178a May 15 17:53:06.859576 ignition[627]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" May 15 17:53:06.864000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:06.866402 systemd[1]: Starting ignition-fetch.service... May 15 17:53:06.859797 ignition[627]: parsed url from cmdline: "" May 15 17:53:06.859806 ignition[627]: no config URL provided May 15 17:53:06.859818 ignition[627]: reading system config file "/usr/lib/ignition/user.ign" May 15 17:53:06.859836 ignition[627]: no config at "/usr/lib/ignition/user.ign" May 15 17:53:06.859845 ignition[627]: failed to fetch config: resource requires networking May 15 17:53:06.860423 ignition[627]: Ignition finished successfully May 15 17:53:06.884091 ignition[666]: Ignition 2.14.0 May 15 17:53:06.884109 ignition[666]: Stage: fetch May 15 17:53:06.884380 ignition[666]: reading system config file "/usr/lib/ignition/base.d/base.ign" May 15 17:53:06.884419 ignition[666]: parsing config with SHA512: ce918cf8568bff1426dda9ea05b778568a1626fcf4c1bded9ebe13fee104bc1b92fac5f7093a3bfc7d99777c3793d01249c863845c2ca48413d9477d40af178a May 15 17:53:06.886489 ignition[666]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" May 15 17:53:06.886709 ignition[666]: parsed url from cmdline: "" May 15 17:53:06.886718 ignition[666]: no config URL provided May 15 17:53:06.886730 ignition[666]: reading system config file "/usr/lib/ignition/user.ign" May 15 17:53:06.886748 ignition[666]: no config at "/usr/lib/ignition/user.ign" May 15 17:53:06.891084 ignition[666]: config drive ("/dev/disk/by-label/config-2") not found. Waiting... May 15 17:53:06.891129 ignition[666]: config drive ("/dev/disk/by-label/CONFIG-2") not found. Waiting... May 15 17:53:06.893981 ignition[666]: GET http://169.254.169.254/openstack/latest/user_data: attempt #1 May 15 17:53:07.156420 ignition[666]: GET result: OK May 15 17:53:07.156513 ignition[666]: parsing config with SHA512: 3fb845e1ea37cf5adbef76bda715ceb04cd1a6c073d284cdc846f60cbd5003e5f4275a4495290fe5841c4d33b04fa2c7868973a0a98985b1be4081a61cb1816a May 15 17:53:07.184456 unknown[666]: fetched base config from "system" May 15 17:53:07.184488 unknown[666]: fetched base config from "system" May 15 17:53:07.185133 ignition[666]: fetch: fetch complete May 15 17:53:07.189000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.184503 unknown[666]: fetched user config from "openstack" May 15 17:53:07.185146 ignition[666]: fetch: fetch passed May 15 17:53:07.187874 systemd[1]: Finished ignition-fetch.service. May 15 17:53:07.185228 ignition[666]: Ignition finished successfully May 15 17:53:07.191495 systemd[1]: Starting ignition-kargs.service... May 15 17:53:07.223572 ignition[672]: Ignition 2.14.0 May 15 17:53:07.223598 ignition[672]: Stage: kargs May 15 17:53:07.223883 ignition[672]: reading system config file "/usr/lib/ignition/base.d/base.ign" May 15 17:53:07.223925 ignition[672]: parsing config with SHA512: ce918cf8568bff1426dda9ea05b778568a1626fcf4c1bded9ebe13fee104bc1b92fac5f7093a3bfc7d99777c3793d01249c863845c2ca48413d9477d40af178a May 15 17:53:07.226187 ignition[672]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" May 15 17:53:07.230000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.229967 systemd[1]: Finished ignition-kargs.service. May 15 17:53:07.228353 ignition[672]: kargs: kargs passed May 15 17:53:07.232892 systemd[1]: Starting ignition-disks.service... May 15 17:53:07.228445 ignition[672]: Ignition finished successfully May 15 17:53:07.250961 ignition[677]: Ignition 2.14.0 May 15 17:53:07.250986 ignition[677]: Stage: disks May 15 17:53:07.251339 ignition[677]: reading system config file "/usr/lib/ignition/base.d/base.ign" May 15 17:53:07.251389 ignition[677]: parsing config with SHA512: ce918cf8568bff1426dda9ea05b778568a1626fcf4c1bded9ebe13fee104bc1b92fac5f7093a3bfc7d99777c3793d01249c863845c2ca48413d9477d40af178a May 15 17:53:07.253651 ignition[677]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" May 15 17:53:07.255502 ignition[677]: disks: disks passed May 15 17:53:07.260000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.257455 systemd[1]: Finished ignition-disks.service. May 15 17:53:07.255592 ignition[677]: Ignition finished successfully May 15 17:53:07.260788 systemd[1]: Reached target initrd-root-device.target. May 15 17:53:07.263023 systemd[1]: Reached target local-fs-pre.target. May 15 17:53:07.265523 systemd[1]: Reached target local-fs.target. May 15 17:53:07.267955 systemd[1]: Reached target sysinit.target. May 15 17:53:07.270415 systemd[1]: Reached target basic.target. May 15 17:53:07.274517 systemd[1]: Starting systemd-fsck-root.service... May 15 17:53:07.309091 systemd-fsck[684]: ROOT: clean, 623/1628000 files, 124060/1617920 blocks May 15 17:53:07.325595 systemd[1]: Finished systemd-fsck-root.service. May 15 17:53:07.326000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.328518 systemd[1]: Mounting sysroot.mount... May 15 17:53:07.355330 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. May 15 17:53:07.355709 systemd[1]: Mounted sysroot.mount. May 15 17:53:07.356424 systemd[1]: Reached target initrd-root-fs.target. May 15 17:53:07.360090 systemd[1]: Mounting sysroot-usr.mount... May 15 17:53:07.361027 systemd[1]: flatcar-metadata-hostname.service was skipped because no trigger condition checks were met. May 15 17:53:07.361734 systemd[1]: Starting flatcar-openstack-hostname.service... May 15 17:53:07.362375 systemd[1]: ignition-remount-sysroot.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). May 15 17:53:07.362406 systemd[1]: Reached target ignition-diskful.target. May 15 17:53:07.368951 systemd[1]: Mounted sysroot-usr.mount. May 15 17:53:07.375852 systemd[1]: Mounting sysroot-usr-share-oem.mount... May 15 17:53:07.378022 systemd[1]: Starting initrd-setup-root.service... May 15 17:53:07.392469 initrd-setup-root[696]: cut: /sysroot/etc/passwd: No such file or directory May 15 17:53:07.393362 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by mount (691) May 15 17:53:07.403639 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm May 15 17:53:07.403698 kernel: BTRFS info (device vda6): using free space tree May 15 17:53:07.403711 kernel: BTRFS info (device vda6): has skinny extents May 15 17:53:07.412973 initrd-setup-root[720]: cut: /sysroot/etc/group: No such file or directory May 15 17:53:07.424377 initrd-setup-root[730]: cut: /sysroot/etc/shadow: No such file or directory May 15 17:53:07.430337 systemd[1]: Mounted sysroot-usr-share-oem.mount. May 15 17:53:07.437399 initrd-setup-root[738]: cut: /sysroot/etc/gshadow: No such file or directory May 15 17:53:07.553000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.552717 systemd[1]: Finished initrd-setup-root.service. May 15 17:53:07.557148 systemd[1]: Starting ignition-mount.service... May 15 17:53:07.561602 systemd[1]: Starting sysroot-boot.service... May 15 17:53:07.579661 systemd[1]: sysusr-usr-share-oem.mount: Deactivated successfully. May 15 17:53:07.581502 systemd[1]: sysroot-usr-share-oem.mount: Deactivated successfully. May 15 17:53:07.610561 ignition[759]: INFO : Ignition 2.14.0 May 15 17:53:07.611772 ignition[759]: INFO : Stage: mount May 15 17:53:07.612604 ignition[759]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign" May 15 17:53:07.613452 ignition[759]: DEBUG : parsing config with SHA512: ce918cf8568bff1426dda9ea05b778568a1626fcf4c1bded9ebe13fee104bc1b92fac5f7093a3bfc7d99777c3793d01249c863845c2ca48413d9477d40af178a May 15 17:53:07.616192 ignition[759]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/openstack" May 15 17:53:07.618233 ignition[759]: INFO : mount: mount passed May 15 17:53:07.618845 ignition[759]: INFO : Ignition finished successfully May 15 17:53:07.620000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.620190 systemd[1]: Finished ignition-mount.service. May 15 17:53:07.630825 coreos-metadata[690]: May 15 17:53:07.630 INFO Fetching http://169.254.169.254/latest/meta-data/hostname: Attempt #1 May 15 17:53:07.635366 systemd[1]: Finished sysroot-boot.service. May 15 17:53:07.635000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.653141 coreos-metadata[690]: May 15 17:53:07.653 INFO Fetch successful May 15 17:53:07.653141 coreos-metadata[690]: May 15 17:53:07.653 INFO wrote hostname ci-3510-3-100-6622fa5004.novalocal to /sysroot/etc/hostname May 15 17:53:07.656678 systemd[1]: flatcar-openstack-hostname.service: Deactivated successfully. May 15 17:53:07.656775 systemd[1]: Finished flatcar-openstack-hostname.service. May 15 17:53:07.658000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=flatcar-openstack-hostname comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.658000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=flatcar-openstack-hostname comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.659404 systemd[1]: Starting ignition-files.service... May 15 17:53:07.668816 systemd[1]: Mounting sysroot-usr-share-oem.mount... May 15 17:53:07.679484 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 scanned by mount (767) May 15 17:53:07.683568 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm May 15 17:53:07.683608 kernel: BTRFS info (device vda6): using free space tree May 15 17:53:07.683628 kernel: BTRFS info (device vda6): has skinny extents May 15 17:53:07.695767 systemd[1]: Mounted sysroot-usr-share-oem.mount. May 15 17:53:07.711244 ignition[786]: INFO : Ignition 2.14.0 May 15 17:53:07.711244 ignition[786]: INFO : Stage: files May 15 17:53:07.712516 ignition[786]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign" May 15 17:53:07.712516 ignition[786]: DEBUG : parsing config with SHA512: ce918cf8568bff1426dda9ea05b778568a1626fcf4c1bded9ebe13fee104bc1b92fac5f7093a3bfc7d99777c3793d01249c863845c2ca48413d9477d40af178a May 15 17:53:07.714385 ignition[786]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/openstack" May 15 17:53:07.719122 ignition[786]: DEBUG : files: compiled without relabeling support, skipping May 15 17:53:07.720099 ignition[786]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" May 15 17:53:07.720099 ignition[786]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" May 15 17:53:07.724017 ignition[786]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" May 15 17:53:07.724844 ignition[786]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" May 15 17:53:07.725870 unknown[786]: wrote ssh authorized keys file for user: core May 15 17:53:07.726622 ignition[786]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" May 15 17:53:07.727448 ignition[786]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" May 15 17:53:07.727448 ignition[786]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" May 15 17:53:07.729221 ignition[786]: INFO : files: op(4): [started] processing unit "coreos-metadata-sshkeys@.service" May 15 17:53:07.730082 ignition[786]: INFO : files: op(4): op(5): [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" May 15 17:53:07.730082 ignition[786]: INFO : files: op(4): op(5): [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" May 15 17:53:07.730082 ignition[786]: INFO : files: op(4): [finished] processing unit "coreos-metadata-sshkeys@.service" May 15 17:53:07.730082 ignition[786]: INFO : files: op(6): [started] processing unit "etcd-member.service" May 15 17:53:07.730082 ignition[786]: INFO : files: op(6): op(7): [started] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" May 15 17:53:07.739829 ignition[786]: INFO : files: op(6): op(7): [finished] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" May 15 17:53:07.739829 ignition[786]: INFO : files: op(6): [finished] processing unit "etcd-member.service" May 15 17:53:07.739829 ignition[786]: INFO : files: op(8): [started] processing unit "coreos-metadata.service" May 15 17:53:07.739829 ignition[786]: INFO : files: op(8): op(9): [started] writing systemd drop-in "20-clct-provider-override.conf" at "/sysroot/etc/systemd/system/coreos-metadata.service.d/20-clct-provider-override.conf" May 15 17:53:07.739829 ignition[786]: INFO : files: op(8): op(9): [finished] writing systemd drop-in "20-clct-provider-override.conf" at "/sysroot/etc/systemd/system/coreos-metadata.service.d/20-clct-provider-override.conf" May 15 17:53:07.739829 ignition[786]: INFO : files: op(8): [finished] processing unit "coreos-metadata.service" May 15 17:53:07.739829 ignition[786]: INFO : files: op(a): [started] setting preset to enabled for "coreos-metadata-sshkeys@.service " May 15 17:53:07.739829 ignition[786]: INFO : files: op(a): [finished] setting preset to enabled for "coreos-metadata-sshkeys@.service " May 15 17:53:07.739829 ignition[786]: INFO : files: op(b): [started] setting preset to enabled for "etcd-member.service" May 15 17:53:07.739829 ignition[786]: INFO : files: op(b): [finished] setting preset to enabled for "etcd-member.service" May 15 17:53:07.745000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.764867 ignition[786]: INFO : files: createResultFile: createFiles: op(c): [started] writing file "/sysroot/etc/.ignition-result.json" May 15 17:53:07.764867 ignition[786]: INFO : files: createResultFile: createFiles: op(c): [finished] writing file "/sysroot/etc/.ignition-result.json" May 15 17:53:07.764867 ignition[786]: INFO : files: files passed May 15 17:53:07.764867 ignition[786]: INFO : Ignition finished successfully May 15 17:53:07.765000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root-after-ignition comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.744648 systemd[1]: Finished ignition-files.service. May 15 17:53:07.746427 systemd[1]: Starting initrd-setup-root-after-ignition.service... May 15 17:53:07.771626 initrd-setup-root-after-ignition[810]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 15 17:53:07.756383 systemd[1]: torcx-profile-populate.service was skipped because of an unmet condition check (ConditionPathExists=/sysroot/etc/torcx/next-profile). May 15 17:53:07.757195 systemd[1]: Starting ignition-quench.service... May 15 17:53:07.764249 systemd[1]: Finished initrd-setup-root-after-ignition.service. May 15 17:53:07.765886 systemd[1]: Reached target ignition-complete.target. May 15 17:53:07.768939 systemd[1]: Starting initrd-parse-etc.service... May 15 17:53:07.777409 systemd[1]: ignition-quench.service: Deactivated successfully. May 15 17:53:07.777953 systemd[1]: Finished ignition-quench.service. May 15 17:53:07.779000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.779000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.791481 systemd[1]: initrd-parse-etc.service: Deactivated successfully. May 15 17:53:07.792842 systemd[1]: Finished initrd-parse-etc.service. May 15 17:53:07.794000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.794000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.794906 systemd[1]: Reached target initrd-fs.target. May 15 17:53:07.795831 systemd[1]: Reached target initrd.target. May 15 17:53:07.797367 systemd[1]: dracut-mount.service was skipped because no trigger condition checks were met. May 15 17:53:07.798589 systemd[1]: Starting dracut-pre-pivot.service... May 15 17:53:07.815000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.815568 systemd[1]: Finished dracut-pre-pivot.service. May 15 17:53:07.817036 systemd[1]: Starting initrd-cleanup.service... May 15 17:53:07.829081 systemd[1]: Stopped target nss-lookup.target. May 15 17:53:07.830378 systemd[1]: Stopped target remote-cryptsetup.target. May 15 17:53:07.831100 systemd[1]: Stopped target timers.target. May 15 17:53:07.832238 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. May 15 17:53:07.832000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.832409 systemd[1]: Stopped dracut-pre-pivot.service. May 15 17:53:07.833356 systemd[1]: Stopped target initrd.target. May 15 17:53:07.834368 systemd[1]: Stopped target basic.target. May 15 17:53:07.835362 systemd[1]: Stopped target ignition-complete.target. May 15 17:53:07.836433 systemd[1]: Stopped target ignition-diskful.target. May 15 17:53:07.837556 systemd[1]: Stopped target initrd-root-device.target. May 15 17:53:07.838648 systemd[1]: Stopped target remote-fs.target. May 15 17:53:07.839684 systemd[1]: Stopped target remote-fs-pre.target. May 15 17:53:07.840813 systemd[1]: Stopped target sysinit.target. May 15 17:53:07.841836 systemd[1]: Stopped target local-fs.target. May 15 17:53:07.842905 systemd[1]: Stopped target local-fs-pre.target. May 15 17:53:07.843958 systemd[1]: Stopped target swap.target. May 15 17:53:07.845000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.844904 systemd[1]: dracut-pre-mount.service: Deactivated successfully. May 15 17:53:07.845054 systemd[1]: Stopped dracut-pre-mount.service. May 15 17:53:07.847000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.845996 systemd[1]: Stopped target cryptsetup.target. May 15 17:53:07.848000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root-after-ignition comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.846873 systemd[1]: dracut-initqueue.service: Deactivated successfully. May 15 17:53:07.849000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.847009 systemd[1]: Stopped dracut-initqueue.service. May 15 17:53:07.848137 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. May 15 17:53:07.859000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.860000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.848340 systemd[1]: Stopped initrd-setup-root-after-ignition.service. May 15 17:53:07.866133 iscsid[648]: iscsid shutting down. May 15 17:53:07.867000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.849108 systemd[1]: ignition-files.service: Deactivated successfully. May 15 17:53:07.870572 ignition[824]: INFO : Ignition 2.14.0 May 15 17:53:07.870572 ignition[824]: INFO : Stage: umount May 15 17:53:07.870572 ignition[824]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign" May 15 17:53:07.870572 ignition[824]: DEBUG : parsing config with SHA512: ce918cf8568bff1426dda9ea05b778568a1626fcf4c1bded9ebe13fee104bc1b92fac5f7093a3bfc7d99777c3793d01249c863845c2ca48413d9477d40af178a May 15 17:53:07.870572 ignition[824]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/openstack" May 15 17:53:07.870572 ignition[824]: INFO : umount: umount passed May 15 17:53:07.870572 ignition[824]: INFO : Ignition finished successfully May 15 17:53:07.871000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.871000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.873000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.875000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.876000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.878000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.879000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.849220 systemd[1]: Stopped ignition-files.service. May 15 17:53:07.851036 systemd[1]: Stopping ignition-mount.service... May 15 17:53:07.851869 systemd[1]: Stopping iscsid.service... May 15 17:53:07.858911 systemd[1]: Stopping sysroot-boot.service... May 15 17:53:07.859410 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. May 15 17:53:07.859537 systemd[1]: Stopped systemd-udev-trigger.service. May 15 17:53:07.886000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.860336 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. May 15 17:53:07.891000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.860503 systemd[1]: Stopped dracut-pre-trigger.service. May 15 17:53:07.866000 systemd[1]: iscsid.service: Deactivated successfully. May 15 17:53:07.893000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.866779 systemd[1]: Stopped iscsid.service. May 15 17:53:07.869846 systemd[1]: initrd-cleanup.service: Deactivated successfully. May 15 17:53:07.896000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.870013 systemd[1]: Finished initrd-cleanup.service. May 15 17:53:07.872115 systemd[1]: ignition-mount.service: Deactivated successfully. May 15 17:53:07.872202 systemd[1]: Stopped ignition-mount.service. May 15 17:53:07.874136 systemd[1]: ignition-disks.service: Deactivated successfully. May 15 17:53:07.874183 systemd[1]: Stopped ignition-disks.service. May 15 17:53:07.876120 systemd[1]: ignition-kargs.service: Deactivated successfully. May 15 17:53:07.876165 systemd[1]: Stopped ignition-kargs.service. May 15 17:53:07.877444 systemd[1]: ignition-fetch.service: Deactivated successfully. May 15 17:53:07.877482 systemd[1]: Stopped ignition-fetch.service. May 15 17:53:07.878511 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. May 15 17:53:07.878552 systemd[1]: Stopped ignition-fetch-offline.service. May 15 17:53:07.879843 systemd[1]: Stopped target paths.target. May 15 17:53:07.880627 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. May 15 17:53:07.884402 systemd[1]: Stopped systemd-ask-password-console.path. May 15 17:53:07.885064 systemd[1]: Stopped target slices.target. May 15 17:53:07.885553 systemd[1]: Stopped target sockets.target. May 15 17:53:07.886018 systemd[1]: iscsid.socket: Deactivated successfully. May 15 17:53:07.886051 systemd[1]: Closed iscsid.socket. May 15 17:53:07.908000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.886587 systemd[1]: ignition-setup.service: Deactivated successfully. May 15 17:53:07.886631 systemd[1]: Stopped ignition-setup.service. May 15 17:53:07.910000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.887227 systemd[1]: Stopping iscsiuio.service... May 15 17:53:07.911000 audit: BPF prog-id=6 op=UNLOAD May 15 17:53:07.891397 systemd[1]: iscsiuio.service: Deactivated successfully. May 15 17:53:07.891487 systemd[1]: Stopped iscsiuio.service. May 15 17:53:07.892964 systemd[1]: sysroot-boot.service: Deactivated successfully. May 15 17:53:07.893050 systemd[1]: Stopped sysroot-boot.service. May 15 17:53:07.894223 systemd[1]: Stopped target network.target. May 15 17:53:07.916000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=parse-ip-for-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.894750 systemd[1]: iscsiuio.socket: Deactivated successfully. May 15 17:53:07.921000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.894780 systemd[1]: Closed iscsiuio.socket. May 15 17:53:07.922000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.895809 systemd[1]: initrd-setup-root.service: Deactivated successfully. May 15 17:53:07.895851 systemd[1]: Stopped initrd-setup-root.service. May 15 17:53:07.897038 systemd[1]: Stopping systemd-networkd.service... May 15 17:53:07.897716 systemd[1]: Stopping systemd-resolved.service... May 15 17:53:07.905300 systemd-networkd[643]: eth0: DHCPv6 lease lost May 15 17:53:07.930000 audit: BPF prog-id=9 op=UNLOAD May 15 17:53:07.907152 systemd[1]: systemd-resolved.service: Deactivated successfully. May 15 17:53:07.907244 systemd[1]: Stopped systemd-resolved.service. May 15 17:53:07.933000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=network-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.909222 systemd[1]: systemd-networkd.service: Deactivated successfully. May 15 17:53:07.934000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.909386 systemd[1]: Stopped systemd-networkd.service. May 15 17:53:07.910995 systemd[1]: systemd-networkd.socket: Deactivated successfully. May 15 17:53:07.911026 systemd[1]: Closed systemd-networkd.socket. May 15 17:53:07.938000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.912295 systemd[1]: Stopping network-cleanup.service... May 15 17:53:07.939000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.915348 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. May 15 17:53:07.940000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.915427 systemd[1]: Stopped parse-ip-for-networkd.service. May 15 17:53:07.916492 systemd[1]: systemd-sysctl.service: Deactivated successfully. May 15 17:53:07.916537 systemd[1]: Stopped systemd-sysctl.service. May 15 17:53:07.921757 systemd[1]: systemd-modules-load.service: Deactivated successfully. May 15 17:53:07.921794 systemd[1]: Stopped systemd-modules-load.service. May 15 17:53:07.922640 systemd[1]: Stopping systemd-udevd.service... May 15 17:53:07.931946 systemd[1]: network-cleanup.service: Deactivated successfully. May 15 17:53:07.932042 systemd[1]: Stopped network-cleanup.service. May 15 17:53:07.934300 systemd[1]: systemd-udevd.service: Deactivated successfully. May 15 17:53:07.934413 systemd[1]: Stopped systemd-udevd.service. May 15 17:53:07.935548 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. May 15 17:53:07.948000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.935595 systemd[1]: Closed systemd-udevd-control.socket. May 15 17:53:07.950000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.936393 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. May 15 17:53:07.951000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.936426 systemd[1]: Closed systemd-udevd-kernel.socket. May 15 17:53:07.952000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-udevadm-cleanup-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.952000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-udevadm-cleanup-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:07.937439 systemd[1]: dracut-pre-udev.service: Deactivated successfully. May 15 17:53:07.937482 systemd[1]: Stopped dracut-pre-udev.service. May 15 17:53:07.938582 systemd[1]: dracut-cmdline.service: Deactivated successfully. May 15 17:53:07.938629 systemd[1]: Stopped dracut-cmdline.service. May 15 17:53:07.939578 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 15 17:53:07.939619 systemd[1]: Stopped dracut-cmdline-ask.service. May 15 17:53:07.941336 systemd[1]: Starting initrd-udevadm-cleanup-db.service... May 15 17:53:07.948116 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. May 15 17:53:07.948183 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service. May 15 17:53:07.949692 systemd[1]: kmod-static-nodes.service: Deactivated successfully. May 15 17:53:07.949731 systemd[1]: Stopped kmod-static-nodes.service. May 15 17:53:07.950463 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 15 17:53:07.950501 systemd[1]: Stopped systemd-vconsole-setup.service. May 15 17:53:07.951820 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. May 15 17:53:07.951901 systemd[1]: Finished initrd-udevadm-cleanup-db.service. May 15 17:53:07.952819 systemd[1]: Reached target initrd-switch-root.target. May 15 17:53:07.954420 systemd[1]: Starting initrd-switch-root.service... May 15 17:53:07.973448 systemd[1]: Switching root. May 15 17:53:07.996084 systemd-journald[186]: Journal stopped May 15 17:53:12.889092 systemd-journald[186]: Received SIGTERM from PID 1 (systemd). May 15 17:53:12.889145 kernel: SELinux: Class mctp_socket not defined in policy. May 15 17:53:12.889164 kernel: SELinux: Class anon_inode not defined in policy. May 15 17:53:12.889177 kernel: SELinux: the above unknown classes and permissions will be allowed May 15 17:53:12.889189 kernel: SELinux: policy capability network_peer_controls=1 May 15 17:53:12.889200 kernel: SELinux: policy capability open_perms=1 May 15 17:53:12.889212 kernel: SELinux: policy capability extended_socket_class=1 May 15 17:53:12.889224 kernel: SELinux: policy capability always_check_network=0 May 15 17:53:12.889235 kernel: SELinux: policy capability cgroup_seclabel=1 May 15 17:53:12.889246 kernel: SELinux: policy capability nnp_nosuid_transition=1 May 15 17:53:12.889278 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 May 15 17:53:12.889294 kernel: SELinux: policy capability ioctl_skip_cloexec=0 May 15 17:53:12.889305 kernel: kauditd_printk_skb: 71 callbacks suppressed May 15 17:53:12.889322 kernel: audit: type=1403 audit(1747331588.380:82): auid=4294967295 ses=4294967295 lsm=selinux res=1 May 15 17:53:12.889336 systemd[1]: Successfully loaded SELinux policy in 106.502ms. May 15 17:53:12.889357 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 23.543ms. May 15 17:53:12.889371 systemd[1]: systemd 252 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE -TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) May 15 17:53:12.889386 systemd[1]: Detected virtualization kvm. May 15 17:53:12.889399 systemd[1]: Detected architecture x86-64. May 15 17:53:12.889412 systemd[1]: Detected first boot. May 15 17:53:12.889425 systemd[1]: Hostname set to . May 15 17:53:12.889437 systemd[1]: Initializing machine ID from VM UUID. May 15 17:53:12.889451 kernel: audit: type=1400 audit(1747331588.538:83): avc: denied { bpf } for pid=1 comm="systemd" capability=39 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 May 15 17:53:12.889463 kernel: audit: type=1400 audit(1747331588.543:84): avc: denied { perfmon } for pid=1 comm="systemd" capability=38 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 May 15 17:53:12.889475 kernel: audit: type=1334 audit(1747331588.551:85): prog-id=10 op=LOAD May 15 17:53:12.889486 kernel: audit: type=1334 audit(1747331588.551:86): prog-id=10 op=UNLOAD May 15 17:53:12.889499 kernel: audit: type=1334 audit(1747331588.564:87): prog-id=11 op=LOAD May 15 17:53:12.889510 kernel: audit: type=1334 audit(1747331588.564:88): prog-id=11 op=UNLOAD May 15 17:53:12.889522 kernel: SELinux: Context system_u:object_r:container_file_t:s0:c1022,c1023 is not valid (left unmapped). May 15 17:53:12.889535 kernel: audit: type=1400 audit(1747331588.731:89): avc: denied { associate } for pid=856 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 srawcon="system_u:object_r:container_file_t:s0:c1022,c1023" May 15 17:53:12.889548 kernel: audit: type=1300 audit(1747331588.731:89): arch=c000003e syscall=188 success=yes exit=0 a0=c0001058c2 a1=c00002ae40 a2=c000029080 a3=32 items=0 ppid=839 pid=856 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="torcx-generator" exe="/usr/lib/systemd/system-generators/torcx-generator" subj=system_u:system_r:kernel_t:s0 key=(null) May 15 17:53:12.889561 kernel: audit: type=1327 audit(1747331588.731:89): proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 May 15 17:53:12.889573 systemd[1]: Populated /etc with preset unit settings. May 15 17:53:12.889587 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. May 15 17:53:12.889600 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. May 15 17:53:12.889614 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. May 15 17:53:12.889627 systemd[1]: initrd-switch-root.service: Deactivated successfully. May 15 17:53:12.889639 systemd[1]: Stopped initrd-switch-root.service. May 15 17:53:12.889652 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. May 15 17:53:12.889666 systemd[1]: Created slice system-addon\x2dconfig.slice. May 15 17:53:12.889680 systemd[1]: Created slice system-addon\x2drun.slice. May 15 17:53:12.889693 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice. May 15 17:53:12.889709 systemd[1]: Created slice system-getty.slice. May 15 17:53:12.889722 systemd[1]: Created slice system-modprobe.slice. May 15 17:53:12.889734 systemd[1]: Created slice system-serial\x2dgetty.slice. May 15 17:53:12.889747 systemd[1]: Created slice system-system\x2dcloudinit.slice. May 15 17:53:12.889760 systemd[1]: Created slice system-systemd\x2dfsck.slice. May 15 17:53:12.889772 systemd[1]: Created slice user.slice. May 15 17:53:12.889784 systemd[1]: Started systemd-ask-password-console.path. May 15 17:53:12.889796 systemd[1]: Started systemd-ask-password-wall.path. May 15 17:53:12.889809 systemd[1]: Set up automount boot.automount. May 15 17:53:12.889822 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount. May 15 17:53:12.889835 systemd[1]: Stopped target initrd-switch-root.target. May 15 17:53:12.889850 systemd[1]: Stopped target initrd-fs.target. May 15 17:53:12.889862 systemd[1]: Stopped target initrd-root-fs.target. May 15 17:53:12.889874 systemd[1]: Reached target integritysetup.target. May 15 17:53:12.889886 systemd[1]: Reached target remote-cryptsetup.target. May 15 17:53:12.889898 systemd[1]: Reached target remote-fs.target. May 15 17:53:12.889911 systemd[1]: Reached target slices.target. May 15 17:53:12.889923 systemd[1]: Reached target swap.target. May 15 17:53:12.889935 systemd[1]: Reached target torcx.target. May 15 17:53:12.889949 systemd[1]: Reached target veritysetup.target. May 15 17:53:12.889961 systemd[1]: Listening on systemd-coredump.socket. May 15 17:53:12.889974 systemd[1]: Listening on systemd-initctl.socket. May 15 17:53:12.889986 systemd[1]: Listening on systemd-networkd.socket. May 15 17:53:12.889998 systemd[1]: Listening on systemd-udevd-control.socket. May 15 17:53:12.890010 systemd[1]: Listening on systemd-udevd-kernel.socket. May 15 17:53:12.890022 systemd[1]: Listening on systemd-userdbd.socket. May 15 17:53:12.890035 systemd[1]: Mounting dev-hugepages.mount... May 15 17:53:12.890047 systemd[1]: Mounting dev-mqueue.mount... May 15 17:53:12.890061 systemd[1]: Mounting media.mount... May 15 17:53:12.890074 systemd[1]: proc-xen.mount was skipped because of an unmet condition check (ConditionVirtualization=xen). May 15 17:53:12.890086 systemd[1]: Mounting sys-kernel-debug.mount... May 15 17:53:12.890098 systemd[1]: Mounting sys-kernel-tracing.mount... May 15 17:53:12.890112 systemd[1]: Mounting tmp.mount... May 15 17:53:12.890125 systemd[1]: Starting flatcar-tmpfiles.service... May 15 17:53:12.890137 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. May 15 17:53:12.890149 systemd[1]: Starting kmod-static-nodes.service... May 15 17:53:12.890162 systemd[1]: Starting modprobe@configfs.service... May 15 17:53:12.890176 systemd[1]: Starting modprobe@dm_mod.service... May 15 17:53:12.890188 systemd[1]: Starting modprobe@drm.service... May 15 17:53:12.890200 systemd[1]: Starting modprobe@efi_pstore.service... May 15 17:53:12.890212 systemd[1]: Starting modprobe@fuse.service... May 15 17:53:12.890224 systemd[1]: Starting modprobe@loop.service... May 15 17:53:12.890236 systemd[1]: setup-nsswitch.service was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). May 15 17:53:12.890249 systemd[1]: systemd-fsck-root.service: Deactivated successfully. May 15 17:53:12.892853 systemd[1]: Stopped systemd-fsck-root.service. May 15 17:53:12.892868 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. May 15 17:53:12.892885 systemd[1]: Stopped systemd-fsck-usr.service. May 15 17:53:12.892898 kernel: fuse: init (API version 7.34) May 15 17:53:12.892910 systemd[1]: Stopped systemd-journald.service. May 15 17:53:12.892922 systemd[1]: Starting systemd-journald.service... May 15 17:53:12.892934 kernel: loop: module loaded May 15 17:53:12.892946 systemd[1]: Starting systemd-modules-load.service... May 15 17:53:12.892958 systemd[1]: Starting systemd-network-generator.service... May 15 17:53:12.892971 systemd[1]: Starting systemd-remount-fs.service... May 15 17:53:12.892983 systemd[1]: Starting systemd-udev-trigger.service... May 15 17:53:12.892997 systemd[1]: verity-setup.service: Deactivated successfully. May 15 17:53:12.893009 systemd[1]: Stopped verity-setup.service. May 15 17:53:12.893021 systemd[1]: xenserver-pv-version.service was skipped because of an unmet condition check (ConditionVirtualization=xen). May 15 17:53:12.893033 systemd[1]: Mounted dev-hugepages.mount. May 15 17:53:12.893045 systemd[1]: Mounted dev-mqueue.mount. May 15 17:53:12.893058 systemd[1]: Mounted media.mount. May 15 17:53:12.893070 systemd[1]: Mounted sys-kernel-debug.mount. May 15 17:53:12.893081 systemd[1]: Mounted sys-kernel-tracing.mount. May 15 17:53:12.893093 systemd[1]: Mounted tmp.mount. May 15 17:53:12.893108 systemd[1]: Finished flatcar-tmpfiles.service. May 15 17:53:12.893121 systemd[1]: Finished kmod-static-nodes.service. May 15 17:53:12.893133 systemd[1]: modprobe@configfs.service: Deactivated successfully. May 15 17:53:12.893145 systemd[1]: Finished modprobe@configfs.service. May 15 17:53:12.893157 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 15 17:53:12.893173 systemd-journald[949]: Journal started May 15 17:53:12.893221 systemd-journald[949]: Runtime Journal (/run/log/journal/115541c0e1ef4b8e8735142ada7d9521) is 8.0M, max 78.4M, 70.4M free. May 15 17:53:12.895286 systemd[1]: Finished modprobe@dm_mod.service. May 15 17:53:08.380000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 May 15 17:53:08.538000 audit[1]: AVC avc: denied { bpf } for pid=1 comm="systemd" capability=39 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 May 15 17:53:08.543000 audit[1]: AVC avc: denied { perfmon } for pid=1 comm="systemd" capability=38 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 May 15 17:53:08.551000 audit: BPF prog-id=10 op=LOAD May 15 17:53:08.551000 audit: BPF prog-id=10 op=UNLOAD May 15 17:53:08.564000 audit: BPF prog-id=11 op=LOAD May 15 17:53:08.564000 audit: BPF prog-id=11 op=UNLOAD May 15 17:53:08.731000 audit[856]: AVC avc: denied { associate } for pid=856 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 srawcon="system_u:object_r:container_file_t:s0:c1022,c1023" May 15 17:53:08.731000 audit[856]: SYSCALL arch=c000003e syscall=188 success=yes exit=0 a0=c0001058c2 a1=c00002ae40 a2=c000029080 a3=32 items=0 ppid=839 pid=856 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="torcx-generator" exe="/usr/lib/systemd/system-generators/torcx-generator" subj=system_u:system_r:kernel_t:s0 key=(null) May 15 17:53:08.731000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 May 15 17:53:08.737000 audit[856]: AVC avc: denied { associate } for pid=856 comm="torcx-generator" name="usr" scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 May 15 17:53:08.737000 audit[856]: SYSCALL arch=c000003e syscall=258 success=yes exit=0 a0=ffffffffffffff9c a1=c000105999 a2=1ed a3=0 items=2 ppid=839 pid=856 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="torcx-generator" exe="/usr/lib/systemd/system-generators/torcx-generator" subj=system_u:system_r:kernel_t:s0 key=(null) May 15 17:53:08.737000 audit: CWD cwd="/" May 15 17:53:08.737000 audit: PATH item=0 name=(null) inode=2 dev=00:1a mode=040755 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:unlabeled_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:08.737000 audit: PATH item=1 name=(null) inode=3 dev=00:1a mode=040755 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:unlabeled_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:08.737000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 May 15 17:53:12.640000 audit: BPF prog-id=12 op=LOAD May 15 17:53:12.640000 audit: BPF prog-id=3 op=UNLOAD May 15 17:53:12.640000 audit: BPF prog-id=13 op=LOAD May 15 17:53:12.640000 audit: BPF prog-id=14 op=LOAD May 15 17:53:12.640000 audit: BPF prog-id=4 op=UNLOAD May 15 17:53:12.640000 audit: BPF prog-id=5 op=UNLOAD May 15 17:53:12.640000 audit: BPF prog-id=15 op=LOAD May 15 17:53:12.640000 audit: BPF prog-id=12 op=UNLOAD May 15 17:53:12.641000 audit: BPF prog-id=16 op=LOAD May 15 17:53:12.641000 audit: BPF prog-id=17 op=LOAD May 15 17:53:12.641000 audit: BPF prog-id=13 op=UNLOAD May 15 17:53:12.641000 audit: BPF prog-id=14 op=UNLOAD May 15 17:53:12.641000 audit: BPF prog-id=18 op=LOAD May 15 17:53:12.641000 audit: BPF prog-id=15 op=UNLOAD May 15 17:53:12.641000 audit: BPF prog-id=19 op=LOAD May 15 17:53:12.641000 audit: BPF prog-id=20 op=LOAD May 15 17:53:12.641000 audit: BPF prog-id=16 op=UNLOAD May 15 17:53:12.641000 audit: BPF prog-id=17 op=UNLOAD May 15 17:53:12.642000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:12.646000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:12.646000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:12.649000 audit: BPF prog-id=18 op=UNLOAD May 15 17:53:12.817000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:12.823000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck-usr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:12.825000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:12.825000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:12.826000 audit: BPF prog-id=21 op=LOAD May 15 17:53:12.829000 audit: BPF prog-id=22 op=LOAD May 15 17:53:12.829000 audit: BPF prog-id=23 op=LOAD May 15 17:53:12.829000 audit: BPF prog-id=19 op=UNLOAD May 15 17:53:12.829000 audit: BPF prog-id=20 op=UNLOAD May 15 17:53:12.857000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=verity-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:12.880000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=flatcar-tmpfiles comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:12.883000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:12.887000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 May 15 17:53:12.887000 audit[949]: SYSCALL arch=c000003e syscall=46 success=yes exit=60 a0=4 a1=7ffc554515c0 a2=4000 a3=7ffc5545165c items=0 ppid=1 pid=949 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="systemd-journal" exe="/usr/lib/systemd/systemd-journald" subj=system_u:system_r:kernel_t:s0 key=(null) May 15 17:53:12.887000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald" May 15 17:53:12.890000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:12.890000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:12.638211 systemd[1]: Queued start job for default target multi-user.target. May 15 17:53:08.726908 /usr/lib/systemd/system-generators/torcx-generator[856]: time="2025-05-15T17:53:08Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3510.3.100 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.100 /var/lib/torcx/store]" May 15 17:53:12.638222 systemd[1]: Unnecessary job was removed for dev-vda6.device. May 15 17:53:08.728478 /usr/lib/systemd/system-generators/torcx-generator[856]: time="2025-05-15T17:53:08Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json May 15 17:53:12.642407 systemd[1]: systemd-journald.service: Deactivated successfully. May 15 17:53:08.728519 /usr/lib/systemd/system-generators/torcx-generator[856]: time="2025-05-15T17:53:08Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json May 15 17:53:12.900733 systemd[1]: Started systemd-journald.service. May 15 17:53:12.897000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:12.897000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:12.899000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:08.728574 /usr/lib/systemd/system-generators/torcx-generator[856]: time="2025-05-15T17:53:08Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" May 15 17:53:12.900067 systemd[1]: modprobe@drm.service: Deactivated successfully. May 15 17:53:08.728595 /usr/lib/systemd/system-generators/torcx-generator[856]: time="2025-05-15T17:53:08Z" level=debug msg="skipped missing lower profile" missing profile=oem May 15 17:53:12.900268 systemd[1]: Finished modprobe@drm.service. May 15 17:53:08.728655 /usr/lib/systemd/system-generators/torcx-generator[856]: time="2025-05-15T17:53:08Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" May 15 17:53:08.728682 /usr/lib/systemd/system-generators/torcx-generator[856]: time="2025-05-15T17:53:08Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= May 15 17:53:12.901000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:12.901000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:12.901000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:12.901000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:12.901604 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 15 17:53:08.729010 /usr/lib/systemd/system-generators/torcx-generator[856]: time="2025-05-15T17:53:08Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack May 15 17:53:12.901778 systemd[1]: Finished modprobe@efi_pstore.service. May 15 17:53:08.729085 /usr/lib/systemd/system-generators/torcx-generator[856]: time="2025-05-15T17:53:08Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json May 15 17:53:12.902443 systemd[1]: modprobe@fuse.service: Deactivated successfully. May 15 17:53:08.729112 /usr/lib/systemd/system-generators/torcx-generator[856]: time="2025-05-15T17:53:08Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json May 15 17:53:12.902585 systemd[1]: Finished modprobe@fuse.service. May 15 17:53:08.730629 /usr/lib/systemd/system-generators/torcx-generator[856]: time="2025-05-15T17:53:08Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:20.10.torcx.tgz" reference=20.10 May 15 17:53:08.730704 /usr/lib/systemd/system-generators/torcx-generator[856]: time="2025-05-15T17:53:08Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:com.coreos.cl.torcx.tgz" reference=com.coreos.cl May 15 17:53:08.730742 /usr/lib/systemd/system-generators/torcx-generator[856]: time="2025-05-15T17:53:08Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3510.3.100: no such file or directory" path=/usr/share/oem/torcx/store/3510.3.100 May 15 17:53:08.730773 /usr/lib/systemd/system-generators/torcx-generator[856]: time="2025-05-15T17:53:08Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store May 15 17:53:08.730806 /usr/lib/systemd/system-generators/torcx-generator[856]: time="2025-05-15T17:53:08Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3510.3.100: no such file or directory" path=/var/lib/torcx/store/3510.3.100 May 15 17:53:08.730835 /usr/lib/systemd/system-generators/torcx-generator[856]: time="2025-05-15T17:53:08Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store May 15 17:53:12.904000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:12.904000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:12.905000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:12.905000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:12.905000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:12.031878 /usr/lib/systemd/system-generators/torcx-generator[856]: time="2025-05-15T17:53:12Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl May 15 17:53:12.904766 systemd[1]: modprobe@loop.service: Deactivated successfully. May 15 17:53:12.032163 /usr/lib/systemd/system-generators/torcx-generator[856]: time="2025-05-15T17:53:12Z" level=debug msg="binaries propagated" assets="[/bin/containerd /bin/containerd-shim /bin/ctr /bin/docker /bin/docker-containerd /bin/docker-containerd-shim /bin/docker-init /bin/docker-proxy /bin/docker-runc /bin/dockerd /bin/runc /bin/tini]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl May 15 17:53:12.907000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-network-generator comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:12.904942 systemd[1]: Finished modprobe@loop.service. May 15 17:53:12.032320 /usr/lib/systemd/system-generators/torcx-generator[856]: time="2025-05-15T17:53:12Z" level=debug msg="networkd units propagated" assets="[/lib/systemd/network/50-docker.network /lib/systemd/network/90-docker-veth.network]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl May 15 17:53:12.905759 systemd[1]: Finished systemd-modules-load.service. May 15 17:53:12.032531 /usr/lib/systemd/system-generators/torcx-generator[856]: time="2025-05-15T17:53:12Z" level=debug msg="systemd units propagated" assets="[/lib/systemd/system/containerd.service /lib/systemd/system/docker.service /lib/systemd/system/docker.socket /lib/systemd/system/sockets.target.wants /lib/systemd/system/multi-user.target.wants]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl May 15 17:53:12.906465 systemd[1]: Finished systemd-network-generator.service. May 15 17:53:12.032593 /usr/lib/systemd/system-generators/torcx-generator[856]: time="2025-05-15T17:53:12Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= May 15 17:53:12.907648 systemd[1]: Finished systemd-remount-fs.service. May 15 17:53:12.032662 /usr/lib/systemd/system-generators/torcx-generator[856]: time="2025-05-15T17:53:12Z" level=debug msg="system state sealed" content="[TORCX_LOWER_PROFILES=\"vendor\" TORCX_UPPER_PROFILE=\"\" TORCX_PROFILE_PATH=\"/run/torcx/profile.json\" TORCX_BINDIR=\"/run/torcx/bin\" TORCX_UNPACKDIR=\"/run/torcx/unpack\"]" path=/run/metadata/torcx May 15 17:53:12.908000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-remount-fs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:12.909048 systemd[1]: Reached target network-pre.target. May 15 17:53:12.910795 systemd[1]: Mounting sys-fs-fuse-connections.mount... May 15 17:53:12.912472 systemd[1]: Mounting sys-kernel-config.mount... May 15 17:53:12.915555 systemd[1]: remount-root.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). May 15 17:53:12.917629 systemd[1]: Starting systemd-hwdb-update.service... May 15 17:53:12.919807 systemd[1]: Starting systemd-journal-flush.service... May 15 17:53:12.920892 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 15 17:53:12.921918 systemd[1]: Starting systemd-random-seed.service... May 15 17:53:12.923126 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. May 15 17:53:12.924295 systemd[1]: Starting systemd-sysctl.service... May 15 17:53:12.926489 systemd[1]: Starting systemd-sysusers.service... May 15 17:53:12.930728 systemd[1]: Mounted sys-fs-fuse-connections.mount. May 15 17:53:12.932188 systemd[1]: Mounted sys-kernel-config.mount. May 15 17:53:12.937387 systemd-journald[949]: Time spent on flushing to /var/log/journal/115541c0e1ef4b8e8735142ada7d9521 is 17.899ms for 1095 entries. May 15 17:53:12.937387 systemd-journald[949]: System Journal (/var/log/journal/115541c0e1ef4b8e8735142ada7d9521) is 8.0M, max 584.8M, 576.8M free. May 15 17:53:13.079839 systemd-journald[949]: Received client request to flush runtime journal. May 15 17:53:12.940000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:12.959000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-random-seed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:13.049000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:12.940394 systemd[1]: Finished systemd-udev-trigger.service. May 15 17:53:13.082065 udevadm[963]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. May 15 17:53:13.082000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journal-flush comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:12.942099 systemd[1]: Starting systemd-udev-settle.service... May 15 17:53:12.959185 systemd[1]: Finished systemd-random-seed.service. May 15 17:53:12.960069 systemd[1]: Reached target first-boot-complete.target. May 15 17:53:13.048929 systemd[1]: Finished systemd-sysctl.service. May 15 17:53:13.081520 systemd[1]: Finished systemd-journal-flush.service. May 15 17:53:13.127199 systemd[1]: Finished systemd-sysusers.service. May 15 17:53:13.128000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysusers comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:13.130862 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... May 15 17:53:13.212204 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. May 15 17:53:13.213000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:13.746703 systemd[1]: Finished systemd-hwdb-update.service. May 15 17:53:13.764557 kernel: kauditd_printk_skb: 65 callbacks suppressed May 15 17:53:13.764693 kernel: audit: type=1130 audit(1747331593.747:148): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-hwdb-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:13.747000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-hwdb-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:13.752000 audit: BPF prog-id=24 op=LOAD May 15 17:53:13.765714 systemd[1]: Starting systemd-udevd.service... May 15 17:53:13.769566 kernel: audit: type=1334 audit(1747331593.752:149): prog-id=24 op=LOAD May 15 17:53:13.769698 kernel: audit: type=1334 audit(1747331593.764:150): prog-id=25 op=LOAD May 15 17:53:13.769763 kernel: audit: type=1334 audit(1747331593.764:151): prog-id=7 op=UNLOAD May 15 17:53:13.769813 kernel: audit: type=1334 audit(1747331593.764:152): prog-id=8 op=UNLOAD May 15 17:53:13.764000 audit: BPF prog-id=25 op=LOAD May 15 17:53:13.764000 audit: BPF prog-id=7 op=UNLOAD May 15 17:53:13.764000 audit: BPF prog-id=8 op=UNLOAD May 15 17:53:13.816540 systemd-udevd[968]: Using default interface naming scheme 'v252'. May 15 17:53:13.898878 systemd[1]: Started systemd-udevd.service. May 15 17:53:13.899000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:13.912321 kernel: audit: type=1130 audit(1747331593.899:153): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:13.920758 systemd[1]: Starting systemd-networkd.service... May 15 17:53:13.918000 audit: BPF prog-id=26 op=LOAD May 15 17:53:13.928348 kernel: audit: type=1334 audit(1747331593.918:154): prog-id=26 op=LOAD May 15 17:53:13.944000 audit: BPF prog-id=27 op=LOAD May 15 17:53:13.949326 kernel: audit: type=1334 audit(1747331593.944:155): prog-id=27 op=LOAD May 15 17:53:13.949000 audit: BPF prog-id=28 op=LOAD May 15 17:53:13.949000 audit: BPF prog-id=29 op=LOAD May 15 17:53:13.958490 kernel: audit: type=1334 audit(1747331593.949:156): prog-id=28 op=LOAD May 15 17:53:13.958533 kernel: audit: type=1334 audit(1747331593.949:157): prog-id=29 op=LOAD May 15 17:53:13.958757 systemd[1]: Starting systemd-userdbd.service... May 15 17:53:13.974948 systemd[1]: Condition check resulted in dev-ttyS0.device being skipped. May 15 17:53:14.015866 systemd[1]: Started systemd-userdbd.service. May 15 17:53:14.016000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-userdbd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:14.022870 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. May 15 17:53:14.067281 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 May 15 17:53:14.089281 kernel: ACPI: button: Power Button [PWRF] May 15 17:53:14.100978 systemd-networkd[981]: lo: Link UP May 15 17:53:14.100989 systemd-networkd[981]: lo: Gained carrier May 15 17:53:14.101472 systemd-networkd[981]: Enumeration completed May 15 17:53:14.101589 systemd-networkd[981]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 15 17:53:14.101823 systemd[1]: Started systemd-networkd.service. May 15 17:53:14.103646 systemd[1]: Starting systemd-networkd-wait-online.service... May 15 17:53:14.102000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:14.093000 audit[969]: AVC avc: denied { confidentiality } for pid=969 comm="(udev-worker)" lockdown_reason="use of tracefs" scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=lockdown permissive=1 May 15 17:53:14.105932 systemd-networkd[981]: eth0: Link UP May 15 17:53:14.105936 systemd-networkd[981]: eth0: Gained carrier May 15 17:53:14.093000 audit[969]: SYSCALL arch=c000003e syscall=175 success=yes exit=0 a0=5624d21f9b30 a1=338ac a2=7fc179363bc5 a3=5 items=110 ppid=968 pid=969 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="(udev-worker)" exe="/usr/bin/udevadm" subj=system_u:system_r:kernel_t:s0 key=(null) May 15 17:53:14.093000 audit: CWD cwd="/" May 15 17:53:14.093000 audit: PATH item=0 name=(null) inode=45 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=1 name=(null) inode=13896 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=2 name=(null) inode=13896 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=3 name=(null) inode=13897 dev=00:0b mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=4 name=(null) inode=13896 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=5 name=(null) inode=13898 dev=00:0b mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=6 name=(null) inode=13896 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=7 name=(null) inode=13899 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=8 name=(null) inode=13899 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=9 name=(null) inode=13900 dev=00:0b mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=10 name=(null) inode=13899 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=11 name=(null) inode=13901 dev=00:0b mode=0100440 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=12 name=(null) inode=13899 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=13 name=(null) inode=13902 dev=00:0b mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=14 name=(null) inode=13899 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=15 name=(null) inode=13903 dev=00:0b mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=16 name=(null) inode=13899 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=17 name=(null) inode=13904 dev=00:0b mode=0100440 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=18 name=(null) inode=13896 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=19 name=(null) inode=13905 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=20 name=(null) inode=13905 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=21 name=(null) inode=13906 dev=00:0b mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=22 name=(null) inode=13905 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=23 name=(null) inode=13907 dev=00:0b mode=0100440 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=24 name=(null) inode=13905 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=25 name=(null) inode=13908 dev=00:0b mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=26 name=(null) inode=13905 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=27 name=(null) inode=13909 dev=00:0b mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=28 name=(null) inode=13905 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=29 name=(null) inode=13910 dev=00:0b mode=0100440 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=30 name=(null) inode=13896 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=31 name=(null) inode=13911 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=32 name=(null) inode=13911 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=33 name=(null) inode=13912 dev=00:0b mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=34 name=(null) inode=13911 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=35 name=(null) inode=13913 dev=00:0b mode=0100440 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=36 name=(null) inode=13911 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=37 name=(null) inode=13914 dev=00:0b mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=38 name=(null) inode=13911 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=39 name=(null) inode=13915 dev=00:0b mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=40 name=(null) inode=13911 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=41 name=(null) inode=13916 dev=00:0b mode=0100440 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=42 name=(null) inode=13896 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=43 name=(null) inode=13917 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=44 name=(null) inode=13917 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=45 name=(null) inode=13918 dev=00:0b mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=46 name=(null) inode=13917 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=47 name=(null) inode=13919 dev=00:0b mode=0100440 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=48 name=(null) inode=13917 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=49 name=(null) inode=13920 dev=00:0b mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=50 name=(null) inode=13917 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=51 name=(null) inode=13921 dev=00:0b mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=52 name=(null) inode=13917 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=53 name=(null) inode=13922 dev=00:0b mode=0100440 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=54 name=(null) inode=45 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=55 name=(null) inode=13923 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=56 name=(null) inode=13923 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=57 name=(null) inode=13924 dev=00:0b mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=58 name=(null) inode=13923 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=59 name=(null) inode=13925 dev=00:0b mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=60 name=(null) inode=13923 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=61 name=(null) inode=13926 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=62 name=(null) inode=13926 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=63 name=(null) inode=13927 dev=00:0b mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=64 name=(null) inode=13926 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=65 name=(null) inode=13928 dev=00:0b mode=0100440 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=66 name=(null) inode=13926 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=67 name=(null) inode=13929 dev=00:0b mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=68 name=(null) inode=13926 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=69 name=(null) inode=13930 dev=00:0b mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=70 name=(null) inode=13926 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=71 name=(null) inode=13931 dev=00:0b mode=0100440 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=72 name=(null) inode=13923 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=73 name=(null) inode=13932 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=74 name=(null) inode=13932 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=75 name=(null) inode=13933 dev=00:0b mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=76 name=(null) inode=13932 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=77 name=(null) inode=13934 dev=00:0b mode=0100440 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=78 name=(null) inode=13932 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=79 name=(null) inode=13935 dev=00:0b mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=80 name=(null) inode=13932 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=81 name=(null) inode=13936 dev=00:0b mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=82 name=(null) inode=13932 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=83 name=(null) inode=13937 dev=00:0b mode=0100440 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=84 name=(null) inode=13923 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=85 name=(null) inode=13938 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=86 name=(null) inode=13938 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=87 name=(null) inode=13939 dev=00:0b mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=88 name=(null) inode=13938 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=89 name=(null) inode=13940 dev=00:0b mode=0100440 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=90 name=(null) inode=13938 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=91 name=(null) inode=13941 dev=00:0b mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=92 name=(null) inode=13938 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=93 name=(null) inode=13942 dev=00:0b mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=94 name=(null) inode=13938 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=95 name=(null) inode=13943 dev=00:0b mode=0100440 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=96 name=(null) inode=13923 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=97 name=(null) inode=13944 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=98 name=(null) inode=13944 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=99 name=(null) inode=13945 dev=00:0b mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=100 name=(null) inode=13944 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=101 name=(null) inode=13946 dev=00:0b mode=0100440 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=102 name=(null) inode=13944 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=103 name=(null) inode=13947 dev=00:0b mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=104 name=(null) inode=13944 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=105 name=(null) inode=13948 dev=00:0b mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=106 name=(null) inode=13944 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=107 name=(null) inode=13949 dev=00:0b mode=0100440 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=108 name=(null) inode=1 dev=00:07 mode=040700 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:debugfs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PATH item=109 name=(null) inode=13950 dev=00:07 mode=040755 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:debugfs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 17:53:14.093000 audit: PROCTITLE proctitle="(udev-worker)" May 15 17:53:14.118304 kernel: piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 May 15 17:53:14.125305 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 May 15 17:53:14.127383 systemd-networkd[981]: eth0: DHCPv4 address 172.24.4.161/24, gateway 172.24.4.1 acquired from 172.24.4.1 May 15 17:53:14.143283 kernel: mousedev: PS/2 mouse device common for all mice May 15 17:53:14.194633 systemd[1]: Finished systemd-udev-settle.service. May 15 17:53:14.194000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udev-settle comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:14.196344 systemd[1]: Starting lvm2-activation-early.service... May 15 17:53:14.237400 lvm[1000]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 15 17:53:14.279178 systemd[1]: Finished lvm2-activation-early.service. May 15 17:53:14.280000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=lvm2-activation-early comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:14.281089 systemd[1]: Reached target cryptsetup.target. May 15 17:53:14.285710 systemd[1]: Starting lvm2-activation.service... May 15 17:53:14.294986 lvm[1001]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 15 17:53:14.332209 systemd[1]: Finished lvm2-activation.service. May 15 17:53:14.333000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=lvm2-activation comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:14.333690 systemd[1]: Reached target local-fs-pre.target. May 15 17:53:14.334885 systemd[1]: var-lib-machines.mount was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). May 15 17:53:14.334946 systemd[1]: Reached target local-fs.target. May 15 17:53:14.336133 systemd[1]: Reached target machines.target. May 15 17:53:14.339842 systemd[1]: Starting ldconfig.service... May 15 17:53:14.342452 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. May 15 17:53:14.342576 systemd[1]: systemd-boot-system-token.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/LoaderFeatures-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). May 15 17:53:14.344888 systemd[1]: Starting systemd-boot-update.service... May 15 17:53:14.358178 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service... May 15 17:53:14.363391 systemd[1]: Starting systemd-machine-id-commit.service... May 15 17:53:14.369363 systemd[1]: systemd-sysext.service was skipped because no trigger condition checks were met. May 15 17:53:14.369453 systemd[1]: ensure-sysext.service was skipped because no trigger condition checks were met. May 15 17:53:14.374641 systemd[1]: Starting systemd-tmpfiles-setup.service... May 15 17:53:14.386723 systemd[1]: boot.automount: Got automount request for /boot, triggered by 1003 (bootctl) May 15 17:53:14.389153 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service... May 15 17:53:14.409639 systemd-tmpfiles[1006]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. May 15 17:53:14.413000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck@dev-disk-by\x2dlabel-OEM comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:14.412955 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service. May 15 17:53:14.417435 systemd-tmpfiles[1006]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. May 15 17:53:14.422977 systemd-tmpfiles[1006]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. May 15 17:53:14.497679 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. May 15 17:53:14.499299 systemd[1]: Finished systemd-machine-id-commit.service. May 15 17:53:14.500000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-machine-id-commit comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:14.668391 systemd-fsck[1011]: fsck.fat 4.2 (2021-01-31) May 15 17:53:14.668391 systemd-fsck[1011]: /dev/vda1: 790 files, 120732/258078 clusters May 15 17:53:14.673417 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service. May 15 17:53:14.674000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:14.678552 systemd[1]: Mounting boot.mount... May 15 17:53:14.703134 systemd[1]: Mounted boot.mount. May 15 17:53:14.725886 systemd[1]: Finished systemd-boot-update.service. May 15 17:53:14.726000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-boot-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:14.809889 systemd[1]: Finished systemd-tmpfiles-setup.service. May 15 17:53:14.810000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:14.812191 systemd[1]: Starting audit-rules.service... May 15 17:53:14.813608 systemd[1]: Starting clean-ca-certificates.service... May 15 17:53:14.816508 systemd[1]: Starting systemd-journal-catalog-update.service... May 15 17:53:14.818000 audit: BPF prog-id=30 op=LOAD May 15 17:53:14.823000 audit: BPF prog-id=31 op=LOAD May 15 17:53:14.821103 systemd[1]: Starting systemd-resolved.service... May 15 17:53:14.825035 systemd[1]: Starting systemd-timesyncd.service... May 15 17:53:14.828548 systemd[1]: Starting systemd-update-utmp.service... May 15 17:53:14.840006 systemd[1]: Finished clean-ca-certificates.service. May 15 17:53:14.840000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=clean-ca-certificates comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:14.840748 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). May 15 17:53:14.842000 audit[1020]: SYSTEM_BOOT pid=1020 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg=' comm="systemd-update-utmp" exe="/usr/lib/systemd/systemd-update-utmp" hostname=? addr=? terminal=? res=success' May 15 17:53:14.845545 systemd[1]: Finished systemd-update-utmp.service. May 15 17:53:14.845000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-update-utmp comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:14.880692 systemd[1]: Finished systemd-journal-catalog-update.service. May 15 17:53:14.881000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journal-catalog-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 17:53:14.921000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 May 15 17:53:14.921000 audit[1035]: SYSCALL arch=c000003e syscall=44 success=yes exit=1056 a0=3 a1=7ffcfe782bb0 a2=420 a3=0 items=0 ppid=1015 pid=1035 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditctl" exe="/usr/sbin/auditctl" subj=system_u:system_r:kernel_t:s0 key=(null) May 15 17:53:14.921000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573 May 15 17:53:14.921961 augenrules[1035]: No rules May 15 17:53:14.922878 systemd[1]: Finished audit-rules.service. May 15 17:53:14.929164 systemd[1]: Started systemd-timesyncd.service. May 15 17:53:14.929796 systemd[1]: Reached target time-set.target. May 15 17:53:14.937785 systemd-resolved[1018]: Positive Trust Anchors: May 15 17:53:14.938051 systemd-resolved[1018]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 15 17:53:14.938153 systemd-resolved[1018]: 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 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test May 15 17:53:14.959801 systemd-resolved[1018]: Using system hostname 'ci-3510-3-100-6622fa5004.novalocal'. May 15 17:53:14.961476 systemd[1]: Started systemd-resolved.service. May 15 17:53:14.962722 systemd[1]: Reached target network.target. May 15 17:53:14.963943 systemd[1]: Reached target nss-lookup.target. May 15 17:53:15.104672 systemd-timesyncd[1019]: Contacted time server 23.186.168.123:123 (0.flatcar.pool.ntp.org). May 15 17:53:15.104802 systemd-timesyncd[1019]: Initial clock synchronization to Thu 2025-05-15 17:53:15.131080 UTC. May 15 17:53:15.311584 systemd-networkd[981]: eth0: Gained IPv6LL May 15 17:53:15.317018 systemd[1]: Finished systemd-networkd-wait-online.service. May 15 17:53:15.318711 systemd[1]: Reached target network-online.target. May 15 17:53:15.818486 ldconfig[1002]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 15 17:53:15.873347 systemd[1]: Finished ldconfig.service. May 15 17:53:15.877644 systemd[1]: Starting systemd-update-done.service... May 15 17:53:15.896554 systemd[1]: Finished systemd-update-done.service. May 15 17:53:15.897915 systemd[1]: Reached target sysinit.target. May 15 17:53:15.899191 systemd[1]: Started motdgen.path. May 15 17:53:15.900416 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path. May 15 17:53:15.902131 systemd[1]: Started logrotate.timer. May 15 17:53:15.903455 systemd[1]: Started mdadm.timer. May 15 17:53:15.904646 systemd[1]: Started systemd-tmpfiles-clean.timer. May 15 17:53:15.905822 systemd[1]: update-engine-stub.timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). May 15 17:53:15.905884 systemd[1]: Reached target paths.target. May 15 17:53:15.907035 systemd[1]: Reached target timers.target. May 15 17:53:15.908945 systemd[1]: Listening on dbus.socket. May 15 17:53:15.912507 systemd[1]: Starting docker.socket... May 15 17:53:15.970853 systemd[1]: Listening on sshd.socket. May 15 17:53:15.972290 systemd[1]: systemd-pcrphase-sysinit.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). May 15 17:53:15.973184 systemd[1]: Listening on docker.socket. May 15 17:53:15.974552 systemd[1]: Reached target sockets.target. May 15 17:53:15.975834 systemd[1]: Reached target basic.target. May 15 17:53:15.977083 systemd[1]: addon-config@usr-share-oem.service was skipped because no trigger condition checks were met. May 15 17:53:15.977151 systemd[1]: addon-run@usr-share-oem.service was skipped because no trigger condition checks were met. May 15 17:53:15.979209 systemd[1]: Starting containerd.service... May 15 17:53:15.982957 systemd[1]: Starting coreos-metadata-sshkeys@core.service... May 15 17:53:15.986680 systemd[1]: Starting coreos-metadata.service... May 15 17:53:15.994858 systemd[1]: Starting dbus.service... May 15 17:53:15.999996 systemd[1]: Starting enable-oem-cloudinit.service... May 15 17:53:16.003957 systemd[1]: Starting extend-filesystems.service... May 15 17:53:16.005237 systemd[1]: flatcar-setup-environment.service was skipped because of an unmet condition check (ConditionPathExists=/usr/share/oem/bin/flatcar-setup-environment). May 15 17:53:16.007562 systemd[1]: Starting motdgen.service... May 15 17:53:16.010974 systemd[1]: Starting ssh-key-proc-cmdline.service... May 15 17:53:16.014517 systemd[1]: Starting sshd-keygen.service... May 15 17:53:16.029162 systemd[1]: Starting systemd-logind.service... May 15 17:53:16.030512 systemd[1]: systemd-pcrphase.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). May 15 17:53:16.030619 systemd[1]: tcsd.service was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). May 15 17:53:16.031657 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. May 15 17:53:16.035558 systemd[1]: Starting update-engine.service... May 15 17:53:16.041318 systemd[1]: Starting update-ssh-keys-after-ignition.service... May 15 17:53:16.058784 jq[1059]: true May 15 17:53:16.067317 jq[1052]: false May 15 17:53:16.075898 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. May 15 17:53:16.076056 systemd[1]: Finished ssh-key-proc-cmdline.service. May 15 17:53:16.077059 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. May 15 17:53:16.077208 systemd[1]: Condition check resulted in enable-oem-cloudinit.service being skipped. May 15 17:53:16.078930 jq[1070]: true May 15 17:53:16.130153 extend-filesystems[1053]: Found vda May 15 17:53:16.130153 extend-filesystems[1053]: Found vda1 May 15 17:53:16.130153 extend-filesystems[1053]: Found vda2 May 15 17:53:16.130153 extend-filesystems[1053]: Found vda3 May 15 17:53:16.130153 extend-filesystems[1053]: Found usr May 15 17:53:16.130153 extend-filesystems[1053]: Found vda4 May 15 17:53:16.130153 extend-filesystems[1053]: Found vda6 May 15 17:53:16.130153 extend-filesystems[1053]: Found vda7 May 15 17:53:16.130153 extend-filesystems[1053]: Found vda9 May 15 17:53:16.130153 extend-filesystems[1053]: Checking size of /dev/vda9 May 15 17:53:16.134575 systemd[1]: motdgen.service: Deactivated successfully. May 15 17:53:16.134739 systemd[1]: Finished motdgen.service. May 15 17:53:16.150928 dbus-daemon[1051]: [system] SELinux support is enabled May 15 17:53:16.151835 systemd[1]: Started dbus.service. May 15 17:53:16.154428 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). May 15 17:53:16.154452 systemd[1]: Reached target system-config.target. May 15 17:53:16.154954 systemd[1]: user-cloudinit-proc-cmdline.service was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). May 15 17:53:16.154973 systemd[1]: Reached target user-config.target. May 15 17:53:16.163104 extend-filesystems[1053]: Resized partition /dev/vda9 May 15 17:53:16.175055 extend-filesystems[1099]: resize2fs 1.46.5 (30-Dec-2021) May 15 17:53:16.185504 update_engine[1058]: I0515 17:53:16.179303 1058 main.cc:92] Flatcar Update Engine starting May 15 17:53:16.203173 systemd[1]: Started update-engine.service. May 15 17:53:16.205946 update_engine[1058]: I0515 17:53:16.203294 1058 update_check_scheduler.cc:74] Next update check in 2m21s May 15 17:53:16.205492 systemd[1]: Started locksmithd.service. May 15 17:53:16.218143 kernel: EXT4-fs (vda9): resizing filesystem from 1617920 to 2014203 blocks May 15 17:53:16.283712 kernel: EXT4-fs (vda9): resized filesystem to 2014203 May 15 17:53:16.284036 env[1061]: time="2025-05-15T17:53:16.283882410Z" level=info msg="starting containerd" revision=92b3a9d6f1b3bcc6dc74875cfdea653fe39f09c2 version=1.6.16 May 15 17:53:16.284884 systemd-logind[1057]: Watching system buttons on /dev/input/event1 (Power Button) May 15 17:53:16.284909 systemd-logind[1057]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) May 15 17:53:16.287332 extend-filesystems[1099]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required May 15 17:53:16.287332 extend-filesystems[1099]: old_desc_blocks = 1, new_desc_blocks = 1 May 15 17:53:16.287332 extend-filesystems[1099]: The filesystem on /dev/vda9 is now 2014203 (4k) blocks long. May 15 17:53:16.299617 extend-filesystems[1053]: Resized filesystem in /dev/vda9 May 15 17:53:16.301480 bash[1095]: Updated "/home/core/.ssh/authorized_keys" May 15 17:53:16.288052 systemd[1]: extend-filesystems.service: Deactivated successfully. May 15 17:53:16.288290 systemd[1]: Finished extend-filesystems.service. May 15 17:53:16.288553 systemd-logind[1057]: New seat seat0. May 15 17:53:16.295318 systemd[1]: Finished update-ssh-keys-after-ignition.service. May 15 17:53:16.298733 systemd[1]: Started systemd-logind.service. May 15 17:53:16.319267 coreos-metadata[1045]: May 15 17:53:16.319 INFO Fetching http://169.254.169.254/latest/meta-data/public-keys: Attempt #1 May 15 17:53:16.325330 env[1061]: time="2025-05-15T17:53:16.325291551Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 May 15 17:53:16.325480 env[1061]: time="2025-05-15T17:53:16.325454958Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 May 15 17:53:16.326749 env[1061]: time="2025-05-15T17:53:16.326679743Z" 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/5.15.182-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 May 15 17:53:16.326749 env[1061]: time="2025-05-15T17:53:16.326713764Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 May 15 17:53:16.326961 env[1061]: time="2025-05-15T17:53:16.326932346Z" 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 May 15 17:53:16.327001 env[1061]: time="2025-05-15T17:53:16.326958970Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 May 15 17:53:16.327001 env[1061]: time="2025-05-15T17:53:16.326976070Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" May 15 17:53:16.327001 env[1061]: time="2025-05-15T17:53:16.326990180Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 May 15 17:53:16.327105 env[1061]: time="2025-05-15T17:53:16.327080710Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 May 15 17:53:16.327386 env[1061]: time="2025-05-15T17:53:16.327360879Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 May 15 17:53:16.327521 env[1061]: time="2025-05-15T17:53:16.327493678Z" 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 May 15 17:53:16.327565 env[1061]: time="2025-05-15T17:53:16.327518636Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 May 15 17:53:16.327596 env[1061]: time="2025-05-15T17:53:16.327577755Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" May 15 17:53:16.327636 env[1061]: time="2025-05-15T17:53:16.327594213Z" level=info msg="metadata content store policy set" policy=shared May 15 17:53:16.337115 coreos-metadata[1045]: May 15 17:53:16.336 INFO Fetch successful May 15 17:53:16.337115 coreos-metadata[1045]: May 15 17:53:16.337 INFO Fetching http://169.254.169.254/latest/meta-data/public-keys/0/openssh-key: Attempt #1 May 15 17:53:16.345480 coreos-metadata[1046]: May 15 17:53:16.345 INFO Fetching http://169.254.169.254/latest/meta-data/hostname: Attempt #1 May 15 17:53:16.350181 coreos-metadata[1045]: May 15 17:53:16.350 INFO Fetch successful May 15 17:53:16.358207 coreos-metadata[1046]: May 15 17:53:16.358 INFO Fetch successful May 15 17:53:16.358207 coreos-metadata[1046]: May 15 17:53:16.358 INFO Fetching http://169.254.169.254/latest/meta-data/instance-id: Attempt #1 May 15 17:53:16.368596 coreos-metadata[1046]: May 15 17:53:16.368 INFO Fetch successful May 15 17:53:16.368596 coreos-metadata[1046]: May 15 17:53:16.368 INFO Fetching http://169.254.169.254/latest/meta-data/instance-type: Attempt #1 May 15 17:53:16.375207 unknown[1045]: wrote ssh authorized keys file for user: core May 15 17:53:16.382617 coreos-metadata[1046]: May 15 17:53:16.382 INFO Fetch successful May 15 17:53:16.382617 coreos-metadata[1046]: May 15 17:53:16.382 INFO Fetching http://169.254.169.254/latest/meta-data/local-ipv4: Attempt #1 May 15 17:53:16.396956 coreos-metadata[1046]: May 15 17:53:16.396 INFO Fetch successful May 15 17:53:16.396956 coreos-metadata[1046]: May 15 17:53:16.396 INFO Fetching http://169.254.169.254/latest/meta-data/public-ipv4: Attempt #1 May 15 17:53:16.407344 coreos-metadata[1046]: May 15 17:53:16.407 INFO Fetch successful May 15 17:53:16.428752 systemd[1]: Finished coreos-metadata.service. May 15 17:53:16.430667 systemd[1]: Starting etcd-member.service... May 15 17:53:16.439340 env[1061]: time="2025-05-15T17:53:16.437437407Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 May 15 17:53:16.439340 env[1061]: time="2025-05-15T17:53:16.437512814Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 May 15 17:53:16.439340 env[1061]: time="2025-05-15T17:53:16.437554461Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 May 15 17:53:16.439340 env[1061]: time="2025-05-15T17:53:16.437618206Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 May 15 17:53:16.439340 env[1061]: time="2025-05-15T17:53:16.437653822Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 May 15 17:53:16.439340 env[1061]: time="2025-05-15T17:53:16.437685915Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 May 15 17:53:16.439340 env[1061]: time="2025-05-15T17:53:16.437715329Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 May 15 17:53:16.439340 env[1061]: time="2025-05-15T17:53:16.437746860Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 May 15 17:53:16.439340 env[1061]: time="2025-05-15T17:53:16.437777136Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1 May 15 17:53:16.439340 env[1061]: time="2025-05-15T17:53:16.437806661Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 May 15 17:53:16.439340 env[1061]: time="2025-05-15T17:53:16.437835955Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 May 15 17:53:16.439340 env[1061]: time="2025-05-15T17:53:16.437866843Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 May 15 17:53:16.439340 env[1061]: time="2025-05-15T17:53:16.438043147Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 May 15 17:53:16.439340 env[1061]: time="2025-05-15T17:53:16.438215375Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 May 15 17:53:16.447112 env[1061]: time="2025-05-15T17:53:16.438731538Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 May 15 17:53:16.447112 env[1061]: time="2025-05-15T17:53:16.438787365Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 May 15 17:53:16.447112 env[1061]: time="2025-05-15T17:53:16.438818976Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 May 15 17:53:16.447112 env[1061]: time="2025-05-15T17:53:16.438893148Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 May 15 17:53:16.447112 env[1061]: time="2025-05-15T17:53:16.438924740Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 May 15 17:53:16.447112 env[1061]: time="2025-05-15T17:53:16.438953140Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 May 15 17:53:16.447112 env[1061]: time="2025-05-15T17:53:16.438979332Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 May 15 17:53:16.447112 env[1061]: time="2025-05-15T17:53:16.439007944Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 May 15 17:53:16.447112 env[1061]: time="2025-05-15T17:53:16.439036956Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 May 15 17:53:16.447112 env[1061]: time="2025-05-15T17:53:16.439064303Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 May 15 17:53:16.447112 env[1061]: time="2025-05-15T17:53:16.439091027Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 May 15 17:53:16.447112 env[1061]: time="2025-05-15T17:53:16.439122829Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 May 15 17:53:16.447112 env[1061]: time="2025-05-15T17:53:16.444578888Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 May 15 17:53:16.447112 env[1061]: time="2025-05-15T17:53:16.444670191Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 May 15 17:53:16.447112 env[1061]: time="2025-05-15T17:53:16.444705225Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 May 15 17:53:16.450541 env[1061]: time="2025-05-15T17:53:16.444775332Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 May 15 17:53:16.450541 env[1061]: time="2025-05-15T17:53:16.444853056Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1 May 15 17:53:16.450541 env[1061]: time="2025-05-15T17:53:16.444886856Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 May 15 17:53:16.450541 env[1061]: time="2025-05-15T17:53:16.444960677Z" level=error msg="failed to initialize a tracing processor \"otlp\"" error="no OpenTelemetry endpoint: skip plugin" May 15 17:53:16.450541 env[1061]: time="2025-05-15T17:53:16.445078011Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 May 15 17:53:16.450834 env[1061]: time="2025-05-15T17:53:16.445794511Z" 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 BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 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.6 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} 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}" May 15 17:53:16.450834 env[1061]: time="2025-05-15T17:53:16.445993795Z" level=info msg="Connect containerd service" May 15 17:53:16.450834 env[1061]: time="2025-05-15T17:53:16.446093287Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" May 15 17:53:16.450834 env[1061]: time="2025-05-15T17:53:16.449316399Z" 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" May 15 17:53:16.450834 env[1061]: time="2025-05-15T17:53:16.449539879Z" level=info msg="Start subscribing containerd event" May 15 17:53:16.450834 env[1061]: time="2025-05-15T17:53:16.449644749Z" level=info msg="Start recovering state" May 15 17:53:16.450834 env[1061]: time="2025-05-15T17:53:16.449750040Z" level=info msg="Start event monitor" May 15 17:53:16.450834 env[1061]: time="2025-05-15T17:53:16.449787644Z" level=info msg="Start snapshots syncer" May 15 17:53:16.450834 env[1061]: time="2025-05-15T17:53:16.449807002Z" level=info msg="Start cni network conf syncer for default" May 15 17:53:16.450834 env[1061]: time="2025-05-15T17:53:16.449825306Z" level=info msg="Start streaming server" May 15 17:53:16.480455 env[1061]: time="2025-05-15T17:53:16.451440210Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 15 17:53:16.480455 env[1061]: time="2025-05-15T17:53:16.451670423Z" level=info msg=serving... address=/run/containerd/containerd.sock May 15 17:53:16.480455 env[1061]: time="2025-05-15T17:53:16.459585937Z" level=info msg="containerd successfully booted in 0.280706s" May 15 17:53:16.457794 systemd[1]: Started containerd.service. May 15 17:53:16.519520 systemd[1]: Starting docker.service... May 15 17:53:16.581862 env[1120]: time="2025-05-15T17:53:16.580953953Z" level=info msg="Starting up" May 15 17:53:16.583567 env[1120]: time="2025-05-15T17:53:16.583232610Z" level=info msg="parsed scheme: \"unix\"" module=grpc May 15 17:53:16.583567 env[1120]: time="2025-05-15T17:53:16.583276144Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc May 15 17:53:16.583567 env[1120]: time="2025-05-15T17:53:16.583307745Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/libcontainerd/docker-containerd.sock 0 }] }" module=grpc May 15 17:53:16.583567 env[1120]: time="2025-05-15T17:53:16.583320922Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc May 15 17:53:16.585945 env[1120]: time="2025-05-15T17:53:16.585779454Z" level=info msg="parsed scheme: \"unix\"" module=grpc May 15 17:53:16.585945 env[1120]: time="2025-05-15T17:53:16.585797558Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc May 15 17:53:16.585945 env[1120]: time="2025-05-15T17:53:16.585811437Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/libcontainerd/docker-containerd.sock 0 }] }" module=grpc May 15 17:53:16.585945 env[1120]: time="2025-05-15T17:53:16.585820941Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc May 15 17:53:16.690472 update-ssh-keys[1108]: Updated "/home/core/.ssh/authorized_keys" May 15 17:53:16.691169 systemd[1]: Finished coreos-metadata-sshkeys@core.service. May 15 17:53:16.833584 sshd_keygen[1079]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 May 15 17:53:16.871240 systemd[1]: Finished sshd-keygen.service. May 15 17:53:16.873197 systemd[1]: Starting issuegen.service... May 15 17:53:16.883353 systemd[1]: issuegen.service: Deactivated successfully. May 15 17:53:16.883529 systemd[1]: Finished issuegen.service. May 15 17:53:16.885638 systemd[1]: Starting systemd-user-sessions.service... May 15 17:53:16.898757 systemd[1]: Finished systemd-user-sessions.service. May 15 17:53:16.900602 systemd[1]: Started getty@tty1.service. May 15 17:53:16.902714 systemd[1]: Started serial-getty@ttyS0.service. May 15 17:53:16.904056 systemd[1]: Reached target getty.target. May 15 17:53:16.944880 env[1120]: time="2025-05-15T17:53:16.944755199Z" level=info msg="Loading containers: start." May 15 17:53:17.119066 locksmithd[1101]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" May 15 17:53:17.321384 kernel: Initializing XFRM netlink socket May 15 17:53:17.488578 env[1120]: time="2025-05-15T17:53:17.488430947Z" level=info msg="Default bridge (docker0) is assigned with an IP address 172.17.0.0/16. Daemon option --bip can be used to set a preferred IP address" May 15 17:53:17.556024 systemd-networkd[981]: docker0: Link UP May 15 17:53:17.590496 env[1120]: time="2025-05-15T17:53:17.590424840Z" level=info msg="Loading containers: done." May 15 17:53:17.608546 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck1268825946-merged.mount: Deactivated successfully. May 15 17:53:17.728642 env[1120]: time="2025-05-15T17:53:17.728550654Z" 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 May 15 17:53:17.729314 env[1120]: time="2025-05-15T17:53:17.729232762Z" level=info msg="Docker daemon" commit=112bdf3343 graphdriver(s)=overlay2 version=20.10.23 May 15 17:53:17.729673 env[1120]: time="2025-05-15T17:53:17.729633807Z" level=info msg="Daemon has completed initialization" May 15 17:53:17.782506 systemd[1]: Started docker.service. May 15 17:53:17.794557 env[1120]: time="2025-05-15T17:53:17.794474627Z" level=info msg="API listen on /run/docker.sock" May 15 17:53:17.796556 etcd-wrapper[1114]: Error response from daemon: No such container: etcd-member May 15 17:53:17.841481 etcd-wrapper[1245]: Error: No such container: etcd-member May 15 17:53:17.939059 etcd-wrapper[1261]: Unable to find image 'quay.io/coreos/etcd:v3.5.16' locally May 15 17:53:20.342845 etcd-wrapper[1261]: v3.5.16: Pulling from coreos/etcd May 15 17:53:20.691613 etcd-wrapper[1261]: 804c8aba2cc6: Pulling fs layer May 15 17:53:20.691613 etcd-wrapper[1261]: 2ae710cd8bfe: Pulling fs layer May 15 17:53:20.691613 etcd-wrapper[1261]: d462aa345367: Pulling fs layer May 15 17:53:20.691613 etcd-wrapper[1261]: 0f8b424aa0b9: Pulling fs layer May 15 17:53:20.691613 etcd-wrapper[1261]: d557676654e5: Pulling fs layer May 15 17:53:20.691613 etcd-wrapper[1261]: c8022d07192e: Pulling fs layer May 15 17:53:20.691613 etcd-wrapper[1261]: d858cbc252ad: Pulling fs layer May 15 17:53:20.691613 etcd-wrapper[1261]: 1069fc2daed1: Pulling fs layer May 15 17:53:20.691613 etcd-wrapper[1261]: b40161cd83fc: Pulling fs layer May 15 17:53:20.691613 etcd-wrapper[1261]: 5318d93a3a65: Pulling fs layer May 15 17:53:20.691613 etcd-wrapper[1261]: 307c1adadb60: Pulling fs layer May 15 17:53:20.691613 etcd-wrapper[1261]: fbb01d9e9dc9: Pulling fs layer May 15 17:53:20.691613 etcd-wrapper[1261]: fbfea02ac3cf: Pulling fs layer May 15 17:53:20.691613 etcd-wrapper[1261]: 8c26e4bf18e2: Pulling fs layer May 15 17:53:20.691613 etcd-wrapper[1261]: 1e59a65f8816: Pulling fs layer May 15 17:53:20.691613 etcd-wrapper[1261]: ffbd4ca5f0bd: Pulling fs layer May 15 17:53:20.693765 etcd-wrapper[1261]: 0f8b424aa0b9: Waiting May 15 17:53:20.693765 etcd-wrapper[1261]: d557676654e5: Waiting May 15 17:53:20.693765 etcd-wrapper[1261]: c8022d07192e: Waiting May 15 17:53:20.693765 etcd-wrapper[1261]: d858cbc252ad: Waiting May 15 17:53:20.693765 etcd-wrapper[1261]: 1069fc2daed1: Waiting May 15 17:53:20.693765 etcd-wrapper[1261]: b40161cd83fc: Waiting May 15 17:53:20.693765 etcd-wrapper[1261]: 5318d93a3a65: Waiting May 15 17:53:20.693765 etcd-wrapper[1261]: 307c1adadb60: Waiting May 15 17:53:20.693765 etcd-wrapper[1261]: fbb01d9e9dc9: Waiting May 15 17:53:20.695365 etcd-wrapper[1261]: fbfea02ac3cf: Waiting May 15 17:53:20.695365 etcd-wrapper[1261]: 8c26e4bf18e2: Waiting May 15 17:53:20.695365 etcd-wrapper[1261]: 1e59a65f8816: Waiting May 15 17:53:20.695365 etcd-wrapper[1261]: ffbd4ca5f0bd: Waiting May 15 17:53:21.022874 etcd-wrapper[1261]: 804c8aba2cc6: Verifying Checksum May 15 17:53:21.023227 etcd-wrapper[1261]: 804c8aba2cc6: Download complete May 15 17:53:21.049706 etcd-wrapper[1261]: d462aa345367: Verifying Checksum May 15 17:53:21.050315 etcd-wrapper[1261]: d462aa345367: Download complete May 15 17:53:21.130145 etcd-wrapper[1261]: 2ae710cd8bfe: Download complete May 15 17:53:21.177806 etcd-wrapper[1261]: 804c8aba2cc6: Pull complete May 15 17:53:21.263737 systemd[1]: var-lib-docker-overlay2-baa358e5cf00a06133dd933c52089183ac366f51b98750acb897a1d5b325ff18-merged.mount: Deactivated successfully. May 15 17:53:21.309839 etcd-wrapper[1261]: 0f8b424aa0b9: Verifying Checksum May 15 17:53:21.310055 etcd-wrapper[1261]: 0f8b424aa0b9: Download complete May 15 17:53:21.325993 etcd-wrapper[1261]: 2ae710cd8bfe: Pull complete May 15 17:53:21.377885 etcd-wrapper[1261]: d557676654e5: Verifying Checksum May 15 17:53:21.378494 etcd-wrapper[1261]: d557676654e5: Download complete May 15 17:53:21.505285 etcd-wrapper[1261]: c8022d07192e: Verifying Checksum May 15 17:53:21.505285 etcd-wrapper[1261]: c8022d07192e: Download complete May 15 17:53:21.586469 etcd-wrapper[1261]: d858cbc252ad: Verifying Checksum May 15 17:53:21.586739 etcd-wrapper[1261]: d858cbc252ad: Download complete May 15 17:53:21.648495 systemd[1]: var-lib-docker-overlay2-3a267d3159cce9ea3baef1c5967f33698148a2b6c7fc584b76b2e449e4dcf2f3-merged.mount: Deactivated successfully. May 15 17:53:21.673509 etcd-wrapper[1261]: 1069fc2daed1: Verifying Checksum May 15 17:53:21.673509 etcd-wrapper[1261]: 1069fc2daed1: Download complete May 15 17:53:21.780990 etcd-wrapper[1261]: b40161cd83fc: Verifying Checksum May 15 17:53:21.806005 etcd-wrapper[1261]: d462aa345367: Pull complete May 15 17:53:21.878941 etcd-wrapper[1261]: 5318d93a3a65: Download complete May 15 17:53:21.985572 etcd-wrapper[1261]: 307c1adadb60: Verifying Checksum May 15 17:53:21.985572 etcd-wrapper[1261]: 307c1adadb60: Download complete May 15 17:53:21.987037 etcd-wrapper[1261]: 0f8b424aa0b9: Pull complete May 15 17:53:22.107146 etcd-wrapper[1261]: d557676654e5: Pull complete May 15 17:53:22.192178 systemd[1]: var-lib-docker-overlay2-68501029d926a2687decbbdcdda46d3e4150f0c3ee798044a6fdaa7387e26804-merged.mount: Deactivated successfully. May 15 17:53:22.218785 systemd[1]: var-lib-docker-overlay2-0dfcf5a2dcb4af901f3e1a930cfb7b4684f214529e7e82da2c870ef67b7fa46c-merged.mount: Deactivated successfully. May 15 17:53:22.278866 etcd-wrapper[1261]: c8022d07192e: Pull complete May 15 17:53:22.379485 systemd[1]: var-lib-docker-overlay2-f8a87d502e165b299cc10aa882284ddff87f14e9517c8a8dd8f86327bed700c6-merged.mount: Deactivated successfully. May 15 17:53:22.510549 etcd-wrapper[1261]: d858cbc252ad: Pull complete May 15 17:53:22.576607 etcd-wrapper[1261]: 8c26e4bf18e2: Verifying Checksum May 15 17:53:22.641687 etcd-wrapper[1261]: 8c26e4bf18e2: Download complete May 15 17:53:22.726782 etcd-wrapper[1261]: fbfea02ac3cf: Verifying Checksum May 15 17:53:22.726953 etcd-wrapper[1261]: fbfea02ac3cf: Download complete May 15 17:53:22.740178 etcd-wrapper[1261]: 1069fc2daed1: Pull complete May 15 17:53:22.803065 etcd-wrapper[1261]: fbb01d9e9dc9: Verifying Checksum May 15 17:53:22.803065 etcd-wrapper[1261]: fbb01d9e9dc9: Download complete May 15 17:53:22.878559 etcd-wrapper[1261]: b40161cd83fc: Pull complete May 15 17:53:22.879885 etcd-wrapper[1261]: 1e59a65f8816: Verifying Checksum May 15 17:53:22.882683 etcd-wrapper[1261]: 1e59a65f8816: Download complete May 15 17:53:23.002295 etcd-wrapper[1261]: ffbd4ca5f0bd: Verifying Checksum May 15 17:53:23.002730 etcd-wrapper[1261]: ffbd4ca5f0bd: Download complete May 15 17:53:23.027173 etcd-wrapper[1261]: 5318d93a3a65: Pull complete May 15 17:53:23.148751 etcd-wrapper[1261]: 307c1adadb60: Pull complete May 15 17:53:23.193143 systemd[1]: var-lib-docker-overlay2-f09e91ed2f181bfc9b16e7534e4d97595bd0a30c7228dfc512dfe9938a5d4788-merged.mount: Deactivated successfully. May 15 17:53:23.811711 systemd[1]: var-lib-docker-overlay2-6ac57108f26567dde7635636e495d372d8ca987acda0258e28db6ff1d30154ff-merged.mount: Deactivated successfully. May 15 17:53:23.963037 etcd-wrapper[1261]: fbb01d9e9dc9: Pull complete May 15 17:53:24.438002 systemd[1]: var-lib-docker-overlay2-d10cd9094c6858173677c2413d4e8dfb3903eb4596ad2bcf6675da00d7e66106-merged.mount: Deactivated successfully. May 15 17:53:24.545978 etcd-wrapper[1261]: fbfea02ac3cf: Pull complete May 15 17:53:24.940110 systemd[1]: var-lib-docker-overlay2-b00c0a1be53ad34a522e910d0843cd69b9ed17c06a6ce28b683909e7c61d5245-merged.mount: Deactivated successfully. May 15 17:53:25.050411 etcd-wrapper[1261]: 8c26e4bf18e2: Pull complete May 15 17:53:25.159192 systemd[1]: var-lib-docker-overlay2-b10ddf61e9af49a0e053d24410f596bff91b930711407ed9d4c1e4626fd08d2a-merged.mount: Deactivated successfully. May 15 17:53:25.182724 etcd-wrapper[1261]: 1e59a65f8816: Pull complete May 15 17:53:25.304492 etcd-wrapper[1261]: ffbd4ca5f0bd: Pull complete May 15 17:53:25.335937 etcd-wrapper[1261]: Digest: sha256:d967d98a12dc220a1a290794711dba7eba04b8ce465e12b02383d1bfbb33e159 May 15 17:53:25.342570 etcd-wrapper[1261]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.16 May 15 17:53:25.543010 env[1061]: time="2025-05-15T17:53:25.542884385Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 15 17:53:25.544387 env[1061]: time="2025-05-15T17:53:25.542971188Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 15 17:53:25.544387 env[1061]: time="2025-05-15T17:53:25.543002867Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 15 17:53:25.544387 env[1061]: time="2025-05-15T17:53:25.543249779Z" level=info msg="starting signal loop" namespace=moby path=/run/containerd/io.containerd.runtime.v2.task/moby/531cc74b1ce0585b585af1d468ce10e0664a07bbb8dcf99805174793e978c6ff pid=1386 runtime=io.containerd.runc.v2 May 15 17:53:25.566871 systemd[1]: run-docker-runtime\x2drunc-moby-531cc74b1ce0585b585af1d468ce10e0664a07bbb8dcf99805174793e978c6ff-runc.GB1YQk.mount: Deactivated successfully. May 15 17:53:25.572882 systemd[1]: Started docker-531cc74b1ce0585b585af1d468ce10e0664a07bbb8dcf99805174793e978c6ff.scope. May 15 17:53:25.648927 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.648623Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} May 15 17:53:25.649219 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.648766Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"115541c0e1ef4b8e8735142ada7d9521"} May 15 17:53:25.649219 etcd-wrapper[1261]: {"level":"warn","ts":"2025-05-15T17:53:25.648828Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} May 15 17:53:25.649219 etcd-wrapper[1261]: {"level":"warn","ts":"2025-05-15T17:53:25.648837Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.16"} May 15 17:53:25.649219 etcd-wrapper[1261]: {"level":"warn","ts":"2025-05-15T17:53:25.648844Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} May 15 17:53:25.649219 etcd-wrapper[1261]: {"level":"warn","ts":"2025-05-15T17:53:25.648850Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} May 15 17:53:25.649219 etcd-wrapper[1261]: {"level":"warn","ts":"2025-05-15T17:53:25.648936Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} May 15 17:53:25.649219 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.648980Z","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.161:2379"]} May 15 17:53:25.649219 etcd-wrapper[1261]: {"level":"warn","ts":"2025-05-15T17:53:25.649045Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} May 15 17:53:25.649219 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.649056Z","caller":"embed/etcd.go:128","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]} May 15 17:53:25.649638 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.649536Z","caller":"embed/etcd.go:136","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} May 15 17:53:25.649860 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.649715Z","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":"115541c0e1ef4b8e8735142ada7d9521","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.161:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"115541c0e1ef4b8e8735142ada7d9521=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"} May 15 17:53:25.654219 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.654035Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.995137ms"} May 15 17:53:25.665232 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.665074Z","caller":"etcdserver/raft.go:505","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"} May 15 17:53:25.665455 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.665198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"} May 15 17:53:25.665455 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.665289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"} May 15 17:53:25.665455 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.665300Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} May 15 17:53:25.665455 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.665308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"} May 15 17:53:25.665662 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.665387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} May 15 17:53:25.672222 etcd-wrapper[1261]: {"level":"warn","ts":"2025-05-15T17:53:25.672065Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} May 15 17:53:25.673344 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.673203Z","caller":"mvcc/kvstore.go:423","msg":"kvstore restored","current-rev":1} May 15 17:53:25.674378 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.674212Z","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"} May 15 17:53:25.675419 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.675181Z","caller":"etcdserver/server.go:873","msg":"starting etcd server","local-member-id":"8e9e05c52164694d","local-server-version":"3.5.16","cluster-version":"to_be_decided"} May 15 17:53:25.675671 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.675499Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} May 15 17:53:25.676869 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.676631Z","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"} May 15 17:53:25.678053 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.677868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} May 15 17:53:25.679525 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.677113Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} May 15 17:53:25.679525 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.679347Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} May 15 17:53:25.679804 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.679379Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} May 15 17:53:25.680088 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.679759Z","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.161:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} May 15 17:53:25.680088 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.679881Z","caller":"embed/etcd.go:600","msg":"serving peer traffic","address":"127.0.0.1:2380"} May 15 17:53:25.680088 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.679892Z","caller":"embed/etcd.go:572","msg":"cmux::serve","address":"127.0.0.1:2380"} May 15 17:53:25.680477 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.680284Z","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"]} May 15 17:53:25.766440 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.766009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"} May 15 17:53:25.766440 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.766201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"} May 15 17:53:25.767065 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.766717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"} May 15 17:53:25.767065 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.766890Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"} May 15 17:53:25.767580 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.767357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"} May 15 17:53:25.768028 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.767523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"} May 15 17:53:25.768198 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.767857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"} May 15 17:53:25.770226 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.769854Z","caller":"etcdserver/server.go:2651","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} May 15 17:53:25.770983 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.770663Z","caller":"etcdserver/server.go:2140","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:115541c0e1ef4b8e8735142ada7d9521 ClientURLs:[http://172.24.4.161:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"} May 15 17:53:25.771405 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.771108Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} May 15 17:53:25.771990 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.771612Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"} May 15 17:53:25.772464 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.772153Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} May 15 17:53:25.772790 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.772329Z","caller":"etcdserver/server.go:2675","msg":"cluster version is updated","cluster-version":"3.5"} May 15 17:53:25.773114 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.772619Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} May 15 17:53:25.773673 systemd[1]: Started etcd-member.service. May 15 17:53:25.774405 systemd[1]: Reached target multi-user.target. May 15 17:53:25.776752 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.773365Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} May 15 17:53:25.777151 systemd[1]: Starting systemd-update-utmp-runlevel.service... May 15 17:53:25.778454 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.773330Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} May 15 17:53:25.779588 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:53:25.779192Z","caller":"embed/serve.go:187","msg":"serving client traffic insecurely; this is strongly discouraged!","traffic":"grpc+http","address":"[::]:2379"} May 15 17:53:25.793823 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. May 15 17:53:25.794182 systemd[1]: Finished systemd-update-utmp-runlevel.service. May 15 17:53:25.794630 systemd[1]: Startup finished in 891ms (kernel) + 4.617s (initrd) + 17.556s (userspace) = 23.065s. May 15 17:53:26.223806 systemd[1]: Created slice system-sshd.slice. May 15 17:53:26.226401 systemd[1]: Started sshd@0-172.24.4.161:22-172.24.4.1:58858.service. May 15 17:53:28.014156 sshd[1428]: Accepted publickey for core from 172.24.4.1 port 58858 ssh2: RSA SHA256:lz85KEoa9OSNE4yBofg0wzTI7RsZJD6xBqTw2Doc0+0 May 15 17:53:28.021064 sshd[1428]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) May 15 17:53:28.046660 systemd[1]: Created slice user-500.slice. May 15 17:53:28.048978 systemd[1]: Starting user-runtime-dir@500.service... May 15 17:53:28.055891 systemd-logind[1057]: New session 1 of user core. May 15 17:53:28.071153 systemd[1]: Finished user-runtime-dir@500.service. May 15 17:53:28.076235 systemd[1]: Starting user@500.service... May 15 17:53:28.084327 (systemd)[1431]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) May 15 17:53:28.208426 systemd[1431]: Queued start job for default target default.target. May 15 17:53:28.209148 systemd[1431]: Reached target paths.target. May 15 17:53:28.209274 systemd[1431]: Reached target sockets.target. May 15 17:53:28.209390 systemd[1431]: Reached target timers.target. May 15 17:53:28.209479 systemd[1431]: Reached target basic.target. May 15 17:53:28.209587 systemd[1431]: Reached target default.target. May 15 17:53:28.209691 systemd[1431]: Startup finished in 112ms. May 15 17:53:28.210217 systemd[1]: Started user@500.service. May 15 17:53:28.215231 systemd[1]: Started session-1.scope. May 15 17:53:28.567728 systemd[1]: Started sshd@1-172.24.4.161:22-172.24.4.1:58872.service. May 15 17:53:29.619781 sshd[1440]: Accepted publickey for core from 172.24.4.1 port 58872 ssh2: RSA SHA256:lz85KEoa9OSNE4yBofg0wzTI7RsZJD6xBqTw2Doc0+0 May 15 17:53:29.622826 sshd[1440]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) May 15 17:53:29.634369 systemd[1]: Started session-2.scope. May 15 17:53:29.638397 systemd-logind[1057]: New session 2 of user core. May 15 17:53:30.166917 sshd[1440]: pam_unix(sshd:session): session closed for user core May 15 17:53:30.175507 systemd[1]: Started sshd@2-172.24.4.161:22-172.24.4.1:58888.service. May 15 17:53:30.178910 systemd[1]: sshd@1-172.24.4.161:22-172.24.4.1:58872.service: Deactivated successfully. May 15 17:53:30.180389 systemd[1]: session-2.scope: Deactivated successfully. May 15 17:53:30.182942 systemd-logind[1057]: Session 2 logged out. Waiting for processes to exit. May 15 17:53:30.185050 systemd-logind[1057]: Removed session 2. May 15 17:53:31.290970 sshd[1445]: Accepted publickey for core from 172.24.4.1 port 58888 ssh2: RSA SHA256:lz85KEoa9OSNE4yBofg0wzTI7RsZJD6xBqTw2Doc0+0 May 15 17:53:31.294389 sshd[1445]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) May 15 17:53:31.304860 systemd[1]: Started session-3.scope. May 15 17:53:31.306115 systemd-logind[1057]: New session 3 of user core. May 15 17:53:31.932092 sshd[1445]: pam_unix(sshd:session): session closed for user core May 15 17:53:31.939068 systemd[1]: Started sshd@3-172.24.4.161:22-172.24.4.1:58900.service. May 15 17:53:31.943002 systemd[1]: sshd@2-172.24.4.161:22-172.24.4.1:58888.service: Deactivated successfully. May 15 17:53:31.944462 systemd[1]: session-3.scope: Deactivated successfully. May 15 17:53:31.946380 systemd-logind[1057]: Session 3 logged out. Waiting for processes to exit. May 15 17:53:31.948525 systemd-logind[1057]: Removed session 3. May 15 17:53:33.209708 sshd[1451]: Accepted publickey for core from 172.24.4.1 port 58900 ssh2: RSA SHA256:lz85KEoa9OSNE4yBofg0wzTI7RsZJD6xBqTw2Doc0+0 May 15 17:53:33.212218 sshd[1451]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) May 15 17:53:33.222491 systemd-logind[1057]: New session 4 of user core. May 15 17:53:33.223143 systemd[1]: Started session-4.scope. May 15 17:53:33.792656 sshd[1451]: pam_unix(sshd:session): session closed for user core May 15 17:53:33.798506 systemd[1]: Started sshd@4-172.24.4.161:22-172.24.4.1:35552.service. May 15 17:53:33.803776 systemd[1]: sshd@3-172.24.4.161:22-172.24.4.1:58900.service: Deactivated successfully. May 15 17:53:33.805382 systemd[1]: session-4.scope: Deactivated successfully. May 15 17:53:33.808539 systemd-logind[1057]: Session 4 logged out. Waiting for processes to exit. May 15 17:53:33.811112 systemd-logind[1057]: Removed session 4. May 15 17:53:35.101027 sshd[1457]: Accepted publickey for core from 172.24.4.1 port 35552 ssh2: RSA SHA256:lz85KEoa9OSNE4yBofg0wzTI7RsZJD6xBqTw2Doc0+0 May 15 17:53:35.105139 sshd[1457]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) May 15 17:53:35.115928 systemd-logind[1057]: New session 5 of user core. May 15 17:53:35.116684 systemd[1]: Started session-5.scope. May 15 17:53:35.723816 sshd[1457]: pam_unix(sshd:session): session closed for user core May 15 17:53:35.730603 systemd[1]: Started sshd@5-172.24.4.161:22-172.24.4.1:35564.service. May 15 17:53:35.733974 systemd[1]: sshd@4-172.24.4.161:22-172.24.4.1:35552.service: Deactivated successfully. May 15 17:53:35.736586 systemd[1]: session-5.scope: Deactivated successfully. May 15 17:53:35.739033 systemd-logind[1057]: Session 5 logged out. Waiting for processes to exit. May 15 17:53:35.741742 systemd-logind[1057]: Removed session 5. May 15 17:53:36.947627 sshd[1465]: Accepted publickey for core from 172.24.4.1 port 35564 ssh2: RSA SHA256:lz85KEoa9OSNE4yBofg0wzTI7RsZJD6xBqTw2Doc0+0 May 15 17:53:36.950520 sshd[1465]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) May 15 17:53:36.958699 systemd-logind[1057]: New session 6 of user core. May 15 17:53:36.959533 systemd[1]: Started session-6.scope. May 15 17:53:38.097094 sshd[1465]: pam_unix(sshd:session): session closed for user core May 15 17:53:38.102941 systemd-logind[1057]: Session 6 logged out. Waiting for processes to exit. May 15 17:53:38.103727 systemd[1]: sshd@5-172.24.4.161:22-172.24.4.1:35564.service: Deactivated successfully. May 15 17:53:38.105067 systemd[1]: session-6.scope: Deactivated successfully. May 15 17:53:38.106895 systemd-logind[1057]: Removed session 6. May 15 17:54:01.737175 update_engine[1058]: I0515 17:54:01.737022 1058 update_attempter.cc:509] Updating boot flags... May 15 17:55:37.788592 update_engine[1058]: I0515 17:55:37.788348 1058 prefs.cc:52] certificate-report-to-send-update not present in /var/lib/update_engine/prefs May 15 17:55:37.788592 update_engine[1058]: I0515 17:55:37.788491 1058 prefs.cc:52] certificate-report-to-send-download not present in /var/lib/update_engine/prefs May 15 17:55:37.793164 update_engine[1058]: I0515 17:55:37.789734 1058 prefs.cc:52] aleph-version not present in /var/lib/update_engine/prefs May 15 17:55:37.793164 update_engine[1058]: I0515 17:55:37.791241 1058 omaha_request_params.cc:62] Current group set to lts May 15 17:55:37.793164 update_engine[1058]: I0515 17:55:37.791762 1058 update_attempter.cc:499] Already updated boot flags. Skipping. May 15 17:55:37.793164 update_engine[1058]: I0515 17:55:37.791772 1058 update_attempter.cc:643] Scheduling an action processor start. May 15 17:55:37.793164 update_engine[1058]: I0515 17:55:37.791817 1058 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction May 15 17:55:37.793164 update_engine[1058]: I0515 17:55:37.791893 1058 prefs.cc:52] previous-version not present in /var/lib/update_engine/prefs May 15 17:55:37.793164 update_engine[1058]: I0515 17:55:37.792079 1058 omaha_request_action.cc:270] Posting an Omaha request to disabled May 15 17:55:37.793164 update_engine[1058]: I0515 17:55:37.792091 1058 omaha_request_action.cc:271] Request: May 15 17:55:37.793164 update_engine[1058]: May 15 17:55:37.793164 update_engine[1058]: May 15 17:55:37.793164 update_engine[1058]: May 15 17:55:37.793164 update_engine[1058]: May 15 17:55:37.793164 update_engine[1058]: May 15 17:55:37.793164 update_engine[1058]: May 15 17:55:37.793164 update_engine[1058]: May 15 17:55:37.793164 update_engine[1058]: May 15 17:55:37.793164 update_engine[1058]: I0515 17:55:37.792106 1058 libcurl_http_fetcher.cc:47] Starting/Resuming transfer May 15 17:55:37.797144 locksmithd[1101]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_CHECKING_FOR_UPDATE" NewVersion=0.0.0 NewSize=0 May 15 17:55:37.800905 update_engine[1058]: I0515 17:55:37.800884 1058 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP May 15 17:55:37.801240 update_engine[1058]: E0515 17:55:37.801223 1058 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled May 15 17:55:37.801421 update_engine[1058]: I0515 17:55:37.801404 1058 libcurl_http_fetcher.cc:283] No HTTP response, retry 1 May 15 17:55:47.740350 update_engine[1058]: I0515 17:55:47.739625 1058 libcurl_http_fetcher.cc:47] Starting/Resuming transfer May 15 17:55:47.740350 update_engine[1058]: I0515 17:55:47.740025 1058 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP May 15 17:55:47.741441 update_engine[1058]: E0515 17:55:47.741163 1058 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled May 15 17:55:47.741441 update_engine[1058]: I0515 17:55:47.741384 1058 libcurl_http_fetcher.cc:283] No HTTP response, retry 2 May 15 17:55:57.740405 update_engine[1058]: I0515 17:55:57.739575 1058 libcurl_http_fetcher.cc:47] Starting/Resuming transfer May 15 17:55:57.740405 update_engine[1058]: I0515 17:55:57.739972 1058 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP May 15 17:55:57.740405 update_engine[1058]: E0515 17:55:57.740152 1058 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled May 15 17:55:57.740405 update_engine[1058]: I0515 17:55:57.740387 1058 libcurl_http_fetcher.cc:283] No HTTP response, retry 3 May 15 17:56:07.733200 update_engine[1058]: I0515 17:56:07.733037 1058 libcurl_http_fetcher.cc:47] Starting/Resuming transfer May 15 17:56:07.733981 update_engine[1058]: I0515 17:56:07.733481 1058 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP May 15 17:56:07.733981 update_engine[1058]: E0515 17:56:07.733748 1058 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled May 15 17:56:07.733981 update_engine[1058]: I0515 17:56:07.733892 1058 libcurl_http_fetcher.cc:297] Transfer resulted in an error (0), 0 bytes downloaded May 15 17:56:07.733981 update_engine[1058]: I0515 17:56:07.733920 1058 omaha_request_action.cc:621] Omaha request response: May 15 17:56:07.734430 update_engine[1058]: E0515 17:56:07.734045 1058 omaha_request_action.cc:640] Omaha request network transfer failed. May 15 17:56:07.734430 update_engine[1058]: I0515 17:56:07.734097 1058 action_processor.cc:68] ActionProcessor::ActionComplete: OmahaRequestAction action failed. Aborting processing. May 15 17:56:07.734430 update_engine[1058]: I0515 17:56:07.734105 1058 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction May 15 17:56:07.734430 update_engine[1058]: I0515 17:56:07.734113 1058 update_attempter.cc:306] Processing Done. May 15 17:56:07.734430 update_engine[1058]: E0515 17:56:07.734148 1058 update_attempter.cc:619] Update failed. May 15 17:56:07.734430 update_engine[1058]: I0515 17:56:07.734207 1058 utils.cc:600] Converting error code 2000 to kActionCodeOmahaErrorInHTTPResponse May 15 17:56:07.734430 update_engine[1058]: I0515 17:56:07.734216 1058 payload_state.cc:97] Updating payload state for error code: 37 (kActionCodeOmahaErrorInHTTPResponse) May 15 17:56:07.734430 update_engine[1058]: I0515 17:56:07.734233 1058 payload_state.cc:103] Ignoring failures until we get a valid Omaha response. May 15 17:56:07.734430 update_engine[1058]: I0515 17:56:07.734426 1058 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction May 15 17:56:07.735800 update_engine[1058]: I0515 17:56:07.734469 1058 omaha_request_action.cc:270] Posting an Omaha request to disabled May 15 17:56:07.735800 update_engine[1058]: I0515 17:56:07.734477 1058 omaha_request_action.cc:271] Request: May 15 17:56:07.735800 update_engine[1058]: May 15 17:56:07.735800 update_engine[1058]: May 15 17:56:07.735800 update_engine[1058]: May 15 17:56:07.735800 update_engine[1058]: May 15 17:56:07.735800 update_engine[1058]: May 15 17:56:07.735800 update_engine[1058]: May 15 17:56:07.735800 update_engine[1058]: I0515 17:56:07.734486 1058 libcurl_http_fetcher.cc:47] Starting/Resuming transfer May 15 17:56:07.735800 update_engine[1058]: I0515 17:56:07.734745 1058 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP May 15 17:56:07.735800 update_engine[1058]: E0515 17:56:07.734943 1058 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled May 15 17:56:07.735800 update_engine[1058]: I0515 17:56:07.735069 1058 libcurl_http_fetcher.cc:297] Transfer resulted in an error (0), 0 bytes downloaded May 15 17:56:07.735800 update_engine[1058]: I0515 17:56:07.735082 1058 omaha_request_action.cc:621] Omaha request response: May 15 17:56:07.735800 update_engine[1058]: I0515 17:56:07.735092 1058 action_processor.cc:65] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction May 15 17:56:07.735800 update_engine[1058]: I0515 17:56:07.735100 1058 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction May 15 17:56:07.735800 update_engine[1058]: I0515 17:56:07.735106 1058 update_attempter.cc:306] Processing Done. May 15 17:56:07.735800 update_engine[1058]: I0515 17:56:07.735113 1058 update_attempter.cc:310] Error event sent. May 15 17:56:07.735800 update_engine[1058]: I0515 17:56:07.735157 1058 update_check_scheduler.cc:74] Next update check in 41m8s May 15 17:56:07.737545 locksmithd[1101]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_REPORTING_ERROR_EVENT" NewVersion=0.0.0 NewSize=0 May 15 17:56:07.737545 locksmithd[1101]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_IDLE" NewVersion=0.0.0 NewSize=0 May 15 17:58:46.247450 etcd-wrapper[1261]: {"level":"warn","ts":"2025-05-15T17:58:46.241506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.297168ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} May 15 17:58:46.247450 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:58:46.242160Z","caller":"traceutil/trace.go:171","msg":"trace[790924831] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"119.728628ms","start":"2025-05-15T17:58:46.122352Z","end":"2025-05-15T17:58:46.242080Z","steps":["trace[790924831] 'compare' (duration: 107.134565ms)"],"step_count":1} May 15 17:58:46.247450 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:58:46.242385Z","caller":"traceutil/trace.go:171","msg":"trace[1842296575] linearizableReadLoop","detail":"{readStateIndex:264; appliedIndex:263; }","duration":"119.36079ms","start":"2025-05-15T17:58:46.122996Z","end":"2025-05-15T17:58:46.242357Z","steps":["trace[1842296575] 'read index received' (duration: 9.356389ms)","trace[1842296575] 'applied index is now lower than readState.Index' (duration: 110.003259ms)"],"step_count":2} May 15 17:58:46.259021 etcd-wrapper[1261]: {"level":"warn","ts":"2025-05-15T17:58:46.247919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.836228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} May 15 17:58:46.259021 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:58:46.248547Z","caller":"traceutil/trace.go:171","msg":"trace[1424632619] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:258; }","duration":"125.509549ms","start":"2025-05-15T17:58:46.122961Z","end":"2025-05-15T17:58:46.248470Z","steps":["trace[1424632619] 'agreement among raft nodes before linearized reading' (duration: 119.51055ms)"],"step_count":1} May 15 17:58:46.259021 etcd-wrapper[1261]: {"level":"warn","ts":"2025-05-15T17:58:46.249505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.984271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} May 15 17:58:46.259021 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:58:46.250052Z","caller":"traceutil/trace.go:171","msg":"trace[650876334] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:258; }","duration":"124.597129ms","start":"2025-05-15T17:58:46.125435Z","end":"2025-05-15T17:58:46.250032Z","steps":["trace[650876334] 'agreement among raft nodes before linearized reading' (duration: 117.691712ms)"],"step_count":1} May 15 17:58:47.565971 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:58:47.565242Z","caller":"traceutil/trace.go:171","msg":"trace[1626710798] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"213.013002ms","start":"2025-05-15T17:58:47.352127Z","end":"2025-05-15T17:58:47.565140Z","steps":["trace[1626710798] 'process raft request' (duration: 212.936158ms)"],"step_count":1} May 15 17:58:47.567397 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:58:47.565544Z","caller":"traceutil/trace.go:171","msg":"trace[1813426143] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"256.925591ms","start":"2025-05-15T17:58:47.308591Z","end":"2025-05-15T17:58:47.565516Z","steps":["trace[1813426143] 'process raft request' (duration: 205.741389ms)","trace[1813426143] 'compare' (duration: 50.564892ms)"],"step_count":2} May 15 17:58:47.567397 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:58:47.565669Z","caller":"traceutil/trace.go:171","msg":"trace[1777028883] linearizableReadLoop","detail":"{readStateIndex:303; appliedIndex:302; }","duration":"213.760662ms","start":"2025-05-15T17:58:47.351890Z","end":"2025-05-15T17:58:47.565651Z","steps":["trace[1777028883] 'read index received' (duration: 162.454471ms)","trace[1777028883] 'applied index is now lower than readState.Index' (duration: 51.304648ms)"],"step_count":2} May 15 17:58:47.568465 etcd-wrapper[1261]: {"level":"warn","ts":"2025-05-15T17:58:47.565761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.851643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:0 size:5"} May 15 17:58:47.568465 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:58:47.567885Z","caller":"traceutil/trace.go:171","msg":"trace[7474952] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:0; response_revision:294; }","duration":"216.004175ms","start":"2025-05-15T17:58:47.351855Z","end":"2025-05-15T17:58:47.567859Z","steps":["trace[7474952] 'agreement among raft nodes before linearized reading' (duration: 213.853426ms)"],"step_count":1} May 15 17:59:08.569922 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:59:08.562648Z","caller":"traceutil/trace.go:171","msg":"trace[1212790388] linearizableReadLoop","detail":"{readStateIndex:456; appliedIndex:455; }","duration":"115.938163ms","start":"2025-05-15T17:59:08.446559Z","end":"2025-05-15T17:59:08.562498Z","steps":["trace[1212790388] 'read index received' (duration: 108.135721ms)","trace[1212790388] 'applied index is now lower than readState.Index' (duration: 7.801761ms)"],"step_count":2} May 15 17:59:08.579365 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:59:08.562958Z","caller":"traceutil/trace.go:171","msg":"trace[1179904371] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"187.091919ms","start":"2025-05-15T17:59:08.375728Z","end":"2025-05-15T17:59:08.562820Z","steps":["trace[1179904371] 'process raft request' (duration: 179.040591ms)"],"step_count":1} May 15 17:59:08.579365 etcd-wrapper[1261]: {"level":"warn","ts":"2025-05-15T17:59:08.570107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.078365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} May 15 17:59:08.579365 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:59:08.570347Z","caller":"traceutil/trace.go:171","msg":"trace[152396402] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:442; }","duration":"123.337209ms","start":"2025-05-15T17:59:08.446975Z","end":"2025-05-15T17:59:08.570312Z","steps":["trace[152396402] 'agreement among raft nodes before linearized reading' (duration: 123.057756ms)"],"step_count":1} May 15 17:59:08.579365 etcd-wrapper[1261]: {"level":"warn","ts":"2025-05-15T17:59:08.564694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.739124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} May 15 17:59:08.579365 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T17:59:08.570841Z","caller":"traceutil/trace.go:171","msg":"trace[588239687] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:442; }","duration":"124.256633ms","start":"2025-05-15T17:59:08.446553Z","end":"2025-05-15T17:59:08.570809Z","steps":["trace[588239687] 'agreement among raft nodes before linearized reading' (duration: 116.492843ms)"],"step_count":1} May 15 18:03:27.707433 etcd-wrapper[1261]: {"level":"warn","ts":"2025-05-15T18:03:27.699640Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.131728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} May 15 18:03:27.707433 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T18:03:27.700578Z","caller":"traceutil/trace.go:171","msg":"trace[440367173] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:896; }","duration":"190.291392ms","start":"2025-05-15T18:03:27.510106Z","end":"2025-05-15T18:03:27.700397Z","steps":["trace[440367173] 'range keys from in-memory index tree' (duration: 188.879815ms)"],"step_count":1} May 15 18:03:34.039006 etcd-wrapper[1261]: {"level":"info","ts":"2025-05-15T18:03:34.037443Z","caller":"traceutil/trace.go:171","msg":"trace[1852261933] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"167.86102ms","start":"2025-05-15T18:03:33.869530Z","end":"2025-05-15T18:03:34.037391Z","steps":["trace[1852261933] 'process raft request' (duration: 167.347887ms)"],"step_count":1}