Dec 13 14:18:31.936193 kernel: Linux version 5.15.173-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 Fri Dec 13 12:55:10 -00 2024
Dec 13 14:18:31.936266 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=8c474c3ec361ec863adbecaa85281a726e1b53f7863ecc4742be8c5f6d02a66e
Dec 13 14:18:31.936295 kernel: BIOS-provided physical RAM map:
Dec 13 14:18:31.936312 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
Dec 13 14:18:31.936328 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
Dec 13 14:18:31.936344 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
Dec 13 14:18:31.936363 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000007ffdcfff] usable
Dec 13 14:18:31.936381 kernel: BIOS-e820: [mem 0x000000007ffdd000-0x000000007fffffff] reserved
Dec 13 14:18:31.936402 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
Dec 13 14:18:31.936418 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
Dec 13 14:18:31.936434 kernel: NX (Execute Disable) protection: active
Dec 13 14:18:31.936450 kernel: SMBIOS 2.8 present.
Dec 13 14:18:31.936466 kernel: DMI: OpenStack Foundation OpenStack Nova, BIOS 1.15.0-1 04/01/2014
Dec 13 14:18:31.936483 kernel: Hypervisor detected: KVM
Dec 13 14:18:31.936503 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00
Dec 13 14:18:31.936525 kernel: kvm-clock: cpu 0, msr 3c19a001, primary cpu clock
Dec 13 14:18:31.936543 kernel: kvm-clock: using sched offset of 6567039999 cycles
Dec 13 14:18:31.936562 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
Dec 13 14:18:31.936580 kernel: tsc: Detected 1996.249 MHz processor
Dec 13 14:18:31.936599 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
Dec 13 14:18:31.936618 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable
Dec 13 14:18:31.936637 kernel: last_pfn = 0x7ffdd max_arch_pfn = 0x400000000
Dec 13 14:18:31.936655 kernel: x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT  
Dec 13 14:18:31.936678 kernel: ACPI: Early table checksum verification disabled
Dec 13 14:18:31.936696 kernel: ACPI: RSDP 0x00000000000F5930 000014 (v00 BOCHS )
Dec 13 14:18:31.936714 kernel: ACPI: RSDT 0x000000007FFE1848 000030 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
Dec 13 14:18:31.936732 kernel: ACPI: FACP 0x000000007FFE172C 000074 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
Dec 13 14:18:31.936750 kernel: ACPI: DSDT 0x000000007FFE0040 0016EC (v01 BOCHS  BXPC     00000001 BXPC 00000001)
Dec 13 14:18:31.936768 kernel: ACPI: FACS 0x000000007FFE0000 000040
Dec 13 14:18:31.936787 kernel: ACPI: APIC 0x000000007FFE17A0 000080 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
Dec 13 14:18:31.936805 kernel: ACPI: WAET 0x000000007FFE1820 000028 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
Dec 13 14:18:31.936823 kernel: ACPI: Reserving FACP table memory at [mem 0x7ffe172c-0x7ffe179f]
Dec 13 14:18:31.936845 kernel: ACPI: Reserving DSDT table memory at [mem 0x7ffe0040-0x7ffe172b]
Dec 13 14:18:31.936862 kernel: ACPI: Reserving FACS table memory at [mem 0x7ffe0000-0x7ffe003f]
Dec 13 14:18:31.936880 kernel: ACPI: Reserving APIC table memory at [mem 0x7ffe17a0-0x7ffe181f]
Dec 13 14:18:31.936898 kernel: ACPI: Reserving WAET table memory at [mem 0x7ffe1820-0x7ffe1847]
Dec 13 14:18:31.936948 kernel: No NUMA configuration found
Dec 13 14:18:31.936966 kernel: Faking a node at [mem 0x0000000000000000-0x000000007ffdcfff]
Dec 13 14:18:31.936985 kernel: NODE_DATA(0) allocated [mem 0x7ffd7000-0x7ffdcfff]
Dec 13 14:18:31.937003 kernel: Zone ranges:
Dec 13 14:18:31.937035 kernel:   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
Dec 13 14:18:31.937053 kernel:   DMA32    [mem 0x0000000001000000-0x000000007ffdcfff]
Dec 13 14:18:31.937072 kernel:   Normal   empty
Dec 13 14:18:31.937090 kernel: Movable zone start for each node
Dec 13 14:18:31.937109 kernel: Early memory node ranges
Dec 13 14:18:31.937127 kernel:   node   0: [mem 0x0000000000001000-0x000000000009efff]
Dec 13 14:18:31.937149 kernel:   node   0: [mem 0x0000000000100000-0x000000007ffdcfff]
Dec 13 14:18:31.937168 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000007ffdcfff]
Dec 13 14:18:31.937187 kernel: On node 0, zone DMA: 1 pages in unavailable ranges
Dec 13 14:18:31.937205 kernel: On node 0, zone DMA: 97 pages in unavailable ranges
Dec 13 14:18:31.937224 kernel: On node 0, zone DMA32: 35 pages in unavailable ranges
Dec 13 14:18:31.937242 kernel: ACPI: PM-Timer IO Port: 0x608
Dec 13 14:18:31.937261 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
Dec 13 14:18:31.937280 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
Dec 13 14:18:31.937298 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
Dec 13 14:18:31.937321 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
Dec 13 14:18:31.937339 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
Dec 13 14:18:31.937358 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
Dec 13 14:18:31.937377 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
Dec 13 14:18:31.937396 kernel: ACPI: Using ACPI (MADT) for SMP configuration information
Dec 13 14:18:31.937415 kernel: smpboot: Allowing 2 CPUs, 0 hotplug CPUs
Dec 13 14:18:31.937433 kernel: [mem 0x80000000-0xfeffbfff] available for PCI devices
Dec 13 14:18:31.937452 kernel: Booting paravirtualized kernel on KVM
Dec 13 14:18:31.937471 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
Dec 13 14:18:31.937490 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:512 nr_cpu_ids:2 nr_node_ids:1
Dec 13 14:18:31.937513 kernel: percpu: Embedded 56 pages/cpu s188696 r8192 d32488 u1048576
Dec 13 14:18:31.937532 kernel: pcpu-alloc: s188696 r8192 d32488 u1048576 alloc=1*2097152
Dec 13 14:18:31.937550 kernel: pcpu-alloc: [0] 0 1 
Dec 13 14:18:31.937568 kernel: kvm-guest: stealtime: cpu 0, msr 7dc1c0c0
Dec 13 14:18:31.937587 kernel: kvm-guest: PV spinlocks disabled, no host support
Dec 13 14:18:31.937606 kernel: Built 1 zonelists, mobility grouping on.  Total pages: 515805
Dec 13 14:18:31.937624 kernel: Policy zone: DMA32
Dec 13 14:18:31.937646 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=8c474c3ec361ec863adbecaa85281a726e1b53f7863ecc4742be8c5f6d02a66e
Dec 13 14:18:31.937670 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space.
Dec 13 14:18:31.937689 kernel: Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
Dec 13 14:18:31.937708 kernel: Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
Dec 13 14:18:31.937727 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off
Dec 13 14:18:31.937747 kernel: Memory: 1973284K/2096620K available (12294K kernel code, 2275K rwdata, 13716K rodata, 47472K init, 4112K bss, 123076K reserved, 0K cma-reserved)
Dec 13 14:18:31.937766 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
Dec 13 14:18:31.937785 kernel: ftrace: allocating 34549 entries in 135 pages
Dec 13 14:18:31.937803 kernel: ftrace: allocated 135 pages with 4 groups
Dec 13 14:18:31.937825 kernel: rcu: Hierarchical RCU implementation.
Dec 13 14:18:31.937845 kernel: rcu:         RCU event tracing is enabled.
Dec 13 14:18:31.937865 kernel: rcu:         RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2.
Dec 13 14:18:31.937884 kernel:         Rude variant of Tasks RCU enabled.
Dec 13 14:18:31.937902 kernel:         Tracing variant of Tasks RCU enabled.
Dec 13 14:18:31.939987 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
Dec 13 14:18:31.940007 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
Dec 13 14:18:31.940026 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 16
Dec 13 14:18:31.940045 kernel: Console: colour VGA+ 80x25
Dec 13 14:18:31.940063 kernel: printk: console [tty0] enabled
Dec 13 14:18:31.940089 kernel: printk: console [ttyS0] enabled
Dec 13 14:18:31.940108 kernel: ACPI: Core revision 20210730
Dec 13 14:18:31.940127 kernel: APIC: Switch to symmetric I/O mode setup
Dec 13 14:18:31.940145 kernel: x2apic enabled
Dec 13 14:18:31.940163 kernel: Switched APIC routing to physical x2apic.
Dec 13 14:18:31.940182 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
Dec 13 14:18:31.940201 kernel: tsc: Marking TSC unstable due to TSCs unsynchronized
Dec 13 14:18:31.940238 kernel: Calibrating delay loop (skipped) preset value.. 3992.49 BogoMIPS (lpj=1996249)
Dec 13 14:18:31.940257 kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
Dec 13 14:18:31.940280 kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
Dec 13 14:18:31.940299 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
Dec 13 14:18:31.940317 kernel: Spectre V2 : Mitigation: Retpolines
Dec 13 14:18:31.940336 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
Dec 13 14:18:31.940355 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT
Dec 13 14:18:31.940375 kernel: Speculative Store Bypass: Vulnerable
Dec 13 14:18:31.940394 kernel: x86/fpu: x87 FPU will use FXSAVE
Dec 13 14:18:31.940412 kernel: Freeing SMP alternatives memory: 32K
Dec 13 14:18:31.940431 kernel: pid_max: default: 32768 minimum: 301
Dec 13 14:18:31.940453 kernel: LSM: Security Framework initializing
Dec 13 14:18:31.940472 kernel: SELinux:  Initializing.
Dec 13 14:18:31.940491 kernel: Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)
Dec 13 14:18:31.940510 kernel: Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)
Dec 13 14:18:31.940529 kernel: smpboot: CPU0: AMD Intel Core i7 9xx (Nehalem Class Core i7) (family: 0x6, model: 0x1a, stepping: 0x3)
Dec 13 14:18:31.940548 kernel: Performance Events: AMD PMU driver.
Dec 13 14:18:31.940566 kernel: ... version:                0
Dec 13 14:18:31.940585 kernel: ... bit width:              48
Dec 13 14:18:31.940604 kernel: ... generic registers:      4
Dec 13 14:18:31.940638 kernel: ... value mask:             0000ffffffffffff
Dec 13 14:18:31.940657 kernel: ... max period:             00007fffffffffff
Dec 13 14:18:31.940676 kernel: ... fixed-purpose events:   0
Dec 13 14:18:31.940699 kernel: ... event mask:             000000000000000f
Dec 13 14:18:31.940719 kernel: signal: max sigframe size: 1440
Dec 13 14:18:31.940738 kernel: rcu: Hierarchical SRCU implementation.
Dec 13 14:18:31.940757 kernel: smp: Bringing up secondary CPUs ...
Dec 13 14:18:31.940777 kernel: x86: Booting SMP configuration:
Dec 13 14:18:31.940801 kernel: .... node  #0, CPUs:      #1
Dec 13 14:18:31.940821 kernel: kvm-clock: cpu 1, msr 3c19a041, secondary cpu clock
Dec 13 14:18:31.940840 kernel: kvm-guest: stealtime: cpu 1, msr 7dd1c0c0
Dec 13 14:18:31.940860 kernel: smp: Brought up 1 node, 2 CPUs
Dec 13 14:18:31.940879 kernel: smpboot: Max logical packages: 2
Dec 13 14:18:31.940899 kernel: smpboot: Total of 2 processors activated (7984.99 BogoMIPS)
Dec 13 14:18:31.940947 kernel: devtmpfs: initialized
Dec 13 14:18:31.940967 kernel: x86/mm: Memory block size: 128MB
Dec 13 14:18:31.940987 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
Dec 13 14:18:31.941012 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear)
Dec 13 14:18:31.941031 kernel: pinctrl core: initialized pinctrl subsystem
Dec 13 14:18:31.941051 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family
Dec 13 14:18:31.941070 kernel: audit: initializing netlink subsys (disabled)
Dec 13 14:18:31.941090 kernel: audit: type=2000 audit(1734099511.091:1): state=initialized audit_enabled=0 res=1
Dec 13 14:18:31.941110 kernel: thermal_sys: Registered thermal governor 'step_wise'
Dec 13 14:18:31.941129 kernel: thermal_sys: Registered thermal governor 'user_space'
Dec 13 14:18:31.941149 kernel: cpuidle: using governor menu
Dec 13 14:18:31.941168 kernel: ACPI: bus type PCI registered
Dec 13 14:18:31.941193 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
Dec 13 14:18:31.941212 kernel: dca service started, version 1.12.1
Dec 13 14:18:31.941232 kernel: PCI: Using configuration type 1 for base access
Dec 13 14:18:31.941252 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.
Dec 13 14:18:31.941272 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
Dec 13 14:18:31.941291 kernel: ACPI: Added _OSI(Module Device)
Dec 13 14:18:31.941311 kernel: ACPI: Added _OSI(Processor Device)
Dec 13 14:18:31.941330 kernel: ACPI: Added _OSI(3.0 _SCP Extensions)
Dec 13 14:18:31.941350 kernel: ACPI: Added _OSI(Processor Aggregator Device)
Dec 13 14:18:31.941373 kernel: ACPI: Added _OSI(Linux-Dell-Video)
Dec 13 14:18:31.941392 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
Dec 13 14:18:31.941412 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics)
Dec 13 14:18:31.941432 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded
Dec 13 14:18:31.941451 kernel: ACPI: Interpreter enabled
Dec 13 14:18:31.941470 kernel: ACPI: PM: (supports S0 S3 S5)
Dec 13 14:18:31.941490 kernel: ACPI: Using IOAPIC for interrupt routing
Dec 13 14:18:31.941510 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
Dec 13 14:18:31.941530 kernel: ACPI: Enabled 2 GPEs in block 00 to 0F
Dec 13 14:18:31.941552 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
Dec 13 14:18:31.941868 kernel: acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3]
Dec 13 14:18:31.944157 kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
Dec 13 14:18:31.944197 kernel: acpiphp: Slot [3] registered
Dec 13 14:18:31.944243 kernel: acpiphp: Slot [4] registered
Dec 13 14:18:31.944264 kernel: acpiphp: Slot [5] registered
Dec 13 14:18:31.944283 kernel: acpiphp: Slot [6] registered
Dec 13 14:18:31.944312 kernel: acpiphp: Slot [7] registered
Dec 13 14:18:31.944331 kernel: acpiphp: Slot [8] registered
Dec 13 14:18:31.944351 kernel: acpiphp: Slot [9] registered
Dec 13 14:18:31.944370 kernel: acpiphp: Slot [10] registered
Dec 13 14:18:31.944390 kernel: acpiphp: Slot [11] registered
Dec 13 14:18:31.944409 kernel: acpiphp: Slot [12] registered
Dec 13 14:18:31.944429 kernel: acpiphp: Slot [13] registered
Dec 13 14:18:31.944448 kernel: acpiphp: Slot [14] registered
Dec 13 14:18:31.944467 kernel: acpiphp: Slot [15] registered
Dec 13 14:18:31.944487 kernel: acpiphp: Slot [16] registered
Dec 13 14:18:31.944510 kernel: acpiphp: Slot [17] registered
Dec 13 14:18:31.944529 kernel: acpiphp: Slot [18] registered
Dec 13 14:18:31.944549 kernel: acpiphp: Slot [19] registered
Dec 13 14:18:31.944568 kernel: acpiphp: Slot [20] registered
Dec 13 14:18:31.944587 kernel: acpiphp: Slot [21] registered
Dec 13 14:18:31.944606 kernel: acpiphp: Slot [22] registered
Dec 13 14:18:31.944626 kernel: acpiphp: Slot [23] registered
Dec 13 14:18:31.944645 kernel: acpiphp: Slot [24] registered
Dec 13 14:18:31.944664 kernel: acpiphp: Slot [25] registered
Dec 13 14:18:31.944687 kernel: acpiphp: Slot [26] registered
Dec 13 14:18:31.944704 kernel: acpiphp: Slot [27] registered
Dec 13 14:18:31.944719 kernel: acpiphp: Slot [28] registered
Dec 13 14:18:31.944733 kernel: acpiphp: Slot [29] registered
Dec 13 14:18:31.944747 kernel: acpiphp: Slot [30] registered
Dec 13 14:18:31.944762 kernel: acpiphp: Slot [31] registered
Dec 13 14:18:31.944776 kernel: PCI host bridge to bus 0000:00
Dec 13 14:18:31.954978 kernel: pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
Dec 13 14:18:31.955078 kernel: pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
Dec 13 14:18:31.955165 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
Dec 13 14:18:31.955244 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff window]
Dec 13 14:18:31.955322 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window]
Dec 13 14:18:31.955397 kernel: pci_bus 0000:00: root bus resource [bus 00-ff]
Dec 13 14:18:31.955507 kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
Dec 13 14:18:31.955611 kernel: pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
Dec 13 14:18:31.955723 kernel: pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
Dec 13 14:18:31.955813 kernel: pci 0000:00:01.1: reg 0x20: [io  0xc120-0xc12f]
Dec 13 14:18:31.955902 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
Dec 13 14:18:31.956016 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
Dec 13 14:18:31.956106 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
Dec 13 14:18:31.956193 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
Dec 13 14:18:31.956304 kernel: pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
Dec 13 14:18:31.956399 kernel: pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
Dec 13 14:18:31.956486 kernel: pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
Dec 13 14:18:31.956585 kernel: pci 0000:00:02.0: [1af4:1050] type 00 class 0x030000
Dec 13 14:18:31.956677 kernel: pci 0000:00:02.0: reg 0x10: [mem 0xfe000000-0xfe7fffff pref]
Dec 13 14:18:31.956771 kernel: pci 0000:00:02.0: reg 0x18: [mem 0xfe800000-0xfe803fff 64bit pref]
Dec 13 14:18:31.956861 kernel: pci 0000:00:02.0: reg 0x20: [mem 0xfeb90000-0xfeb90fff]
Dec 13 14:18:31.963946 kernel: pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfeb8ffff pref]
Dec 13 14:18:31.964058 kernel: pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
Dec 13 14:18:31.964159 kernel: pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000
Dec 13 14:18:31.964265 kernel: pci 0000:00:03.0: reg 0x10: [io  0xc080-0xc0bf]
Dec 13 14:18:31.964355 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xfeb91000-0xfeb91fff]
Dec 13 14:18:31.964445 kernel: pci 0000:00:03.0: reg 0x20: [mem 0xfe804000-0xfe807fff 64bit pref]
Dec 13 14:18:31.964533 kernel: pci 0000:00:03.0: reg 0x30: [mem 0xfeb00000-0xfeb7ffff pref]
Dec 13 14:18:31.964635 kernel: pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000
Dec 13 14:18:31.964726 kernel: pci 0000:00:04.0: reg 0x10: [io  0xc000-0xc07f]
Dec 13 14:18:31.964815 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xfeb92000-0xfeb92fff]
Dec 13 14:18:31.964903 kernel: pci 0000:00:04.0: reg 0x20: [mem 0xfe808000-0xfe80bfff 64bit pref]
Dec 13 14:18:31.965037 kernel: pci 0000:00:05.0: [1af4:1002] type 00 class 0x00ff00
Dec 13 14:18:31.965129 kernel: pci 0000:00:05.0: reg 0x10: [io  0xc0c0-0xc0ff]
Dec 13 14:18:31.965219 kernel: pci 0000:00:05.0: reg 0x20: [mem 0xfe80c000-0xfe80ffff 64bit pref]
Dec 13 14:18:31.965322 kernel: pci 0000:00:06.0: [1af4:1005] type 00 class 0x00ff00
Dec 13 14:18:31.965410 kernel: pci 0000:00:06.0: reg 0x10: [io  0xc100-0xc11f]
Dec 13 14:18:31.965507 kernel: pci 0000:00:06.0: reg 0x20: [mem 0xfe810000-0xfe813fff 64bit pref]
Dec 13 14:18:31.965521 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10
Dec 13 14:18:31.965530 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10
Dec 13 14:18:31.965539 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11
Dec 13 14:18:31.965548 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11
Dec 13 14:18:31.965557 kernel: ACPI: PCI: Interrupt link LNKS configured for IRQ 9
Dec 13 14:18:31.965569 kernel: iommu: Default domain type: Translated 
Dec 13 14:18:31.965578 kernel: iommu: DMA domain TLB invalidation policy: lazy mode 
Dec 13 14:18:31.965667 kernel: pci 0000:00:02.0: vgaarb: setting as boot VGA device
Dec 13 14:18:31.965756 kernel: pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
Dec 13 14:18:31.965847 kernel: pci 0000:00:02.0: vgaarb: bridge control possible
Dec 13 14:18:31.965860 kernel: vgaarb: loaded
Dec 13 14:18:31.965869 kernel: pps_core: LinuxPPS API ver. 1 registered
Dec 13 14:18:31.965878 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
Dec 13 14:18:31.965886 kernel: PTP clock support registered
Dec 13 14:18:31.965898 kernel: PCI: Using ACPI for IRQ routing
Dec 13 14:18:31.965979 kernel: PCI: pci_cache_line_size set to 64 bytes
Dec 13 14:18:31.965989 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
Dec 13 14:18:31.965998 kernel: e820: reserve RAM buffer [mem 0x7ffdd000-0x7fffffff]
Dec 13 14:18:31.966006 kernel: clocksource: Switched to clocksource kvm-clock
Dec 13 14:18:31.966015 kernel: VFS: Disk quotas dquot_6.6.0
Dec 13 14:18:31.966024 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
Dec 13 14:18:31.966032 kernel: pnp: PnP ACPI init
Dec 13 14:18:31.966135 kernel: pnp 00:03: [dma 2]
Dec 13 14:18:31.966154 kernel: pnp: PnP ACPI: found 5 devices
Dec 13 14:18:31.966163 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
Dec 13 14:18:31.966172 kernel: NET: Registered PF_INET protocol family
Dec 13 14:18:31.966181 kernel: IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear)
Dec 13 14:18:31.966189 kernel: tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear)
Dec 13 14:18:31.966198 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
Dec 13 14:18:31.966207 kernel: TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear)
Dec 13 14:18:31.966216 kernel: TCP bind hash table entries: 16384 (order: 6, 262144 bytes, linear)
Dec 13 14:18:31.966227 kernel: TCP: Hash tables configured (established 16384 bind 16384)
Dec 13 14:18:31.966236 kernel: UDP hash table entries: 1024 (order: 3, 32768 bytes, linear)
Dec 13 14:18:31.966245 kernel: UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear)
Dec 13 14:18:31.966254 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family
Dec 13 14:18:31.966262 kernel: NET: Registered PF_XDP protocol family
Dec 13 14:18:31.966345 kernel: pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
Dec 13 14:18:31.966422 kernel: pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
Dec 13 14:18:31.966499 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
Dec 13 14:18:31.966574 kernel: pci_bus 0000:00: resource 7 [mem 0x80000000-0xfebfffff window]
Dec 13 14:18:31.966655 kernel: pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window]
Dec 13 14:18:31.966744 kernel: pci 0000:00:01.0: PIIX3: Enabling Passive Release
Dec 13 14:18:31.966833 kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers
Dec 13 14:18:31.966959 kernel: pci 0000:00:01.0: Activating ISA DMA hang workarounds
Dec 13 14:18:31.966974 kernel: PCI: CLS 0 bytes, default 64
Dec 13 14:18:31.966982 kernel: Initialise system trusted keyrings
Dec 13 14:18:31.966991 kernel: workingset: timestamp_bits=39 max_order=19 bucket_order=0
Dec 13 14:18:31.967000 kernel: Key type asymmetric registered
Dec 13 14:18:31.967012 kernel: Asymmetric key parser 'x509' registered
Dec 13 14:18:31.967021 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
Dec 13 14:18:31.967030 kernel: io scheduler mq-deadline registered
Dec 13 14:18:31.967038 kernel: io scheduler kyber registered
Dec 13 14:18:31.967047 kernel: io scheduler bfq registered
Dec 13 14:18:31.967056 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00
Dec 13 14:18:31.967065 kernel: ACPI: \_SB_.LNKB: Enabled at IRQ 10
Dec 13 14:18:31.967074 kernel: ACPI: \_SB_.LNKC: Enabled at IRQ 11
Dec 13 14:18:31.967083 kernel: ACPI: \_SB_.LNKD: Enabled at IRQ 11
Dec 13 14:18:31.967094 kernel: ACPI: \_SB_.LNKA: Enabled at IRQ 10
Dec 13 14:18:31.967103 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
Dec 13 14:18:31.967112 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
Dec 13 14:18:31.967121 kernel: random: crng init done
Dec 13 14:18:31.967129 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
Dec 13 14:18:31.967138 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1
Dec 13 14:18:31.967147 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12
Dec 13 14:18:31.967237 kernel: rtc_cmos 00:04: RTC can wake from S4
Dec 13 14:18:31.967255 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
Dec 13 14:18:31.967332 kernel: rtc_cmos 00:04: registered as rtc0
Dec 13 14:18:31.967410 kernel: rtc_cmos 00:04: setting system clock to 2024-12-13T14:18:31 UTC (1734099511)
Dec 13 14:18:31.967489 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram
Dec 13 14:18:31.967502 kernel: NET: Registered PF_INET6 protocol family
Dec 13 14:18:31.967511 kernel: Segment Routing with IPv6
Dec 13 14:18:31.967520 kernel: In-situ OAM (IOAM) with IPv6
Dec 13 14:18:31.967528 kernel: NET: Registered PF_PACKET protocol family
Dec 13 14:18:31.967537 kernel: Key type dns_resolver registered
Dec 13 14:18:31.967549 kernel: IPI shorthand broadcast: enabled
Dec 13 14:18:31.967558 kernel: sched_clock: Marking stable (754490202, 127726459)->(905655427, -23438766)
Dec 13 14:18:31.967567 kernel: registered taskstats version 1
Dec 13 14:18:31.967575 kernel: Loading compiled-in X.509 certificates
Dec 13 14:18:31.967584 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.15.173-flatcar: e1d88c9e01f5bb2adeb5b99325e46e5ca8dff115'
Dec 13 14:18:31.967593 kernel: Key type .fscrypt registered
Dec 13 14:18:31.967601 kernel: Key type fscrypt-provisioning registered
Dec 13 14:18:31.967610 kernel: ima: No TPM chip found, activating TPM-bypass!
Dec 13 14:18:31.967621 kernel: ima: Allocated hash algorithm: sha1
Dec 13 14:18:31.967629 kernel: ima: No architecture policies found
Dec 13 14:18:31.967638 kernel: clk: Disabling unused clocks
Dec 13 14:18:31.967646 kernel: Freeing unused kernel image (initmem) memory: 47472K
Dec 13 14:18:31.967655 kernel: Write protecting the kernel read-only data: 28672k
Dec 13 14:18:31.967664 kernel: Freeing unused kernel image (text/rodata gap) memory: 2040K
Dec 13 14:18:31.967673 kernel: Freeing unused kernel image (rodata/data gap) memory: 620K
Dec 13 14:18:31.967681 kernel: Run /init as init process
Dec 13 14:18:31.967690 kernel:   with arguments:
Dec 13 14:18:31.967699 kernel:     /init
Dec 13 14:18:31.967710 kernel:   with environment:
Dec 13 14:18:31.967718 kernel:     HOME=/
Dec 13 14:18:31.967727 kernel:     TERM=linux
Dec 13 14:18:31.967735 kernel:     BOOT_IMAGE=/flatcar/vmlinuz-a
Dec 13 14:18:31.967747 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)
Dec 13 14:18:31.967760 systemd[1]: Detected virtualization kvm.
Dec 13 14:18:31.967770 systemd[1]: Detected architecture x86-64.
Dec 13 14:18:31.967782 systemd[1]: Running in initrd.
Dec 13 14:18:31.967791 systemd[1]: No hostname configured, using default hostname.
Dec 13 14:18:31.967800 systemd[1]: Hostname set to <localhost>.
Dec 13 14:18:31.967810 systemd[1]: Initializing machine ID from VM UUID.
Dec 13 14:18:31.967819 systemd[1]: Queued start job for default target initrd.target.
Dec 13 14:18:31.967828 systemd[1]: Started systemd-ask-password-console.path.
Dec 13 14:18:31.967838 systemd[1]: Reached target cryptsetup.target.
Dec 13 14:18:31.967847 systemd[1]: Reached target paths.target.
Dec 13 14:18:31.967858 systemd[1]: Reached target slices.target.
Dec 13 14:18:31.967867 systemd[1]: Reached target swap.target.
Dec 13 14:18:31.967876 systemd[1]: Reached target timers.target.
Dec 13 14:18:31.967886 systemd[1]: Listening on iscsid.socket.
Dec 13 14:18:31.967895 systemd[1]: Listening on iscsiuio.socket.
Dec 13 14:18:31.967919 systemd[1]: Listening on systemd-journald-audit.socket.
Dec 13 14:18:31.967929 systemd[1]: Listening on systemd-journald-dev-log.socket.
Dec 13 14:18:31.967938 systemd[1]: Listening on systemd-journald.socket.
Dec 13 14:18:31.967950 systemd[1]: Listening on systemd-networkd.socket.
Dec 13 14:18:31.967960 systemd[1]: Listening on systemd-udevd-control.socket.
Dec 13 14:18:31.967969 systemd[1]: Listening on systemd-udevd-kernel.socket.
Dec 13 14:18:31.967978 systemd[1]: Reached target sockets.target.
Dec 13 14:18:31.967998 systemd[1]: Starting kmod-static-nodes.service...
Dec 13 14:18:31.968009 systemd[1]: Finished network-cleanup.service.
Dec 13 14:18:31.968020 systemd[1]: Starting systemd-fsck-usr.service...
Dec 13 14:18:31.968030 systemd[1]: Starting systemd-journald.service...
Dec 13 14:18:31.968040 systemd[1]: Starting systemd-modules-load.service...
Dec 13 14:18:31.968049 systemd[1]: Starting systemd-resolved.service...
Dec 13 14:18:31.968059 systemd[1]: Starting systemd-vconsole-setup.service...
Dec 13 14:18:31.968068 systemd[1]: Finished kmod-static-nodes.service.
Dec 13 14:18:31.968078 systemd[1]: Finished systemd-fsck-usr.service.
Dec 13 14:18:31.968088 systemd[1]: Starting systemd-tmpfiles-setup-dev.service...
Dec 13 14:18:31.968102 systemd-journald[186]: Journal started
Dec 13 14:18:31.968157 systemd-journald[186]: Runtime Journal (/run/log/journal/871eb79b86204ac5a6c1e2d684c398ce) is 4.9M, max 39.5M, 34.5M free.
Dec 13 14:18:31.913560 systemd-modules-load[187]: Inserted module 'overlay'
Dec 13 14:18:31.986642 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
Dec 13 14:18:31.986672 kernel: Bridge firewalling registered
Dec 13 14:18:31.952090 systemd-resolved[188]: Positive Trust Anchors:
Dec 13 14:18:31.988544 systemd[1]: Started systemd-resolved.service.
Dec 13 14:18:31.952098 systemd-resolved[188]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
Dec 13 14:18:31.995873 systemd[1]: Started systemd-journald.service.
Dec 13 14:18:31.995936 kernel: audit: type=1130 audit(1734099511.988:2): 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'
Dec 13 14:18:31.988000 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'
Dec 13 14:18:31.952133 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
Dec 13 14:18:31.954834 systemd-resolved[188]: Defaulting to hostname 'linux'.
Dec 13 14:18:31.980651 systemd-modules-load[187]: Inserted module 'br_netfilter'
Dec 13 14:18:31.999000 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'
Dec 13 14:18:32.001003 systemd[1]: Finished systemd-vconsole-setup.service.
Dec 13 14:18:32.011181 kernel: audit: type=1130 audit(1734099511.999: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'
Dec 13 14:18:32.011204 kernel: audit: type=1130 audit(1734099512.003:4): 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'
Dec 13 14:18:32.003000 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'
Dec 13 14:18:32.004633 systemd[1]: Finished systemd-tmpfiles-setup-dev.service.
Dec 13 14:18:32.008998 systemd[1]: Reached target nss-lookup.target.
Dec 13 14:18:32.010120 systemd[1]: Starting dracut-cmdline-ask.service...
Dec 13 14:18:32.007000 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'
Dec 13 14:18:32.017056 kernel: audit: type=1130 audit(1734099512.007:5): 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'
Dec 13 14:18:32.019946 kernel: SCSI subsystem initialized
Dec 13 14:18:32.025035 systemd[1]: Finished dracut-cmdline-ask.service.
Dec 13 14:18:32.026305 systemd[1]: Starting dracut-cmdline.service...
Dec 13 14:18:32.024000 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'
Dec 13 14:18:32.031962 kernel: audit: type=1130 audit(1734099512.024:6): 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'
Dec 13 14:18:32.037961 dracut-cmdline[204]: dracut-dracut-053
Dec 13 14:18:32.043255 dracut-cmdline[204]: 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=8c474c3ec361ec863adbecaa85281a726e1b53f7863ecc4742be8c5f6d02a66e
Dec 13 14:18:32.045341 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.
Dec 13 14:18:32.047924 kernel: device-mapper: uevent: version 1.0.3
Dec 13 14:18:32.047946 kernel: device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com
Dec 13 14:18:32.051319 systemd-modules-load[187]: Inserted module 'dm_multipath'
Dec 13 14:18:32.052000 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'
Dec 13 14:18:32.052622 systemd[1]: Finished systemd-modules-load.service.
Dec 13 14:18:32.058176 kernel: audit: type=1130 audit(1734099512.052: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'
Dec 13 14:18:32.053747 systemd[1]: Starting systemd-sysctl.service...
Dec 13 14:18:32.064299 systemd[1]: Finished systemd-sysctl.service.
Dec 13 14:18:32.063000 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'
Dec 13 14:18:32.068931 kernel: audit: type=1130 audit(1734099512.063:8): 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'
Dec 13 14:18:32.106953 kernel: Loading iSCSI transport class v2.0-870.
Dec 13 14:18:32.126946 kernel: iscsi: registered transport (tcp)
Dec 13 14:18:32.159329 kernel: iscsi: registered transport (qla4xxx)
Dec 13 14:18:32.159359 kernel: QLogic iSCSI HBA Driver
Dec 13 14:18:32.197198 systemd[1]: Finished dracut-cmdline.service.
Dec 13 14:18:32.196000 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'
Dec 13 14:18:32.198698 systemd[1]: Starting dracut-pre-udev.service...
Dec 13 14:18:32.203351 kernel: audit: type=1130 audit(1734099512.196:9): 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'
Dec 13 14:18:32.254989 kernel: raid6: sse2x4   gen() 12786 MB/s
Dec 13 14:18:32.271958 kernel: raid6: sse2x4   xor()  4950 MB/s
Dec 13 14:18:32.288956 kernel: raid6: sse2x2   gen() 13494 MB/s
Dec 13 14:18:32.305959 kernel: raid6: sse2x2   xor()  8256 MB/s
Dec 13 14:18:32.322955 kernel: raid6: sse2x1   gen() 10470 MB/s
Dec 13 14:18:32.340691 kernel: raid6: sse2x1   xor()  6853 MB/s
Dec 13 14:18:32.340726 kernel: raid6: using algorithm sse2x2 gen() 13494 MB/s
Dec 13 14:18:32.340739 kernel: raid6: .... xor() 8256 MB/s, rmw enabled
Dec 13 14:18:32.341646 kernel: raid6: using ssse3x2 recovery algorithm
Dec 13 14:18:32.357146 kernel: xor: measuring software checksum speed
Dec 13 14:18:32.357180 kernel:    prefetch64-sse  : 17649 MB/sec
Dec 13 14:18:32.358158 kernel:    generic_sse     : 15590 MB/sec
Dec 13 14:18:32.358183 kernel: xor: using function: prefetch64-sse (17649 MB/sec)
Dec 13 14:18:32.471959 kernel: Btrfs loaded, crc32c=crc32c-intel, zoned=no, fsverity=no
Dec 13 14:18:32.482223 systemd[1]: Finished dracut-pre-udev.service.
Dec 13 14:18:32.481000 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'
Dec 13 14:18:32.483753 systemd[1]: Starting systemd-udevd.service...
Dec 13 14:18:32.493416 kernel: audit: type=1130 audit(1734099512.481:10): 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'
Dec 13 14:18:32.482000 audit: BPF prog-id=7 op=LOAD
Dec 13 14:18:32.482000 audit: BPF prog-id=8 op=LOAD
Dec 13 14:18:32.499759 systemd-udevd[386]: Using default interface naming scheme 'v252'.
Dec 13 14:18:32.504000 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'
Dec 13 14:18:32.504718 systemd[1]: Started systemd-udevd.service.
Dec 13 14:18:32.509071 systemd[1]: Starting dracut-pre-trigger.service...
Dec 13 14:18:32.530170 dracut-pre-trigger[402]: rd.md=0: removing MD RAID activation
Dec 13 14:18:32.569495 systemd[1]: Finished dracut-pre-trigger.service.
Dec 13 14:18:32.570000 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'
Dec 13 14:18:32.572613 systemd[1]: Starting systemd-udev-trigger.service...
Dec 13 14:18:32.614472 systemd[1]: Finished systemd-udev-trigger.service.
Dec 13 14:18:32.616000 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'
Dec 13 14:18:32.680068 kernel: virtio_blk virtio2: [vda] 41943040 512-byte logical blocks (21.5 GB/20.0 GiB)
Dec 13 14:18:32.696068 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk.
Dec 13 14:18:32.696086 kernel: GPT:17805311 != 41943039
Dec 13 14:18:32.696097 kernel: GPT:Alternate GPT header not at the end of the disk.
Dec 13 14:18:32.696107 kernel: GPT:17805311 != 41943039
Dec 13 14:18:32.696117 kernel: GPT: Use GNU Parted to correct GPT errors.
Dec 13 14:18:32.696139 kernel:  vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9
Dec 13 14:18:32.698030 kernel: libata version 3.00 loaded.
Dec 13 14:18:32.711976 kernel: ata_piix 0000:00:01.1: version 2.13
Dec 13 14:18:32.742831 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by (udev-worker) (445)
Dec 13 14:18:32.742860 kernel: scsi host0: ata_piix
Dec 13 14:18:32.743039 kernel: scsi host1: ata_piix
Dec 13 14:18:32.743156 kernel: ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14
Dec 13 14:18:32.743176 kernel: ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15
Dec 13 14:18:32.737630 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device.
Dec 13 14:18:32.783268 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device.
Dec 13 14:18:32.787408 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device.
Dec 13 14:18:32.790785 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device.
Dec 13 14:18:32.791317 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device.
Dec 13 14:18:32.794329 systemd[1]: Starting disk-uuid.service...
Dec 13 14:18:32.806009 disk-uuid[462]: Primary Header is updated.
Dec 13 14:18:32.806009 disk-uuid[462]: Secondary Entries is updated.
Dec 13 14:18:32.806009 disk-uuid[462]: Secondary Header is updated.
Dec 13 14:18:32.815939 kernel:  vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9
Dec 13 14:18:32.823940 kernel:  vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9
Dec 13 14:18:33.833956 kernel:  vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9
Dec 13 14:18:33.834534 disk-uuid[463]: The operation has completed successfully.
Dec 13 14:18:33.896845 systemd[1]: disk-uuid.service: Deactivated successfully.
Dec 13 14:18:33.896000 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'
Dec 13 14:18:33.896000 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'
Dec 13 14:18:33.896984 systemd[1]: Finished disk-uuid.service.
Dec 13 14:18:33.902264 systemd[1]: Starting verity-setup.service...
Dec 13 14:18:33.936978 kernel: device-mapper: verity: sha256 using implementation "sha256-ssse3"
Dec 13 14:18:34.038860 systemd[1]: Found device dev-mapper-usr.device.
Dec 13 14:18:34.040893 systemd[1]: Mounting sysusr-usr.mount...
Dec 13 14:18:34.043685 systemd[1]: Finished verity-setup.service.
Dec 13 14:18:34.043000 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'
Dec 13 14:18:34.151968 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: norecovery. Quota mode: none.
Dec 13 14:18:34.151945 systemd[1]: Mounted sysusr-usr.mount.
Dec 13 14:18:34.152536 systemd[1]: afterburn-network-kargs.service was skipped because no trigger condition checks were met.
Dec 13 14:18:34.153266 systemd[1]: Starting ignition-setup.service...
Dec 13 14:18:34.154501 systemd[1]: Starting parse-ip-for-networkd.service...
Dec 13 14:18:34.190714 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm
Dec 13 14:18:34.190818 kernel: BTRFS info (device vda6): using free space tree
Dec 13 14:18:34.190848 kernel: BTRFS info (device vda6): has skinny extents
Dec 13 14:18:34.219464 systemd[1]: mnt-oem.mount: Deactivated successfully.
Dec 13 14:18:34.234063 systemd[1]: Finished parse-ip-for-networkd.service.
Dec 13 14:18:34.233000 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'
Dec 13 14:18:34.234000 audit: BPF prog-id=9 op=LOAD
Dec 13 14:18:34.236459 systemd[1]: Starting systemd-networkd.service...
Dec 13 14:18:34.246747 systemd[1]: Finished ignition-setup.service.
Dec 13 14:18:34.246000 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'
Dec 13 14:18:34.248976 systemd[1]: Starting ignition-fetch-offline.service...
Dec 13 14:18:34.281433 systemd-networkd[630]: lo: Link UP
Dec 13 14:18:34.282164 systemd-networkd[630]: lo: Gained carrier
Dec 13 14:18:34.283727 systemd-networkd[630]: Enumeration completed
Dec 13 14:18:34.284000 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'
Dec 13 14:18:34.284438 systemd[1]: Started systemd-networkd.service.
Dec 13 14:18:34.285048 systemd[1]: Reached target network.target.
Dec 13 14:18:34.285301 systemd-networkd[630]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
Dec 13 14:18:34.286331 systemd[1]: Starting iscsiuio.service...
Dec 13 14:18:34.286791 systemd-networkd[630]: eth0: Link UP
Dec 13 14:18:34.286795 systemd-networkd[630]: eth0: Gained carrier
Dec 13 14:18:34.292000 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'
Dec 13 14:18:34.293375 systemd[1]: Started iscsiuio.service.
Dec 13 14:18:34.294729 systemd[1]: Starting iscsid.service...
Dec 13 14:18:34.298089 iscsid[637]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi
Dec 13 14:18:34.298089 iscsid[637]: 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.<reversed domain name>[:identifier].
Dec 13 14:18:34.298089 iscsid[637]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6.
Dec 13 14:18:34.298089 iscsid[637]: If using hardware iscsi like qla4xxx this message can be ignored.
Dec 13 14:18:34.298089 iscsid[637]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi
Dec 13 14:18:34.298089 iscsid[637]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf
Dec 13 14:18:34.302000 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'
Dec 13 14:18:34.300257 systemd[1]: Started iscsid.service.
Dec 13 14:18:34.303839 systemd[1]: Starting dracut-initqueue.service...
Dec 13 14:18:34.306970 systemd-networkd[630]: eth0: DHCPv4 address 172.24.4.30/24, gateway 172.24.4.1 acquired from 172.24.4.1
Dec 13 14:18:34.316195 systemd[1]: Finished dracut-initqueue.service.
Dec 13 14:18:34.315000 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'
Dec 13 14:18:34.316813 systemd[1]: Reached target remote-fs-pre.target.
Dec 13 14:18:34.318341 systemd[1]: Reached target remote-cryptsetup.target.
Dec 13 14:18:34.319825 systemd[1]: Reached target remote-fs.target.
Dec 13 14:18:34.321631 systemd[1]: Starting dracut-pre-mount.service...
Dec 13 14:18:34.331054 systemd[1]: Finished dracut-pre-mount.service.
Dec 13 14:18:34.331000 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'
Dec 13 14:18:34.627025 ignition[632]: Ignition 2.14.0
Dec 13 14:18:34.628035 ignition[632]: Stage: fetch-offline
Dec 13 14:18:34.628171 ignition[632]: reading system config file "/usr/lib/ignition/base.d/base.ign"
Dec 13 14:18:34.628249 ignition[632]: parsing config with SHA512: ce918cf8568bff1426dda9ea05b778568a1626fcf4c1bded9ebe13fee104bc1b92fac5f7093a3bfc7d99777c3793d01249c863845c2ca48413d9477d40af178a
Dec 13 14:18:34.632595 systemd[1]: Finished ignition-fetch-offline.service.
Dec 13 14:18:34.630520 ignition[632]: no config dir at "/usr/lib/ignition/base.platform.d/openstack"
Dec 13 14:18:34.632000 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'
Dec 13 14:18:34.630741 ignition[632]: parsed url from cmdline: ""
Dec 13 14:18:34.635599 systemd[1]: Starting ignition-fetch.service...
Dec 13 14:18:34.630750 ignition[632]: no config URL provided
Dec 13 14:18:34.630762 ignition[632]: reading system config file "/usr/lib/ignition/user.ign"
Dec 13 14:18:34.630806 ignition[632]: no config at "/usr/lib/ignition/user.ign"
Dec 13 14:18:34.630826 ignition[632]: failed to fetch config: resource requires networking
Dec 13 14:18:34.631061 ignition[632]: Ignition finished successfully
Dec 13 14:18:34.654432 ignition[655]: Ignition 2.14.0
Dec 13 14:18:34.654458 ignition[655]: Stage: fetch
Dec 13 14:18:34.654688 ignition[655]: reading system config file "/usr/lib/ignition/base.d/base.ign"
Dec 13 14:18:34.654728 ignition[655]: parsing config with SHA512: ce918cf8568bff1426dda9ea05b778568a1626fcf4c1bded9ebe13fee104bc1b92fac5f7093a3bfc7d99777c3793d01249c863845c2ca48413d9477d40af178a
Dec 13 14:18:34.657355 ignition[655]: no config dir at "/usr/lib/ignition/base.platform.d/openstack"
Dec 13 14:18:34.657575 ignition[655]: parsed url from cmdline: ""
Dec 13 14:18:34.657585 ignition[655]: no config URL provided
Dec 13 14:18:34.657598 ignition[655]: reading system config file "/usr/lib/ignition/user.ign"
Dec 13 14:18:34.657618 ignition[655]: no config at "/usr/lib/ignition/user.ign"
Dec 13 14:18:34.662351 ignition[655]: config drive ("/dev/disk/by-label/CONFIG-2") not found. Waiting...
Dec 13 14:18:34.662418 ignition[655]: GET http://169.254.169.254/openstack/latest/user_data: attempt #1
Dec 13 14:18:34.662557 ignition[655]: config drive ("/dev/disk/by-label/config-2") not found. Waiting...
Dec 13 14:18:35.070112 ignition[655]: GET result: OK
Dec 13 14:18:35.070263 ignition[655]: parsing config with SHA512: 156efc738635430ef4bedd7bcefe3803fc2daf3a42ede7bf12c150015d06304fd637af18d54035e3e241c9b8715604f220271f0f16a92c7cf86ac50c400f80fe
Dec 13 14:18:35.105065 unknown[655]: fetched base config from "system"
Dec 13 14:18:35.106757 unknown[655]: fetched base config from "system"
Dec 13 14:18:35.108410 unknown[655]: fetched user config from "openstack"
Dec 13 14:18:35.115461 ignition[655]: fetch: fetch complete
Dec 13 14:18:35.118066 ignition[655]: fetch: fetch passed
Dec 13 14:18:35.120603 ignition[655]: Ignition finished successfully
Dec 13 14:18:35.125013 systemd[1]: Finished ignition-fetch.service.
Dec 13 14:18:35.125000 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'
Dec 13 14:18:35.127788 systemd[1]: Starting ignition-kargs.service...
Dec 13 14:18:35.152223 ignition[661]: Ignition 2.14.0
Dec 13 14:18:35.152255 ignition[661]: Stage: kargs
Dec 13 14:18:35.152527 ignition[661]: reading system config file "/usr/lib/ignition/base.d/base.ign"
Dec 13 14:18:35.152572 ignition[661]: parsing config with SHA512: ce918cf8568bff1426dda9ea05b778568a1626fcf4c1bded9ebe13fee104bc1b92fac5f7093a3bfc7d99777c3793d01249c863845c2ca48413d9477d40af178a
Dec 13 14:18:35.155134 ignition[661]: no config dir at "/usr/lib/ignition/base.platform.d/openstack"
Dec 13 14:18:35.157326 ignition[661]: kargs: kargs passed
Dec 13 14:18:35.167986 systemd[1]: Finished ignition-kargs.service.
Dec 13 14:18:35.168000 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'
Dec 13 14:18:35.157437 ignition[661]: Ignition finished successfully
Dec 13 14:18:35.170709 systemd[1]: Starting ignition-disks.service...
Dec 13 14:18:35.187967 ignition[666]: Ignition 2.14.0
Dec 13 14:18:35.187986 ignition[666]: Stage: disks
Dec 13 14:18:35.188263 ignition[666]: reading system config file "/usr/lib/ignition/base.d/base.ign"
Dec 13 14:18:35.188306 ignition[666]: parsing config with SHA512: ce918cf8568bff1426dda9ea05b778568a1626fcf4c1bded9ebe13fee104bc1b92fac5f7093a3bfc7d99777c3793d01249c863845c2ca48413d9477d40af178a
Dec 13 14:18:35.190374 ignition[666]: no config dir at "/usr/lib/ignition/base.platform.d/openstack"
Dec 13 14:18:35.194000 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'
Dec 13 14:18:35.193704 systemd[1]: Finished ignition-disks.service.
Dec 13 14:18:35.192152 ignition[666]: disks: disks passed
Dec 13 14:18:35.195262 systemd[1]: Reached target initrd-root-device.target.
Dec 13 14:18:35.192278 ignition[666]: Ignition finished successfully
Dec 13 14:18:35.199315 systemd[1]: Reached target local-fs-pre.target.
Dec 13 14:18:35.201447 systemd[1]: Reached target local-fs.target.
Dec 13 14:18:35.203800 systemd[1]: Reached target sysinit.target.
Dec 13 14:18:35.206190 systemd[1]: Reached target basic.target.
Dec 13 14:18:35.210290 systemd[1]: Starting systemd-fsck-root.service...
Dec 13 14:18:35.242494 systemd-fsck[674]: ROOT: clean, 621/1628000 files, 124058/1617920 blocks
Dec 13 14:18:35.264393 systemd[1]: Finished systemd-fsck-root.service.
Dec 13 14:18:35.264000 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'
Dec 13 14:18:35.267590 systemd[1]: Mounting sysroot.mount...
Dec 13 14:18:35.302262 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none.
Dec 13 14:18:35.303546 systemd[1]: Mounted sysroot.mount.
Dec 13 14:18:35.304963 systemd[1]: Reached target initrd-root-fs.target.
Dec 13 14:18:35.311122 systemd[1]: Mounting sysroot-usr.mount...
Dec 13 14:18:35.313204 systemd[1]: flatcar-metadata-hostname.service was skipped because no trigger condition checks were met.
Dec 13 14:18:35.314759 systemd[1]: Starting flatcar-openstack-hostname.service...
Dec 13 14:18:35.320510 systemd[1]: ignition-remount-sysroot.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot).
Dec 13 14:18:35.320585 systemd[1]: Reached target ignition-diskful.target.
Dec 13 14:18:35.326004 systemd[1]: Mounted sysroot-usr.mount.
Dec 13 14:18:35.341396 systemd[1]: Mounting sysroot-usr-share-oem.mount...
Dec 13 14:18:35.343458 systemd[1]: Starting initrd-setup-root.service...
Dec 13 14:18:35.354012 initrd-setup-root[686]: cut: /sysroot/etc/passwd: No such file or directory
Dec 13 14:18:35.362025 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by mount (681)
Dec 13 14:18:35.370857 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm
Dec 13 14:18:35.370988 kernel: BTRFS info (device vda6): using free space tree
Dec 13 14:18:35.371032 kernel: BTRFS info (device vda6): has skinny extents
Dec 13 14:18:35.371063 initrd-setup-root[694]: cut: /sysroot/etc/group: No such file or directory
Dec 13 14:18:35.385646 initrd-setup-root[718]: cut: /sysroot/etc/shadow: No such file or directory
Dec 13 14:18:35.396656 initrd-setup-root[728]: cut: /sysroot/etc/gshadow: No such file or directory
Dec 13 14:18:35.398987 systemd[1]: Mounted sysroot-usr-share-oem.mount.
Dec 13 14:18:35.512874 systemd[1]: Finished initrd-setup-root.service.
Dec 13 14:18:35.513000 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'
Dec 13 14:18:35.516165 systemd[1]: Starting ignition-mount.service...
Dec 13 14:18:35.526637 systemd[1]: Starting sysroot-boot.service...
Dec 13 14:18:35.544306 systemd[1]: sysusr-usr-share-oem.mount: Deactivated successfully.
Dec 13 14:18:35.544577 systemd[1]: sysroot-usr-share-oem.mount: Deactivated successfully.
Dec 13 14:18:35.585897 ignition[748]: INFO     : Ignition 2.14.0
Dec 13 14:18:35.586749 ignition[748]: INFO     : Stage: mount
Dec 13 14:18:35.587395 ignition[748]: INFO     : reading system config file "/usr/lib/ignition/base.d/base.ign"
Dec 13 14:18:35.588226 ignition[748]: DEBUG    : parsing config with SHA512: ce918cf8568bff1426dda9ea05b778568a1626fcf4c1bded9ebe13fee104bc1b92fac5f7093a3bfc7d99777c3793d01249c863845c2ca48413d9477d40af178a
Dec 13 14:18:35.590346 ignition[748]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/openstack"
Dec 13 14:18:35.592502 ignition[748]: INFO     : mount: mount passed
Dec 13 14:18:35.593162 ignition[748]: INFO     : Ignition finished successfully
Dec 13 14:18:35.594518 systemd[1]: Finished ignition-mount.service.
Dec 13 14:18:35.594000 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'
Dec 13 14:18:35.605000 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'
Dec 13 14:18:35.605360 systemd[1]: Finished sysroot-boot.service.
Dec 13 14:18:35.626514 coreos-metadata[680]: Dec 13 14:18:35.626 INFO Fetching http://169.254.169.254/latest/meta-data/hostname: Attempt #1
Dec 13 14:18:35.644006 coreos-metadata[680]: Dec 13 14:18:35.643 INFO Fetch successful
Dec 13 14:18:35.644672 coreos-metadata[680]: Dec 13 14:18:35.644 INFO wrote hostname ci-3510-3-6-7-2d0c40e50b.novalocal to /sysroot/etc/hostname
Dec 13 14:18:35.651675 systemd[1]: flatcar-openstack-hostname.service: Deactivated successfully.
Dec 13 14:18:35.651945 systemd[1]: Finished flatcar-openstack-hostname.service.
Dec 13 14:18:35.652000 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'
Dec 13 14:18:35.652000 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'
Dec 13 14:18:35.655880 systemd[1]: Starting ignition-files.service...
Dec 13 14:18:35.668170 systemd[1]: Mounting sysroot-usr-share-oem.mount...
Dec 13 14:18:35.682784 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 scanned by mount (757)
Dec 13 14:18:35.689726 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm
Dec 13 14:18:35.689780 kernel: BTRFS info (device vda6): using free space tree
Dec 13 14:18:35.689807 kernel: BTRFS info (device vda6): has skinny extents
Dec 13 14:18:35.705960 systemd[1]: Mounted sysroot-usr-share-oem.mount.
Dec 13 14:18:35.729804 ignition[776]: INFO     : Ignition 2.14.0
Dec 13 14:18:35.729804 ignition[776]: INFO     : Stage: files
Dec 13 14:18:35.732550 ignition[776]: INFO     : reading system config file "/usr/lib/ignition/base.d/base.ign"
Dec 13 14:18:35.732550 ignition[776]: DEBUG    : parsing config with SHA512: ce918cf8568bff1426dda9ea05b778568a1626fcf4c1bded9ebe13fee104bc1b92fac5f7093a3bfc7d99777c3793d01249c863845c2ca48413d9477d40af178a
Dec 13 14:18:35.732550 ignition[776]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/openstack"
Dec 13 14:18:35.739297 ignition[776]: DEBUG    : files: compiled without relabeling support, skipping
Dec 13 14:18:35.739297 ignition[776]: INFO     : files: ensureUsers: op(1): [started]  creating or modifying user "core"
Dec 13 14:18:35.739297 ignition[776]: DEBUG    : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core"
Dec 13 14:18:35.745087 ignition[776]: INFO     : files: ensureUsers: op(1): [finished] creating or modifying user "core"
Dec 13 14:18:35.745087 ignition[776]: INFO     : files: ensureUsers: op(2): [started]  adding ssh keys to user "core"
Dec 13 14:18:35.749167 unknown[776]: wrote ssh authorized keys file for user: core
Dec 13 14:18:35.750739 ignition[776]: INFO     : files: ensureUsers: op(2): [finished] adding ssh keys to user "core"
Dec 13 14:18:35.752797 ignition[776]: INFO     : files: createFilesystemsFiles: createFiles: op(3): [started]  writing file "/sysroot/etc/flatcar/update.conf"
Dec 13 14:18:35.752797 ignition[776]: INFO     : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf"
Dec 13 14:18:35.752797 ignition[776]: INFO     : files: op(4): [started]  processing unit "coreos-metadata-sshkeys@.service"
Dec 13 14:18:35.759965 ignition[776]: 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"
Dec 13 14:18:35.759965 ignition[776]: 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"
Dec 13 14:18:35.759965 ignition[776]: INFO     : files: op(4): [finished] processing unit "coreos-metadata-sshkeys@.service"
Dec 13 14:18:35.759965 ignition[776]: INFO     : files: op(6): [started]  processing unit "coreos-metadata.service"
Dec 13 14:18:35.759965 ignition[776]: INFO     : files: op(6): op(7): [started]  writing systemd drop-in "20-clct-provider-override.conf" at "/sysroot/etc/systemd/system/coreos-metadata.service.d/20-clct-provider-override.conf"
Dec 13 14:18:35.759965 ignition[776]: INFO     : files: op(6): op(7): [finished] writing systemd drop-in "20-clct-provider-override.conf" at "/sysroot/etc/systemd/system/coreos-metadata.service.d/20-clct-provider-override.conf"
Dec 13 14:18:35.759965 ignition[776]: INFO     : files: op(6): [finished] processing unit "coreos-metadata.service"
Dec 13 14:18:35.759965 ignition[776]: INFO     : files: op(8): [started]  processing unit "etcd-member.service"
Dec 13 14:18:35.759965 ignition[776]: INFO     : files: op(8): op(9): [started]  writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf"
Dec 13 14:18:35.759965 ignition[776]: INFO     : files: op(8): op(9): [finished] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf"
Dec 13 14:18:35.759965 ignition[776]: INFO     : files: op(8): [finished] processing unit "etcd-member.service"
Dec 13 14:18:35.759965 ignition[776]: INFO     : files: op(a): [started]  setting preset to enabled for "coreos-metadata-sshkeys@.service "
Dec 13 14:18:35.759965 ignition[776]: INFO     : files: op(a): [finished] setting preset to enabled for "coreos-metadata-sshkeys@.service "
Dec 13 14:18:35.759965 ignition[776]: INFO     : files: op(b): [started]  setting preset to enabled for "etcd-member.service"
Dec 13 14:18:35.759965 ignition[776]: INFO     : files: op(b): [finished] setting preset to enabled for "etcd-member.service"
Dec 13 14:18:35.773000 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'
Dec 13 14:18:35.795000 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'
Dec 13 14:18:35.795000 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'
Dec 13 14:18:35.801000 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'
Dec 13 14:18:35.773422 systemd[1]: Finished ignition-files.service.
Dec 13 14:18:35.805382 ignition[776]: INFO     : files: createResultFile: createFiles: op(c): [started]  writing file "/sysroot/etc/.ignition-result.json"
Dec 13 14:18:35.805382 ignition[776]: INFO     : files: createResultFile: createFiles: op(c): [finished] writing file "/sysroot/etc/.ignition-result.json"
Dec 13 14:18:35.805382 ignition[776]: INFO     : files: files passed
Dec 13 14:18:35.805382 ignition[776]: INFO     : Ignition finished successfully
Dec 13 14:18:35.778984 systemd[1]: Starting initrd-setup-root-after-ignition.service...
Dec 13 14:18:35.780738 systemd[1]: torcx-profile-populate.service was skipped because of an unmet condition check (ConditionPathExists=/sysroot/etc/torcx/next-profile).
Dec 13 14:18:35.815844 initrd-setup-root-after-ignition[801]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory
Dec 13 14:18:35.782495 systemd[1]: Starting ignition-quench.service...
Dec 13 14:18:35.795294 systemd[1]: ignition-quench.service: Deactivated successfully.
Dec 13 14:18:35.795383 systemd[1]: Finished ignition-quench.service.
Dec 13 14:18:35.797396 systemd[1]: Finished initrd-setup-root-after-ignition.service.
Dec 13 14:18:35.802362 systemd[1]: Reached target ignition-complete.target.
Dec 13 14:18:35.822000 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'
Dec 13 14:18:35.822000 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'
Dec 13 14:18:35.805430 systemd[1]: Starting initrd-parse-etc.service...
Dec 13 14:18:35.822475 systemd[1]: initrd-parse-etc.service: Deactivated successfully.
Dec 13 14:18:35.822564 systemd[1]: Finished initrd-parse-etc.service.
Dec 13 14:18:35.823584 systemd[1]: Reached target initrd-fs.target.
Dec 13 14:18:35.825617 systemd[1]: Reached target initrd.target.
Dec 13 14:18:35.826847 systemd[1]: dracut-mount.service was skipped because no trigger condition checks were met.
Dec 13 14:18:35.827627 systemd[1]: Starting dracut-pre-pivot.service...
Dec 13 14:18:35.838321 systemd[1]: Finished dracut-pre-pivot.service.
Dec 13 14:18:35.838000 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'
Dec 13 14:18:35.840341 systemd[1]: Starting initrd-cleanup.service...
Dec 13 14:18:35.849831 systemd[1]: Stopped target nss-lookup.target.
Dec 13 14:18:35.851038 systemd[1]: Stopped target remote-cryptsetup.target.
Dec 13 14:18:35.852288 systemd[1]: Stopped target timers.target.
Dec 13 14:18:35.853364 systemd[1]: dracut-pre-pivot.service: Deactivated successfully.
Dec 13 14:18:35.854075 systemd[1]: Stopped dracut-pre-pivot.service.
Dec 13 14:18:35.854000 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'
Dec 13 14:18:35.855303 systemd[1]: Stopped target initrd.target.
Dec 13 14:18:35.856355 systemd[1]: Stopped target basic.target.
Dec 13 14:18:35.857424 systemd[1]: Stopped target ignition-complete.target.
Dec 13 14:18:35.858513 systemd[1]: Stopped target ignition-diskful.target.
Dec 13 14:18:35.859106 systemd[1]: Stopped target initrd-root-device.target.
Dec 13 14:18:35.860093 systemd[1]: Stopped target remote-fs.target.
Dec 13 14:18:35.861054 systemd[1]: Stopped target remote-fs-pre.target.
Dec 13 14:18:35.862021 systemd[1]: Stopped target sysinit.target.
Dec 13 14:18:35.862887 systemd[1]: Stopped target local-fs.target.
Dec 13 14:18:35.863779 systemd[1]: Stopped target local-fs-pre.target.
Dec 13 14:18:35.864662 systemd[1]: Stopped target swap.target.
Dec 13 14:18:35.865527 systemd[1]: dracut-pre-mount.service: Deactivated successfully.
Dec 13 14:18:35.865000 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'
Dec 13 14:18:35.865638 systemd[1]: Stopped dracut-pre-mount.service.
Dec 13 14:18:35.866527 systemd[1]: Stopped target cryptsetup.target.
Dec 13 14:18:35.867000 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'
Dec 13 14:18:35.867312 systemd[1]: dracut-initqueue.service: Deactivated successfully.
Dec 13 14:18:35.868000 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'
Dec 13 14:18:35.867416 systemd[1]: Stopped dracut-initqueue.service.
Dec 13 14:18:35.869000 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'
Dec 13 14:18:35.868385 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully.
Dec 13 14:18:35.868541 systemd[1]: Stopped initrd-setup-root-after-ignition.service.
Dec 13 14:18:35.869289 systemd[1]: ignition-files.service: Deactivated successfully.
Dec 13 14:18:35.869435 systemd[1]: Stopped ignition-files.service.
Dec 13 14:18:35.871304 systemd[1]: Stopping ignition-mount.service...
Dec 13 14:18:35.878942 iscsid[637]: iscsid shutting down.
Dec 13 14:18:35.881980 ignition[814]: INFO     : Ignition 2.14.0
Dec 13 14:18:35.881980 ignition[814]: INFO     : Stage: umount
Dec 13 14:18:35.881980 ignition[814]: INFO     : reading system config file "/usr/lib/ignition/base.d/base.ign"
Dec 13 14:18:35.881980 ignition[814]: DEBUG    : parsing config with SHA512: ce918cf8568bff1426dda9ea05b778568a1626fcf4c1bded9ebe13fee104bc1b92fac5f7093a3bfc7d99777c3793d01249c863845c2ca48413d9477d40af178a
Dec 13 14:18:35.881980 ignition[814]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/openstack"
Dec 13 14:18:35.883000 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'
Dec 13 14:18:35.888110 ignition[814]: INFO     : umount: umount passed
Dec 13 14:18:35.888110 ignition[814]: INFO     : Ignition finished successfully
Dec 13 14:18:35.889000 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'
Dec 13 14:18:35.883088 systemd[1]: Stopping iscsid.service...
Dec 13 14:18:35.891000 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'
Dec 13 14:18:35.883557 systemd[1]: kmod-static-nodes.service: Deactivated successfully.
Dec 13 14:18:35.883693 systemd[1]: Stopped kmod-static-nodes.service.
Dec 13 14:18:35.885548 systemd[1]: Stopping sysroot-boot.service...
Dec 13 14:18:35.888642 systemd[1]: systemd-udev-trigger.service: Deactivated successfully.
Dec 13 14:18:35.897000 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'
Dec 13 14:18:35.889397 systemd[1]: Stopped systemd-udev-trigger.service.
Dec 13 14:18:35.890718 systemd[1]: dracut-pre-trigger.service: Deactivated successfully.
Dec 13 14:18:35.899000 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'
Dec 13 14:18:35.890892 systemd[1]: Stopped dracut-pre-trigger.service.
Dec 13 14:18:35.896640 systemd[1]: iscsid.service: Deactivated successfully.
Dec 13 14:18:35.896748 systemd[1]: Stopped iscsid.service.
Dec 13 14:18:35.899162 systemd[1]: ignition-mount.service: Deactivated successfully.
Dec 13 14:18:35.902000 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'
Dec 13 14:18:35.903000 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'
Dec 13 14:18:35.899249 systemd[1]: Stopped ignition-mount.service.
Dec 13 14:18:35.901530 systemd[1]: initrd-cleanup.service: Deactivated successfully.
Dec 13 14:18:35.904000 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'
Dec 13 14:18:35.901981 systemd[1]: Finished initrd-cleanup.service.
Dec 13 14:18:35.905000 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'
Dec 13 14:18:35.904790 systemd[1]: ignition-disks.service: Deactivated successfully.
Dec 13 14:18:35.906000 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'
Dec 13 14:18:35.904836 systemd[1]: Stopped ignition-disks.service.
Dec 13 14:18:35.907000 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'
Dec 13 14:18:35.905614 systemd[1]: ignition-kargs.service: Deactivated successfully.
Dec 13 14:18:35.905655 systemd[1]: Stopped ignition-kargs.service.
Dec 13 14:18:35.906581 systemd[1]: ignition-fetch.service: Deactivated successfully.
Dec 13 14:18:35.906620 systemd[1]: Stopped ignition-fetch.service.
Dec 13 14:18:35.907509 systemd[1]: ignition-fetch-offline.service: Deactivated successfully.
Dec 13 14:18:35.907550 systemd[1]: Stopped ignition-fetch-offline.service.
Dec 13 14:18:35.908519 systemd[1]: Stopped target paths.target.
Dec 13 14:18:35.909403 systemd[1]: systemd-ask-password-console.path: Deactivated successfully.
Dec 13 14:18:35.913966 systemd[1]: Stopped systemd-ask-password-console.path.
Dec 13 14:18:35.914837 systemd[1]: Stopped target slices.target.
Dec 13 14:18:35.915778 systemd[1]: Stopped target sockets.target.
Dec 13 14:18:35.916755 systemd[1]: iscsid.socket: Deactivated successfully.
Dec 13 14:18:35.916791 systemd[1]: Closed iscsid.socket.
Dec 13 14:18:35.917000 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'
Dec 13 14:18:35.917629 systemd[1]: ignition-setup.service: Deactivated successfully.
Dec 13 14:18:35.917671 systemd[1]: Stopped ignition-setup.service.
Dec 13 14:18:35.922000 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'
Dec 13 14:18:35.922369 systemd[1]: Stopping iscsiuio.service...
Dec 13 14:18:35.923104 systemd[1]: iscsiuio.service: Deactivated successfully.
Dec 13 14:18:35.923210 systemd[1]: Stopped iscsiuio.service.
Dec 13 14:18:35.923764 systemd[1]: Stopped target network.target.
Dec 13 14:18:35.924313 systemd[1]: iscsiuio.socket: Deactivated successfully.
Dec 13 14:18:35.927000 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'
Dec 13 14:18:35.924345 systemd[1]: Closed iscsiuio.socket.
Dec 13 14:18:35.928000 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'
Dec 13 14:18:35.925528 systemd[1]: Stopping systemd-networkd.service...
Dec 13 14:18:35.930000 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'
Dec 13 14:18:35.926300 systemd[1]: Stopping systemd-resolved.service...
Dec 13 14:18:35.927427 systemd[1]: sysroot-boot.service: Deactivated successfully.
Dec 13 14:18:35.927512 systemd[1]: Stopped sysroot-boot.service.
Dec 13 14:18:35.928273 systemd[1]: initrd-setup-root.service: Deactivated successfully.
Dec 13 14:18:35.928317 systemd[1]: Stopped initrd-setup-root.service.
Dec 13 14:18:35.935000 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'
Dec 13 14:18:35.929024 systemd-networkd[630]: eth0: DHCPv6 lease lost
Dec 13 14:18:35.936000 audit: BPF prog-id=9 op=UNLOAD
Dec 13 14:18:35.936000 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'
Dec 13 14:18:35.930063 systemd[1]: systemd-networkd.service: Deactivated successfully.
Dec 13 14:18:35.941000 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'
Dec 13 14:18:35.930152 systemd[1]: Stopped systemd-networkd.service.
Dec 13 14:18:35.932179 systemd[1]: systemd-networkd.socket: Deactivated successfully.
Dec 13 14:18:35.932235 systemd[1]: Closed systemd-networkd.socket.
Dec 13 14:18:35.933863 systemd[1]: Stopping network-cleanup.service...
Dec 13 14:18:35.935600 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully.
Dec 13 14:18:35.935649 systemd[1]: Stopped parse-ip-for-networkd.service.
Dec 13 14:18:35.947000 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'
Dec 13 14:18:35.936624 systemd[1]: systemd-sysctl.service: Deactivated successfully.
Dec 13 14:18:35.936662 systemd[1]: Stopped systemd-sysctl.service.
Dec 13 14:18:35.937697 systemd[1]: systemd-modules-load.service: Deactivated successfully.
Dec 13 14:18:35.950000 audit: BPF prog-id=6 op=UNLOAD
Dec 13 14:18:35.937735 systemd[1]: Stopped systemd-modules-load.service.
Dec 13 14:18:35.952000 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'
Dec 13 14:18:35.942444 systemd[1]: Stopping systemd-udevd.service...
Dec 13 14:18:35.947454 systemd[1]: systemd-resolved.service: Deactivated successfully.
Dec 13 14:18:35.947549 systemd[1]: Stopped systemd-resolved.service.
Dec 13 14:18:35.952510 systemd[1]: network-cleanup.service: Deactivated successfully.
Dec 13 14:18:35.954000 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'
Dec 13 14:18:35.952592 systemd[1]: Stopped network-cleanup.service.
Dec 13 14:18:35.954713 systemd[1]: systemd-udevd.service: Deactivated successfully.
Dec 13 14:18:35.954834 systemd[1]: Stopped systemd-udevd.service.
Dec 13 14:18:35.956789 systemd[1]: systemd-udevd-control.socket: Deactivated successfully.
Dec 13 14:18:35.958000 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'
Dec 13 14:18:35.956839 systemd[1]: Closed systemd-udevd-control.socket.
Dec 13 14:18:35.959000 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'
Dec 13 14:18:35.957833 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully.
Dec 13 14:18:35.960000 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'
Dec 13 14:18:35.957863 systemd[1]: Closed systemd-udevd-kernel.socket.
Dec 13 14:18:35.958745 systemd[1]: dracut-pre-udev.service: Deactivated successfully.
Dec 13 14:18:35.958791 systemd[1]: Stopped dracut-pre-udev.service.
Dec 13 14:18:35.959750 systemd[1]: dracut-cmdline.service: Deactivated successfully.
Dec 13 14:18:35.959787 systemd[1]: Stopped dracut-cmdline.service.
Dec 13 14:18:35.960876 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully.
Dec 13 14:18:35.960978 systemd[1]: Stopped dracut-cmdline-ask.service.
Dec 13 14:18:35.962572 systemd[1]: Starting initrd-udevadm-cleanup-db.service...
Dec 13 14:18:35.969000 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'
Dec 13 14:18:35.969596 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
Dec 13 14:18:35.970000 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'
Dec 13 14:18:35.970000 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'
Dec 13 14:18:35.969658 systemd[1]: Stopped systemd-vconsole-setup.service.
Dec 13 14:18:35.970881 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.
Dec 13 14:18:35.970992 systemd[1]: Finished initrd-udevadm-cleanup-db.service.
Dec 13 14:18:35.971618 systemd[1]: Reached target initrd-switch-root.target.
Dec 13 14:18:35.973290 systemd[1]: Starting initrd-switch-root.service...
Dec 13 14:18:35.992804 systemd[1]: Switching root.
Dec 13 14:18:36.014551 systemd-journald[186]: Journal stopped
Dec 13 14:18:40.752791 systemd-journald[186]: Received SIGTERM from PID 1 (n/a).
Dec 13 14:18:40.752847 kernel: SELinux:  Class mctp_socket not defined in policy.
Dec 13 14:18:40.752862 kernel: SELinux:  Class anon_inode not defined in policy.
Dec 13 14:18:40.752875 kernel: SELinux: the above unknown classes and permissions will be allowed
Dec 13 14:18:40.752889 kernel: SELinux:  policy capability network_peer_controls=1
Dec 13 14:18:40.752901 kernel: SELinux:  policy capability open_perms=1
Dec 13 14:18:40.752935 kernel: SELinux:  policy capability extended_socket_class=1
Dec 13 14:18:40.752948 kernel: SELinux:  policy capability always_check_network=0
Dec 13 14:18:40.752959 kernel: SELinux:  policy capability cgroup_seclabel=1
Dec 13 14:18:40.753966 kernel: SELinux:  policy capability nnp_nosuid_transition=1
Dec 13 14:18:40.753986 kernel: SELinux:  policy capability genfs_seclabel_symlinks=0
Dec 13 14:18:40.753998 kernel: SELinux:  policy capability ioctl_skip_cloexec=0
Dec 13 14:18:40.754009 kernel: kauditd_printk_skb: 68 callbacks suppressed
Dec 13 14:18:40.754026 kernel: audit: type=1403 audit(1734099516.334:79): auid=4294967295 ses=4294967295 lsm=selinux res=1
Dec 13 14:18:40.754040 systemd[1]: Successfully loaded SELinux policy in 88.374ms.
Dec 13 14:18:40.754082 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 20.855ms.
Dec 13 14:18:40.754097 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)
Dec 13 14:18:40.754110 systemd[1]: Detected virtualization kvm.
Dec 13 14:18:40.754122 systemd[1]: Detected architecture x86-64.
Dec 13 14:18:40.754134 systemd[1]: Detected first boot.
Dec 13 14:18:40.754148 systemd[1]: Hostname set to <ci-3510-3-6-7-2d0c40e50b.novalocal>.
Dec 13 14:18:40.754160 systemd[1]: Initializing machine ID from VM UUID.
Dec 13 14:18:40.754172 kernel: audit: type=1400 audit(1734099516.475:80): 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
Dec 13 14:18:40.754185 kernel: audit: type=1400 audit(1734099516.479:81): 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
Dec 13 14:18:40.754196 kernel: audit: type=1334 audit(1734099516.484:82): prog-id=10 op=LOAD
Dec 13 14:18:40.754207 kernel: audit: type=1334 audit(1734099516.484:83): prog-id=10 op=UNLOAD
Dec 13 14:18:40.754217 kernel: audit: type=1334 audit(1734099516.497:84): prog-id=11 op=LOAD
Dec 13 14:18:40.754232 kernel: audit: type=1334 audit(1734099516.497:85): prog-id=11 op=UNLOAD
Dec 13 14:18:40.754246 kernel: SELinux:  Context system_u:object_r:container_file_t:s0:c1022,c1023 is not valid (left unmapped).
Dec 13 14:18:40.754258 kernel: audit: type=1400 audit(1734099516.727:86): avc:  denied  { associate } for  pid=847 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"
Dec 13 14:18:40.754271 kernel: audit: type=1300 audit(1734099516.727:86): arch=c000003e syscall=188 success=yes exit=0 a0=c0001178d2 a1=c00002ae40 a2=c000029100 a3=32 items=0 ppid=830 pid=847 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)
Dec 13 14:18:40.754283 kernel: audit: type=1327 audit(1734099516.727:86): proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61
Dec 13 14:18:40.754295 systemd[1]: Populated /etc with preset unit settings.
Dec 13 14:18:40.754307 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon.
Dec 13 14:18:40.754319 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon.
Dec 13 14:18:40.754335 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.
Dec 13 14:18:40.754347 systemd[1]: initrd-switch-root.service: Deactivated successfully.
Dec 13 14:18:40.754360 systemd[1]: Stopped initrd-switch-root.service.
Dec 13 14:18:40.754372 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.
Dec 13 14:18:40.754384 systemd[1]: Created slice system-addon\x2dconfig.slice.
Dec 13 14:18:40.754398 systemd[1]: Created slice system-addon\x2drun.slice.
Dec 13 14:18:40.754412 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice.
Dec 13 14:18:40.754424 systemd[1]: Created slice system-getty.slice.
Dec 13 14:18:40.754436 systemd[1]: Created slice system-modprobe.slice.
Dec 13 14:18:40.754448 systemd[1]: Created slice system-serial\x2dgetty.slice.
Dec 13 14:18:40.754462 systemd[1]: Created slice system-system\x2dcloudinit.slice.
Dec 13 14:18:40.754474 systemd[1]: Created slice system-systemd\x2dfsck.slice.
Dec 13 14:18:40.754486 systemd[1]: Created slice user.slice.
Dec 13 14:18:40.754498 systemd[1]: Started systemd-ask-password-console.path.
Dec 13 14:18:40.754511 systemd[1]: Started systemd-ask-password-wall.path.
Dec 13 14:18:40.754523 systemd[1]: Set up automount boot.automount.
Dec 13 14:18:40.754535 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount.
Dec 13 14:18:40.754546 systemd[1]: Stopped target initrd-switch-root.target.
Dec 13 14:18:40.754558 systemd[1]: Stopped target initrd-fs.target.
Dec 13 14:18:40.754571 systemd[1]: Stopped target initrd-root-fs.target.
Dec 13 14:18:40.754584 systemd[1]: Reached target integritysetup.target.
Dec 13 14:18:40.754595 systemd[1]: Reached target remote-cryptsetup.target.
Dec 13 14:18:40.754608 systemd[1]: Reached target remote-fs.target.
Dec 13 14:18:40.754620 systemd[1]: Reached target slices.target.
Dec 13 14:18:40.754632 systemd[1]: Reached target swap.target.
Dec 13 14:18:40.754644 systemd[1]: Reached target torcx.target.
Dec 13 14:18:40.754656 systemd[1]: Reached target veritysetup.target.
Dec 13 14:18:40.754668 systemd[1]: Listening on systemd-coredump.socket.
Dec 13 14:18:40.754681 systemd[1]: Listening on systemd-initctl.socket.
Dec 13 14:18:40.754694 systemd[1]: Listening on systemd-networkd.socket.
Dec 13 14:18:40.754705 systemd[1]: Listening on systemd-udevd-control.socket.
Dec 13 14:18:40.754717 systemd[1]: Listening on systemd-udevd-kernel.socket.
Dec 13 14:18:40.754729 systemd[1]: Listening on systemd-userdbd.socket.
Dec 13 14:18:40.754741 systemd[1]: Mounting dev-hugepages.mount...
Dec 13 14:18:40.754753 systemd[1]: Mounting dev-mqueue.mount...
Dec 13 14:18:40.754765 systemd[1]: Mounting media.mount...
Dec 13 14:18:40.754777 systemd[1]: proc-xen.mount was skipped because of an unmet condition check (ConditionVirtualization=xen).
Dec 13 14:18:40.754791 systemd[1]: Mounting sys-kernel-debug.mount...
Dec 13 14:18:40.754803 systemd[1]: Mounting sys-kernel-tracing.mount...
Dec 13 14:18:40.754815 systemd[1]: Mounting tmp.mount...
Dec 13 14:18:40.754827 systemd[1]: Starting flatcar-tmpfiles.service...
Dec 13 14:18:40.754840 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met.
Dec 13 14:18:40.754852 systemd[1]: Starting kmod-static-nodes.service...
Dec 13 14:18:40.754864 systemd[1]: Starting modprobe@configfs.service...
Dec 13 14:18:40.754876 systemd[1]: Starting modprobe@dm_mod.service...
Dec 13 14:18:40.754889 systemd[1]: Starting modprobe@drm.service...
Dec 13 14:18:40.754903 systemd[1]: Starting modprobe@efi_pstore.service...
Dec 13 14:18:40.754933 systemd[1]: Starting modprobe@fuse.service...
Dec 13 14:18:40.754945 systemd[1]: Starting modprobe@loop.service...
Dec 13 14:18:40.754957 systemd[1]: setup-nsswitch.service was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf).
Dec 13 14:18:40.754970 systemd[1]: systemd-fsck-root.service: Deactivated successfully.
Dec 13 14:18:40.754982 systemd[1]: Stopped systemd-fsck-root.service.
Dec 13 14:18:40.754994 systemd[1]: systemd-fsck-usr.service: Deactivated successfully.
Dec 13 14:18:40.755006 systemd[1]: Stopped systemd-fsck-usr.service.
Dec 13 14:18:40.755017 systemd[1]: Stopped systemd-journald.service.
Dec 13 14:18:40.755032 systemd[1]: Starting systemd-journald.service...
Dec 13 14:18:40.755044 systemd[1]: Starting systemd-modules-load.service...
Dec 13 14:18:40.755056 systemd[1]: Starting systemd-network-generator.service...
Dec 13 14:18:40.755069 systemd[1]: Starting systemd-remount-fs.service...
Dec 13 14:18:40.755081 systemd[1]: Starting systemd-udev-trigger.service...
Dec 13 14:18:40.755093 systemd[1]: verity-setup.service: Deactivated successfully.
Dec 13 14:18:40.755105 systemd[1]: Stopped verity-setup.service.
Dec 13 14:18:40.755117 systemd[1]: xenserver-pv-version.service was skipped because of an unmet condition check (ConditionVirtualization=xen).
Dec 13 14:18:40.755129 systemd[1]: Mounted dev-hugepages.mount.
Dec 13 14:18:40.755142 systemd[1]: Mounted dev-mqueue.mount.
Dec 13 14:18:40.755154 systemd[1]: Mounted media.mount.
Dec 13 14:18:40.755166 systemd[1]: Mounted sys-kernel-debug.mount.
Dec 13 14:18:40.755178 systemd[1]: Mounted sys-kernel-tracing.mount.
Dec 13 14:18:40.755191 systemd[1]: Mounted tmp.mount.
Dec 13 14:18:40.755203 systemd[1]: Finished kmod-static-nodes.service.
Dec 13 14:18:40.755215 systemd[1]: modprobe@configfs.service: Deactivated successfully.
Dec 13 14:18:40.755227 systemd[1]: Finished modprobe@configfs.service.
Dec 13 14:18:40.755242 systemd-journald[913]: Journal started
Dec 13 14:18:40.755288 systemd-journald[913]: Runtime Journal (/run/log/journal/871eb79b86204ac5a6c1e2d684c398ce) is 4.9M, max 39.5M, 34.5M free.
Dec 13 14:18:36.334000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1
Dec 13 14:18:36.475000 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
Dec 13 14:18:36.479000 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
Dec 13 14:18:36.484000 audit: BPF prog-id=10 op=LOAD
Dec 13 14:18:36.484000 audit: BPF prog-id=10 op=UNLOAD
Dec 13 14:18:36.497000 audit: BPF prog-id=11 op=LOAD
Dec 13 14:18:36.497000 audit: BPF prog-id=11 op=UNLOAD
Dec 13 14:18:36.727000 audit[847]: AVC avc:  denied  { associate } for  pid=847 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"
Dec 13 14:18:36.727000 audit[847]: SYSCALL arch=c000003e syscall=188 success=yes exit=0 a0=c0001178d2 a1=c00002ae40 a2=c000029100 a3=32 items=0 ppid=830 pid=847 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)
Dec 13 14:18:36.727000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61
Dec 13 14:18:36.736000 audit[847]: AVC avc:  denied  { associate } for  pid=847 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
Dec 13 14:18:40.756951 systemd[1]: Started systemd-journald.service.
Dec 13 14:18:36.736000 audit[847]: SYSCALL arch=c000003e syscall=258 success=yes exit=0 a0=ffffffffffffff9c a1=c0001179a9 a2=1ed a3=0 items=2 ppid=830 pid=847 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)
Dec 13 14:18:36.736000 audit: CWD cwd="/"
Dec 13 14:18:36.736000 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
Dec 13 14:18:36.736000 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
Dec 13 14:18:36.736000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61
Dec 13 14:18:40.555000 audit: BPF prog-id=12 op=LOAD
Dec 13 14:18:40.555000 audit: BPF prog-id=3 op=UNLOAD
Dec 13 14:18:40.555000 audit: BPF prog-id=13 op=LOAD
Dec 13 14:18:40.556000 audit: BPF prog-id=14 op=LOAD
Dec 13 14:18:40.556000 audit: BPF prog-id=4 op=UNLOAD
Dec 13 14:18:40.556000 audit: BPF prog-id=5 op=UNLOAD
Dec 13 14:18:40.556000 audit: BPF prog-id=15 op=LOAD
Dec 13 14:18:40.556000 audit: BPF prog-id=12 op=UNLOAD
Dec 13 14:18:40.556000 audit: BPF prog-id=16 op=LOAD
Dec 13 14:18:40.556000 audit: BPF prog-id=17 op=LOAD
Dec 13 14:18:40.557000 audit: BPF prog-id=13 op=UNLOAD
Dec 13 14:18:40.557000 audit: BPF prog-id=14 op=UNLOAD
Dec 13 14:18:40.557000 audit: BPF prog-id=18 op=LOAD
Dec 13 14:18:40.557000 audit: BPF prog-id=15 op=UNLOAD
Dec 13 14:18:40.557000 audit: BPF prog-id=19 op=LOAD
Dec 13 14:18:40.557000 audit: BPF prog-id=20 op=LOAD
Dec 13 14:18:40.557000 audit: BPF prog-id=16 op=UNLOAD
Dec 13 14:18:40.557000 audit: BPF prog-id=17 op=UNLOAD
Dec 13 14:18:40.558000 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'
Dec 13 14:18:40.562000 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'
Dec 13 14:18:40.562000 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'
Dec 13 14:18:40.566000 audit: BPF prog-id=18 op=UNLOAD
Dec 13 14:18:40.692000 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'
Dec 13 14:18:40.695000 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'
Dec 13 14:18:40.697000 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'
Dec 13 14:18:40.697000 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'
Dec 13 14:18:40.698000 audit: BPF prog-id=21 op=LOAD
Dec 13 14:18:40.698000 audit: BPF prog-id=22 op=LOAD
Dec 13 14:18:40.698000 audit: BPF prog-id=23 op=LOAD
Dec 13 14:18:40.698000 audit: BPF prog-id=19 op=UNLOAD
Dec 13 14:18:40.698000 audit: BPF prog-id=20 op=UNLOAD
Dec 13 14:18:40.727000 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'
Dec 13 14:18:40.749000 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'
Dec 13 14:18:40.749000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1
Dec 13 14:18:40.749000 audit[913]: SYSCALL arch=c000003e syscall=46 success=yes exit=60 a0=6 a1=7fff7c5bd630 a2=4000 a3=7fff7c5bd6cc items=0 ppid=1 pid=913 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)
Dec 13 14:18:40.749000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald"
Dec 13 14:18:40.755000 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'
Dec 13 14:18:40.755000 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'
Dec 13 14:18:36.719732 /usr/lib/systemd/system-generators/torcx-generator[847]: time="2024-12-13T14:18:36Z" 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.6 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.6 /var/lib/torcx/store]"
Dec 13 14:18:40.553897 systemd[1]: Queued start job for default target multi-user.target.
Dec 13 14:18:36.721079 /usr/lib/systemd/system-generators/torcx-generator[847]: time="2024-12-13T14:18:36Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json
Dec 13 14:18:40.758000 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'
Dec 13 14:18:40.758000 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'
Dec 13 14:18:40.758000 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'
Dec 13 14:18:40.553925 systemd[1]: Unnecessary job was removed for dev-vda6.device.
Dec 13 14:18:36.721137 /usr/lib/systemd/system-generators/torcx-generator[847]: time="2024-12-13T14:18:36Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json
Dec 13 14:18:40.559217 systemd[1]: systemd-journald.service: Deactivated successfully.
Dec 13 14:18:36.721212 /usr/lib/systemd/system-generators/torcx-generator[847]: time="2024-12-13T14:18:36Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12"
Dec 13 14:18:40.759242 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Dec 13 14:18:36.721240 /usr/lib/systemd/system-generators/torcx-generator[847]: time="2024-12-13T14:18:36Z" level=debug msg="skipped missing lower profile" missing profile=oem
Dec 13 14:18:40.759393 systemd[1]: Finished modprobe@dm_mod.service.
Dec 13 14:18:36.721313 /usr/lib/systemd/system-generators/torcx-generator[847]: time="2024-12-13T14:18:36Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory"
Dec 13 14:18:40.760240 systemd[1]: modprobe@drm.service: Deactivated successfully.
Dec 13 14:18:40.759000 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'
Dec 13 14:18:40.759000 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'
Dec 13 14:18:36.721349 /usr/lib/systemd/system-generators/torcx-generator[847]: time="2024-12-13T14:18:36Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)=
Dec 13 14:18:40.760364 systemd[1]: Finished modprobe@drm.service.
Dec 13 14:18:36.721796 /usr/lib/systemd/system-generators/torcx-generator[847]: time="2024-12-13T14:18:36Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack
Dec 13 14:18:40.761140 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Dec 13 14:18:36.721892 /usr/lib/systemd/system-generators/torcx-generator[847]: time="2024-12-13T14:18:36Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json
Dec 13 14:18:36.721977 /usr/lib/systemd/system-generators/torcx-generator[847]: time="2024-12-13T14:18:36Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json
Dec 13 14:18:36.726269 /usr/lib/systemd/system-generators/torcx-generator[847]: time="2024-12-13T14:18:36Z" 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
Dec 13 14:18:36.726361 /usr/lib/systemd/system-generators/torcx-generator[847]: time="2024-12-13T14:18:36Z" 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
Dec 13 14:18:36.726411 /usr/lib/systemd/system-generators/torcx-generator[847]: time="2024-12-13T14:18:36Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3510.3.6: no such file or directory" path=/usr/share/oem/torcx/store/3510.3.6
Dec 13 14:18:40.762056 systemd[1]: Finished modprobe@efi_pstore.service.
Dec 13 14:18:36.726452 /usr/lib/systemd/system-generators/torcx-generator[847]: time="2024-12-13T14:18:36Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store
Dec 13 14:18:36.726496 /usr/lib/systemd/system-generators/torcx-generator[847]: time="2024-12-13T14:18:36Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3510.3.6: no such file or directory" path=/var/lib/torcx/store/3510.3.6
Dec 13 14:18:36.726535 /usr/lib/systemd/system-generators/torcx-generator[847]: time="2024-12-13T14:18:36Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store
Dec 13 14:18:40.761000 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'
Dec 13 14:18:40.761000 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'
Dec 13 14:18:39.976353 /usr/lib/systemd/system-generators/torcx-generator[847]: time="2024-12-13T14:18:39Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl
Dec 13 14:18:39.976660 /usr/lib/systemd/system-generators/torcx-generator[847]: time="2024-12-13T14:18:39Z" 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
Dec 13 14:18:39.976783 /usr/lib/systemd/system-generators/torcx-generator[847]: time="2024-12-13T14:18:39Z" 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
Dec 13 14:18:39.977003 /usr/lib/systemd/system-generators/torcx-generator[847]: time="2024-12-13T14:18:39Z" 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
Dec 13 14:18:39.977065 /usr/lib/systemd/system-generators/torcx-generator[847]: time="2024-12-13T14:18:39Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile=
Dec 13 14:18:39.977136 /usr/lib/systemd/system-generators/torcx-generator[847]: time="2024-12-13T14:18:39Z" 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
Dec 13 14:18:40.763088 systemd[1]: Finished systemd-modules-load.service.
Dec 13 14:18:40.762000 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'
Dec 13 14:18:40.763000 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'
Dec 13 14:18:40.764000 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'
Dec 13 14:18:40.763764 systemd[1]: Finished systemd-network-generator.service.
Dec 13 14:18:40.764487 systemd[1]: Finished systemd-remount-fs.service.
Dec 13 14:18:40.765944 systemd[1]: Reached target network-pre.target.
Dec 13 14:18:40.769188 systemd[1]: Mounting sys-kernel-config.mount...
Dec 13 14:18:40.769665 systemd[1]: remount-root.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).
Dec 13 14:18:40.775473 systemd[1]: Starting systemd-hwdb-update.service...
Dec 13 14:18:40.777060 systemd[1]: Starting systemd-journal-flush.service...
Dec 13 14:18:40.777570 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
Dec 13 14:18:40.778714 systemd[1]: Starting systemd-random-seed.service...
Dec 13 14:18:40.780596 systemd[1]: Starting systemd-sysctl.service...
Dec 13 14:18:40.785531 systemd[1]: Mounted sys-kernel-config.mount.
Dec 13 14:18:40.789278 kernel: loop: module loaded
Dec 13 14:18:40.789185 systemd[1]: modprobe@loop.service: Deactivated successfully.
Dec 13 14:18:40.789000 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'
Dec 13 14:18:40.789000 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'
Dec 13 14:18:40.790023 systemd[1]: Finished modprobe@loop.service.
Dec 13 14:18:40.790614 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met.
Dec 13 14:18:40.796122 kernel: fuse: init (API version 7.34)
Dec 13 14:18:40.796965 systemd[1]: modprobe@fuse.service: Deactivated successfully.
Dec 13 14:18:40.797102 systemd[1]: Finished modprobe@fuse.service.
Dec 13 14:18:40.796000 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'
Dec 13 14:18:40.796000 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'
Dec 13 14:18:40.798962 systemd[1]: Mounting sys-fs-fuse-connections.mount...
Dec 13 14:18:40.802424 systemd[1]: Mounted sys-fs-fuse-connections.mount.
Dec 13 14:18:40.809728 systemd-journald[913]: Time spent on flushing to /var/log/journal/871eb79b86204ac5a6c1e2d684c398ce is 26.231ms for 1082 entries.
Dec 13 14:18:40.809728 systemd-journald[913]: System Journal (/var/log/journal/871eb79b86204ac5a6c1e2d684c398ce) is 8.0M, max 584.8M, 576.8M free.
Dec 13 14:18:40.870843 systemd-journald[913]: Received client request to flush runtime journal.
Dec 13 14:18:40.843000 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'
Dec 13 14:18:40.848000 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'
Dec 13 14:18:40.852000 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'
Dec 13 14:18:40.871000 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'
Dec 13 14:18:40.843466 systemd[1]: Finished systemd-random-seed.service.
Dec 13 14:18:40.844100 systemd[1]: Reached target first-boot-complete.target.
Dec 13 14:18:40.872702 udevadm[946]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in.
Dec 13 14:18:40.849386 systemd[1]: Finished systemd-udev-trigger.service.
Dec 13 14:18:40.851323 systemd[1]: Starting systemd-udev-settle.service...
Dec 13 14:18:40.853046 systemd[1]: Finished systemd-sysctl.service.
Dec 13 14:18:40.871697 systemd[1]: Finished systemd-journal-flush.service.
Dec 13 14:18:40.879000 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'
Dec 13 14:18:40.879782 systemd[1]: Finished flatcar-tmpfiles.service.
Dec 13 14:18:40.881582 systemd[1]: Starting systemd-sysusers.service...
Dec 13 14:18:40.946000 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'
Dec 13 14:18:40.946234 systemd[1]: Finished systemd-sysusers.service.
Dec 13 14:18:41.628257 kernel: kauditd_printk_skb: 64 callbacks suppressed
Dec 13 14:18:41.628387 kernel: audit: type=1130 audit(1734099521.614:144): 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'
Dec 13 14:18:41.628426 kernel: audit: type=1334 audit(1734099521.617:145): prog-id=24 op=LOAD
Dec 13 14:18:41.614000 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'
Dec 13 14:18:41.617000 audit: BPF prog-id=24 op=LOAD
Dec 13 14:18:41.614743 systemd[1]: Finished systemd-hwdb-update.service.
Dec 13 14:18:41.630000 audit: BPF prog-id=25 op=LOAD
Dec 13 14:18:41.632571 systemd[1]: Starting systemd-udevd.service...
Dec 13 14:18:41.634625 kernel: audit: type=1334 audit(1734099521.630:146): prog-id=25 op=LOAD
Dec 13 14:18:41.634693 kernel: audit: type=1334 audit(1734099521.630:147): prog-id=7 op=UNLOAD
Dec 13 14:18:41.630000 audit: BPF prog-id=7 op=UNLOAD
Dec 13 14:18:41.630000 audit: BPF prog-id=8 op=UNLOAD
Dec 13 14:18:41.641277 kernel: audit: type=1334 audit(1734099521.630:148): prog-id=8 op=UNLOAD
Dec 13 14:18:41.670482 systemd-udevd[958]: Using default interface naming scheme 'v252'.
Dec 13 14:18:41.782325 systemd[1]: Started systemd-udevd.service.
Dec 13 14:18:41.785000 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'
Dec 13 14:18:41.797948 kernel: audit: type=1130 audit(1734099521.785:149): 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'
Dec 13 14:18:41.804294 systemd[1]: Starting systemd-networkd.service...
Dec 13 14:18:41.798000 audit: BPF prog-id=26 op=LOAD
Dec 13 14:18:41.813956 kernel: audit: type=1334 audit(1734099521.798:150): prog-id=26 op=LOAD
Dec 13 14:18:41.831000 audit: BPF prog-id=27 op=LOAD
Dec 13 14:18:41.836061 kernel: audit: type=1334 audit(1734099521.831:151): prog-id=27 op=LOAD
Dec 13 14:18:41.835000 audit: BPF prog-id=28 op=LOAD
Dec 13 14:18:41.835000 audit: BPF prog-id=29 op=LOAD
Dec 13 14:18:41.844944 kernel: audit: type=1334 audit(1734099521.835:152): prog-id=28 op=LOAD
Dec 13 14:18:41.845066 kernel: audit: type=1334 audit(1734099521.835:153): prog-id=29 op=LOAD
Dec 13 14:18:41.845263 systemd[1]: Starting systemd-userdbd.service...
Dec 13 14:18:41.848610 systemd[1]: Condition check resulted in dev-ttyS0.device being skipped.
Dec 13 14:18:41.893298 systemd[1]: Started systemd-userdbd.service.
Dec 13 14:18:41.892000 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'
Dec 13 14:18:41.974266 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2
Dec 13 14:18:41.985929 kernel: ACPI: button: Power Button [PWRF]
Dec 13 14:18:41.962000 audit[967]: AVC avc:  denied  { confidentiality } for  pid=967 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
Dec 13 14:18:41.999006 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device.
Dec 13 14:18:41.962000 audit[967]: SYSCALL arch=c000003e syscall=175 success=yes exit=0 a0=557ca857fc40 a1=337fc a2=7f29cadabbc5 a3=5 items=110 ppid=958 pid=967 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)
Dec 13 14:18:41.962000 audit: CWD cwd="/"
Dec 13 14:18:41.962000 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
Dec 13 14:18:41.962000 audit: PATH item=1 name=(null) inode=14362 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
Dec 13 14:18:41.962000 audit: PATH item=2 name=(null) inode=14362 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
Dec 13 14:18:41.962000 audit: PATH item=3 name=(null) inode=14363 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
Dec 13 14:18:41.962000 audit: PATH item=4 name=(null) inode=14362 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
Dec 13 14:18:41.962000 audit: PATH item=5 name=(null) inode=14364 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
Dec 13 14:18:41.962000 audit: PATH item=6 name=(null) inode=14362 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
Dec 13 14:18:41.962000 audit: PATH item=7 name=(null) inode=14365 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
Dec 13 14:18:41.962000 audit: PATH item=8 name=(null) inode=14365 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
Dec 13 14:18:41.962000 audit: PATH item=9 name=(null) inode=14366 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
Dec 13 14:18:41.962000 audit: PATH item=10 name=(null) inode=14365 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
Dec 13 14:18:41.962000 audit: PATH item=11 name=(null) inode=14367 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
Dec 13 14:18:41.962000 audit: PATH item=12 name=(null) inode=14365 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
Dec 13 14:18:41.962000 audit: PATH item=13 name=(null) inode=14368 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
Dec 13 14:18:41.962000 audit: PATH item=14 name=(null) inode=14365 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
Dec 13 14:18:41.962000 audit: PATH item=15 name=(null) inode=14369 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
Dec 13 14:18:41.962000 audit: PATH item=16 name=(null) inode=14365 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
Dec 13 14:18:41.962000 audit: PATH item=17 name=(null) inode=14370 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
Dec 13 14:18:41.962000 audit: PATH item=18 name=(null) inode=14362 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
Dec 13 14:18:41.962000 audit: PATH item=19 name=(null) inode=14371 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
Dec 13 14:18:41.962000 audit: PATH item=20 name=(null) inode=14371 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
Dec 13 14:18:41.962000 audit: PATH item=21 name=(null) inode=14372 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
Dec 13 14:18:41.962000 audit: PATH item=22 name=(null) inode=14371 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
Dec 13 14:18:41.962000 audit: PATH item=23 name=(null) inode=14373 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
Dec 13 14:18:41.962000 audit: PATH item=24 name=(null) inode=14371 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
Dec 13 14:18:41.962000 audit: PATH item=25 name=(null) inode=14374 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
Dec 13 14:18:41.962000 audit: PATH item=26 name=(null) inode=14371 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
Dec 13 14:18:41.962000 audit: PATH item=27 name=(null) inode=14375 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
Dec 13 14:18:41.962000 audit: PATH item=28 name=(null) inode=14371 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
Dec 13 14:18:41.962000 audit: PATH item=29 name=(null) inode=14376 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
Dec 13 14:18:41.962000 audit: PATH item=30 name=(null) inode=14362 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
Dec 13 14:18:41.962000 audit: PATH item=31 name=(null) inode=14377 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
Dec 13 14:18:41.962000 audit: PATH item=32 name=(null) inode=14377 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
Dec 13 14:18:41.962000 audit: PATH item=33 name=(null) inode=14378 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
Dec 13 14:18:41.962000 audit: PATH item=34 name=(null) inode=14377 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
Dec 13 14:18:41.962000 audit: PATH item=35 name=(null) inode=14379 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
Dec 13 14:18:41.962000 audit: PATH item=36 name=(null) inode=14377 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
Dec 13 14:18:41.962000 audit: PATH item=37 name=(null) inode=14380 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
Dec 13 14:18:41.962000 audit: PATH item=38 name=(null) inode=14377 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
Dec 13 14:18:41.962000 audit: PATH item=39 name=(null) inode=14381 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
Dec 13 14:18:41.962000 audit: PATH item=40 name=(null) inode=14377 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
Dec 13 14:18:41.962000 audit: PATH item=41 name=(null) inode=14382 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
Dec 13 14:18:41.962000 audit: PATH item=42 name=(null) inode=14362 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
Dec 13 14:18:41.962000 audit: PATH item=43 name=(null) inode=14383 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
Dec 13 14:18:41.962000 audit: PATH item=44 name=(null) inode=14383 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
Dec 13 14:18:41.962000 audit: PATH item=45 name=(null) inode=14384 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
Dec 13 14:18:41.962000 audit: PATH item=46 name=(null) inode=14383 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
Dec 13 14:18:41.962000 audit: PATH item=47 name=(null) inode=14385 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
Dec 13 14:18:41.962000 audit: PATH item=48 name=(null) inode=14383 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
Dec 13 14:18:41.962000 audit: PATH item=49 name=(null) inode=14386 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
Dec 13 14:18:41.962000 audit: PATH item=50 name=(null) inode=14383 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
Dec 13 14:18:41.962000 audit: PATH item=51 name=(null) inode=14387 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
Dec 13 14:18:41.962000 audit: PATH item=52 name=(null) inode=14383 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
Dec 13 14:18:41.962000 audit: PATH item=53 name=(null) inode=14388 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
Dec 13 14:18:41.962000 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
Dec 13 14:18:41.962000 audit: PATH item=55 name=(null) inode=14389 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
Dec 13 14:18:41.962000 audit: PATH item=56 name=(null) inode=14389 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
Dec 13 14:18:41.962000 audit: PATH item=57 name=(null) inode=14390 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
Dec 13 14:18:41.962000 audit: PATH item=58 name=(null) inode=14389 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
Dec 13 14:18:41.962000 audit: PATH item=59 name=(null) inode=14391 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
Dec 13 14:18:41.962000 audit: PATH item=60 name=(null) inode=14389 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
Dec 13 14:18:41.962000 audit: PATH item=61 name=(null) inode=14392 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
Dec 13 14:18:41.962000 audit: PATH item=62 name=(null) inode=14392 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
Dec 13 14:18:41.962000 audit: PATH item=63 name=(null) inode=14393 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
Dec 13 14:18:41.962000 audit: PATH item=64 name=(null) inode=14392 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
Dec 13 14:18:41.962000 audit: PATH item=65 name=(null) inode=14394 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
Dec 13 14:18:41.962000 audit: PATH item=66 name=(null) inode=14392 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
Dec 13 14:18:41.962000 audit: PATH item=67 name=(null) inode=14395 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
Dec 13 14:18:41.962000 audit: PATH item=68 name=(null) inode=14392 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
Dec 13 14:18:41.962000 audit: PATH item=69 name=(null) inode=14396 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
Dec 13 14:18:41.962000 audit: PATH item=70 name=(null) inode=14392 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
Dec 13 14:18:41.962000 audit: PATH item=71 name=(null) inode=14397 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
Dec 13 14:18:41.962000 audit: PATH item=72 name=(null) inode=14389 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
Dec 13 14:18:41.962000 audit: PATH item=73 name=(null) inode=14398 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
Dec 13 14:18:41.962000 audit: PATH item=74 name=(null) inode=14398 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
Dec 13 14:18:41.962000 audit: PATH item=75 name=(null) inode=14399 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
Dec 13 14:18:41.962000 audit: PATH item=76 name=(null) inode=14398 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
Dec 13 14:18:41.962000 audit: PATH item=77 name=(null) inode=14400 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
Dec 13 14:18:41.962000 audit: PATH item=78 name=(null) inode=14398 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
Dec 13 14:18:41.962000 audit: PATH item=79 name=(null) inode=14401 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
Dec 13 14:18:41.962000 audit: PATH item=80 name=(null) inode=14398 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
Dec 13 14:18:41.962000 audit: PATH item=81 name=(null) inode=14402 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
Dec 13 14:18:41.962000 audit: PATH item=82 name=(null) inode=14398 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
Dec 13 14:18:41.962000 audit: PATH item=83 name=(null) inode=14403 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
Dec 13 14:18:41.962000 audit: PATH item=84 name=(null) inode=14389 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
Dec 13 14:18:41.962000 audit: PATH item=85 name=(null) inode=14404 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
Dec 13 14:18:41.962000 audit: PATH item=86 name=(null) inode=14404 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
Dec 13 14:18:41.962000 audit: PATH item=87 name=(null) inode=14405 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
Dec 13 14:18:41.962000 audit: PATH item=88 name=(null) inode=14404 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
Dec 13 14:18:41.962000 audit: PATH item=89 name=(null) inode=14406 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
Dec 13 14:18:41.962000 audit: PATH item=90 name=(null) inode=14404 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
Dec 13 14:18:41.962000 audit: PATH item=91 name=(null) inode=14407 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
Dec 13 14:18:41.962000 audit: PATH item=92 name=(null) inode=14404 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
Dec 13 14:18:41.962000 audit: PATH item=93 name=(null) inode=14408 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
Dec 13 14:18:41.962000 audit: PATH item=94 name=(null) inode=14404 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
Dec 13 14:18:41.962000 audit: PATH item=95 name=(null) inode=14409 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
Dec 13 14:18:41.962000 audit: PATH item=96 name=(null) inode=14389 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
Dec 13 14:18:41.962000 audit: PATH item=97 name=(null) inode=14410 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
Dec 13 14:18:41.962000 audit: PATH item=98 name=(null) inode=14410 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
Dec 13 14:18:41.962000 audit: PATH item=99 name=(null) inode=14411 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
Dec 13 14:18:41.962000 audit: PATH item=100 name=(null) inode=14410 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
Dec 13 14:18:41.962000 audit: PATH item=101 name=(null) inode=14412 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
Dec 13 14:18:41.962000 audit: PATH item=102 name=(null) inode=14410 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
Dec 13 14:18:41.962000 audit: PATH item=103 name=(null) inode=14413 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
Dec 13 14:18:41.962000 audit: PATH item=104 name=(null) inode=14410 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
Dec 13 14:18:41.962000 audit: PATH item=105 name=(null) inode=14414 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
Dec 13 14:18:41.962000 audit: PATH item=106 name=(null) inode=14410 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
Dec 13 14:18:41.962000 audit: PATH item=107 name=(null) inode=14415 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
Dec 13 14:18:41.962000 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
Dec 13 14:18:41.962000 audit: PATH item=109 name=(null) inode=14420 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
Dec 13 14:18:41.962000 audit: PROCTITLE proctitle="(udev-worker)"
Dec 13 14:18:42.012295 kernel: piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0
Dec 13 14:18:42.026794 systemd-networkd[974]: lo: Link UP
Dec 13 14:18:42.029019 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3
Dec 13 14:18:42.027661 systemd-networkd[974]: lo: Gained carrier
Dec 13 14:18:42.028164 systemd-networkd[974]: Enumeration completed
Dec 13 14:18:42.028304 systemd[1]: Started systemd-networkd.service.
Dec 13 14:18:42.028988 systemd-networkd[974]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
Dec 13 14:18:42.030898 systemd-networkd[974]: eth0: Link UP
Dec 13 14:18:42.031011 systemd-networkd[974]: eth0: Gained carrier
Dec 13 14:18:42.041152 systemd-networkd[974]: eth0: DHCPv4 address 172.24.4.30/24, gateway 172.24.4.1 acquired from 172.24.4.1
Dec 13 14:18:42.047928 kernel: mousedev: PS/2 mouse device common for all mice
Dec 13 14:18:42.058000 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'
Dec 13 14:18:42.060509 systemd[1]: Starting systemd-networkd-wait-online.service...
Dec 13 14:18:42.064350 systemd[1]: Finished systemd-udev-settle.service.
Dec 13 14:18:42.063000 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'
Dec 13 14:18:42.066220 systemd[1]: Starting lvm2-activation-early.service...
Dec 13 14:18:42.113797 lvm[988]:   WARNING: Failed to connect to lvmetad. Falling back to device scanning.
Dec 13 14:18:42.157886 systemd[1]: Finished lvm2-activation-early.service.
Dec 13 14:18:42.158000 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'
Dec 13 14:18:42.159353 systemd[1]: Reached target cryptsetup.target.
Dec 13 14:18:42.163029 systemd[1]: Starting lvm2-activation.service...
Dec 13 14:18:42.172585 lvm[989]:   WARNING: Failed to connect to lvmetad. Falling back to device scanning.
Dec 13 14:18:42.213036 systemd[1]: Finished lvm2-activation.service.
Dec 13 14:18:42.213000 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'
Dec 13 14:18:42.214433 systemd[1]: Reached target local-fs-pre.target.
Dec 13 14:18:42.215627 systemd[1]: var-lib-machines.mount was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw).
Dec 13 14:18:42.215690 systemd[1]: Reached target local-fs.target.
Dec 13 14:18:42.216887 systemd[1]: Reached target machines.target.
Dec 13 14:18:42.220651 systemd[1]: Starting ldconfig.service...
Dec 13 14:18:42.223079 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met.
Dec 13 14:18:42.223179 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).
Dec 13 14:18:42.225779 systemd[1]: Starting systemd-boot-update.service...
Dec 13 14:18:42.230436 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service...
Dec 13 14:18:42.239001 systemd[1]: Starting systemd-machine-id-commit.service...
Dec 13 14:18:42.240570 systemd[1]: systemd-sysext.service was skipped because no trigger condition checks were met.
Dec 13 14:18:42.240693 systemd[1]: ensure-sysext.service was skipped because no trigger condition checks were met.
Dec 13 14:18:42.245139 systemd[1]: Starting systemd-tmpfiles-setup.service...
Dec 13 14:18:42.258995 systemd[1]: boot.automount: Got automount request for /boot, triggered by 991 (bootctl)
Dec 13 14:18:42.262160 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service...
Dec 13 14:18:42.289000 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'
Dec 13 14:18:42.289246 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service.
Dec 13 14:18:42.371076 systemd-tmpfiles[994]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring.
Dec 13 14:18:42.383979 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
Dec 13 14:18:42.386476 systemd[1]: Finished systemd-machine-id-commit.service.
Dec 13 14:18:42.386000 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'
Dec 13 14:18:42.405731 systemd-tmpfiles[994]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring.
Dec 13 14:18:42.512550 systemd-tmpfiles[994]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring.
Dec 13 14:18:42.658624 systemd-fsck[999]: fsck.fat 4.2 (2021-01-31)
Dec 13 14:18:42.658624 systemd-fsck[999]: /dev/vda1: 789 files, 119291/258078 clusters
Dec 13 14:18:42.660000 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'
Dec 13 14:18:42.660553 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service.
Dec 13 14:18:42.662886 systemd[1]: Mounting boot.mount...
Dec 13 14:18:42.692505 systemd[1]: Mounted boot.mount.
Dec 13 14:18:42.750864 systemd[1]: Finished systemd-boot-update.service.
Dec 13 14:18:42.751000 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'
Dec 13 14:18:42.960265 systemd[1]: Finished systemd-tmpfiles-setup.service.
Dec 13 14:18:42.959000 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'
Dec 13 14:18:42.963712 systemd[1]: Starting audit-rules.service...
Dec 13 14:18:42.966306 systemd[1]: Starting clean-ca-certificates.service...
Dec 13 14:18:42.968449 systemd[1]: Starting systemd-journal-catalog-update.service...
Dec 13 14:18:42.973000 audit: BPF prog-id=30 op=LOAD
Dec 13 14:18:42.974851 systemd[1]: Starting systemd-resolved.service...
Dec 13 14:18:42.975000 audit: BPF prog-id=31 op=LOAD
Dec 13 14:18:42.978092 systemd[1]: Starting systemd-timesyncd.service...
Dec 13 14:18:42.981249 systemd[1]: Starting systemd-update-utmp.service...
Dec 13 14:18:42.991579 systemd[1]: Finished clean-ca-certificates.service.
Dec 13 14:18:42.991000 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'
Dec 13 14:18:42.992229 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt).
Dec 13 14:18:42.996000 audit[1008]: SYSTEM_BOOT pid=1008 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'
Dec 13 14:18:42.998804 systemd[1]: Finished systemd-update-utmp.service.
Dec 13 14:18:42.998000 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'
Dec 13 14:18:43.047989 systemd[1]: Finished systemd-journal-catalog-update.service.
Dec 13 14:18:43.051000 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'
Dec 13 14:18:43.115000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-timesyncd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Dec 13 14:18:43.114762 systemd[1]: Started systemd-timesyncd.service.
Dec 13 14:18:43.116644 systemd[1]: Reached target time-set.target.
Dec 13 14:18:43.117000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1
Dec 13 14:18:43.117000 audit[1023]: SYSCALL arch=c000003e syscall=44 success=yes exit=1056 a0=3 a1=7ffe178a6d30 a2=420 a3=0 items=0 ppid=1003 pid=1023 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)
Dec 13 14:18:43.117000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573
Dec 13 14:18:43.118782 augenrules[1023]: No rules
Dec 13 14:18:43.120902 systemd[1]: Finished audit-rules.service.
Dec 13 14:18:43.123650 systemd-resolved[1006]: Positive Trust Anchors:
Dec 13 14:18:43.123691 systemd-resolved[1006]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
Dec 13 14:18:43.123770 systemd-resolved[1006]: 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
Dec 13 14:18:43.139055 systemd-resolved[1006]: Using system hostname 'ci-3510-3-6-7-2d0c40e50b.novalocal'.
Dec 13 14:18:43.142183 systemd[1]: Started systemd-resolved.service.
Dec 13 14:18:43.143421 systemd[1]: Reached target network.target.
Dec 13 14:18:43.144515 systemd[1]: Reached target nss-lookup.target.
Dec 13 14:18:43.177084 systemd-timesyncd[1007]: Contacted time server 146.59.30.179:123 (0.flatcar.pool.ntp.org).
Dec 13 14:18:43.177697 systemd-timesyncd[1007]: Initial clock synchronization to Fri 2024-12-13 14:18:43.419670 UTC.
Dec 13 14:18:43.565676 ldconfig[990]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start.
Dec 13 14:18:43.593539 systemd[1]: Finished ldconfig.service.
Dec 13 14:18:43.597640 systemd[1]: Starting systemd-update-done.service...
Dec 13 14:18:43.612051 systemd[1]: Finished systemd-update-done.service.
Dec 13 14:18:43.613545 systemd[1]: Reached target sysinit.target.
Dec 13 14:18:43.614793 systemd[1]: Started motdgen.path.
Dec 13 14:18:43.616074 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path.
Dec 13 14:18:43.617816 systemd[1]: Started logrotate.timer.
Dec 13 14:18:43.619078 systemd[1]: Started mdadm.timer.
Dec 13 14:18:43.620090 systemd[1]: Started systemd-tmpfiles-clean.timer.
Dec 13 14:18:43.621330 systemd[1]: update-engine-stub.timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate).
Dec 13 14:18:43.621410 systemd[1]: Reached target paths.target.
Dec 13 14:18:43.622449 systemd[1]: Reached target timers.target.
Dec 13 14:18:43.624240 systemd[1]: Listening on dbus.socket.
Dec 13 14:18:43.627962 systemd[1]: Starting docker.socket...
Dec 13 14:18:43.634957 systemd[1]: Listening on sshd.socket.
Dec 13 14:18:43.636655 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).
Dec 13 14:18:43.637848 systemd[1]: Listening on docker.socket.
Dec 13 14:18:43.639315 systemd[1]: Reached target sockets.target.
Dec 13 14:18:43.640758 systemd[1]: Reached target basic.target.
Dec 13 14:18:43.642143 systemd[1]: addon-config@usr-share-oem.service was skipped because no trigger condition checks were met.
Dec 13 14:18:43.642383 systemd[1]: addon-run@usr-share-oem.service was skipped because no trigger condition checks were met.
Dec 13 14:18:43.644833 systemd[1]: Starting containerd.service...
Dec 13 14:18:43.648689 systemd[1]: Starting coreos-metadata-sshkeys@core.service...
Dec 13 14:18:43.652532 systemd[1]: Starting coreos-metadata.service...
Dec 13 14:18:43.661200 systemd[1]: Starting dbus.service...
Dec 13 14:18:43.667198 systemd[1]: Starting enable-oem-cloudinit.service...
Dec 13 14:18:43.674854 systemd[1]: Starting extend-filesystems.service...
Dec 13 14:18:43.676741 systemd[1]: flatcar-setup-environment.service was skipped because of an unmet condition check (ConditionPathExists=/usr/share/oem/bin/flatcar-setup-environment).
Dec 13 14:18:43.679220 systemd[1]: Starting motdgen.service...
Dec 13 14:18:43.684002 systemd[1]: Starting ssh-key-proc-cmdline.service...
Dec 13 14:18:43.685730 systemd[1]: Starting sshd-keygen.service...
Dec 13 14:18:43.690902 systemd[1]: Starting systemd-logind.service...
Dec 13 14:18:43.691448 systemd[1]: systemd-pcrphase.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f).
Dec 13 14:18:43.691492 systemd[1]: tcsd.service was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0).
Dec 13 14:18:43.712757 jq[1047]: true
Dec 13 14:18:43.692565 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details.
Dec 13 14:18:43.693459 systemd[1]: Starting update-engine.service...
Dec 13 14:18:43.694071 systemd-networkd[974]: eth0: Gained IPv6LL
Dec 13 14:18:43.697089 systemd[1]: Starting update-ssh-keys-after-ignition.service...
Dec 13 14:18:43.702968 systemd[1]: Finished systemd-networkd-wait-online.service.
Dec 13 14:18:43.703588 systemd[1]: Reached target network-online.target.
Dec 13 14:18:43.715667 jq[1040]: false
Dec 13 14:18:43.714632 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully.
Dec 13 14:18:43.714790 systemd[1]: Finished ssh-key-proc-cmdline.service.
Dec 13 14:18:43.715845 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'.
Dec 13 14:18:43.716004 systemd[1]: Condition check resulted in enable-oem-cloudinit.service being skipped.
Dec 13 14:18:43.741117 jq[1050]: true
Dec 13 14:18:43.761736 systemd[1]: Created slice system-sshd.slice.
Dec 13 14:18:43.762825 systemd[1]: motdgen.service: Deactivated successfully.
Dec 13 14:18:43.762975 systemd[1]: Finished motdgen.service.
Dec 13 14:18:43.763796 extend-filesystems[1041]: Found vda
Dec 13 14:18:43.775553 extend-filesystems[1041]: Found vda1
Dec 13 14:18:43.776201 extend-filesystems[1041]: Found vda2
Dec 13 14:18:43.778870 extend-filesystems[1041]: Found vda3
Dec 13 14:18:43.779448 extend-filesystems[1041]: Found usr
Dec 13 14:18:43.782081 extend-filesystems[1041]: Found vda4
Dec 13 14:18:43.783371 extend-filesystems[1041]: Found vda6
Dec 13 14:18:43.783981 extend-filesystems[1041]: Found vda7
Dec 13 14:18:43.787618 dbus-daemon[1039]: [system] SELinux support is enabled
Dec 13 14:18:43.788223 systemd[1]: Started dbus.service.
Dec 13 14:18:43.791610 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).
Dec 13 14:18:43.791648 systemd[1]: Reached target system-config.target.
Dec 13 14:18:43.797237 extend-filesystems[1041]: Found vda9
Dec 13 14:18:43.797237 extend-filesystems[1041]: Checking size of /dev/vda9
Dec 13 14:18:43.792252 systemd[1]: user-cloudinit-proc-cmdline.service was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url).
Dec 13 14:18:43.792270 systemd[1]: Reached target user-config.target.
Dec 13 14:18:43.825116 env[1049]: time="2024-12-13T14:18:43.824769562Z" level=info msg="starting containerd" revision=92b3a9d6f1b3bcc6dc74875cfdea653fe39f09c2 version=1.6.16
Dec 13 14:18:43.855650 systemd-logind[1045]: Watching system buttons on /dev/input/event1 (Power Button)
Dec 13 14:18:43.855681 systemd-logind[1045]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)
Dec 13 14:18:43.855884 systemd-logind[1045]: New seat seat0.
Dec 13 14:18:43.857505 systemd[1]: Started systemd-logind.service.
Dec 13 14:18:43.866611 extend-filesystems[1041]: Resized partition /dev/vda9
Dec 13 14:18:43.882004 bash[1082]: Updated "/home/core/.ssh/authorized_keys"
Dec 13 14:18:43.882606 systemd[1]: Finished update-ssh-keys-after-ignition.service.
Dec 13 14:18:43.891072 extend-filesystems[1089]: resize2fs 1.46.5 (30-Dec-2021)
Dec 13 14:18:43.898966 env[1049]: time="2024-12-13T14:18:43.897864602Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Dec 13 14:18:43.928025 coreos-metadata[1033]: Dec 13 14:18:43.927 INFO Fetching http://169.254.169.254/latest/meta-data/public-keys: Attempt #1
Dec 13 14:18:43.947376 env[1049]: time="2024-12-13T14:18:43.947001678Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Dec 13 14:18:43.949288 env[1049]: time="2024-12-13T14:18:43.948601689Z" 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.173-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1
Dec 13 14:18:43.949288 env[1049]: time="2024-12-13T14:18:43.948636284Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Dec 13 14:18:43.949288 env[1049]: time="2024-12-13T14:18:43.948996079Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1
Dec 13 14:18:43.949288 env[1049]: time="2024-12-13T14:18:43.949040512Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Dec 13 14:18:43.949288 env[1049]: time="2024-12-13T14:18:43.949058235Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured"
Dec 13 14:18:43.949288 env[1049]: time="2024-12-13T14:18:43.949071340Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Dec 13 14:18:43.949288 env[1049]: time="2024-12-13T14:18:43.949181516Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Dec 13 14:18:43.949846 env[1049]: time="2024-12-13T14:18:43.949826206Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Dec 13 14:18:43.950124 env[1049]: time="2024-12-13T14:18:43.950083077Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1
Dec 13 14:18:43.950231 env[1049]: time="2024-12-13T14:18:43.950213602Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Dec 13 14:18:43.950406 env[1049]: time="2024-12-13T14:18:43.950365467Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured"
Dec 13 14:18:43.950504 env[1049]: time="2024-12-13T14:18:43.950488287Z" level=info msg="metadata content store policy set" policy=shared
Dec 13 14:18:43.954343 coreos-metadata[1034]: Dec 13 14:18:43.954 INFO Fetching http://169.254.169.254/latest/meta-data/hostname: Attempt #1
Dec 13 14:18:43.955751 coreos-metadata[1033]: Dec 13 14:18:43.955 INFO Fetch successful
Dec 13 14:18:43.955814 coreos-metadata[1033]: Dec 13 14:18:43.955 INFO Fetching http://169.254.169.254/latest/meta-data/public-keys/0/openssh-key: Attempt #1
Dec 13 14:18:43.969604 coreos-metadata[1033]: Dec 13 14:18:43.969 INFO Fetch successful
Dec 13 14:18:43.974049 kernel: EXT4-fs (vda9): resizing filesystem from 1617920 to 4635643 blocks
Dec 13 14:18:43.977786 coreos-metadata[1034]: Dec 13 14:18:43.977 INFO Fetch successful
Dec 13 14:18:43.977786 coreos-metadata[1034]: Dec 13 14:18:43.977 INFO Fetching http://169.254.169.254/latest/meta-data/instance-id: Attempt #1
Dec 13 14:18:43.986617 update_engine[1046]: I1213 14:18:43.975100  1046 main.cc:92] Flatcar Update Engine starting
Dec 13 14:18:43.992461 update_engine[1046]: I1213 14:18:43.992430  1046 update_check_scheduler.cc:74] Next update check in 9m7s
Dec 13 14:18:43.992631 systemd[1]: Started update-engine.service.
Dec 13 14:18:43.996300 systemd[1]: Started locksmithd.service.
Dec 13 14:18:43.998581 coreos-metadata[1034]: Dec 13 14:18:43.995 INFO Fetch successful
Dec 13 14:18:43.998581 coreos-metadata[1034]: Dec 13 14:18:43.997 INFO Fetching http://169.254.169.254/latest/meta-data/instance-type: Attempt #1
Dec 13 14:18:44.011726 coreos-metadata[1034]: Dec 13 14:18:44.011 INFO Fetch successful
Dec 13 14:18:44.011726 coreos-metadata[1034]: Dec 13 14:18:44.011 INFO Fetching http://169.254.169.254/latest/meta-data/local-ipv4: Attempt #1
Dec 13 14:18:44.052003 systemd[1]: Finished coreos-metadata.service.
Dec 13 14:18:44.170928 coreos-metadata[1034]: Dec 13 14:18:44.026 INFO Fetch successful
Dec 13 14:18:44.170928 coreos-metadata[1034]: Dec 13 14:18:44.026 INFO Fetching http://169.254.169.254/latest/meta-data/public-ipv4: Attempt #1
Dec 13 14:18:44.170928 coreos-metadata[1034]: Dec 13 14:18:44.041 INFO Fetch successful
Dec 13 14:18:44.054235 systemd[1]: Starting etcd-member.service...
Dec 13 14:18:44.158510 locksmithd[1092]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot"
Dec 13 14:18:44.162277 unknown[1033]: wrote ssh authorized keys file for user: core
Dec 13 14:18:44.204926 kernel: EXT4-fs (vda9): resized filesystem to 4635643
Dec 13 14:18:44.314563 extend-filesystems[1089]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required
Dec 13 14:18:44.314563 extend-filesystems[1089]: old_desc_blocks = 1, new_desc_blocks = 3
Dec 13 14:18:44.314563 extend-filesystems[1089]: The filesystem on /dev/vda9 is now 4635643 (4k) blocks long.
Dec 13 14:18:44.331346 extend-filesystems[1041]: Resized filesystem in /dev/vda9
Dec 13 14:18:44.333020 env[1049]: time="2024-12-13T14:18:44.319698860Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Dec 13 14:18:44.333020 env[1049]: time="2024-12-13T14:18:44.319784436Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Dec 13 14:18:44.333020 env[1049]: time="2024-12-13T14:18:44.319826026Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Dec 13 14:18:44.333020 env[1049]: time="2024-12-13T14:18:44.320227287Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Dec 13 14:18:44.333020 env[1049]: time="2024-12-13T14:18:44.320290608Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Dec 13 14:18:44.333020 env[1049]: time="2024-12-13T14:18:44.320328906Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Dec 13 14:18:44.333020 env[1049]: time="2024-12-13T14:18:44.320364798Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Dec 13 14:18:44.333020 env[1049]: time="2024-12-13T14:18:44.320402745Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Dec 13 14:18:44.333020 env[1049]: time="2024-12-13T14:18:44.320438173Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1
Dec 13 14:18:44.333020 env[1049]: time="2024-12-13T14:18:44.320472713Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Dec 13 14:18:44.333020 env[1049]: time="2024-12-13T14:18:44.320506428Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Dec 13 14:18:44.333020 env[1049]: time="2024-12-13T14:18:44.320541639Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Dec 13 14:18:44.333020 env[1049]: time="2024-12-13T14:18:44.320793847Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Dec 13 14:18:44.333020 env[1049]: time="2024-12-13T14:18:44.321168278Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Dec 13 14:18:44.316561 systemd[1]: extend-filesystems.service: Deactivated successfully.
Dec 13 14:18:44.348442 env[1049]: time="2024-12-13T14:18:44.323430277Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Dec 13 14:18:44.348442 env[1049]: time="2024-12-13T14:18:44.323513563Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Dec 13 14:18:44.348442 env[1049]: time="2024-12-13T14:18:44.323556526Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Dec 13 14:18:44.348442 env[1049]: time="2024-12-13T14:18:44.323696483Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Dec 13 14:18:44.348442 env[1049]: time="2024-12-13T14:18:44.325140963Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Dec 13 14:18:44.348442 env[1049]: time="2024-12-13T14:18:44.325196272Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Dec 13 14:18:44.348442 env[1049]: time="2024-12-13T14:18:44.325228181Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Dec 13 14:18:44.348442 env[1049]: time="2024-12-13T14:18:44.325261048Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Dec 13 14:18:44.348442 env[1049]: time="2024-12-13T14:18:44.325295702Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Dec 13 14:18:44.348442 env[1049]: time="2024-12-13T14:18:44.325326548Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Dec 13 14:18:44.348442 env[1049]: time="2024-12-13T14:18:44.325362161Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Dec 13 14:18:44.348442 env[1049]: time="2024-12-13T14:18:44.325402400Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Dec 13 14:18:44.348442 env[1049]: time="2024-12-13T14:18:44.325745490Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Dec 13 14:18:44.348442 env[1049]: time="2024-12-13T14:18:44.325814179Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Dec 13 14:18:44.348442 env[1049]: time="2024-12-13T14:18:44.325849184Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Dec 13 14:18:44.316997 systemd[1]: Finished extend-filesystems.service.
Dec 13 14:18:44.349682 env[1049]: time="2024-12-13T14:18:44.325882640Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Dec 13 14:18:44.349682 env[1049]: time="2024-12-13T14:18:44.325924695Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1
Dec 13 14:18:44.349682 env[1049]: time="2024-12-13T14:18:44.326001725Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Dec 13 14:18:44.349682 env[1049]: time="2024-12-13T14:18:44.326047280Z" level=error msg="failed to initialize a tracing processor \"otlp\"" error="no OpenTelemetry endpoint: skip plugin"
Dec 13 14:18:44.349682 env[1049]: time="2024-12-13T14:18:44.326136407Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1
Dec 13 14:18:44.338628 systemd[1]: Started containerd.service.
Dec 13 14:18:44.343355 systemd[1]: Starting docker.service...
Dec 13 14:18:44.350638 env[1049]: time="2024-12-13T14:18:44.326628158Z" 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}"
Dec 13 14:18:44.350638 env[1049]: time="2024-12-13T14:18:44.326787440Z" level=info msg="Connect containerd service"
Dec 13 14:18:44.350638 env[1049]: time="2024-12-13T14:18:44.326876061Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\""
Dec 13 14:18:44.350638 env[1049]: time="2024-12-13T14:18:44.336613707Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config"
Dec 13 14:18:44.350638 env[1049]: time="2024-12-13T14:18:44.337428098Z" level=info msg="Start subscribing containerd event"
Dec 13 14:18:44.350638 env[1049]: time="2024-12-13T14:18:44.337516380Z" level=info msg="Start recovering state"
Dec 13 14:18:44.350638 env[1049]: time="2024-12-13T14:18:44.337637384Z" level=info msg="Start event monitor"
Dec 13 14:18:44.350638 env[1049]: time="2024-12-13T14:18:44.337666051Z" level=info msg="Start snapshots syncer"
Dec 13 14:18:44.350638 env[1049]: time="2024-12-13T14:18:44.337688348Z" level=info msg="Start cni network conf syncer for default"
Dec 13 14:18:44.350638 env[1049]: time="2024-12-13T14:18:44.337706558Z" level=info msg="Start streaming server"
Dec 13 14:18:44.350638 env[1049]: time="2024-12-13T14:18:44.338248953Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc
Dec 13 14:18:44.350638 env[1049]: time="2024-12-13T14:18:44.338349105Z" level=info msg=serving... address=/run/containerd/containerd.sock
Dec 13 14:18:44.363769 env[1049]: time="2024-12-13T14:18:44.358187120Z" level=info msg="containerd successfully booted in 0.550924s"
Dec 13 14:18:44.382556 update-ssh-keys[1110]: Updated "/home/core/.ssh/authorized_keys"
Dec 13 14:18:44.383769 systemd[1]: Finished coreos-metadata-sshkeys@core.service.
Dec 13 14:18:44.418565 env[1113]: time="2024-12-13T14:18:44.418512029Z" level=info msg="Starting up"
Dec 13 14:18:44.419734 env[1113]: time="2024-12-13T14:18:44.419706127Z" level=info msg="parsed scheme: \"unix\"" module=grpc
Dec 13 14:18:44.419734 env[1113]: time="2024-12-13T14:18:44.419729756Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
Dec 13 14:18:44.419813 env[1113]: time="2024-12-13T14:18:44.419754738Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/libcontainerd/docker-containerd.sock  <nil> 0 <nil>}] <nil> <nil>}" module=grpc
Dec 13 14:18:44.419813 env[1113]: time="2024-12-13T14:18:44.419767982Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
Dec 13 14:18:44.422974 env[1113]: time="2024-12-13T14:18:44.422872058Z" level=info msg="parsed scheme: \"unix\"" module=grpc
Dec 13 14:18:44.423116 env[1113]: time="2024-12-13T14:18:44.423097974Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
Dec 13 14:18:44.423203 env[1113]: time="2024-12-13T14:18:44.423179969Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/libcontainerd/docker-containerd.sock  <nil> 0 <nil>}] <nil> <nil>}" module=grpc
Dec 13 14:18:44.423272 env[1113]: time="2024-12-13T14:18:44.423257690Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
Dec 13 14:18:44.429677 systemd[1]: var-lib-docker-check\x2doverlayfs\x2dsupport3705497156-merged.mount: Deactivated successfully.
Dec 13 14:18:44.518587 env[1113]: time="2024-12-13T14:18:44.518423326Z" level=info msg="Loading containers: start."
Dec 13 14:18:44.769188 kernel: Initializing XFRM netlink socket
Dec 13 14:18:44.848799 env[1113]: time="2024-12-13T14:18:44.848735958Z" 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"
Dec 13 14:18:44.938628 systemd-networkd[974]: docker0: Link UP
Dec 13 14:18:44.962410 env[1113]: time="2024-12-13T14:18:44.962362476Z" level=info msg="Loading containers: done."
Dec 13 14:18:44.974832 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck3766396762-merged.mount: Deactivated successfully.
Dec 13 14:18:44.991115 env[1113]: time="2024-12-13T14:18:44.990488033Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2
Dec 13 14:18:44.991115 env[1113]: time="2024-12-13T14:18:44.990728897Z" level=info msg="Docker daemon" commit=112bdf3343 graphdriver(s)=overlay2 version=20.10.23
Dec 13 14:18:44.991115 env[1113]: time="2024-12-13T14:18:44.990855496Z" level=info msg="Daemon has completed initialization"
Dec 13 14:18:45.038458 systemd[1]: Started docker.service.
Dec 13 14:18:45.045821 env[1113]: time="2024-12-13T14:18:45.045752408Z" level=info msg="API listen on /run/docker.sock"
Dec 13 14:18:45.047531 etcd-wrapper[1103]: Error response from daemon: No such container: etcd-member
Dec 13 14:18:45.097763 etcd-wrapper[1223]: Error: No such container: etcd-member
Dec 13 14:18:45.160854 etcd-wrapper[1240]: Unable to find image 'quay.io/coreos/etcd:v3.5.16' locally
Dec 13 14:18:45.764972 sshd_keygen[1078]: ssh-keygen: generating new host keys: RSA ECDSA ED25519
Dec 13 14:18:45.804519 systemd[1]: Finished sshd-keygen.service.
Dec 13 14:18:45.806659 systemd[1]: Starting issuegen.service...
Dec 13 14:18:45.808331 systemd[1]: Started sshd@0-172.24.4.30:22-172.24.4.1:47020.service.
Dec 13 14:18:45.818526 systemd[1]: issuegen.service: Deactivated successfully.
Dec 13 14:18:45.818669 systemd[1]: Finished issuegen.service.
Dec 13 14:18:45.820991 systemd[1]: Starting systemd-user-sessions.service...
Dec 13 14:18:45.831092 systemd[1]: Finished systemd-user-sessions.service.
Dec 13 14:18:45.833106 systemd[1]: Started getty@tty1.service.
Dec 13 14:18:45.834872 systemd[1]: Started serial-getty@ttyS0.service.
Dec 13 14:18:45.835574 systemd[1]: Reached target getty.target.
Dec 13 14:18:46.819955 sshd[1255]: Accepted publickey for core from 172.24.4.1 port 47020 ssh2: RSA SHA256:2ngTm68CMx36X1xnKPqUJq9w0RJJht3bhOuOq01A7tI
Dec 13 14:18:46.825145 sshd[1255]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Dec 13 14:18:46.856051 systemd[1]: Created slice user-500.slice.
Dec 13 14:18:46.861363 systemd[1]: Starting user-runtime-dir@500.service...
Dec 13 14:18:46.872143 systemd-logind[1045]: New session 1 of user core.
Dec 13 14:18:46.891252 systemd[1]: Finished user-runtime-dir@500.service.
Dec 13 14:18:46.897928 systemd[1]: Starting user@500.service...
Dec 13 14:18:46.905421 (systemd)[1263]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0)
Dec 13 14:18:47.032633 systemd[1263]: Queued start job for default target default.target.
Dec 13 14:18:47.033261 systemd[1263]: Reached target paths.target.
Dec 13 14:18:47.033284 systemd[1263]: Reached target sockets.target.
Dec 13 14:18:47.033299 systemd[1263]: Reached target timers.target.
Dec 13 14:18:47.033313 systemd[1263]: Reached target basic.target.
Dec 13 14:18:47.033430 systemd[1]: Started user@500.service.
Dec 13 14:18:47.035334 systemd[1]: Started session-1.scope.
Dec 13 14:18:47.036375 systemd[1263]: Reached target default.target.
Dec 13 14:18:47.036547 systemd[1263]: Startup finished in 118ms.
Dec 13 14:18:47.157674 etcd-wrapper[1240]: v3.5.16: Pulling from coreos/etcd
Dec 13 14:18:47.448163 systemd[1]: Started sshd@1-172.24.4.30:22-172.24.4.1:56918.service.
Dec 13 14:18:47.505275 etcd-wrapper[1240]: 804c8aba2cc6: Pulling fs layer
Dec 13 14:18:47.505275 etcd-wrapper[1240]: 2ae710cd8bfe: Pulling fs layer
Dec 13 14:18:47.505676 etcd-wrapper[1240]: d462aa345367: Pulling fs layer
Dec 13 14:18:47.505676 etcd-wrapper[1240]: 0f8b424aa0b9: Pulling fs layer
Dec 13 14:18:47.505676 etcd-wrapper[1240]: d557676654e5: Pulling fs layer
Dec 13 14:18:47.505676 etcd-wrapper[1240]: c8022d07192e: Pulling fs layer
Dec 13 14:18:47.505676 etcd-wrapper[1240]: d858cbc252ad: Pulling fs layer
Dec 13 14:18:47.505676 etcd-wrapper[1240]: 1069fc2daed1: Pulling fs layer
Dec 13 14:18:47.505676 etcd-wrapper[1240]: b40161cd83fc: Pulling fs layer
Dec 13 14:18:47.505676 etcd-wrapper[1240]: 5318d93a3a65: Pulling fs layer
Dec 13 14:18:47.505676 etcd-wrapper[1240]: 307c1adadb60: Pulling fs layer
Dec 13 14:18:47.505676 etcd-wrapper[1240]: fbb01d9e9dc9: Pulling fs layer
Dec 13 14:18:47.506796 etcd-wrapper[1240]: fbfea02ac3cf: Pulling fs layer
Dec 13 14:18:47.508275 etcd-wrapper[1240]: 8c26e4bf18e2: Pulling fs layer
Dec 13 14:18:47.508275 etcd-wrapper[1240]: 1e59a65f8816: Pulling fs layer
Dec 13 14:18:47.508275 etcd-wrapper[1240]: ffbd4ca5f0bd: Pulling fs layer
Dec 13 14:18:47.508275 etcd-wrapper[1240]: d858cbc252ad: Waiting
Dec 13 14:18:47.508781 etcd-wrapper[1240]: 1069fc2daed1: Waiting
Dec 13 14:18:47.508895 etcd-wrapper[1240]: b40161cd83fc: Waiting
Dec 13 14:18:47.510730 etcd-wrapper[1240]: 5318d93a3a65: Waiting
Dec 13 14:18:47.511583 etcd-wrapper[1240]: 307c1adadb60: Waiting
Dec 13 14:18:47.511717 etcd-wrapper[1240]: fbb01d9e9dc9: Waiting
Dec 13 14:18:47.511717 etcd-wrapper[1240]: fbfea02ac3cf: Waiting
Dec 13 14:18:47.511717 etcd-wrapper[1240]: 0f8b424aa0b9: Waiting
Dec 13 14:18:47.511912 etcd-wrapper[1240]: d557676654e5: Waiting
Dec 13 14:18:47.511912 etcd-wrapper[1240]: c8022d07192e: Waiting
Dec 13 14:18:47.511912 etcd-wrapper[1240]: 8c26e4bf18e2: Waiting
Dec 13 14:18:47.511912 etcd-wrapper[1240]: 1e59a65f8816: Waiting
Dec 13 14:18:47.511912 etcd-wrapper[1240]: ffbd4ca5f0bd: Waiting
Dec 13 14:18:47.902203 etcd-wrapper[1240]: 2ae710cd8bfe: Verifying Checksum
Dec 13 14:18:47.902203 etcd-wrapper[1240]: 2ae710cd8bfe: Download complete
Dec 13 14:18:47.912766 etcd-wrapper[1240]: d462aa345367: Verifying Checksum
Dec 13 14:18:47.912897 etcd-wrapper[1240]: d462aa345367: Download complete
Dec 13 14:18:48.136055 etcd-wrapper[1240]: 804c8aba2cc6: Verifying Checksum
Dec 13 14:18:48.136205 etcd-wrapper[1240]: 804c8aba2cc6: Download complete
Dec 13 14:18:48.234021 etcd-wrapper[1240]: 0f8b424aa0b9: Verifying Checksum
Dec 13 14:18:48.234021 etcd-wrapper[1240]: 0f8b424aa0b9: Download complete
Dec 13 14:18:48.271139 etcd-wrapper[1240]: 804c8aba2cc6: Pull complete
Dec 13 14:18:48.371504 systemd[1]: var-lib-docker-overlay2-5cea1f674e015bdc0bf47da8339da9e1913f66a1def96bdfbf5e52836d299946-merged.mount: Deactivated successfully.
Dec 13 14:18:48.420903 etcd-wrapper[1240]: 2ae710cd8bfe: Pull complete
Dec 13 14:18:48.515499 sshd[1272]: Accepted publickey for core from 172.24.4.1 port 56918 ssh2: RSA SHA256:2ngTm68CMx36X1xnKPqUJq9w0RJJht3bhOuOq01A7tI
Dec 13 14:18:48.518373 sshd[1272]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Dec 13 14:18:48.524386 systemd-logind[1045]: New session 2 of user core.
Dec 13 14:18:48.527037 systemd[1]: Started session-2.scope.
Dec 13 14:18:48.530546 etcd-wrapper[1240]: d557676654e5: Verifying Checksum
Dec 13 14:18:48.530546 etcd-wrapper[1240]: d557676654e5: Download complete
Dec 13 14:18:48.808264 etcd-wrapper[1240]: d858cbc252ad: Verifying Checksum
Dec 13 14:18:48.808482 etcd-wrapper[1240]: d858cbc252ad: Download complete
Dec 13 14:18:48.810056 etcd-wrapper[1240]: c8022d07192e: Verifying Checksum
Dec 13 14:18:48.810307 etcd-wrapper[1240]: c8022d07192e: Download complete
Dec 13 14:18:48.866531 systemd[1]: var-lib-docker-overlay2-d34f4bf9ddf5e373b5d4b566c57fa64ebcd910f1c7b735b8cf2f8075ddba708f-merged.mount: Deactivated successfully.
Dec 13 14:18:48.987530 etcd-wrapper[1240]: 1069fc2daed1: Verifying Checksum
Dec 13 14:18:48.987530 etcd-wrapper[1240]: 1069fc2daed1: Download complete
Dec 13 14:18:49.045554 etcd-wrapper[1240]: d462aa345367: Pull complete
Dec 13 14:18:49.063631 sshd[1272]: pam_unix(sshd:session): session closed for user core
Dec 13 14:18:49.070635 systemd[1]: Started sshd@2-172.24.4.30:22-172.24.4.1:56920.service.
Dec 13 14:18:49.075376 systemd[1]: sshd@1-172.24.4.30:22-172.24.4.1:56918.service: Deactivated successfully.
Dec 13 14:18:49.076499 systemd[1]: session-2.scope: Deactivated successfully.
Dec 13 14:18:49.079014 systemd-logind[1045]: Session 2 logged out. Waiting for processes to exit.
Dec 13 14:18:49.080497 systemd-logind[1045]: Removed session 2.
Dec 13 14:18:49.162451 etcd-wrapper[1240]: b40161cd83fc: Verifying Checksum
Dec 13 14:18:49.167147 etcd-wrapper[1240]: b40161cd83fc: Download complete
Dec 13 14:18:49.187392 etcd-wrapper[1240]: 0f8b424aa0b9: Pull complete
Dec 13 14:18:49.288356 etcd-wrapper[1240]: d557676654e5: Pull complete
Dec 13 14:18:49.292868 systemd[1]: var-lib-docker-overlay2-035514644a8525653b0688cd44c753960e8990c594af70126ff25959b11f6dfd-merged.mount: Deactivated successfully.
Dec 13 14:18:49.296071 etcd-wrapper[1240]: 5318d93a3a65: Verifying Checksum
Dec 13 14:18:49.296071 etcd-wrapper[1240]: 5318d93a3a65: Download complete
Dec 13 14:18:49.377311 systemd[1]: var-lib-docker-overlay2-55252d69322dd2f8b665b95ba801f96c6e1f78a9a462b2384f7ee774e8992105-merged.mount: Deactivated successfully.
Dec 13 14:18:49.496909 etcd-wrapper[1240]: 307c1adadb60: Download complete
Dec 13 14:18:49.697864 etcd-wrapper[1240]: fbb01d9e9dc9: Verifying Checksum
Dec 13 14:18:49.697864 etcd-wrapper[1240]: fbb01d9e9dc9: Download complete
Dec 13 14:18:49.814195 etcd-wrapper[1240]: c8022d07192e: Pull complete
Dec 13 14:18:49.860501 etcd-wrapper[1240]: fbfea02ac3cf: Verifying Checksum
Dec 13 14:18:49.860501 etcd-wrapper[1240]: fbfea02ac3cf: Download complete
Dec 13 14:18:49.938915 systemd[1]: var-lib-docker-overlay2-15d2667d904c9ab8941c074d2c4bffeaf2025a6ed13398924b2290670b9837ed-merged.mount: Deactivated successfully.
Dec 13 14:18:50.141547 sshd[1299]: Accepted publickey for core from 172.24.4.1 port 56920 ssh2: RSA SHA256:2ngTm68CMx36X1xnKPqUJq9w0RJJht3bhOuOq01A7tI
Dec 13 14:18:50.141957 etcd-wrapper[1240]: 1e59a65f8816: Verifying Checksum
Dec 13 14:18:50.141957 etcd-wrapper[1240]: 1e59a65f8816: Download complete
Dec 13 14:18:50.108165 systemd[1]: Started session-3.scope.
Dec 13 14:18:50.101481 sshd[1299]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Dec 13 14:18:50.109284 systemd-logind[1045]: New session 3 of user core.
Dec 13 14:18:50.192853 etcd-wrapper[1240]: ffbd4ca5f0bd: Download complete
Dec 13 14:18:50.223790 etcd-wrapper[1240]: d858cbc252ad: Pull complete
Dec 13 14:18:50.341415 systemd[1]: var-lib-docker-overlay2-66d5657cee62d59f60019508db512bcc91572dbfd343c6cd0b949f4f13d62a69-merged.mount: Deactivated successfully.
Dec 13 14:18:50.385537 etcd-wrapper[1240]: 1069fc2daed1: Pull complete
Dec 13 14:18:50.470337 systemd[1]: var-lib-docker-overlay2-c6afaf5dc752f528b4163cd75787be9bf7b345db52adf4c3ae858e51273ad713-merged.mount: Deactivated successfully.
Dec 13 14:18:50.574553 etcd-wrapper[1240]: 8c26e4bf18e2: Verifying Checksum
Dec 13 14:18:50.575084 etcd-wrapper[1240]: 8c26e4bf18e2: Download complete
Dec 13 14:18:50.601283 sshd[1299]: pam_unix(sshd:session): session closed for user core
Dec 13 14:18:50.608990 systemd[1]: sshd@2-172.24.4.30:22-172.24.4.1:56920.service: Deactivated successfully.
Dec 13 14:18:50.611357 systemd[1]: session-3.scope: Deactivated successfully.
Dec 13 14:18:50.614119 systemd-logind[1045]: Session 3 logged out. Waiting for processes to exit.
Dec 13 14:18:50.617009 systemd-logind[1045]: Removed session 3.
Dec 13 14:18:50.638292 etcd-wrapper[1240]: b40161cd83fc: Pull complete
Dec 13 14:18:50.747293 etcd-wrapper[1240]: 5318d93a3a65: Pull complete
Dec 13 14:18:50.840540 systemd[1]: getty@tty1.service: Deactivated successfully.
Dec 13 14:18:50.874346 systemd[1]: getty@tty1.service: Scheduled restart job, restart counter is at 1.
Dec 13 14:18:50.874560 systemd[1]: Stopped getty@tty1.service.
Dec 13 14:18:50.876471 systemd[1]: Started getty@tty1.service.
Dec 13 14:18:51.294954 systemd[1]: var-lib-docker-overlay2-c069c8709fb26a40fded4d7275c1a136fbf825bf5b35688115c60276d66e9676-merged.mount: Deactivated successfully.
Dec 13 14:18:51.632397 etcd-wrapper[1240]: 307c1adadb60: Pull complete
Dec 13 14:18:52.225705 systemd[1]: var-lib-docker-overlay2-260100288d4164d7cac3015a25d04aad11d5307232f35c7c72953919a779362a-merged.mount: Deactivated successfully.
Dec 13 14:18:52.596453 etcd-wrapper[1240]: fbb01d9e9dc9: Pull complete
Dec 13 14:18:53.226612 systemd[1]: var-lib-docker-overlay2-a7be4493ef33a63c0678632066dce6257d4e5f4a7519f8b1417f3d2473bfd5d7-merged.mount: Deactivated successfully.
Dec 13 14:18:53.548484 etcd-wrapper[1240]: fbfea02ac3cf: Pull complete
Dec 13 14:18:54.105017 systemd[1]: var-lib-docker-overlay2-0035e1497a52a9564e768947664196d2aeb88f6dc0fb8a16e14b4c365303bca1-merged.mount: Deactivated successfully.
Dec 13 14:18:54.238423 etcd-wrapper[1240]: 8c26e4bf18e2: Pull complete
Dec 13 14:18:54.337060 systemd[1]: var-lib-docker-overlay2-80e73e9bd37eacf1196556bd6ec5849986fc0c74b296992492c763335c1cb301-merged.mount: Deactivated successfully.
Dec 13 14:18:54.413496 etcd-wrapper[1240]: 1e59a65f8816: Pull complete
Dec 13 14:18:54.525112 systemd[1]: var-lib-docker-overlay2-2b2adcf8d8a5e510c0792c72ec478e70d8aacafab43b0a62e7ec6f232c6dc3e2-merged.mount: Deactivated successfully.
Dec 13 14:18:54.550033 etcd-wrapper[1240]: ffbd4ca5f0bd: Pull complete
Dec 13 14:18:54.566048 etcd-wrapper[1240]: Digest: sha256:d967d98a12dc220a1a290794711dba7eba04b8ce465e12b02383d1bfbb33e159
Dec 13 14:18:54.572844 etcd-wrapper[1240]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.16
Dec 13 14:18:54.888058 env[1049]: time="2024-12-13T14:18:54.885704419Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Dec 13 14:18:54.889106 env[1049]: time="2024-12-13T14:18:54.885811557Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Dec 13 14:18:54.889106 env[1049]: time="2024-12-13T14:18:54.885845993Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Dec 13 14:18:54.889106 env[1049]: time="2024-12-13T14:18:54.886206884Z" level=info msg="starting signal loop" namespace=moby path=/run/containerd/io.containerd.runtime.v2.task/moby/deefbac6c863ed936864bad29ba2bab601dc80ede60b7bafc2f7460279ec6f53 pid=1396 runtime=io.containerd.runc.v2
Dec 13 14:18:54.924346 systemd[1]: Started docker-deefbac6c863ed936864bad29ba2bab601dc80ede60b7bafc2f7460279ec6f53.scope.
Dec 13 14:18:55.006043 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.004979Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"}
Dec 13 14:18:55.006043 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.005172Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"871eb79b86204ac5a6c1e2d684c398ce"}
Dec 13 14:18:55.006043 etcd-wrapper[1240]: {"level":"warn","ts":"2024-12-13T14:18:55.005218Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"}
Dec 13 14:18:55.006043 etcd-wrapper[1240]: {"level":"warn","ts":"2024-12-13T14:18:55.005239Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.16"}
Dec 13 14:18:55.006043 etcd-wrapper[1240]: {"level":"warn","ts":"2024-12-13T14:18:55.005258Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"}
Dec 13 14:18:55.006043 etcd-wrapper[1240]: {"level":"warn","ts":"2024-12-13T14:18:55.005276Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"}
Dec 13 14:18:55.006710 etcd-wrapper[1240]: {"level":"warn","ts":"2024-12-13T14:18:55.006430Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."}
Dec 13 14:18:55.006710 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.006494Z","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.30:2379"]}
Dec 13 14:18:55.007145 etcd-wrapper[1240]: {"level":"warn","ts":"2024-12-13T14:18:55.006809Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."}
Dec 13 14:18:55.007145 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.006854Z","caller":"embed/etcd.go:128","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]}
Dec 13 14:18:55.008652 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.008361Z","caller":"embed/etcd.go:136","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]}
Dec 13 14:18:55.009348 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.008969Z","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":"871eb79b86204ac5a6c1e2d684c398ce","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.30:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"871eb79b86204ac5a6c1e2d684c398ce=http://localhost:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":false,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"}
Dec 13 14:18:55.015101 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.014736Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.925466ms"}
Dec 13 14:18:55.024637 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.024285Z","caller":"etcdserver/raft.go:505","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"}
Dec 13 14:18:55.024984 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.024682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"}
Dec 13 14:18:55.025271 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.025005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"}
Dec 13 14:18:55.025271 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.025053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
Dec 13 14:18:55.025604 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.025073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"}
Dec 13 14:18:55.025604 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.025369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"}
Dec 13 14:18:55.035061 etcd-wrapper[1240]: {"level":"warn","ts":"2024-12-13T14:18:55.034671Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
Dec 13 14:18:55.036783 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.036564Z","caller":"mvcc/kvstore.go:423","msg":"kvstore restored","current-rev":1}
Dec 13 14:18:55.038524 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.038348Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"}
Dec 13 14:18:55.040116 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.039853Z","caller":"etcdserver/server.go:873","msg":"starting etcd server","local-member-id":"8e9e05c52164694d","local-server-version":"3.5.16","cluster-version":"to_be_decided"}
Dec 13 14:18:55.040736 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.040566Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"}
Dec 13 14:18:55.041048 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.040851Z","caller":"etcdserver/server.go:757","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8e9e05c52164694d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
Dec 13 14:18:55.042554 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.042427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"}
Dec 13 14:18:55.042891 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.042737Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","added-peer-id":"8e9e05c52164694d","added-peer-peer-urls":["http://localhost:2380"]}
Dec 13 14:18:55.043119 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.042126Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"}
Dec 13 14:18:55.043357 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.043207Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"}
Dec 13 14:18:55.043357 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.043248Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"}
Dec 13 14:18:55.047006 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.046764Z","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.30:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]}
Dec 13 14:18:55.047810 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.047627Z","caller":"embed/etcd.go:600","msg":"serving peer traffic","address":"127.0.0.1:2380"}
Dec 13 14:18:55.047810 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.047672Z","caller":"embed/etcd.go:572","msg":"cmux::serve","address":"127.0.0.1:2380"}
Dec 13 14:18:55.432035 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.426353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"}
Dec 13 14:18:55.432035 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.426432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"}
Dec 13 14:18:55.432035 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.426513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"}
Dec 13 14:18:55.432035 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.426541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"}
Dec 13 14:18:55.432035 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.426556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"}
Dec 13 14:18:55.433018 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.426649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"}
Dec 13 14:18:55.433018 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.426675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"}
Dec 13 14:18:55.433018 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.427963Z","caller":"etcdserver/server.go:2651","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
Dec 13 14:18:55.433018 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.429613Z","caller":"etcdserver/server.go:2140","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:871eb79b86204ac5a6c1e2d684c398ce ClientURLs:[http://172.24.4.30:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"}
Dec 13 14:18:55.433018 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.430679Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"}
Dec 13 14:18:55.433018 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.430887Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
Dec 13 14:18:55.433018 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.430980Z","caller":"etcdserver/server.go:2675","msg":"cluster version is updated","cluster-version":"3.5"}
Dec 13 14:18:55.433018 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.431052Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
Dec 13 14:18:55.433018 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.432343Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"}
Dec 13 14:18:55.434277 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.433394Z","caller":"embed/serve.go:187","msg":"serving client traffic insecurely; this is strongly discouraged!","traffic":"grpc+http","address":"[::]:2379"}
Dec 13 14:18:55.435162 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.434632Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
Dec 13 14:18:55.436025 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:18:55.435514Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
Dec 13 14:18:55.436028 systemd[1]: Started etcd-member.service.
Dec 13 14:18:55.437694 systemd[1]: Reached target multi-user.target.
Dec 13 14:18:55.442680 systemd[1]: Starting systemd-update-utmp-runlevel.service...
Dec 13 14:18:55.463439 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully.
Dec 13 14:18:55.463902 systemd[1]: Finished systemd-update-utmp-runlevel.service.
Dec 13 14:18:55.465249 systemd[1]: Startup finished in 923ms (kernel) + 4.538s (initrd) + 19.247s (userspace) = 24.710s.
Dec 13 14:19:00.685678 systemd[1]: Started sshd@3-172.24.4.30:22-172.24.4.1:36358.service.
Dec 13 14:19:02.022318 sshd[1439]: Accepted publickey for core from 172.24.4.1 port 36358 ssh2: RSA SHA256:2ngTm68CMx36X1xnKPqUJq9w0RJJht3bhOuOq01A7tI
Dec 13 14:19:02.024439 sshd[1439]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Dec 13 14:19:02.030716 systemd-logind[1045]: New session 4 of user core.
Dec 13 14:19:02.031524 systemd[1]: Started session-4.scope.
Dec 13 14:19:02.562599 sshd[1439]: pam_unix(sshd:session): session closed for user core
Dec 13 14:19:02.570113 systemd[1]: sshd@3-172.24.4.30:22-172.24.4.1:36358.service: Deactivated successfully.
Dec 13 14:19:02.572297 systemd[1]: session-4.scope: Deactivated successfully.
Dec 13 14:19:02.574096 systemd-logind[1045]: Session 4 logged out. Waiting for processes to exit.
Dec 13 14:19:02.578575 systemd[1]: Started sshd@4-172.24.4.30:22-172.24.4.1:36372.service.
Dec 13 14:19:02.582490 systemd-logind[1045]: Removed session 4.
Dec 13 14:19:03.544841 sshd[1445]: Accepted publickey for core from 172.24.4.1 port 36372 ssh2: RSA SHA256:2ngTm68CMx36X1xnKPqUJq9w0RJJht3bhOuOq01A7tI
Dec 13 14:19:03.547626 sshd[1445]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Dec 13 14:19:03.559384 systemd[1]: Started session-5.scope.
Dec 13 14:19:03.561085 systemd-logind[1045]: New session 5 of user core.
Dec 13 14:19:04.122559 sshd[1445]: pam_unix(sshd:session): session closed for user core
Dec 13 14:19:04.127498 systemd[1]: sshd@4-172.24.4.30:22-172.24.4.1:36372.service: Deactivated successfully.
Dec 13 14:19:04.128813 systemd[1]: session-5.scope: Deactivated successfully.
Dec 13 14:19:04.130183 systemd-logind[1045]: Session 5 logged out. Waiting for processes to exit.
Dec 13 14:19:04.132995 systemd[1]: Started sshd@5-172.24.4.30:22-172.24.4.1:36376.service.
Dec 13 14:19:04.140332 systemd-logind[1045]: Removed session 5.
Dec 13 14:19:05.404257 sshd[1451]: Accepted publickey for core from 172.24.4.1 port 36376 ssh2: RSA SHA256:2ngTm68CMx36X1xnKPqUJq9w0RJJht3bhOuOq01A7tI
Dec 13 14:19:05.406778 sshd[1451]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Dec 13 14:19:05.417705 systemd-logind[1045]: New session 6 of user core.
Dec 13 14:19:05.418504 systemd[1]: Started session-6.scope.
Dec 13 14:19:06.190295 sshd[1451]: pam_unix(sshd:session): session closed for user core
Dec 13 14:19:06.198058 systemd[1]: sshd@5-172.24.4.30:22-172.24.4.1:36376.service: Deactivated successfully.
Dec 13 14:19:06.199827 systemd[1]: session-6.scope: Deactivated successfully.
Dec 13 14:19:06.201508 systemd-logind[1045]: Session 6 logged out. Waiting for processes to exit.
Dec 13 14:19:06.205249 systemd[1]: Started sshd@6-172.24.4.30:22-172.24.4.1:42660.service.
Dec 13 14:19:06.207145 systemd-logind[1045]: Removed session 6.
Dec 13 14:19:07.699744 sshd[1457]: Accepted publickey for core from 172.24.4.1 port 42660 ssh2: RSA SHA256:2ngTm68CMx36X1xnKPqUJq9w0RJJht3bhOuOq01A7tI
Dec 13 14:19:07.702393 sshd[1457]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Dec 13 14:19:07.712031 systemd-logind[1045]: New session 7 of user core.
Dec 13 14:19:07.713725 systemd[1]: Started session-7.scope.
Dec 13 14:19:08.345275 sshd[1457]: pam_unix(sshd:session): session closed for user core
Dec 13 14:19:08.353284 systemd[1]: Started sshd@7-172.24.4.30:22-172.24.4.1:42676.service.
Dec 13 14:19:08.354694 systemd[1]: sshd@6-172.24.4.30:22-172.24.4.1:42660.service: Deactivated successfully.
Dec 13 14:19:08.356364 systemd[1]: session-7.scope: Deactivated successfully.
Dec 13 14:19:08.360294 systemd-logind[1045]: Session 7 logged out. Waiting for processes to exit.
Dec 13 14:19:08.362822 systemd-logind[1045]: Removed session 7.
Dec 13 14:19:09.713883 sshd[1464]: Accepted publickey for core from 172.24.4.1 port 42676 ssh2: RSA SHA256:2ngTm68CMx36X1xnKPqUJq9w0RJJht3bhOuOq01A7tI
Dec 13 14:19:09.715790 sshd[1464]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Dec 13 14:19:09.727384 systemd-logind[1045]: New session 8 of user core.
Dec 13 14:19:09.728363 systemd[1]: Started session-8.scope.
Dec 13 14:19:10.641081 sshd[1464]: pam_unix(sshd:session): session closed for user core
Dec 13 14:19:10.647342 systemd-logind[1045]: Session 8 logged out. Waiting for processes to exit.
Dec 13 14:19:10.648565 systemd[1]: sshd@7-172.24.4.30:22-172.24.4.1:42676.service: Deactivated successfully.
Dec 13 14:19:10.650127 systemd[1]: session-8.scope: Deactivated successfully.
Dec 13 14:19:10.651667 systemd-logind[1045]: Removed session 8.
Dec 13 14:19:28.775020 update_engine[1046]: I1213 14:19:28.773959  1046 update_attempter.cc:509] Updating boot flags...
Dec 13 14:21:15.607524 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:21:15.605799Z","caller":"traceutil/trace.go:171","msg":"trace[607303363] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"361.670283ms","start":"2024-12-13T14:21:15.244093Z","end":"2024-12-13T14:21:15.605763Z","steps":["trace[607303363] 'process raft request'  (duration: 263.996743ms)","trace[607303363] 'compare'  (duration: 97.561536ms)"],"step_count":2}
Dec 13 14:21:15.610206 etcd-wrapper[1240]: {"level":"warn","ts":"2024-12-13T14:21:15.608848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-12-13T14:21:15.244069Z","time spent":"363.240522ms","remote":"172.24.4.143:51374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4052,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/minions/ci-3510-3-6-7-32bd0a2680.novalocal\" mod_revision:410 > success:<request_put:<key:\"/registry/minions/ci-3510-3-6-7-32bd0a2680.novalocal\" value_size:3992 >> failure:<request_range:<key:\"/registry/minions/ci-3510-3-6-7-32bd0a2680.novalocal\" > >"}
Dec 13 14:21:15.865997 etcd-wrapper[1240]: {"level":"warn","ts":"2024-12-13T14:21:15.863867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.926056ms","expected-duration":"100ms","prefix":"","request":"header:<ID:7587883401693410497 > txn:<compare:<target:MOD key:\"/registry/pods/kube-system/coredns-7db6d8ff4d-rtp5g\" mod_revision:369 > success:<request_put:<key:\"/registry/pods/kube-system/coredns-7db6d8ff4d-rtp5g\" value_size:3765 >> failure:<request_range:<key:\"/registry/pods/kube-system/coredns-7db6d8ff4d-rtp5g\" > >>","response":"size:16"}
Dec 13 14:21:15.865997 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:21:15.864186Z","caller":"traceutil/trace.go:171","msg":"trace[1338585648] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"248.507582ms","start":"2024-12-13T14:21:15.615651Z","end":"2024-12-13T14:21:15.864159Z","steps":["trace[1338585648] 'process raft request'  (duration: 100.037278ms)","trace[1338585648] 'compare'  (duration: 147.775457ms)"],"step_count":2}
Dec 13 14:21:15.880017 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:21:15.879405Z","caller":"traceutil/trace.go:171","msg":"trace[1178060716] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"193.002946ms","start":"2024-12-13T14:21:15.686375Z","end":"2024-12-13T14:21:15.879378Z","steps":["trace[1178060716] 'process raft request'  (duration: 192.946067ms)"],"step_count":1}
Dec 13 14:21:15.880450 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:21:15.879822Z","caller":"traceutil/trace.go:171","msg":"trace[114648675] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:429; }","duration":"151.789534ms","start":"2024-12-13T14:21:15.727660Z","end":"2024-12-13T14:21:15.879449Z","steps":["trace[114648675] 'read index received'  (duration: 151.779195ms)","trace[114648675] 'applied index is now lower than readState.Index'  (duration: 8.406µs)"],"step_count":2}
Dec 13 14:21:15.881032 etcd-wrapper[1240]: {"level":"warn","ts":"2024-12-13T14:21:15.880623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.938608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-flannel/kube-flannel-ds-s7htg\" ","response":"range_response_count:1 size:7148"}
Dec 13 14:21:15.881450 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:21:15.881071Z","caller":"traceutil/trace.go:171","msg":"trace[1796383081] range","detail":"{range_begin:/registry/pods/kube-flannel/kube-flannel-ds-s7htg; range_end:; response_count:1; response_revision:415; }","duration":"153.387585ms","start":"2024-12-13T14:21:15.727579Z","end":"2024-12-13T14:21:15.880966Z","steps":["trace[1796383081] 'agreement among raft nodes before linearized reading'  (duration: 152.900466ms)"],"step_count":1}
Dec 13 14:21:15.881978 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:21:15.880636Z","caller":"traceutil/trace.go:171","msg":"trace[1173822427] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"264.563586ms","start":"2024-12-13T14:21:15.616049Z","end":"2024-12-13T14:21:15.880612Z","steps":["trace[1173822427] 'process raft request'  (duration: 263.14156ms)"],"step_count":1}
Dec 13 14:21:16.265683 etcd-wrapper[1240]: {"level":"warn","ts":"2024-12-13T14:21:16.264981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.160666ms","expected-duration":"100ms","prefix":"","request":"header:<ID:7587883401693410500 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:406 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:439 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >>","response":"size:16"}
Dec 13 14:21:16.266412 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:21:16.265304Z","caller":"traceutil/trace.go:171","msg":"trace[250075150] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"182.116365ms","start":"2024-12-13T14:21:16.083166Z","end":"2024-12-13T14:21:16.265283Z","steps":["trace[250075150] 'process raft request'  (duration: 182.065167ms)"],"step_count":1}
Dec 13 14:21:16.266891 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:21:16.266507Z","caller":"traceutil/trace.go:171","msg":"trace[358358261] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"395.244259ms","start":"2024-12-13T14:21:15.871198Z","end":"2024-12-13T14:21:16.266442Z","steps":["trace[358358261] 'process raft request'  (duration: 393.893148ms)"],"step_count":1}
Dec 13 14:21:16.267304 etcd-wrapper[1240]: {"level":"warn","ts":"2024-12-13T14:21:16.266994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-12-13T14:21:15.871168Z","time spent":"395.661395ms","remote":"172.24.4.143:51278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":745,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/events/kube-system/coredns-7db6d8ff4d-rtp5g.1810c277b11826bb\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-system/coredns-7db6d8ff4d-rtp5g.1810c277b11826bb\" value_size:657 lease:7587883401693410082 >> failure:<>"}
Dec 13 14:21:16.268056 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:21:16.267490Z","caller":"traceutil/trace.go:171","msg":"trace[1527834157] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"413.925808ms","start":"2024-12-13T14:21:15.853544Z","end":"2024-12-13T14:21:16.267470Z","steps":["trace[1527834157] 'process raft request'  (duration: 116.152794ms)","trace[1527834157] 'compare'  (duration: 295.014395ms)"],"step_count":2}
Dec 13 14:21:16.268454 etcd-wrapper[1240]: {"level":"warn","ts":"2024-12-13T14:21:16.267824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-12-13T14:21:15.853506Z","time spent":"414.216213ms","remote":"172.24.4.143:51432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:406 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:439 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
Dec 13 14:21:16.268851 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:21:16.268382Z","caller":"traceutil/trace.go:171","msg":"trace[1002142603] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"370.693742ms","start":"2024-12-13T14:21:15.897668Z","end":"2024-12-13T14:21:16.268362Z","steps":["trace[1002142603] 'process raft request'  (duration: 367.503599ms)"],"step_count":1}
Dec 13 14:21:16.269382 etcd-wrapper[1240]: {"level":"warn","ts":"2024-12-13T14:21:16.268892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-12-13T14:21:15.897626Z","time spent":"370.979258ms","remote":"172.24.4.143:51386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7299,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/pods/kube-flannel/kube-flannel-ds-s7htg\" mod_revision:401 > success:<request_put:<key:\"/registry/pods/kube-flannel/kube-flannel-ds-s7htg\" value_size:7242 >> failure:<request_range:<key:\"/registry/pods/kube-flannel/kube-flannel-ds-s7htg\" > >"}
Dec 13 14:21:16.611171 etcd-wrapper[1240]: {"level":"warn","ts":"2024-12-13T14:21:16.607898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.270599ms","expected-duration":"100ms","prefix":"","request":"header:<ID:7587883401693410507 > txn:<compare:<target:MOD key:\"/registry/pods/kube-system/coredns-7db6d8ff4d-rtp5g\" mod_revision:413 > success:<request_put:<key:\"/registry/pods/kube-system/coredns-7db6d8ff4d-rtp5g\" value_size:4858 >> failure:<request_range:<key:\"/registry/pods/kube-system/coredns-7db6d8ff4d-rtp5g\" > >>","response":"size:16"}
Dec 13 14:21:16.611171 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:21:16.608495Z","caller":"traceutil/trace.go:171","msg":"trace[32704756] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:434; }","duration":"135.949465ms","start":"2024-12-13T14:21:16.472438Z","end":"2024-12-13T14:21:16.608388Z","steps":["trace[32704756] 'read index received'  (duration: 65.685µs)","trace[32704756] 'applied index is now lower than readState.Index'  (duration: 135.881055ms)"],"step_count":2}
Dec 13 14:21:16.611171 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:21:16.608756Z","caller":"traceutil/trace.go:171","msg":"trace[1008750007] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"313.075329ms","start":"2024-12-13T14:21:16.295660Z","end":"2024-12-13T14:21:16.608735Z","steps":["trace[1008750007] 'process raft request'  (duration: 166.870505ms)","trace[1008750007] 'compare'  (duration: 145.096177ms)"],"step_count":2}
Dec 13 14:21:16.612577 etcd-wrapper[1240]: {"level":"warn","ts":"2024-12-13T14:21:16.609023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-12-13T14:21:16.295626Z","time spent":"313.236586ms","remote":"172.24.4.143:51386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4917,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/coredns-7db6d8ff4d-rtp5g\" mod_revision:413 > success:<request_put:<key:\"/registry/pods/kube-system/coredns-7db6d8ff4d-rtp5g\" value_size:4858 >> failure:<request_range:<key:\"/registry/pods/kube-system/coredns-7db6d8ff4d-rtp5g\" > >"}
Dec 13 14:21:16.612577 etcd-wrapper[1240]: {"level":"warn","ts":"2024-12-13T14:21:16.609309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.860997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"}
Dec 13 14:21:16.612577 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:21:16.609462Z","caller":"traceutil/trace.go:171","msg":"trace[1935312743] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:421; }","duration":"137.06133ms","start":"2024-12-13T14:21:16.472383Z","end":"2024-12-13T14:21:16.609445Z","steps":["trace[1935312743] 'agreement among raft nodes before linearized reading'  (duration: 136.864253ms)"],"step_count":1}
Dec 13 14:21:16.612577 etcd-wrapper[1240]: {"level":"warn","ts":"2024-12-13T14:21:16.609666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.672067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"}
Dec 13 14:21:16.613164 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:21:16.609857Z","caller":"traceutil/trace.go:171","msg":"trace[1191182921] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:421; }","duration":"136.859664ms","start":"2024-12-13T14:21:16.472980Z","end":"2024-12-13T14:21:16.609840Z","steps":["trace[1191182921] 'agreement among raft nodes before linearized reading'  (duration: 136.647229ms)"],"step_count":1}
Dec 13 14:21:16.966137 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:21:16.965438Z","caller":"traceutil/trace.go:171","msg":"trace[1739303247] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"145.562957ms","start":"2024-12-13T14:21:16.819838Z","end":"2024-12-13T14:21:16.965401Z","steps":["trace[1739303247] 'process raft request'  (duration: 106.168883ms)","trace[1739303247] 'compare'  (duration: 39.19824ms)"],"step_count":2}
Dec 13 14:21:21.098868 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:21:21.098303Z","caller":"traceutil/trace.go:171","msg":"trace[27325970] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"116.989821ms","start":"2024-12-13T14:21:20.981284Z","end":"2024-12-13T14:21:21.098274Z","steps":["trace[27325970] 'process raft request'  (duration: 116.843692ms)"],"step_count":1}
Dec 13 14:21:21.776255 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:21:21.775704Z","caller":"traceutil/trace.go:171","msg":"trace[1970650983] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"289.574345ms","start":"2024-12-13T14:21:21.486101Z","end":"2024-12-13T14:21:21.775675Z","steps":["trace[1970650983] 'process raft request'  (duration: 289.421904ms)"],"step_count":1}
Dec 13 14:21:21.795523 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:21:21.795115Z","caller":"traceutil/trace.go:171","msg":"trace[835194105] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"159.62042ms","start":"2024-12-13T14:21:21.635470Z","end":"2024-12-13T14:21:21.795090Z","steps":["trace[835194105] 'process raft request'  (duration: 159.48918ms)"],"step_count":1}
Dec 13 14:22:10.405632 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:22:10.403612Z","caller":"traceutil/trace.go:171","msg":"trace[247688540] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"162.888271ms","start":"2024-12-13T14:22:10.240642Z","end":"2024-12-13T14:22:10.403531Z","steps":["trace[247688540] 'process raft request'  (duration: 122.135955ms)","trace[247688540] 'compare'  (duration: 40.171364ms)"],"step_count":2}
Dec 13 14:22:12.582363 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:22:12.581563Z","caller":"traceutil/trace.go:171","msg":"trace[1004154546] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"352.468411ms","start":"2024-12-13T14:22:12.229060Z","end":"2024-12-13T14:22:12.581529Z","steps":["trace[1004154546] 'process raft request'  (duration: 352.304478ms)"],"step_count":1}
Dec 13 14:22:12.583676 etcd-wrapper[1240]: {"level":"warn","ts":"2024-12-13T14:22:12.582499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-12-13T14:22:12.229032Z","time spent":"352.631894ms","remote":"172.24.4.143:51432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:576 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:456 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
Dec 13 14:22:12.590377 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:22:12.590007Z","caller":"traceutil/trace.go:171","msg":"trace[1722288379] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"348.286555ms","start":"2024-12-13T14:22:12.241676Z","end":"2024-12-13T14:22:12.589963Z","steps":["trace[1722288379] 'process raft request'  (duration: 348.056084ms)"],"step_count":1}
Dec 13 14:22:12.591005 etcd-wrapper[1240]: {"level":"warn","ts":"2024-12-13T14:22:12.590653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-12-13T14:22:12.241638Z","time spent":"348.474906ms","remote":"172.24.4.143:51386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7243,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/pods/kube-flannel/kube-flannel-ds-hwrpj\" mod_revision:580 > success:<request_put:<key:\"/registry/pods/kube-flannel/kube-flannel-ds-hwrpj\" value_size:7186 >> failure:<request_range:<key:\"/registry/pods/kube-flannel/kube-flannel-ds-hwrpj\" > >"}
Dec 13 14:22:57.001329 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:22:56.999667Z","caller":"traceutil/trace.go:171","msg":"trace[840081277] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"116.192479ms","start":"2024-12-13T14:22:56.883433Z","end":"2024-12-13T14:22:56.999626Z","steps":["trace[840081277] 'process raft request'  (duration: 115.939058ms)"],"step_count":1}
Dec 13 14:23:03.382046 etcd-wrapper[1240]: {"level":"info","ts":"2024-12-13T14:23:03.381423Z","caller":"traceutil/trace.go:171","msg":"trace[1911996702] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"126.563162ms","start":"2024-12-13T14:23:03.254811Z","end":"2024-12-13T14:23:03.381374Z","steps":["trace[1911996702] 'process raft request'  (duration: 83.318824ms)","trace[1911996702] 'compare'  (duration: 43.060426ms)"],"step_count":2}