Apr 12 18:45:16.818939 kernel: Linux version 5.15.154-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 Apr 12 17:19:00 -00 2024
Apr 12 18:45:16.818959 kernel: Command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected verity.usrhash=189121f7c8c0a24098d3bb1e040d34611f7c276be43815ff7fe409fce185edaf
Apr 12 18:45:16.818980 kernel: BIOS-provided physical RAM map:
Apr 12 18:45:16.818987 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable
Apr 12 18:45:16.818993 kernel: BIOS-e820: [mem 0x0000000000100000-0x00000000007fffff] usable
Apr 12 18:45:16.818999 kernel: BIOS-e820: [mem 0x0000000000800000-0x0000000000807fff] ACPI NVS
Apr 12 18:45:16.819007 kernel: BIOS-e820: [mem 0x0000000000808000-0x000000000080afff] usable
Apr 12 18:45:16.819014 kernel: BIOS-e820: [mem 0x000000000080b000-0x000000000080bfff] ACPI NVS
Apr 12 18:45:16.819021 kernel: BIOS-e820: [mem 0x000000000080c000-0x000000000080ffff] usable
Apr 12 18:45:16.819029 kernel: BIOS-e820: [mem 0x0000000000810000-0x00000000008fffff] ACPI NVS
Apr 12 18:45:16.819035 kernel: BIOS-e820: [mem 0x0000000000900000-0x000000009c8eefff] usable
Apr 12 18:45:16.819042 kernel: BIOS-e820: [mem 0x000000009c8ef000-0x000000009cb6efff] reserved
Apr 12 18:45:16.819049 kernel: BIOS-e820: [mem 0x000000009cb6f000-0x000000009cb7efff] ACPI data
Apr 12 18:45:16.819055 kernel: BIOS-e820: [mem 0x000000009cb7f000-0x000000009cbfefff] ACPI NVS
Apr 12 18:45:16.819064 kernel: BIOS-e820: [mem 0x000000009cbff000-0x000000009cf3ffff] usable
Apr 12 18:45:16.819073 kernel: BIOS-e820: [mem 0x000000009cf40000-0x000000009cf5ffff] reserved
Apr 12 18:45:16.819080 kernel: BIOS-e820: [mem 0x000000009cf60000-0x000000009cffffff] ACPI NVS
Apr 12 18:45:16.819087 kernel: NX (Execute Disable) protection: active
Apr 12 18:45:16.819094 kernel: e820: update [mem 0x9b3f8018-0x9b401c57] usable ==> usable
Apr 12 18:45:16.819101 kernel: e820: update [mem 0x9b3f8018-0x9b401c57] usable ==> usable
Apr 12 18:45:16.819108 kernel: e820: update [mem 0x9b1a7018-0x9b1e3e57] usable ==> usable
Apr 12 18:45:16.819115 kernel: e820: update [mem 0x9b1a7018-0x9b1e3e57] usable ==> usable
Apr 12 18:45:16.819122 kernel: extended physical RAM map:
Apr 12 18:45:16.819129 kernel: reserve setup_data: [mem 0x0000000000000000-0x000000000009ffff] usable
Apr 12 18:45:16.819136 kernel: reserve setup_data: [mem 0x0000000000100000-0x00000000007fffff] usable
Apr 12 18:45:16.819145 kernel: reserve setup_data: [mem 0x0000000000800000-0x0000000000807fff] ACPI NVS
Apr 12 18:45:16.819152 kernel: reserve setup_data: [mem 0x0000000000808000-0x000000000080afff] usable
Apr 12 18:45:16.819161 kernel: reserve setup_data: [mem 0x000000000080b000-0x000000000080bfff] ACPI NVS
Apr 12 18:45:16.819168 kernel: reserve setup_data: [mem 0x000000000080c000-0x000000000080ffff] usable
Apr 12 18:45:16.819175 kernel: reserve setup_data: [mem 0x0000000000810000-0x00000000008fffff] ACPI NVS
Apr 12 18:45:16.819182 kernel: reserve setup_data: [mem 0x0000000000900000-0x000000009b1a7017] usable
Apr 12 18:45:16.819189 kernel: reserve setup_data: [mem 0x000000009b1a7018-0x000000009b1e3e57] usable
Apr 12 18:45:16.819196 kernel: reserve setup_data: [mem 0x000000009b1e3e58-0x000000009b3f8017] usable
Apr 12 18:45:16.819203 kernel: reserve setup_data: [mem 0x000000009b3f8018-0x000000009b401c57] usable
Apr 12 18:45:16.819210 kernel: reserve setup_data: [mem 0x000000009b401c58-0x000000009c8eefff] usable
Apr 12 18:45:16.819226 kernel: reserve setup_data: [mem 0x000000009c8ef000-0x000000009cb6efff] reserved
Apr 12 18:45:16.819250 kernel: reserve setup_data: [mem 0x000000009cb6f000-0x000000009cb7efff] ACPI data
Apr 12 18:45:16.819264 kernel: reserve setup_data: [mem 0x000000009cb7f000-0x000000009cbfefff] ACPI NVS
Apr 12 18:45:16.819271 kernel: reserve setup_data: [mem 0x000000009cbff000-0x000000009cf3ffff] usable
Apr 12 18:45:16.819279 kernel: reserve setup_data: [mem 0x000000009cf40000-0x000000009cf5ffff] reserved
Apr 12 18:45:16.819289 kernel: reserve setup_data: [mem 0x000000009cf60000-0x000000009cffffff] ACPI NVS
Apr 12 18:45:16.819296 kernel: efi: EFI v2.70 by EDK II
Apr 12 18:45:16.819304 kernel: efi: SMBIOS=0x9c9ab000 ACPI=0x9cb7e000 ACPI 2.0=0x9cb7e014 MEMATTR=0x9b772018 RNG=0x9cb75018
Apr 12 18:45:16.819318 kernel: random: crng init done
Apr 12 18:45:16.819325 kernel: SMBIOS 2.8 present.
Apr 12 18:45:16.819336 kernel: DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 0.0.0 02/06/2015
Apr 12 18:45:16.819344 kernel: Hypervisor detected: KVM
Apr 12 18:45:16.819351 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00
Apr 12 18:45:16.819359 kernel: kvm-clock: cpu 0, msr 59191001, primary cpu clock
Apr 12 18:45:16.819368 kernel: kvm-clock: using sched offset of 4241351177 cycles
Apr 12 18:45:16.819376 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
Apr 12 18:45:16.819385 kernel: tsc: Detected 2794.750 MHz processor
Apr 12 18:45:16.819396 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
Apr 12 18:45:16.819405 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable
Apr 12 18:45:16.819414 kernel: last_pfn = 0x9cf40 max_arch_pfn = 0x400000000
Apr 12 18:45:16.819422 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT
Apr 12 18:45:16.819430 kernel: Using GB pages for direct mapping
Apr 12 18:45:16.819438 kernel: Secure boot disabled
Apr 12 18:45:16.819447 kernel: ACPI: Early table checksum verification disabled
Apr 12 18:45:16.819456 kernel: ACPI: RSDP 0x000000009CB7E014 000024 (v02 BOCHS )
Apr 12 18:45:16.819465 kernel: ACPI: XSDT 0x000000009CB7D0E8 00004C (v01 BOCHS BXPC 00000001 01000013)
Apr 12 18:45:16.819476 kernel: ACPI: FACP 0x000000009CB7A000 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001)
Apr 12 18:45:16.819484 kernel: ACPI: DSDT 0x000000009CB7B000 001A39 (v01 BOCHS BXPC 00000001 BXPC 00000001)
Apr 12 18:45:16.819493 kernel: ACPI: FACS 0x000000009CBDD000 000040
Apr 12 18:45:16.819502 kernel: ACPI: APIC 0x000000009CB79000 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001)
Apr 12 18:45:16.819510 kernel: ACPI: HPET 0x000000009CB78000 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)
Apr 12 18:45:16.819529 kernel: ACPI: WAET 0x000000009CB77000 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)
Apr 12 18:45:16.819545 kernel: ACPI: BGRT 0x000000009CB76000 000038 (v01 INTEL EDK2 00000002 01000013)
Apr 12 18:45:16.819554 kernel: ACPI: Reserving FACP table memory at [mem 0x9cb7a000-0x9cb7a073]
Apr 12 18:45:16.819562 kernel: ACPI: Reserving DSDT table memory at [mem 0x9cb7b000-0x9cb7ca38]
Apr 12 18:45:16.819573 kernel: ACPI: Reserving FACS table memory at [mem 0x9cbdd000-0x9cbdd03f]
Apr 12 18:45:16.819582 kernel: ACPI: Reserving APIC table memory at [mem 0x9cb79000-0x9cb7908f]
Apr 12 18:45:16.819591 kernel: ACPI: Reserving HPET table memory at [mem 0x9cb78000-0x9cb78037]
Apr 12 18:45:16.819599 kernel: ACPI: Reserving WAET table memory at [mem 0x9cb77000-0x9cb77027]
Apr 12 18:45:16.819608 kernel: ACPI: Reserving BGRT table memory at [mem 0x9cb76000-0x9cb76037]
Apr 12 18:45:16.819628 kernel: No NUMA configuration found
Apr 12 18:45:16.820568 kernel: Faking a node at [mem 0x0000000000000000-0x000000009cf3ffff]
Apr 12 18:45:16.820579 kernel: NODE_DATA(0) allocated [mem 0x9cea6000-0x9ceabfff]
Apr 12 18:45:16.820586 kernel: Zone ranges:
Apr 12 18:45:16.820596 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff]
Apr 12 18:45:16.820603 kernel: DMA32 [mem 0x0000000001000000-0x000000009cf3ffff]
Apr 12 18:45:16.820609 kernel: Normal empty
Apr 12 18:45:16.820647 kernel: Movable zone start for each node
Apr 12 18:45:16.820654 kernel: Early memory node ranges
Apr 12 18:45:16.820660 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff]
Apr 12 18:45:16.820667 kernel: node 0: [mem 0x0000000000100000-0x00000000007fffff]
Apr 12 18:45:16.820673 kernel: node 0: [mem 0x0000000000808000-0x000000000080afff]
Apr 12 18:45:16.820680 kernel: node 0: [mem 0x000000000080c000-0x000000000080ffff]
Apr 12 18:45:16.820688 kernel: node 0: [mem 0x0000000000900000-0x000000009c8eefff]
Apr 12 18:45:16.820694 kernel: node 0: [mem 0x000000009cbff000-0x000000009cf3ffff]
Apr 12 18:45:16.820700 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009cf3ffff]
Apr 12 18:45:16.820707 kernel: On node 0, zone DMA: 1 pages in unavailable ranges
Apr 12 18:45:16.820713 kernel: On node 0, zone DMA: 96 pages in unavailable ranges
Apr 12 18:45:16.820720 kernel: On node 0, zone DMA: 8 pages in unavailable ranges
Apr 12 18:45:16.820726 kernel: On node 0, zone DMA: 1 pages in unavailable ranges
Apr 12 18:45:16.820732 kernel: On node 0, zone DMA: 240 pages in unavailable ranges
Apr 12 18:45:16.820739 kernel: On node 0, zone DMA32: 784 pages in unavailable ranges
Apr 12 18:45:16.820747 kernel: On node 0, zone DMA32: 12480 pages in unavailable ranges
Apr 12 18:45:16.820753 kernel: ACPI: PM-Timer IO Port: 0xb008
Apr 12 18:45:16.820759 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
Apr 12 18:45:16.820766 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
Apr 12 18:45:16.820772 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
Apr 12 18:45:16.820779 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
Apr 12 18:45:16.820785 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
Apr 12 18:45:16.820792 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
Apr 12 18:45:16.820798 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
Apr 12 18:45:16.820806 kernel: ACPI: Using ACPI (MADT) for SMP configuration information
Apr 12 18:45:16.820812 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000
Apr 12 18:45:16.820818 kernel: TSC deadline timer available
Apr 12 18:45:16.820825 kernel: smpboot: Allowing 4 CPUs, 0 hotplug CPUs
Apr 12 18:45:16.820831 kernel: kvm-guest: KVM setup pv remote TLB flush
Apr 12 18:45:16.820837 kernel: kvm-guest: setup PV sched yield
Apr 12 18:45:16.820844 kernel: [mem 0x9d000000-0xffffffff] available for PCI devices
Apr 12 18:45:16.820850 kernel: Booting paravirtualized kernel on KVM
Apr 12 18:45:16.820857 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
Apr 12 18:45:16.820863 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:512 nr_cpu_ids:4 nr_node_ids:1
Apr 12 18:45:16.820871 kernel: percpu: Embedded 56 pages/cpu s188696 r8192 d32488 u524288
Apr 12 18:45:16.820878 kernel: pcpu-alloc: s188696 r8192 d32488 u524288 alloc=1*2097152
Apr 12 18:45:16.820888 kernel: pcpu-alloc: [0] 0 1 2 3
Apr 12 18:45:16.820897 kernel: kvm-guest: setup async PF for cpu 0
Apr 12 18:45:16.820904 kernel: kvm-guest: stealtime: cpu 0, msr 9ae1c0c0
Apr 12 18:45:16.820910 kernel: kvm-guest: PV spinlocks enabled
Apr 12 18:45:16.820917 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear)
Apr 12 18:45:16.820924 kernel: Built 1 zonelists, mobility grouping on. Total pages: 629759
Apr 12 18:45:16.820930 kernel: Policy zone: DMA32
Apr 12 18:45:16.820938 kernel: Kernel command line: rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected verity.usrhash=189121f7c8c0a24098d3bb1e040d34611f7c276be43815ff7fe409fce185edaf
Apr 12 18:45:16.820945 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space.
Apr 12 18:45:16.820953 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
Apr 12 18:45:16.820960 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
Apr 12 18:45:16.820975 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off
Apr 12 18:45:16.820982 kernel: Memory: 2398384K/2567000K available (12294K kernel code, 2275K rwdata, 13708K rodata, 47440K init, 4148K bss, 168356K reserved, 0K cma-reserved)
Apr 12 18:45:16.820990 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
Apr 12 18:45:16.820998 kernel: ftrace: allocating 34508 entries in 135 pages
Apr 12 18:45:16.821006 kernel: ftrace: allocated 135 pages with 4 groups
Apr 12 18:45:16.821012 kernel: rcu: Hierarchical RCU implementation.
Apr 12 18:45:16.821020 kernel: rcu: RCU event tracing is enabled.
Apr 12 18:45:16.821027 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4.
Apr 12 18:45:16.821034 kernel: Rude variant of Tasks RCU enabled.
Apr 12 18:45:16.821041 kernel: Tracing variant of Tasks RCU enabled.
Apr 12 18:45:16.821047 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
Apr 12 18:45:16.821054 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
Apr 12 18:45:16.821062 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16
Apr 12 18:45:16.821069 kernel: Console: colour dummy device 80x25
Apr 12 18:45:16.821076 kernel: printk: console [ttyS0] enabled
Apr 12 18:45:16.821082 kernel: ACPI: Core revision 20210730
Apr 12 18:45:16.821089 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
Apr 12 18:45:16.821096 kernel: APIC: Switch to symmetric I/O mode setup
Apr 12 18:45:16.821103 kernel: x2apic enabled
Apr 12 18:45:16.821109 kernel: Switched APIC routing to physical x2apic.
Apr 12 18:45:16.821116 kernel: kvm-guest: setup PV IPIs
Apr 12 18:45:16.821124 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
Apr 12 18:45:16.821131 kernel: tsc: Marking TSC unstable due to TSCs unsynchronized
Apr 12 18:45:16.821138 kernel: Calibrating delay loop (skipped) preset value.. 5589.50 BogoMIPS (lpj=2794750)
Apr 12 18:45:16.821144 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated
Apr 12 18:45:16.821151 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127
Apr 12 18:45:16.821158 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0
Apr 12 18:45:16.821164 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
Apr 12 18:45:16.821171 kernel: Spectre V2 : Mitigation: Retpolines
Apr 12 18:45:16.821178 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
Apr 12 18:45:16.821187 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT
Apr 12 18:45:16.821195 kernel: Spectre V2 : Enabling Speculation Barrier for firmware calls
Apr 12 18:45:16.821202 kernel: RETBleed: Mitigation: untrained return thunk
Apr 12 18:45:16.821211 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier
Apr 12 18:45:16.821219 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl and seccomp
Apr 12 18:45:16.821226 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
Apr 12 18:45:16.821232 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
Apr 12 18:45:16.821239 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
Apr 12 18:45:16.821246 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256
Apr 12 18:45:16.821254 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format.
Apr 12 18:45:16.821260 kernel: Freeing SMP alternatives memory: 32K
Apr 12 18:45:16.821267 kernel: pid_max: default: 32768 minimum: 301
Apr 12 18:45:16.821274 kernel: LSM: Security Framework initializing
Apr 12 18:45:16.821280 kernel: SELinux: Initializing.
Apr 12 18:45:16.821287 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
Apr 12 18:45:16.821294 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
Apr 12 18:45:16.821302 kernel: smpboot: CPU0: AMD EPYC 7402P 24-Core Processor (family: 0x17, model: 0x31, stepping: 0x0)
Apr 12 18:45:16.821308 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver.
Apr 12 18:45:16.821316 kernel: ... version: 0
Apr 12 18:45:16.821323 kernel: ... bit width: 48
Apr 12 18:45:16.821330 kernel: ... generic registers: 6
Apr 12 18:45:16.821336 kernel: ... value mask: 0000ffffffffffff
Apr 12 18:45:16.821343 kernel: ... max period: 00007fffffffffff
Apr 12 18:45:16.821349 kernel: ... fixed-purpose events: 0
Apr 12 18:45:16.821356 kernel: ... event mask: 000000000000003f
Apr 12 18:45:16.821363 kernel: signal: max sigframe size: 1776
Apr 12 18:45:16.821370 kernel: rcu: Hierarchical SRCU implementation.
Apr 12 18:45:16.821377 kernel: smp: Bringing up secondary CPUs ...
Apr 12 18:45:16.821384 kernel: x86: Booting SMP configuration:
Apr 12 18:45:16.821391 kernel: .... node #0, CPUs: #1
Apr 12 18:45:16.821397 kernel: kvm-clock: cpu 1, msr 59191041, secondary cpu clock
Apr 12 18:45:16.821404 kernel: kvm-guest: setup async PF for cpu 1
Apr 12 18:45:16.821411 kernel: kvm-guest: stealtime: cpu 1, msr 9ae9c0c0
Apr 12 18:45:16.821417 kernel: #2
Apr 12 18:45:16.821424 kernel: kvm-clock: cpu 2, msr 59191081, secondary cpu clock
Apr 12 18:45:16.821431 kernel: kvm-guest: setup async PF for cpu 2
Apr 12 18:45:16.821438 kernel: kvm-guest: stealtime: cpu 2, msr 9af1c0c0
Apr 12 18:45:16.821445 kernel: #3
Apr 12 18:45:16.821452 kernel: kvm-clock: cpu 3, msr 591910c1, secondary cpu clock
Apr 12 18:45:16.821458 kernel: kvm-guest: setup async PF for cpu 3
Apr 12 18:45:16.821465 kernel: kvm-guest: stealtime: cpu 3, msr 9af9c0c0
Apr 12 18:45:16.821472 kernel: smp: Brought up 1 node, 4 CPUs
Apr 12 18:45:16.821478 kernel: smpboot: Max logical packages: 1
Apr 12 18:45:16.821485 kernel: smpboot: Total of 4 processors activated (22358.00 BogoMIPS)
Apr 12 18:45:16.821492 kernel: devtmpfs: initialized
Apr 12 18:45:16.821499 kernel: x86/mm: Memory block size: 128MB
Apr 12 18:45:16.821507 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x00800000-0x00807fff] (32768 bytes)
Apr 12 18:45:16.821513 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x0080b000-0x0080bfff] (4096 bytes)
Apr 12 18:45:16.821520 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x00810000-0x008fffff] (983040 bytes)
Apr 12 18:45:16.821527 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9cb7f000-0x9cbfefff] (524288 bytes)
Apr 12 18:45:16.821534 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9cf60000-0x9cffffff] (655360 bytes)
Apr 12 18:45:16.821541 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
Apr 12 18:45:16.821547 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
Apr 12 18:45:16.821554 kernel: pinctrl core: initialized pinctrl subsystem
Apr 12 18:45:16.821562 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family
Apr 12 18:45:16.821569 kernel: audit: initializing netlink subsys (disabled)
Apr 12 18:45:16.821576 kernel: audit: type=2000 audit(1712947516.557:1): state=initialized audit_enabled=0 res=1
Apr 12 18:45:16.821582 kernel: thermal_sys: Registered thermal governor 'step_wise'
Apr 12 18:45:16.821589 kernel: thermal_sys: Registered thermal governor 'user_space'
Apr 12 18:45:16.821596 kernel: cpuidle: using governor menu
Apr 12 18:45:16.821602 kernel: ACPI: bus type PCI registered
Apr 12 18:45:16.821609 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
Apr 12 18:45:16.821624 kernel: dca service started, version 1.12.1
Apr 12 18:45:16.821632 kernel: PCI: Using configuration type 1 for base access
Apr 12 18:45:16.821639 kernel: PCI: Using configuration type 1 for extended access
Apr 12 18:45:16.821646 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.
Apr 12 18:45:16.821653 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages
Apr 12 18:45:16.821660 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
Apr 12 18:45:16.821666 kernel: ACPI: Added _OSI(Module Device)
Apr 12 18:45:16.821673 kernel: ACPI: Added _OSI(Processor Device)
Apr 12 18:45:16.821680 kernel: ACPI: Added _OSI(3.0 _SCP Extensions)
Apr 12 18:45:16.821687 kernel: ACPI: Added _OSI(Processor Aggregator Device)
Apr 12 18:45:16.821695 kernel: ACPI: Added _OSI(Linux-Dell-Video)
Apr 12 18:45:16.821702 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
Apr 12 18:45:16.821708 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics)
Apr 12 18:45:16.821715 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded
Apr 12 18:45:16.821722 kernel: ACPI: Interpreter enabled
Apr 12 18:45:16.821728 kernel: ACPI: PM: (supports S0 S3 S5)
Apr 12 18:45:16.821735 kernel: ACPI: Using IOAPIC for interrupt routing
Apr 12 18:45:16.821742 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
Apr 12 18:45:16.821749 kernel: ACPI: Enabled 2 GPEs in block 00 to 0F
Apr 12 18:45:16.821755 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
Apr 12 18:45:16.821872 kernel: acpi PNP0A03:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]
Apr 12 18:45:16.821885 kernel: acpiphp: Slot [3] registered
Apr 12 18:45:16.821892 kernel: acpiphp: Slot [4] registered
Apr 12 18:45:16.821899 kernel: acpiphp: Slot [5] registered
Apr 12 18:45:16.821905 kernel: acpiphp: Slot [6] registered
Apr 12 18:45:16.821912 kernel: acpiphp: Slot [7] registered
Apr 12 18:45:16.821919 kernel: acpiphp: Slot [8] registered
Apr 12 18:45:16.821925 kernel: acpiphp: Slot [9] registered
Apr 12 18:45:16.821934 kernel: acpiphp: Slot [10] registered
Apr 12 18:45:16.821940 kernel: acpiphp: Slot [11] registered
Apr 12 18:45:16.821948 kernel: acpiphp: Slot [12] registered
Apr 12 18:45:16.821954 kernel: acpiphp: Slot [13] registered
Apr 12 18:45:16.821969 kernel: acpiphp: Slot [14] registered
Apr 12 18:45:16.821976 kernel: acpiphp: Slot [15] registered
Apr 12 18:45:16.821983 kernel: acpiphp: Slot [16] registered
Apr 12 18:45:16.821990 kernel: acpiphp: Slot [17] registered
Apr 12 18:45:16.821997 kernel: acpiphp: Slot [18] registered
Apr 12 18:45:16.822005 kernel: acpiphp: Slot [19] registered
Apr 12 18:45:16.822012 kernel: acpiphp: Slot [20] registered
Apr 12 18:45:16.822018 kernel: acpiphp: Slot [21] registered
Apr 12 18:45:16.822025 kernel: acpiphp: Slot [22] registered
Apr 12 18:45:16.822032 kernel: acpiphp: Slot [23] registered
Apr 12 18:45:16.822038 kernel: acpiphp: Slot [24] registered
Apr 12 18:45:16.822045 kernel: acpiphp: Slot [25] registered
Apr 12 18:45:16.822052 kernel: acpiphp: Slot [26] registered
Apr 12 18:45:16.822058 kernel: acpiphp: Slot [27] registered
Apr 12 18:45:16.822066 kernel: acpiphp: Slot [28] registered
Apr 12 18:45:16.822073 kernel: acpiphp: Slot [29] registered
Apr 12 18:45:16.822080 kernel: acpiphp: Slot [30] registered
Apr 12 18:45:16.822087 kernel: acpiphp: Slot [31] registered
Apr 12 18:45:16.822093 kernel: PCI host bridge to bus 0000:00
Apr 12 18:45:16.822167 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]
Apr 12 18:45:16.822237 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]
Apr 12 18:45:16.822309 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
Apr 12 18:45:16.822369 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xfebfffff window]
Apr 12 18:45:16.822430 kernel: pci_bus 0000:00: root bus resource [mem 0x800000000-0x87fffffff window]
Apr 12 18:45:16.822495 kernel: pci_bus 0000:00: root bus resource [bus 00-ff]
Apr 12 18:45:16.822573 kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
Apr 12 18:45:16.822662 kernel: pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
Apr 12 18:45:16.822736 kernel: pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
Apr 12 18:45:16.822804 kernel: pci 0000:00:01.1: reg 0x20: [io 0xc0c0-0xc0cf]
Apr 12 18:45:16.822875 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7]
Apr 12 18:45:16.822983 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6]
Apr 12 18:45:16.823053 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177]
Apr 12 18:45:16.823129 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376]
Apr 12 18:45:16.823254 kernel: pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
Apr 12 18:45:16.823330 kernel: pci 0000:00:01.3: quirk: [io 0xb000-0xb03f] claimed by PIIX4 ACPI
Apr 12 18:45:16.823400 kernel: pci 0000:00:01.3: quirk: [io 0xb100-0xb10f] claimed by PIIX4 SMB
Apr 12 18:45:16.823473 kernel: pci 0000:00:02.0: [1234:1111] type 00 class 0x030000
Apr 12 18:45:16.823539 kernel: pci 0000:00:02.0: reg 0x10: [mem 0xc0000000-0xc0ffffff pref]
Apr 12 18:45:16.823604 kernel: pci 0000:00:02.0: reg 0x18: [mem 0xc1043000-0xc1043fff]
Apr 12 18:45:16.823684 kernel: pci 0000:00:02.0: reg 0x30: [mem 0xffff0000-0xffffffff pref]
Apr 12 18:45:16.823751 kernel: pci 0000:00:02.0: BAR 0: assigned to efifb
Apr 12 18:45:16.823817 kernel: pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
Apr 12 18:45:16.823900 kernel: pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00
Apr 12 18:45:16.823978 kernel: pci 0000:00:03.0: reg 0x10: [io 0xc0a0-0xc0bf]
Apr 12 18:45:16.824050 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xc1042000-0xc1042fff]
Apr 12 18:45:16.824118 kernel: pci 0000:00:03.0: reg 0x20: [mem 0x800000000-0x800003fff 64bit pref]
Apr 12 18:45:16.824190 kernel: pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000
Apr 12 18:45:16.824258 kernel: pci 0000:00:04.0: reg 0x10: [io 0xc000-0xc07f]
Apr 12 18:45:16.824333 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xc1041000-0xc1041fff]
Apr 12 18:45:16.824403 kernel: pci 0000:00:04.0: reg 0x20: [mem 0x800004000-0x800007fff 64bit pref]
Apr 12 18:45:16.824477 kernel: pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000
Apr 12 18:45:16.824545 kernel: pci 0000:00:05.0: reg 0x10: [io 0xc080-0xc09f]
Apr 12 18:45:16.824640 kernel: pci 0000:00:05.0: reg 0x14: [mem 0xc1040000-0xc1040fff]
Apr 12 18:45:16.824713 kernel: pci 0000:00:05.0: reg 0x20: [mem 0x800008000-0x80000bfff 64bit pref]
Apr 12 18:45:16.824780 kernel: pci 0000:00:05.0: reg 0x30: [mem 0xfffc0000-0xffffffff pref]
Apr 12 18:45:16.824789 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10
Apr 12 18:45:16.824799 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10
Apr 12 18:45:16.824806 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11
Apr 12 18:45:16.824814 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11
Apr 12 18:45:16.824820 kernel: ACPI: PCI: Interrupt link LNKS configured for IRQ 9
Apr 12 18:45:16.824827 kernel: iommu: Default domain type: Translated
Apr 12 18:45:16.824835 kernel: iommu: DMA domain TLB invalidation policy: lazy mode
Apr 12 18:45:16.824902 kernel: pci 0000:00:02.0: vgaarb: setting as boot VGA device
Apr 12 18:45:16.824977 kernel: pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
Apr 12 18:45:16.825044 kernel: pci 0000:00:02.0: vgaarb: bridge control possible
Apr 12 18:45:16.825056 kernel: vgaarb: loaded
Apr 12 18:45:16.825062 kernel: pps_core: LinuxPPS API ver. 1 registered
Apr 12 18:45:16.825069 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
Apr 12 18:45:16.825078 kernel: PTP clock support registered
Apr 12 18:45:16.825087 kernel: Registered efivars operations
Apr 12 18:45:16.825095 kernel: PCI: Using ACPI for IRQ routing
Apr 12 18:45:16.825104 kernel: PCI: pci_cache_line_size set to 64 bytes
Apr 12 18:45:16.825112 kernel: e820: reserve RAM buffer [mem 0x0080b000-0x008fffff]
Apr 12 18:45:16.825120 kernel: e820: reserve RAM buffer [mem 0x00810000-0x008fffff]
Apr 12 18:45:16.825130 kernel: e820: reserve RAM buffer [mem 0x9b1a7018-0x9bffffff]
Apr 12 18:45:16.825138 kernel: e820: reserve RAM buffer [mem 0x9b3f8018-0x9bffffff]
Apr 12 18:45:16.825146 kernel: e820: reserve RAM buffer [mem 0x9c8ef000-0x9fffffff]
Apr 12 18:45:16.825155 kernel: e820: reserve RAM buffer [mem 0x9cf40000-0x9fffffff]
Apr 12 18:45:16.825163 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
Apr 12 18:45:16.825172 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter
Apr 12 18:45:16.825181 kernel: clocksource: Switched to clocksource kvm-clock
Apr 12 18:45:16.825189 kernel: VFS: Disk quotas dquot_6.6.0
Apr 12 18:45:16.825198 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
Apr 12 18:45:16.825207 kernel: pnp: PnP ACPI init
Apr 12 18:45:16.825280 kernel: pnp 00:02: [dma 2]
Apr 12 18:45:16.825291 kernel: pnp: PnP ACPI: found 6 devices
Apr 12 18:45:16.825298 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
Apr 12 18:45:16.825305 kernel: NET: Registered PF_INET protocol family
Apr 12 18:45:16.825312 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)
Apr 12 18:45:16.825319 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)
Apr 12 18:45:16.825326 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
Apr 12 18:45:16.825335 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)
Apr 12 18:45:16.825342 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear)
Apr 12 18:45:16.825349 kernel: TCP: Hash tables configured (established 32768 bind 32768)
Apr 12 18:45:16.825356 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)
Apr 12 18:45:16.825363 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)
Apr 12 18:45:16.825370 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family
Apr 12 18:45:16.825377 kernel: NET: Registered PF_XDP protocol family
Apr 12 18:45:16.825445 kernel: pci 0000:00:05.0: can't claim BAR 6 [mem 0xfffc0000-0xffffffff pref]: no compatible bridge window
Apr 12 18:45:16.825523 kernel: pci 0000:00:05.0: BAR 6: assigned [mem 0x9d000000-0x9d03ffff pref]
Apr 12 18:45:16.825585 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]
Apr 12 18:45:16.825660 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]
Apr 12 18:45:16.825721 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
Apr 12 18:45:16.825780 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xfebfffff window]
Apr 12 18:45:16.825844 kernel: pci_bus 0000:00: resource 8 [mem 0x800000000-0x87fffffff window]
Apr 12 18:45:16.825931 kernel: pci 0000:00:01.0: PIIX3: Enabling Passive Release
Apr 12 18:45:16.826011 kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers
Apr 12 18:45:16.826098 kernel: pci 0000:00:01.0: Activating ISA DMA hang workarounds
Apr 12 18:45:16.826108 kernel: PCI: CLS 0 bytes, default 64
Apr 12 18:45:16.826123 kernel: Initialise system trusted keyrings
Apr 12 18:45:16.826131 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0
Apr 12 18:45:16.826138 kernel: Key type asymmetric registered
Apr 12 18:45:16.826145 kernel: Asymmetric key parser 'x509' registered
Apr 12 18:45:16.826152 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
Apr 12 18:45:16.826159 kernel: io scheduler mq-deadline registered
Apr 12 18:45:16.826167 kernel: io scheduler kyber registered
Apr 12 18:45:16.826176 kernel: io scheduler bfq registered
Apr 12 18:45:16.826183 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00
Apr 12 18:45:16.826191 kernel: ACPI: \_SB_.LNKC: Enabled at IRQ 11
Apr 12 18:45:16.826198 kernel: ACPI: \_SB_.LNKD: Enabled at IRQ 10
Apr 12 18:45:16.826207 kernel: ACPI: \_SB_.LNKA: Enabled at IRQ 10
Apr 12 18:45:16.826216 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
Apr 12 18:45:16.826225 kernel: 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
Apr 12 18:45:16.826234 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
Apr 12 18:45:16.826243 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1
Apr 12 18:45:16.826253 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12
Apr 12 18:45:16.826343 kernel: rtc_cmos 00:05: RTC can wake from S4
Apr 12 18:45:16.826360 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
Apr 12 18:45:16.826425 kernel: rtc_cmos 00:05: registered as rtc0
Apr 12 18:45:16.826487 kernel: rtc_cmos 00:05: setting system clock to 2024-04-12T18:45:16 UTC (1712947516)
Apr 12 18:45:16.826550 kernel: rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs
Apr 12 18:45:16.826560 kernel: efifb: probing for efifb
Apr 12 18:45:16.826567 kernel: efifb: framebuffer at 0xc0000000, using 4000k, total 4000k
Apr 12 18:45:16.826575 kernel: efifb: mode is 1280x800x32, linelength=5120, pages=1
Apr 12 18:45:16.826582 kernel: efifb: scrolling: redraw
Apr 12 18:45:16.826589 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0
Apr 12 18:45:16.826596 kernel: Console: switching to colour frame buffer device 160x50
Apr 12 18:45:16.826603 kernel: fb0: EFI VGA frame buffer device
Apr 12 18:45:16.826622 kernel: pstore: Registered efi as persistent store backend
Apr 12 18:45:16.826632 kernel: NET: Registered PF_INET6 protocol family
Apr 12 18:45:16.826639 kernel: Segment Routing with IPv6
Apr 12 18:45:16.826646 kernel: In-situ OAM (IOAM) with IPv6
Apr 12 18:45:16.826654 kernel: NET: Registered PF_PACKET protocol family
Apr 12 18:45:16.826661 kernel: Key type dns_resolver registered
Apr 12 18:45:16.826668 kernel: IPI shorthand broadcast: enabled
Apr 12 18:45:16.826675 kernel: sched_clock: Marking stable (421132194, 126137127)->(587352947, -40083626)
Apr 12 18:45:16.826682 kernel: registered taskstats version 1
Apr 12 18:45:16.826689 kernel: Loading compiled-in X.509 certificates
Apr 12 18:45:16.826698 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.15.154-flatcar: 1fa140a38fc6bd27c8b56127e4d1eb4f665c7ec4'
Apr 12 18:45:16.826705 kernel: Key type .fscrypt registered
Apr 12 18:45:16.826712 kernel: Key type fscrypt-provisioning registered
Apr 12 18:45:16.826719 kernel: pstore: Using crash dump compression: deflate
Apr 12 18:45:16.826727 kernel: ima: No TPM chip found, activating TPM-bypass!
Apr 12 18:45:16.826734 kernel: ima: Allocated hash algorithm: sha1
Apr 12 18:45:16.826741 kernel: ima: No architecture policies found
Apr 12 18:45:16.826748 kernel: Freeing unused kernel image (initmem) memory: 47440K
Apr 12 18:45:16.826756 kernel: Write protecting the kernel read-only data: 28672k
Apr 12 18:45:16.826763 kernel: Freeing unused kernel image (text/rodata gap) memory: 2040K
Apr 12 18:45:16.826771 kernel: Freeing unused kernel image (rodata/data gap) memory: 628K
Apr 12 18:45:16.826778 kernel: Run /init as init process
Apr 12 18:45:16.826785 kernel: with arguments:
Apr 12 18:45:16.826792 kernel: /init
Apr 12 18:45:16.826799 kernel: with environment:
Apr 12 18:45:16.826806 kernel: HOME=/
Apr 12 18:45:16.826813 kernel: TERM=linux
Apr 12 18:45:16.826820 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a
Apr 12 18:45:16.826830 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)
Apr 12 18:45:16.826839 systemd[1]: Detected virtualization kvm.
Apr 12 18:45:16.826847 systemd[1]: Detected architecture x86-64.
Apr 12 18:45:16.826855 systemd[1]: Running in initrd.
Apr 12 18:45:16.826862 systemd[1]: No hostname configured, using default hostname.
Apr 12 18:45:16.826870 systemd[1]: Hostname set to <localhost>.
Apr 12 18:45:16.826878 systemd[1]: Initializing machine ID from VM UUID.
Apr 12 18:45:16.826887 systemd[1]: Queued start job for default target initrd.target.
Apr 12 18:45:16.826894 systemd[1]: Started systemd-ask-password-console.path.
Apr 12 18:45:16.826903 systemd[1]: Reached target cryptsetup.target.
Apr 12 18:45:16.826911 systemd[1]: Reached target paths.target.
Apr 12 18:45:16.826918 systemd[1]: Reached target slices.target.
Apr 12 18:45:16.826926 systemd[1]: Reached target swap.target.
Apr 12 18:45:16.826934 systemd[1]: Reached target timers.target.
Apr 12 18:45:16.826942 systemd[1]: Listening on iscsid.socket.
Apr 12 18:45:16.826951 systemd[1]: Listening on iscsiuio.socket.
Apr 12 18:45:16.826959 systemd[1]: Listening on systemd-journald-audit.socket.
Apr 12 18:45:16.826974 systemd[1]: Listening on systemd-journald-dev-log.socket.
Apr 12 18:45:16.826983 systemd[1]: Listening on systemd-journald.socket.
Apr 12 18:45:16.826990 systemd[1]: Listening on systemd-networkd.socket.
Apr 12 18:45:16.826998 systemd[1]: Listening on systemd-udevd-control.socket.
Apr 12 18:45:16.827006 systemd[1]: Listening on systemd-udevd-kernel.socket.
Apr 12 18:45:16.827013 systemd[1]: Reached target sockets.target.
Apr 12 18:45:16.827022 systemd[1]: Starting kmod-static-nodes.service...
Apr 12 18:45:16.827030 systemd[1]: Finished network-cleanup.service.
Apr 12 18:45:16.827037 systemd[1]: Starting systemd-fsck-usr.service...
Apr 12 18:45:16.827045 systemd[1]: Starting systemd-journald.service...
Apr 12 18:45:16.827053 systemd[1]: Starting systemd-modules-load.service...
Apr 12 18:45:16.827060 systemd[1]: Starting systemd-resolved.service...
Apr 12 18:45:16.827068 systemd[1]: Starting systemd-vconsole-setup.service...
Apr 12 18:45:16.827077 systemd[1]: Finished kmod-static-nodes.service.
Apr 12 18:45:16.827086 systemd[1]: Finished systemd-fsck-usr.service.
Apr 12 18:45:16.827097 systemd[1]: Starting systemd-tmpfiles-setup-dev.service...
Apr 12 18:45:16.827107 systemd[1]: Finished systemd-vconsole-setup.service.
Apr 12 18:45:16.827117 kernel: audit: type=1130 audit(1712947516.825:2): 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'
Apr 12 18:45:16.827130 systemd-journald[198]: Journal started
Apr 12 18:45:16.827178 systemd-journald[198]: Runtime Journal (/run/log/journal/ed3f747e97df4da6bf539c0b55b8958a) is 6.0M, max 48.4M, 42.4M free.
Apr 12 18:45:16.825000 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'
Apr 12 18:45:16.819566 systemd-modules-load[199]: Inserted module 'overlay'
Apr 12 18:45:16.834625 systemd[1]: Started systemd-journald.service.
Apr 12 18:45:16.834000 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'
Apr 12 18:45:16.838212 systemd[1]: Finished systemd-tmpfiles-setup-dev.service.
Apr 12 18:45:16.845693 kernel: audit: type=1130 audit(1712947516.834: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'
Apr 12 18:45:16.845714 kernel: audit: type=1130 audit(1712947516.839:4): 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'
Apr 12 18:45:16.839000 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'
Apr 12 18:45:16.843004 systemd[1]: Starting dracut-cmdline-ask.service...
Apr 12 18:45:16.851860 systemd-resolved[200]: Positive Trust Anchors:
Apr 12 18:45:16.851875 systemd-resolved[200]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
Apr 12 18:45:16.851911 systemd-resolved[200]: 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
Apr 12 18:45:16.856541 systemd[1]: Finished dracut-cmdline-ask.service.
Apr 12 18:45:16.863269 kernel: audit: type=1130 audit(1712947516.856:5): 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'
Apr 12 18:45:16.863294 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
Apr 12 18:45:16.856000 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'
Apr 12 18:45:16.860224 systemd[1]: Starting dracut-cmdline.service...
Apr 12 18:45:16.867504 systemd-resolved[200]: Defaulting to hostname 'linux'.
Apr 12 18:45:16.868808 dracut-cmdline[217]: dracut-dracut-053
Apr 12 18:45:16.870291 systemd[1]: Started systemd-resolved.service.
Apr 12 18:45:16.876763 kernel: Bridge firewalling registered
Apr 12 18:45:16.876783 kernel: audit: type=1130 audit(1712947516.871:6): 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'
Apr 12 18:45:16.871000 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'
Apr 12 18:45:16.870996 systemd-modules-load[199]: Inserted module 'br_netfilter'
Apr 12 18:45:16.877765 dracut-cmdline[217]: Using kernel command line parameters: rd.driver.pre=btrfs rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected verity.usrhash=189121f7c8c0a24098d3bb1e040d34611f7c276be43815ff7fe409fce185edaf
Apr 12 18:45:16.872023 systemd[1]: Reached target nss-lookup.target.
Apr 12 18:45:16.893629 kernel: SCSI subsystem initialized
Apr 12 18:45:16.906257 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.
Apr 12 18:45:16.906295 kernel: device-mapper: uevent: version 1.0.3
Apr 12 18:45:16.907749 kernel: device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com
Apr 12 18:45:16.910779 systemd-modules-load[199]: Inserted module 'dm_multipath'
Apr 12 18:45:16.911405 systemd[1]: Finished systemd-modules-load.service.
Apr 12 18:45:16.914000 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'
Apr 12 18:45:16.915027 systemd[1]: Starting systemd-sysctl.service...
Apr 12 18:45:16.919086 kernel: audit: type=1130 audit(1712947516.914: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'
Apr 12 18:45:16.921999 systemd[1]: Finished systemd-sysctl.service.
Apr 12 18:45:16.923000 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'
Apr 12 18:45:16.927635 kernel: audit: type=1130 audit(1712947516.923: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'
Apr 12 18:45:16.927656 kernel: Loading iSCSI transport class v2.0-870.
Apr 12 18:45:16.944641 kernel: iscsi: registered transport (tcp)
Apr 12 18:45:16.965095 kernel: iscsi: registered transport (qla4xxx)
Apr 12 18:45:16.965126 kernel: QLogic iSCSI HBA Driver
Apr 12 18:45:16.985832 systemd[1]: Finished dracut-cmdline.service.
Apr 12 18:45:16.995000 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'
Apr 12 18:45:16.996454 systemd[1]: Starting dracut-pre-udev.service...
Apr 12 18:45:17.000459 kernel: audit: type=1130 audit(1712947516.995: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'
Apr 12 18:45:17.039636 kernel: raid6: avx2x4 gen() 29975 MB/s
Apr 12 18:45:17.056651 kernel: raid6: avx2x4 xor() 7178 MB/s
Apr 12 18:45:17.073648 kernel: raid6: avx2x2 gen() 30562 MB/s
Apr 12 18:45:17.090638 kernel: raid6: avx2x2 xor() 18606 MB/s
Apr 12 18:45:17.107638 kernel: raid6: avx2x1 gen() 25958 MB/s
Apr 12 18:45:17.124635 kernel: raid6: avx2x1 xor() 14894 MB/s
Apr 12 18:45:17.141638 kernel: raid6: sse2x4 gen() 14100 MB/s
Apr 12 18:45:17.158638 kernel: raid6: sse2x4 xor() 6901 MB/s
Apr 12 18:45:17.175643 kernel: raid6: sse2x2 gen() 15695 MB/s
Apr 12 18:45:17.192633 kernel: raid6: sse2x2 xor() 9476 MB/s
Apr 12 18:45:17.212644 kernel: raid6: sse2x1 gen() 11799 MB/s
Apr 12 18:45:17.230508 kernel: raid6: sse2x1 xor() 4732 MB/s
Apr 12 18:45:17.230528 kernel: raid6: using algorithm avx2x2 gen() 30562 MB/s
Apr 12 18:45:17.230538 kernel: raid6: .... xor() 18606 MB/s, rmw enabled
Apr 12 18:45:17.231463 kernel: raid6: using avx2x2 recovery algorithm
Apr 12 18:45:17.247637 kernel: xor: automatically using best checksumming function avx
Apr 12 18:45:17.353648 kernel: Btrfs loaded, crc32c=crc32c-intel, zoned=no, fsverity=no
Apr 12 18:45:17.360998 systemd[1]: Finished dracut-pre-udev.service.
Apr 12 18:45:17.366037 kernel: audit: type=1130 audit(1712947517.361: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'
Apr 12 18:45:17.361000 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'
Apr 12 18:45:17.365000 audit: BPF prog-id=7 op=LOAD
Apr 12 18:45:17.365000 audit: BPF prog-id=8 op=LOAD
Apr 12 18:45:17.366363 systemd[1]: Starting systemd-udevd.service...
Apr 12 18:45:17.378429 systemd-udevd[400]: Using default interface naming scheme 'v252'.
Apr 12 18:45:17.383200 systemd[1]: Started systemd-udevd.service.
Apr 12 18:45:17.383000 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'
Apr 12 18:45:17.384537 systemd[1]: Starting dracut-pre-trigger.service...
Apr 12 18:45:17.394323 dracut-pre-trigger[401]: rd.md=0: removing MD RAID activation
Apr 12 18:45:17.419021 systemd[1]: Finished dracut-pre-trigger.service.
Apr 12 18:45:17.419000 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'
Apr 12 18:45:17.420683 systemd[1]: Starting systemd-udev-trigger.service...
Apr 12 18:45:17.462549 systemd[1]: Finished systemd-udev-trigger.service.
Apr 12 18:45:17.462000 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'
Apr 12 18:45:17.493131 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB)
Apr 12 18:45:17.497675 kernel: cryptd: max_cpu_qlen set to 1000
Apr 12 18:45:17.512649 kernel: AVX2 version of gcm_enc/dec engaged.
Apr 12 18:45:17.512726 kernel: AES CTR mode by8 optimization enabled
Apr 12 18:45:17.517104 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9
Apr 12 18:45:17.526675 kernel: libata version 3.00 loaded.
Apr 12 18:45:17.531641 kernel: ata_piix 0000:00:01.1: version 2.13
Apr 12 18:45:17.534587 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device.
Apr 12 18:45:17.537343 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device.
Apr 12 18:45:17.544063 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by (udev-worker) (438)
Apr 12 18:45:17.544085 kernel: scsi host0: ata_piix
Apr 12 18:45:17.546280 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device.
Apr 12 18:45:17.551600 kernel: scsi host1: ata_piix
Apr 12 18:45:17.551745 kernel: ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc0c0 irq 14
Apr 12 18:45:17.551756 kernel: ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc0c8 irq 15
Apr 12 18:45:17.558634 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device.
Apr 12 18:45:17.565761 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device.
Apr 12 18:45:17.568900 systemd[1]: Starting disk-uuid.service...
Apr 12 18:45:17.576636 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9
Apr 12 18:45:17.579660 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9
Apr 12 18:45:17.709746 kernel: ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
Apr 12 18:45:17.712741 kernel: scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5
Apr 12 18:45:17.750545 kernel: sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
Apr 12 18:45:17.750910 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20
Apr 12 18:45:17.769754 kernel: sr 1:0:0:0: Attached scsi CD-ROM sr0
Apr 12 18:45:18.602732 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9
Apr 12 18:45:18.603797 disk-uuid[520]: The operation has completed successfully.
Apr 12 18:45:18.657952 systemd[1]: disk-uuid.service: Deactivated successfully.
Apr 12 18:45:18.659115 systemd[1]: Finished disk-uuid.service.
Apr 12 18:45:18.674000 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'
Apr 12 18:45:18.674000 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'
Apr 12 18:45:18.689108 systemd[1]: Starting verity-setup.service...
Apr 12 18:45:18.731082 kernel: device-mapper: verity: sha256 using implementation "sha256-ni"
Apr 12 18:45:18.850647 systemd[1]: Found device dev-mapper-usr.device.
Apr 12 18:45:18.852753 systemd[1]: Mounting sysusr-usr.mount...
Apr 12 18:45:18.857378 systemd[1]: Finished verity-setup.service.
Apr 12 18:45:18.857000 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'
Apr 12 18:45:18.996654 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: norecovery. Quota mode: none.
Apr 12 18:45:18.997166 systemd[1]: Mounted sysusr-usr.mount.
Apr 12 18:45:18.997438 systemd[1]: afterburn-network-kargs.service was skipped because no trigger condition checks were met.
Apr 12 18:45:19.000364 systemd[1]: Starting ignition-setup.service...
Apr 12 18:45:19.004001 systemd[1]: Starting parse-ip-for-networkd.service...
Apr 12 18:45:19.035887 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm
Apr 12 18:45:19.035971 kernel: BTRFS info (device vda6): using free space tree
Apr 12 18:45:19.035987 kernel: BTRFS info (device vda6): has skinny extents
Apr 12 18:45:19.073676 systemd[1]: mnt-oem.mount: Deactivated successfully.
Apr 12 18:45:19.101978 systemd[1]: Finished ignition-setup.service.
Apr 12 18:45:19.101000 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'
Apr 12 18:45:19.103136 systemd[1]: Starting ignition-fetch-offline.service...
Apr 12 18:45:19.157443 systemd[1]: Finished parse-ip-for-networkd.service.
Apr 12 18:45:19.158000 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'
Apr 12 18:45:19.173067 systemd[1]: Starting systemd-networkd.service...
Apr 12 18:45:19.170000 audit: BPF prog-id=9 op=LOAD
Apr 12 18:45:19.225057 systemd-networkd[695]: lo: Link UP
Apr 12 18:45:19.225069 systemd-networkd[695]: lo: Gained carrier
Apr 12 18:45:19.232000 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'
Apr 12 18:45:19.225576 systemd-networkd[695]: Enumeration completed
Apr 12 18:45:19.225721 systemd[1]: Started systemd-networkd.service.
Apr 12 18:45:19.226006 systemd-networkd[695]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
Apr 12 18:45:19.231274 systemd-networkd[695]: eth0: Link UP
Apr 12 18:45:19.231279 systemd-networkd[695]: eth0: Gained carrier
Apr 12 18:45:19.232258 systemd[1]: Reached target network.target.
Apr 12 18:45:19.249000 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'
Apr 12 18:45:19.238354 systemd[1]: Starting iscsiuio.service...
Apr 12 18:45:19.245370 systemd[1]: Started iscsiuio.service.
Apr 12 18:45:19.265000 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'
Apr 12 18:45:19.251597 systemd[1]: Starting iscsid.service...
Apr 12 18:45:19.275699 iscsid[705]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi
Apr 12 18:45:19.275699 iscsid[705]: 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
Apr 12 18:45:19.275699 iscsid[705]: 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].
Apr 12 18:45:19.275699 iscsid[705]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6.
Apr 12 18:45:19.275699 iscsid[705]: If using hardware iscsi like qla4xxx this message can be ignored.
Apr 12 18:45:19.275699 iscsid[705]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi
Apr 12 18:45:19.275699 iscsid[705]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf
Apr 12 18:45:19.295000 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'
Apr 12 18:45:19.261240 systemd[1]: Started iscsid.service.
Apr 12 18:45:19.293287 ignition[664]: Ignition 2.14.0
Apr 12 18:45:19.261751 systemd-networkd[695]: eth0: DHCPv4 address 10.0.0.53/16, gateway 10.0.0.1 acquired from 10.0.0.1
Apr 12 18:45:19.293302 ignition[664]: Stage: fetch-offline
Apr 12 18:45:19.266838 systemd[1]: Starting dracut-initqueue.service...
Apr 12 18:45:19.293364 ignition[664]: no configs at "/usr/lib/ignition/base.d"
Apr 12 18:45:19.287309 systemd[1]: Finished dracut-initqueue.service.
Apr 12 18:45:19.293376 ignition[664]: no config dir at "/usr/lib/ignition/base.platform.d/qemu"
Apr 12 18:45:19.295830 systemd[1]: Reached target remote-fs-pre.target.
Apr 12 18:45:19.293527 ignition[664]: parsed url from cmdline: ""
Apr 12 18:45:19.302417 systemd[1]: Reached target remote-cryptsetup.target.
Apr 12 18:45:19.328000 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'
Apr 12 18:45:19.293533 ignition[664]: no config URL provided
Apr 12 18:45:19.334000 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'
Apr 12 18:45:19.307173 systemd[1]: Reached target remote-fs.target.
Apr 12 18:45:19.293540 ignition[664]: reading system config file "/usr/lib/ignition/user.ign"
Apr 12 18:45:19.314723 systemd[1]: Starting dracut-pre-mount.service...
Apr 12 18:45:19.293549 ignition[664]: no config at "/usr/lib/ignition/user.ign"
Apr 12 18:45:19.326061 unknown[664]: fetched base config from "system"
Apr 12 18:45:19.293572 ignition[664]: op(1): [started] loading QEMU firmware config module
Apr 12 18:45:19.326071 unknown[664]: fetched user config from "qemu"
Apr 12 18:45:19.293579 ignition[664]: op(1): executing: "modprobe" "qemu_fw_cfg"
Apr 12 18:45:19.327705 systemd[1]: Finished ignition-fetch-offline.service.
Apr 12 18:45:19.301328 ignition[664]: op(1): [finished] loading QEMU firmware config module
Apr 12 18:45:19.329213 systemd[1]: ignition-fetch.service was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json).
Apr 12 18:45:19.306604 ignition[664]: parsing config with SHA512: 138d09bada4e75d49eb33f4e211579ce0c7390835de264a9baa0f0820bd82ece3c74db016603902cd18066379c5aee6d8d592ef6584db2124d58fcb0e2fdf279
Apr 12 18:45:19.330384 systemd[1]: Starting ignition-kargs.service...
Apr 12 18:45:19.326322 ignition[664]: fetch-offline: fetch-offline passed
Apr 12 18:45:19.332980 systemd[1]: Finished dracut-pre-mount.service.
Apr 12 18:45:19.326568 ignition[664]: Ignition finished successfully
Apr 12 18:45:19.349219 ignition[721]: Ignition 2.14.0
Apr 12 18:45:19.355000 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'
Apr 12 18:45:19.353655 systemd[1]: Finished ignition-kargs.service.
Apr 12 18:45:19.349228 ignition[721]: Stage: kargs
Apr 12 18:45:19.356336 systemd[1]: Starting ignition-disks.service...
Apr 12 18:45:19.349385 ignition[721]: no configs at "/usr/lib/ignition/base.d"
Apr 12 18:45:19.349397 ignition[721]: no config dir at "/usr/lib/ignition/base.platform.d/qemu"
Apr 12 18:45:19.350249 ignition[721]: kargs: kargs passed
Apr 12 18:45:19.350289 ignition[721]: Ignition finished successfully
Apr 12 18:45:19.364781 ignition[728]: Ignition 2.14.0
Apr 12 18:45:19.364789 ignition[728]: Stage: disks
Apr 12 18:45:19.364947 ignition[728]: no configs at "/usr/lib/ignition/base.d"
Apr 12 18:45:19.364959 ignition[728]: no config dir at "/usr/lib/ignition/base.platform.d/qemu"
Apr 12 18:45:19.369000 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'
Apr 12 18:45:19.367524 systemd[1]: Finished ignition-disks.service.
Apr 12 18:45:19.365816 ignition[728]: disks: disks passed
Apr 12 18:45:19.369318 systemd[1]: Reached target initrd-root-device.target.
Apr 12 18:45:19.365866 ignition[728]: Ignition finished successfully
Apr 12 18:45:19.371577 systemd[1]: Reached target local-fs-pre.target.
Apr 12 18:45:19.372701 systemd[1]: Reached target local-fs.target.
Apr 12 18:45:19.374722 systemd[1]: Reached target sysinit.target.
Apr 12 18:45:19.376575 systemd[1]: Reached target basic.target.
Apr 12 18:45:19.377773 systemd[1]: Starting systemd-fsck-root.service...
Apr 12 18:45:19.390419 systemd-fsck[736]: ROOT: clean, 612/553520 files, 56019/553472 blocks
Apr 12 18:45:19.399165 systemd[1]: Finished systemd-fsck-root.service.
Apr 12 18:45:19.400000 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'
Apr 12 18:45:19.402322 systemd[1]: Mounting sysroot.mount...
Apr 12 18:45:19.413641 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none.
Apr 12 18:45:19.413788 systemd[1]: Mounted sysroot.mount.
Apr 12 18:45:19.415601 systemd[1]: Reached target initrd-root-fs.target.
Apr 12 18:45:19.419002 systemd[1]: Mounting sysroot-usr.mount...
Apr 12 18:45:19.421274 systemd[1]: flatcar-metadata-hostname.service was skipped because no trigger condition checks were met.
Apr 12 18:45:19.421341 systemd[1]: ignition-remount-sysroot.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot).
Apr 12 18:45:19.421370 systemd[1]: Reached target ignition-diskful.target.
Apr 12 18:45:19.430425 systemd[1]: Mounted sysroot-usr.mount.
Apr 12 18:45:19.432237 systemd[1]: Starting initrd-setup-root.service...
Apr 12 18:45:19.440049 initrd-setup-root[746]: cut: /sysroot/etc/passwd: No such file or directory
Apr 12 18:45:19.445125 initrd-setup-root[754]: cut: /sysroot/etc/group: No such file or directory
Apr 12 18:45:19.450028 initrd-setup-root[762]: cut: /sysroot/etc/shadow: No such file or directory
Apr 12 18:45:19.454326 initrd-setup-root[770]: cut: /sysroot/etc/gshadow: No such file or directory
Apr 12 18:45:19.486794 systemd[1]: Finished initrd-setup-root.service.
Apr 12 18:45:19.486000 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'
Apr 12 18:45:19.487838 systemd[1]: Starting ignition-mount.service...
Apr 12 18:45:19.489762 systemd[1]: Starting sysroot-boot.service...
Apr 12 18:45:19.495474 bash[787]: umount: /sysroot/usr/share/oem: not mounted.
Apr 12 18:45:19.504488 ignition[789]: INFO : Ignition 2.14.0
Apr 12 18:45:19.504488 ignition[789]: INFO : Stage: mount
Apr 12 18:45:19.506392 ignition[789]: INFO : no configs at "/usr/lib/ignition/base.d"
Apr 12 18:45:19.506392 ignition[789]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu"
Apr 12 18:45:19.508986 systemd[1]: Finished sysroot-boot.service.
Apr 12 18:45:19.510578 ignition[789]: INFO : mount: mount passed
Apr 12 18:45:19.510578 ignition[789]: INFO : Ignition finished successfully
Apr 12 18:45:19.510000 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'
Apr 12 18:45:19.511000 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'
Apr 12 18:45:19.510879 systemd[1]: Finished ignition-mount.service.
Apr 12 18:45:19.896733 systemd[1]: Mounting sysroot-usr-share-oem.mount...
Apr 12 18:45:19.904659 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/vda6 scanned by mount (797)
Apr 12 18:45:19.904699 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm
Apr 12 18:45:19.904708 kernel: BTRFS info (device vda6): using free space tree
Apr 12 18:45:19.905630 kernel: BTRFS info (device vda6): has skinny extents
Apr 12 18:45:19.909721 systemd[1]: Mounted sysroot-usr-share-oem.mount.
Apr 12 18:45:19.912142 systemd[1]: Starting ignition-files.service...
Apr 12 18:45:19.925716 ignition[817]: INFO : Ignition 2.14.0
Apr 12 18:45:19.925716 ignition[817]: INFO : Stage: files
Apr 12 18:45:19.927393 ignition[817]: INFO : no configs at "/usr/lib/ignition/base.d"
Apr 12 18:45:19.927393 ignition[817]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu"
Apr 12 18:45:19.930073 ignition[817]: DEBUG : files: compiled without relabeling support, skipping
Apr 12 18:45:19.931484 ignition[817]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core"
Apr 12 18:45:19.931484 ignition[817]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core"
Apr 12 18:45:19.934381 ignition[817]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core"
Apr 12 18:45:19.935767 ignition[817]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core"
Apr 12 18:45:19.935767 ignition[817]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core"
Apr 12 18:45:19.935767 ignition[817]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf"
Apr 12 18:45:19.935767 ignition[817]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf"
Apr 12 18:45:19.935767 ignition[817]: INFO : files: op(4): [started] processing unit "etcd-member.service"
Apr 12 18:45:19.935096 unknown[817]: wrote ssh authorized keys file for user: core
Apr 12 18:45:19.945360 ignition[817]: INFO : files: op(4): op(5): [started] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf"
Apr 12 18:45:19.945360 ignition[817]: INFO : files: op(4): op(5): [finished] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf"
Apr 12 18:45:19.945360 ignition[817]: INFO : files: op(4): [finished] processing unit "etcd-member.service"
Apr 12 18:45:19.945360 ignition[817]: INFO : files: op(6): [started] processing unit "coreos-metadata.service"
Apr 12 18:45:19.945360 ignition[817]: INFO : files: op(6): op(7): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service"
Apr 12 18:45:19.945360 ignition[817]: INFO : files: op(6): op(7): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service"
Apr 12 18:45:19.945360 ignition[817]: INFO : files: op(6): [finished] processing unit "coreos-metadata.service"
Apr 12 18:45:19.945360 ignition[817]: INFO : files: op(8): [started] setting preset to enabled for "etcd-member.service"
Apr 12 18:45:19.945360 ignition[817]: INFO : files: op(8): [finished] setting preset to enabled for "etcd-member.service"
Apr 12 18:45:19.945360 ignition[817]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service"
Apr 12 18:45:19.945360 ignition[817]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service"
Apr 12 18:45:19.976808 ignition[817]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service"
Apr 12 18:45:19.978537 ignition[817]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service"
Apr 12 18:45:19.978537 ignition[817]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json"
Apr 12 18:45:19.978537 ignition[817]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json"
Apr 12 18:45:19.978537 ignition[817]: INFO : files: files passed
Apr 12 18:45:19.978537 ignition[817]: INFO : Ignition finished successfully
Apr 12 18:45:19.985410 systemd[1]: Finished ignition-files.service.
Apr 12 18:45:19.984000 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'
Apr 12 18:45:19.986347 systemd[1]: Starting initrd-setup-root-after-ignition.service...
Apr 12 18:45:19.987818 systemd[1]: torcx-profile-populate.service was skipped because of an unmet condition check (ConditionPathExists=/sysroot/etc/torcx/next-profile).
Apr 12 18:45:19.988454 systemd[1]: Starting ignition-quench.service...
Apr 12 18:45:19.991000 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'
Apr 12 18:45:19.991000 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'
Apr 12 18:45:19.990975 systemd[1]: ignition-quench.service: Deactivated successfully.
Apr 12 18:45:19.991037 systemd[1]: Finished ignition-quench.service.
Apr 12 18:45:19.995964 initrd-setup-root-after-ignition[841]: grep: /sysroot/usr/share/oem/oem-release: No such file or directory
Apr 12 18:45:19.998685 initrd-setup-root-after-ignition[843]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory
Apr 12 18:45:20.000735 systemd[1]: Finished initrd-setup-root-after-ignition.service.
Apr 12 18:45:20.002000 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'
Apr 12 18:45:20.002779 systemd[1]: Reached target ignition-complete.target.
Apr 12 18:45:20.005327 systemd[1]: Starting initrd-parse-etc.service...
Apr 12 18:45:20.017253 systemd[1]: initrd-parse-etc.service: Deactivated successfully.
Apr 12 18:45:20.018338 systemd[1]: Finished initrd-parse-etc.service.
Apr 12 18:45:20.019000 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'
Apr 12 18:45:20.019000 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'
Apr 12 18:45:20.020119 systemd[1]: Reached target initrd-fs.target.
Apr 12 18:45:20.021789 systemd[1]: Reached target initrd.target.
Apr 12 18:45:20.023339 systemd[1]: dracut-mount.service was skipped because no trigger condition checks were met.
Apr 12 18:45:20.025330 systemd[1]: Starting dracut-pre-pivot.service...
Apr 12 18:45:20.034656 systemd[1]: Finished dracut-pre-pivot.service.
Apr 12 18:45:20.035000 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'
Apr 12 18:45:20.037228 systemd[1]: Starting initrd-cleanup.service...
Apr 12 18:45:20.048426 systemd[1]: Stopped target network.target.
Apr 12 18:45:20.050564 systemd[1]: Stopped target nss-lookup.target.
Apr 12 18:45:20.052783 systemd[1]: Stopped target remote-cryptsetup.target.
Apr 12 18:45:20.055132 systemd[1]: Stopped target timers.target.
Apr 12 18:45:20.057154 systemd[1]: dracut-pre-pivot.service: Deactivated successfully.
Apr 12 18:45:20.058457 systemd[1]: Stopped dracut-pre-pivot.service.
Apr 12 18:45:20.060000 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'
Apr 12 18:45:20.060786 systemd[1]: Stopped target initrd.target.
Apr 12 18:45:20.062808 systemd[1]: Stopped target basic.target.
Apr 12 18:45:20.064841 systemd[1]: Stopped target ignition-complete.target.
Apr 12 18:45:20.067241 systemd[1]: Stopped target ignition-diskful.target.
Apr 12 18:45:20.070186 systemd[1]: Stopped target initrd-root-device.target.
Apr 12 18:45:20.072086 systemd[1]: Stopped target remote-fs.target.
Apr 12 18:45:20.073715 systemd[1]: Stopped target remote-fs-pre.target.
Apr 12 18:45:20.075533 systemd[1]: Stopped target sysinit.target.
Apr 12 18:45:20.077397 systemd[1]: Stopped target local-fs.target.
Apr 12 18:45:20.079363 systemd[1]: Stopped target local-fs-pre.target.
Apr 12 18:45:20.081199 systemd[1]: Stopped target swap.target.
Apr 12 18:45:20.082779 systemd[1]: dracut-pre-mount.service: Deactivated successfully.
Apr 12 18:45:20.082911 systemd[1]: Stopped dracut-pre-mount.service.
Apr 12 18:45:20.085000 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'
Apr 12 18:45:20.085915 systemd[1]: Stopped target cryptsetup.target.
Apr 12 18:45:20.087624 systemd[1]: dracut-initqueue.service: Deactivated successfully.
Apr 12 18:45:20.088691 systemd[1]: Stopped dracut-initqueue.service.
Apr 12 18:45:20.090000 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'
Apr 12 18:45:20.090847 systemd[1]: ignition-fetch-offline.service: Deactivated successfully.
Apr 12 18:45:20.092170 systemd[1]: Stopped ignition-fetch-offline.service.
Apr 12 18:45:20.094000 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'
Apr 12 18:45:20.094527 systemd[1]: Stopped target paths.target.
Apr 12 18:45:20.096460 systemd[1]: systemd-ask-password-console.path: Deactivated successfully.
Apr 12 18:45:20.101727 systemd[1]: Stopped systemd-ask-password-console.path.
Apr 12 18:45:20.104153 systemd[1]: Stopped target slices.target.
Apr 12 18:45:20.105753 systemd[1]: Stopped target sockets.target.
Apr 12 18:45:20.107468 systemd[1]: iscsid.socket: Deactivated successfully.
Apr 12 18:45:20.108419 systemd[1]: Closed iscsid.socket.
Apr 12 18:45:20.109879 systemd[1]: iscsiuio.socket: Deactivated successfully.
Apr 12 18:45:20.110756 systemd[1]: Closed iscsiuio.socket.
Apr 12 18:45:20.112266 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully.
Apr 12 18:45:20.113499 systemd[1]: Stopped initrd-setup-root-after-ignition.service.
Apr 12 18:45:20.115000 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'
Apr 12 18:45:20.115597 systemd[1]: ignition-files.service: Deactivated successfully.
Apr 12 18:45:20.115697 systemd[1]: Stopped ignition-files.service.
Apr 12 18:45:20.118000 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'
Apr 12 18:45:20.120183 systemd[1]: Stopping ignition-mount.service...
Apr 12 18:45:20.121860 systemd[1]: kmod-static-nodes.service: Deactivated successfully.
Apr 12 18:45:20.122974 systemd[1]: Stopped kmod-static-nodes.service.
Apr 12 18:45:20.124000 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'
Apr 12 18:45:20.125916 systemd[1]: Stopping sysroot-boot.service...
Apr 12 18:45:20.127054 systemd[1]: Stopping systemd-networkd.service...
Apr 12 18:45:20.129570 ignition[856]: INFO : Ignition 2.14.0
Apr 12 18:45:20.129570 ignition[856]: INFO : Stage: umount
Apr 12 18:45:20.129570 ignition[856]: INFO : no configs at "/usr/lib/ignition/base.d"
Apr 12 18:45:20.129570 ignition[856]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu"
Apr 12 18:45:20.129570 ignition[856]: INFO : umount: umount passed
Apr 12 18:45:20.129570 ignition[856]: INFO : Ignition finished successfully
Apr 12 18:45:20.132000 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'
Apr 12 18:45:20.135000 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'
Apr 12 18:45:20.139000 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'
Apr 12 18:45:20.129584 systemd[1]: Stopping systemd-resolved.service...
Apr 12 18:45:20.141000 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'
Apr 12 18:45:20.131062 systemd[1]: systemd-udev-trigger.service: Deactivated successfully.
Apr 12 18:45:20.131810 systemd[1]: Stopped systemd-udev-trigger.service.
Apr 12 18:45:20.145000 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'
Apr 12 18:45:20.145000 audit: BPF prog-id=6 op=UNLOAD
Apr 12 18:45:20.132654 systemd-networkd[695]: eth0: DHCPv6 lease lost
Apr 12 18:45:20.147000 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'
Apr 12 18:45:20.148000 audit: BPF prog-id=9 op=UNLOAD
Apr 12 18:45:20.149000 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'
Apr 12 18:45:20.133147 systemd[1]: dracut-pre-trigger.service: Deactivated successfully.
Apr 12 18:45:20.151000 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'
Apr 12 18:45:20.133283 systemd[1]: Stopped dracut-pre-trigger.service.
Apr 12 18:45:20.154000 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'
Apr 12 18:45:20.156000 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'
Apr 12 18:45:20.157000 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'
Apr 12 18:45:20.138044 systemd[1]: systemd-networkd.service: Deactivated successfully.
Apr 12 18:45:20.138139 systemd[1]: Stopped systemd-networkd.service.
Apr 12 18:45:20.139653 systemd[1]: systemd-resolved.service: Deactivated successfully.
Apr 12 18:45:20.139763 systemd[1]: Stopped systemd-resolved.service.
Apr 12 18:45:20.142544 systemd[1]: sysroot-boot.mount: Deactivated successfully.
Apr 12 18:45:20.144389 systemd[1]: ignition-mount.service: Deactivated successfully.
Apr 12 18:45:20.144524 systemd[1]: Stopped ignition-mount.service.
Apr 12 18:45:20.146152 systemd[1]: systemd-networkd.socket: Deactivated successfully.
Apr 12 18:45:20.146226 systemd[1]: Closed systemd-networkd.socket.
Apr 12 18:45:20.147577 systemd[1]: ignition-disks.service: Deactivated successfully.
Apr 12 18:45:20.147640 systemd[1]: Stopped ignition-disks.service.
Apr 12 18:45:20.148734 systemd[1]: ignition-kargs.service: Deactivated successfully.
Apr 12 18:45:20.170000 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'
Apr 12 18:45:20.170000 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'
Apr 12 18:45:20.148776 systemd[1]: Stopped ignition-kargs.service.
Apr 12 18:45:20.172000 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'
Apr 12 18:45:20.150651 systemd[1]: ignition-setup.service: Deactivated successfully.
Apr 12 18:45:20.150693 systemd[1]: Stopped ignition-setup.service.
Apr 12 18:45:20.175000 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'
Apr 12 18:45:20.152715 systemd[1]: Stopping network-cleanup.service...
Apr 12 18:45:20.176000 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'
Apr 12 18:45:20.153666 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully.
Apr 12 18:45:20.153718 systemd[1]: Stopped parse-ip-for-networkd.service.
Apr 12 18:45:20.154828 systemd[1]: systemd-sysctl.service: Deactivated successfully.
Apr 12 18:45:20.154880 systemd[1]: Stopped systemd-sysctl.service.
Apr 12 18:45:20.156815 systemd[1]: systemd-modules-load.service: Deactivated successfully.
Apr 12 18:45:20.156857 systemd[1]: Stopped systemd-modules-load.service.
Apr 12 18:45:20.158016 systemd[1]: Stopping systemd-udevd.service...
Apr 12 18:45:20.167143 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully.
Apr 12 18:45:20.167866 systemd[1]: initrd-cleanup.service: Deactivated successfully.
Apr 12 18:45:20.167997 systemd[1]: Finished initrd-cleanup.service.
Apr 12 18:45:20.171021 systemd[1]: sysroot-boot.service: Deactivated successfully.
Apr 12 18:45:20.171115 systemd[1]: Stopped sysroot-boot.service.
Apr 12 18:45:20.174496 systemd[1]: initrd-setup-root.service: Deactivated successfully.
Apr 12 18:45:20.174555 systemd[1]: Stopped initrd-setup-root.service.
Apr 12 18:45:20.176149 systemd[1]: network-cleanup.service: Deactivated successfully.
Apr 12 18:45:20.176246 systemd[1]: Stopped network-cleanup.service.
Apr 12 18:45:20.186556 systemd[1]: systemd-udevd.service: Deactivated successfully.
Apr 12 18:45:20.190123 systemd[1]: Stopped systemd-udevd.service.
Apr 12 18:45:20.196000 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'
Apr 12 18:45:20.196945 systemd[1]: systemd-udevd-control.socket: Deactivated successfully.
Apr 12 18:45:20.197003 systemd[1]: Closed systemd-udevd-control.socket.
Apr 12 18:45:20.200242 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully.
Apr 12 18:45:20.200285 systemd[1]: Closed systemd-udevd-kernel.socket.
Apr 12 18:45:20.203281 systemd[1]: dracut-pre-udev.service: Deactivated successfully.
Apr 12 18:45:20.204444 systemd[1]: Stopped dracut-pre-udev.service.
Apr 12 18:45:20.206000 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'
Apr 12 18:45:20.206479 systemd[1]: dracut-cmdline.service: Deactivated successfully.
Apr 12 18:45:20.206539 systemd[1]: Stopped dracut-cmdline.service.
Apr 12 18:45:20.209000 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'
Apr 12 18:45:20.209570 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully.
Apr 12 18:45:20.210770 systemd[1]: Stopped dracut-cmdline-ask.service.
Apr 12 18:45:20.212000 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'
Apr 12 18:45:20.213788 systemd[1]: Starting initrd-udevadm-cleanup-db.service...
Apr 12 18:45:20.215968 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
Apr 12 18:45:20.217110 systemd[1]: Stopped systemd-vconsole-setup.service.
Apr 12 18:45:20.218000 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'
Apr 12 18:45:20.219191 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.
Apr 12 18:45:20.220329 systemd[1]: Finished initrd-udevadm-cleanup-db.service.
Apr 12 18:45:20.222000 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'
Apr 12 18:45:20.222000 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'
Apr 12 18:45:20.222462 systemd[1]: Reached target initrd-switch-root.target.
Apr 12 18:45:20.224955 systemd[1]: Starting initrd-switch-root.service...
Apr 12 18:45:20.237626 systemd[1]: Switching root.
Apr 12 18:45:20.261355 iscsid[705]: iscsid shutting down.
Apr 12 18:45:20.262160 systemd-journald[198]: Received SIGTERM from PID 1 (systemd).
Apr 12 18:45:20.262217 systemd-journald[198]: Journal stopped
Apr 12 18:45:22.614317 kernel: SELinux: Class mctp_socket not defined in policy.
Apr 12 18:45:22.614376 kernel: SELinux: Class anon_inode not defined in policy.
Apr 12 18:45:22.614395 kernel: SELinux: the above unknown classes and permissions will be allowed
Apr 12 18:45:22.614408 kernel: SELinux: policy capability network_peer_controls=1
Apr 12 18:45:22.614421 kernel: SELinux: policy capability open_perms=1
Apr 12 18:45:22.614435 kernel: SELinux: policy capability extended_socket_class=1
Apr 12 18:45:22.614449 kernel: SELinux: policy capability always_check_network=0
Apr 12 18:45:22.614461 kernel: SELinux: policy capability cgroup_seclabel=1
Apr 12 18:45:22.614476 kernel: SELinux: policy capability nnp_nosuid_transition=1
Apr 12 18:45:22.614489 kernel: SELinux: policy capability genfs_seclabel_symlinks=0
Apr 12 18:45:22.614504 kernel: SELinux: policy capability ioctl_skip_cloexec=0
Apr 12 18:45:22.614518 systemd[1]: Successfully loaded SELinux policy in 42.821ms.
Apr 12 18:45:22.614546 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 7.622ms.
Apr 12 18:45:22.614567 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)
Apr 12 18:45:22.614582 systemd[1]: Detected virtualization kvm.
Apr 12 18:45:22.614595 systemd[1]: Detected architecture x86-64.
Apr 12 18:45:22.614611 systemd[1]: Detected first boot.
Apr 12 18:45:22.614639 systemd[1]: Initializing machine ID from VM UUID.
Apr 12 18:45:22.614653 kernel: SELinux: Context system_u:object_r:container_file_t:s0:c1022,c1023 is not valid (left unmapped).
Apr 12 18:45:22.614666 systemd[1]: Populated /etc with preset unit settings.
Apr 12 18:45:22.614681 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon.
Apr 12 18:45:22.614695 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon.
Apr 12 18:45:22.614710 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.
Apr 12 18:45:22.614727 kernel: kauditd_printk_skb: 78 callbacks suppressed
Apr 12 18:45:22.614740 kernel: audit: type=1334 audit(1712947522.464:82): prog-id=12 op=LOAD
Apr 12 18:45:22.614753 kernel: audit: type=1334 audit(1712947522.465:83): prog-id=3 op=UNLOAD
Apr 12 18:45:22.614767 kernel: audit: type=1334 audit(1712947522.466:84): prog-id=13 op=LOAD
Apr 12 18:45:22.614780 kernel: audit: type=1334 audit(1712947522.468:85): prog-id=14 op=LOAD
Apr 12 18:45:22.614793 systemd[1]: iscsiuio.service: Deactivated successfully.
Apr 12 18:45:22.614815 kernel: audit: type=1334 audit(1712947522.468:86): prog-id=4 op=UNLOAD
Apr 12 18:45:22.614829 systemd[1]: Stopped iscsiuio.service.
Apr 12 18:45:22.614842 kernel: audit: type=1334 audit(1712947522.468:87): prog-id=5 op=UNLOAD
Apr 12 18:45:22.614859 kernel: audit: type=1131 audit(1712947522.469:88): 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'
Apr 12 18:45:22.614873 kernel: audit: type=1131 audit(1712947522.477:89): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Apr 12 18:45:22.614886 systemd[1]: iscsid.service: Deactivated successfully.
Apr 12 18:45:22.614901 systemd[1]: Stopped iscsid.service.
Apr 12 18:45:22.614916 kernel: audit: type=1131 audit(1712947522.482:90): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Apr 12 18:45:22.614930 systemd[1]: initrd-switch-root.service: Deactivated successfully.
Apr 12 18:45:22.614947 kernel: audit: type=1334 audit(1712947522.486:91): prog-id=12 op=UNLOAD
Apr 12 18:45:22.614960 systemd[1]: Stopped initrd-switch-root.service.
Apr 12 18:45:22.614976 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.
Apr 12 18:45:22.614991 systemd[1]: Created slice system-addon\x2dconfig.slice.
Apr 12 18:45:22.615006 systemd[1]: Created slice system-addon\x2drun.slice.
Apr 12 18:45:22.615020 systemd[1]: Created slice system-getty.slice.
Apr 12 18:45:22.615034 systemd[1]: Created slice system-modprobe.slice.
Apr 12 18:45:22.615049 systemd[1]: Created slice system-serial\x2dgetty.slice.
Apr 12 18:45:22.615066 systemd[1]: Created slice system-system\x2dcloudinit.slice.
Apr 12 18:45:22.615081 systemd[1]: Created slice system-systemd\x2dfsck.slice.
Apr 12 18:45:22.615095 systemd[1]: Created slice user.slice.
Apr 12 18:45:22.615110 systemd[1]: Started systemd-ask-password-console.path.
Apr 12 18:45:22.615124 systemd[1]: Started systemd-ask-password-wall.path.
Apr 12 18:45:22.615139 systemd[1]: Set up automount boot.automount.
Apr 12 18:45:22.615154 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount.
Apr 12 18:45:22.615169 systemd[1]: Stopped target initrd-switch-root.target.
Apr 12 18:45:22.615183 systemd[1]: Stopped target initrd-fs.target.
Apr 12 18:45:22.615200 systemd[1]: Stopped target initrd-root-fs.target.
Apr 12 18:45:22.615214 systemd[1]: Reached target integritysetup.target.
Apr 12 18:45:22.615228 systemd[1]: Reached target remote-cryptsetup.target.
Apr 12 18:45:22.615247 systemd[1]: Reached target remote-fs.target.
Apr 12 18:45:22.615262 systemd[1]: Reached target slices.target.
Apr 12 18:45:22.615276 systemd[1]: Reached target swap.target.
Apr 12 18:45:22.615293 systemd[1]: Reached target torcx.target.
Apr 12 18:45:22.615308 systemd[1]: Reached target veritysetup.target.
Apr 12 18:45:22.615322 systemd[1]: Listening on systemd-coredump.socket.
Apr 12 18:45:22.615337 systemd[1]: Listening on systemd-initctl.socket.
Apr 12 18:45:22.615352 systemd[1]: Listening on systemd-networkd.socket.
Apr 12 18:45:22.615367 systemd[1]: Listening on systemd-udevd-control.socket.
Apr 12 18:45:22.615380 systemd[1]: Listening on systemd-udevd-kernel.socket.
Apr 12 18:45:22.615395 systemd[1]: Listening on systemd-userdbd.socket.
Apr 12 18:45:22.615413 systemd[1]: Mounting dev-hugepages.mount...
Apr 12 18:45:22.615430 systemd[1]: Mounting dev-mqueue.mount...
Apr 12 18:45:22.615444 systemd[1]: Mounting media.mount...
Apr 12 18:45:22.615459 systemd[1]: proc-xen.mount was skipped because of an unmet condition check (ConditionVirtualization=xen).
Apr 12 18:45:22.615473 systemd[1]: Mounting sys-kernel-debug.mount...
Apr 12 18:45:22.615489 systemd[1]: Mounting sys-kernel-tracing.mount...
Apr 12 18:45:22.615503 systemd[1]: Mounting tmp.mount...
Apr 12 18:45:22.615517 systemd[1]: Starting flatcar-tmpfiles.service...
Apr 12 18:45:22.615532 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met.
Apr 12 18:45:22.615546 systemd[1]: Starting kmod-static-nodes.service...
Apr 12 18:45:22.615563 systemd[1]: Starting modprobe@configfs.service...
Apr 12 18:45:22.615579 systemd[1]: Starting modprobe@dm_mod.service...
Apr 12 18:45:22.615594 systemd[1]: Starting modprobe@drm.service...
Apr 12 18:45:22.615609 systemd[1]: Starting modprobe@efi_pstore.service...
Apr 12 18:45:22.615649 systemd[1]: Starting modprobe@fuse.service...
Apr 12 18:45:22.615664 systemd[1]: Starting modprobe@loop.service...
Apr 12 18:45:22.615680 systemd[1]: setup-nsswitch.service was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf).
Apr 12 18:45:22.615695 systemd[1]: systemd-fsck-root.service: Deactivated successfully.
Apr 12 18:45:22.615709 systemd[1]: Stopped systemd-fsck-root.service.
Apr 12 18:45:22.615726 systemd[1]: systemd-fsck-usr.service: Deactivated successfully.
Apr 12 18:45:22.615741 systemd[1]: Stopped systemd-fsck-usr.service.
Apr 12 18:45:22.615754 kernel: fuse: init (API version 7.34)
Apr 12 18:45:22.615769 systemd[1]: Stopped systemd-journald.service.
Apr 12 18:45:22.615782 kernel: loop: module loaded
Apr 12 18:45:22.615795 systemd[1]: Starting systemd-journald.service...
Apr 12 18:45:22.615819 systemd[1]: Starting systemd-modules-load.service...
Apr 12 18:45:22.615833 systemd[1]: Starting systemd-network-generator.service...
Apr 12 18:45:22.615847 systemd[1]: Starting systemd-remount-fs.service...
Apr 12 18:45:22.615863 systemd[1]: Starting systemd-udev-trigger.service...
Apr 12 18:45:22.615877 systemd[1]: verity-setup.service: Deactivated successfully.
Apr 12 18:45:22.615892 systemd[1]: Stopped verity-setup.service.
Apr 12 18:45:22.615906 systemd[1]: xenserver-pv-version.service was skipped because of an unmet condition check (ConditionVirtualization=xen).
Apr 12 18:45:22.615920 systemd[1]: Mounted dev-hugepages.mount.
Apr 12 18:45:22.615933 systemd[1]: Mounted dev-mqueue.mount.
Apr 12 18:45:22.615948 systemd-journald[967]: Journal started
Apr 12 18:45:22.615997 systemd-journald[967]: Runtime Journal (/run/log/journal/ed3f747e97df4da6bf539c0b55b8958a) is 6.0M, max 48.4M, 42.4M free.
Apr 12 18:45:20.326000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1
Apr 12 18:45:20.362000 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
Apr 12 18:45:20.362000 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
Apr 12 18:45:20.362000 audit: BPF prog-id=10 op=LOAD
Apr 12 18:45:20.362000 audit: BPF prog-id=10 op=UNLOAD
Apr 12 18:45:20.362000 audit: BPF prog-id=11 op=LOAD
Apr 12 18:45:20.362000 audit: BPF prog-id=11 op=UNLOAD
Apr 12 18:45:20.398000 audit[889]: AVC avc: denied { associate } for pid=889 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"
Apr 12 18:45:20.398000 audit[889]: SYSCALL arch=c000003e syscall=188 success=yes exit=0 a0=c0001858e2 a1=c00002ae40 a2=c000029100 a3=32 items=0 ppid=872 pid=889 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)
Apr 12 18:45:20.398000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61
Apr 12 18:45:20.400000 audit[889]: AVC avc: denied { associate } for pid=889 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
Apr 12 18:45:20.400000 audit[889]: SYSCALL arch=c000003e syscall=258 success=yes exit=0 a0=ffffffffffffff9c a1=c0001859b9 a2=1ed a3=0 items=2 ppid=872 pid=889 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)
Apr 12 18:45:20.400000 audit: CWD cwd="/"
Apr 12 18:45:20.400000 audit: PATH item=0 name=(null) inode=2 dev=00:1c 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
Apr 12 18:45:20.400000 audit: PATH item=1 name=(null) inode=3 dev=00:1c 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
Apr 12 18:45:20.400000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61
Apr 12 18:45:22.464000 audit: BPF prog-id=12 op=LOAD
Apr 12 18:45:22.465000 audit: BPF prog-id=3 op=UNLOAD
Apr 12 18:45:22.466000 audit: BPF prog-id=13 op=LOAD
Apr 12 18:45:22.468000 audit: BPF prog-id=14 op=LOAD
Apr 12 18:45:22.468000 audit: BPF prog-id=4 op=UNLOAD
Apr 12 18:45:22.468000 audit: BPF prog-id=5 op=UNLOAD
Apr 12 18:45:22.469000 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'
Apr 12 18:45:22.477000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Apr 12 18:45:22.482000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Apr 12 18:45:22.486000 audit: BPF prog-id=12 op=UNLOAD
Apr 12 18:45:22.489000 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'
Apr 12 18:45:22.489000 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'
Apr 12 18:45:22.586000 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'
Apr 12 18:45:22.590000 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'
Apr 12 18:45:22.592000 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'
Apr 12 18:45:22.592000 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'
Apr 12 18:45:22.593000 audit: BPF prog-id=15 op=LOAD
Apr 12 18:45:22.593000 audit: BPF prog-id=16 op=LOAD
Apr 12 18:45:22.593000 audit: BPF prog-id=17 op=LOAD
Apr 12 18:45:22.593000 audit: BPF prog-id=13 op=UNLOAD
Apr 12 18:45:22.593000 audit: BPF prog-id=14 op=UNLOAD
Apr 12 18:45:22.609000 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'
Apr 12 18:45:22.612000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1
Apr 12 18:45:22.612000 audit[967]: SYSCALL arch=c000003e syscall=46 success=yes exit=60 a0=3 a1=7fffc59897d0 a2=4000 a3=7fffc598986c items=0 ppid=1 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="systemd-journal" exe="/usr/lib/systemd/systemd-journald" subj=system_u:system_r:kernel_t:s0 key=(null)
Apr 12 18:45:22.612000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald"
Apr 12 18:45:22.463392 systemd[1]: Queued start job for default target multi-user.target.
Apr 12 18:45:20.396533 /usr/lib/systemd/system-generators/torcx-generator[889]: time="2024-04-12T18:45:20Z" 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.3 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.3 /var/lib/torcx/store]"
Apr 12 18:45:22.463402 systemd[1]: Unnecessary job was removed for dev-vda6.device.
Apr 12 18:45:22.617667 systemd[1]: Started systemd-journald.service.
Apr 12 18:45:20.396808 /usr/lib/systemd/system-generators/torcx-generator[889]: time="2024-04-12T18:45:20Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json
Apr 12 18:45:22.469754 systemd[1]: systemd-journald.service: Deactivated successfully.
Apr 12 18:45:20.396851 /usr/lib/systemd/system-generators/torcx-generator[889]: time="2024-04-12T18:45:20Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json
Apr 12 18:45:20.396905 /usr/lib/systemd/system-generators/torcx-generator[889]: time="2024-04-12T18:45:20Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12"
Apr 12 18:45:20.396919 /usr/lib/systemd/system-generators/torcx-generator[889]: time="2024-04-12T18:45:20Z" level=debug msg="skipped missing lower profile" missing profile=oem
Apr 12 18:45:20.396957 /usr/lib/systemd/system-generators/torcx-generator[889]: time="2024-04-12T18:45:20Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory"
Apr 12 18:45:20.396972 /usr/lib/systemd/system-generators/torcx-generator[889]: time="2024-04-12T18:45:20Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)=
Apr 12 18:45:20.397232 /usr/lib/systemd/system-generators/torcx-generator[889]: time="2024-04-12T18:45:20Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack
Apr 12 18:45:20.397281 /usr/lib/systemd/system-generators/torcx-generator[889]: time="2024-04-12T18:45:20Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json
Apr 12 18:45:22.617000 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'
Apr 12 18:45:20.397298 /usr/lib/systemd/system-generators/torcx-generator[889]: time="2024-04-12T18:45:20Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json
Apr 12 18:45:20.398060 /usr/lib/systemd/system-generators/torcx-generator[889]: time="2024-04-12T18:45:20Z" 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
Apr 12 18:45:22.618717 systemd[1]: Mounted media.mount.
Apr 12 18:45:20.398108 /usr/lib/systemd/system-generators/torcx-generator[889]: time="2024-04-12T18:45:20Z" 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
Apr 12 18:45:20.398131 /usr/lib/systemd/system-generators/torcx-generator[889]: time="2024-04-12T18:45:20Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3510.3.3: no such file or directory" path=/usr/share/oem/torcx/store/3510.3.3
Apr 12 18:45:20.398148 /usr/lib/systemd/system-generators/torcx-generator[889]: time="2024-04-12T18:45:20Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store
Apr 12 18:45:20.398171 /usr/lib/systemd/system-generators/torcx-generator[889]: time="2024-04-12T18:45:20Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3510.3.3: no such file or directory" path=/var/lib/torcx/store/3510.3.3
Apr 12 18:45:20.398188 /usr/lib/systemd/system-generators/torcx-generator[889]: time="2024-04-12T18:45:20Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store
Apr 12 18:45:22.220639 /usr/lib/systemd/system-generators/torcx-generator[889]: time="2024-04-12T18:45:22Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl
Apr 12 18:45:22.220884 /usr/lib/systemd/system-generators/torcx-generator[889]: time="2024-04-12T18:45:22Z" 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
Apr 12 18:45:22.220970 /usr/lib/systemd/system-generators/torcx-generator[889]: time="2024-04-12T18:45:22Z" 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
Apr 12 18:45:22.221110 /usr/lib/systemd/system-generators/torcx-generator[889]: time="2024-04-12T18:45:22Z" 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
Apr 12 18:45:22.221152 /usr/lib/systemd/system-generators/torcx-generator[889]: time="2024-04-12T18:45:22Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile=
Apr 12 18:45:22.221202 /usr/lib/systemd/system-generators/torcx-generator[889]: time="2024-04-12T18:45:22Z" 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
Apr 12 18:45:22.619788 systemd[1]: Mounted sys-kernel-debug.mount.
Apr 12 18:45:22.620701 systemd[1]: Mounted sys-kernel-tracing.mount.
Apr 12 18:45:22.621673 systemd[1]: Mounted tmp.mount.
Apr 12 18:45:22.622535 systemd[1]: Finished flatcar-tmpfiles.service.
Apr 12 18:45:22.622000 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'
Apr 12 18:45:22.623561 systemd[1]: Finished kmod-static-nodes.service.
Apr 12 18:45:22.623000 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'
Apr 12 18:45:22.624584 systemd[1]: modprobe@configfs.service: Deactivated successfully.
Apr 12 18:45:22.624714 systemd[1]: Finished modprobe@configfs.service.
Apr 12 18:45:22.624000 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'
Apr 12 18:45:22.624000 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'
Apr 12 18:45:22.625773 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Apr 12 18:45:22.625903 systemd[1]: Finished modprobe@dm_mod.service.
Apr 12 18:45:22.626000 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'
Apr 12 18:45:22.626000 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'
Apr 12 18:45:22.626925 systemd[1]: modprobe@drm.service: Deactivated successfully.
Apr 12 18:45:22.627037 systemd[1]: Finished modprobe@drm.service.
Apr 12 18:45:22.627000 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'
Apr 12 18:45:22.627000 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'
Apr 12 18:45:22.627989 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Apr 12 18:45:22.628102 systemd[1]: Finished modprobe@efi_pstore.service.
Apr 12 18:45:22.628000 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'
Apr 12 18:45:22.628000 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'
Apr 12 18:45:22.629130 systemd[1]: modprobe@fuse.service: Deactivated successfully.
Apr 12 18:45:22.629253 systemd[1]: Finished modprobe@fuse.service.
Apr 12 18:45:22.629000 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'
Apr 12 18:45:22.629000 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'
Apr 12 18:45:22.630230 systemd[1]: modprobe@loop.service: Deactivated successfully.
Apr 12 18:45:22.630341 systemd[1]: Finished modprobe@loop.service.
Apr 12 18:45:22.630000 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'
Apr 12 18:45:22.630000 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'
Apr 12 18:45:22.631367 systemd[1]: Finished systemd-modules-load.service.
Apr 12 18:45:22.631000 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'
Apr 12 18:45:22.632418 systemd[1]: Finished systemd-network-generator.service.
Apr 12 18:45:22.632000 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'
Apr 12 18:45:22.633546 systemd[1]: Finished systemd-remount-fs.service.
Apr 12 18:45:22.633000 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'
Apr 12 18:45:22.634823 systemd[1]: Reached target network-pre.target.
Apr 12 18:45:22.636470 systemd[1]: Mounting sys-fs-fuse-connections.mount...
Apr 12 18:45:22.638174 systemd[1]: Mounting sys-kernel-config.mount...
Apr 12 18:45:22.639039 systemd[1]: remount-root.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).
Apr 12 18:45:22.639999 systemd[1]: Starting systemd-hwdb-update.service...
Apr 12 18:45:22.649239 systemd[1]: Starting systemd-journal-flush.service...
Apr 12 18:45:22.650341 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
Apr 12 18:45:22.651022 systemd[1]: Starting systemd-random-seed.service...
Apr 12 18:45:22.652108 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met.
Apr 12 18:45:22.653701 systemd-journald[967]: Time spent on flushing to /var/log/journal/ed3f747e97df4da6bf539c0b55b8958a is 13.517ms for 1120 entries.
Apr 12 18:45:22.653701 systemd-journald[967]: System Journal (/var/log/journal/ed3f747e97df4da6bf539c0b55b8958a) is 8.0M, max 195.6M, 187.6M free.
Apr 12 18:45:22.829814 systemd-journald[967]: Received client request to flush runtime journal.
Apr 12 18:45:22.672000 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'
Apr 12 18:45:22.673000 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'
Apr 12 18:45:22.698000 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'
Apr 12 18:45:22.740000 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'
Apr 12 18:45:22.652762 systemd[1]: Starting systemd-sysctl.service...
Apr 12 18:45:22.654909 systemd[1]: Starting systemd-sysusers.service...
Apr 12 18:45:22.657367 systemd[1]: Mounted sys-fs-fuse-connections.mount.
Apr 12 18:45:22.658557 systemd[1]: Mounted sys-kernel-config.mount.
Apr 12 18:45:22.830633 udevadm[995]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in.
Apr 12 18:45:22.672104 systemd[1]: Finished systemd-sysctl.service.
Apr 12 18:45:22.673381 systemd[1]: Finished systemd-sysusers.service.
Apr 12 18:45:22.697869 systemd[1]: Finished systemd-udev-trigger.service.
Apr 12 18:45:22.700300 systemd[1]: Starting systemd-udev-settle.service...
Apr 12 18:45:22.740147 systemd[1]: Finished systemd-random-seed.service.
Apr 12 18:45:22.741216 systemd[1]: Reached target first-boot-complete.target.
Apr 12 18:45:22.830740 systemd[1]: Finished systemd-journal-flush.service.
Apr 12 18:45:22.831000 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'
Apr 12 18:45:23.161070 systemd[1]: Finished systemd-hwdb-update.service.
Apr 12 18:45:23.161000 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'
Apr 12 18:45:23.161000 audit: BPF prog-id=18 op=LOAD
Apr 12 18:45:23.162000 audit: BPF prog-id=19 op=LOAD
Apr 12 18:45:23.162000 audit: BPF prog-id=7 op=UNLOAD
Apr 12 18:45:23.162000 audit: BPF prog-id=8 op=UNLOAD
Apr 12 18:45:23.163211 systemd[1]: Starting systemd-udevd.service...
Apr 12 18:45:23.177310 systemd-udevd[997]: Using default interface naming scheme 'v252'.
Apr 12 18:45:23.188969 systemd[1]: Started systemd-udevd.service.
Apr 12 18:45:23.189000 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'
Apr 12 18:45:23.191000 audit: BPF prog-id=20 op=LOAD
Apr 12 18:45:23.192633 systemd[1]: Starting systemd-networkd.service...
Apr 12 18:45:23.198000 audit: BPF prog-id=21 op=LOAD
Apr 12 18:45:23.198000 audit: BPF prog-id=22 op=LOAD
Apr 12 18:45:23.198000 audit: BPF prog-id=23 op=LOAD
Apr 12 18:45:23.199949 systemd[1]: Starting systemd-userdbd.service...
Apr 12 18:45:23.220059 systemd[1]: Condition check resulted in dev-ttyS0.device being skipped.
Apr 12 18:45:23.235753 systemd[1]: Started systemd-userdbd.service.
Apr 12 18:45:23.236000 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'
Apr 12 18:45:23.241143 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device.
Apr 12 18:45:23.260640 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2
Apr 12 18:45:23.265638 kernel: ACPI: button: Power Button [PWRF]
Apr 12 18:45:23.278000 audit[1024]: AVC avc: denied { confidentiality } for pid=1024 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
Apr 12 18:45:23.278000 audit[1024]: SYSCALL arch=c000003e syscall=175 success=yes exit=0 a0=55e30d320a80 a1=32194 a2=7fa57b42dbc5 a3=5 items=108 ppid=997 pid=1024 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)
Apr 12 18:45:23.278000 audit: CWD cwd="/"
Apr 12 18:45:23.278000 audit: PATH item=0 name=(null) inode=50 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
Apr 12 18:45:23.278000 audit: PATH item=1 name=(null) inode=1819 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
Apr 12 18:45:23.278000 audit: PATH item=2 name=(null) inode=1819 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
Apr 12 18:45:23.278000 audit: PATH item=3 name=(null) inode=1820 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
Apr 12 18:45:23.278000 audit: PATH item=4 name=(null) inode=1819 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
Apr 12 18:45:23.278000 audit: PATH item=5 name=(null) inode=1821 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
Apr 12 18:45:23.278000 audit: PATH item=6 name=(null) inode=1819 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
Apr 12 18:45:23.278000 audit: PATH item=7 name=(null) inode=1822 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
Apr 12 18:45:23.278000 audit: PATH item=8 name=(null) inode=1822 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
Apr 12 18:45:23.278000 audit: PATH item=9 name=(null) inode=1823 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
Apr 12 18:45:23.278000 audit: PATH item=10 name=(null) inode=1822 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
Apr 12 18:45:23.278000 audit: PATH item=11 name=(null) inode=1824 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
Apr 12 18:45:23.278000 audit: PATH item=12 name=(null) inode=1822 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
Apr 12 18:45:23.278000 audit: PATH item=13 name=(null) inode=1825 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
Apr 12 18:45:23.278000 audit: PATH item=14 name=(null) inode=1822 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
Apr 12 18:45:23.278000 audit: PATH item=15 name=(null) inode=1826 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
Apr 12 18:45:23.278000 audit: PATH item=16 name=(null) inode=1822 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
Apr 12 18:45:23.278000 audit: PATH item=17 name=(null) inode=1827 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
Apr 12 18:45:23.278000 audit: PATH item=18 name=(null) inode=1819 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
Apr 12 18:45:23.278000 audit: PATH item=19 name=(null) inode=1828 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
Apr 12 18:45:23.278000 audit: PATH item=20 name=(null) inode=1828 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
Apr 12 18:45:23.278000 audit: PATH item=21 name=(null) inode=1829 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
Apr 12 18:45:23.278000 audit: PATH item=22 name=(null) inode=1828 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
Apr 12 18:45:23.278000 audit: PATH item=23 name=(null) inode=1830 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
Apr 12 18:45:23.278000 audit: PATH item=24 name=(null) inode=1828 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
Apr 12 18:45:23.278000 audit: PATH item=25 name=(null) inode=1831 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
Apr 12 18:45:23.278000 audit: PATH item=26 name=(null) inode=1828 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
Apr 12 18:45:23.278000 audit: PATH item=27 name=(null) inode=1832 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
Apr 12 18:45:23.278000 audit: PATH item=28 name=(null) inode=1828 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
Apr 12 18:45:23.278000 audit: PATH item=29 name=(null) inode=1833 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
Apr 12 18:45:23.278000 audit: PATH item=30 name=(null) inode=1819 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
Apr 12 18:45:23.278000 audit: PATH item=31 name=(null) inode=1834 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
Apr 12 18:45:23.278000 audit: PATH item=32 name=(null) inode=1834 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
Apr 12 18:45:23.278000 audit: PATH item=33 name=(null) inode=1835 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
Apr 12 18:45:23.278000 audit: PATH item=34 name=(null) inode=1834 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
Apr 12 18:45:23.278000 audit: PATH item=35 name=(null) inode=1836 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
Apr 12 18:45:23.278000 audit: PATH item=36 name=(null) inode=1834 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
Apr 12 18:45:23.278000 audit: PATH item=37 name=(null) inode=1837 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
Apr 12 18:45:23.278000 audit: PATH item=38 name=(null) inode=1834 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
Apr 12 18:45:23.278000 audit: PATH item=39 name=(null) inode=1838 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
Apr 12 18:45:23.278000 audit: PATH item=40 name=(null) inode=1834 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
Apr 12 18:45:23.278000 audit: PATH item=41 name=(null) inode=1839 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
Apr 12 18:45:23.278000 audit: PATH item=42 name=(null) inode=1819 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
Apr 12 18:45:23.278000 audit: PATH item=43 name=(null) inode=1840 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
Apr 12 18:45:23.278000 audit: PATH item=44 name=(null) inode=1840 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
Apr 12 18:45:23.278000 audit: PATH item=45 name=(null) inode=1841 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
Apr 12 18:45:23.278000 audit: PATH item=46 name=(null) inode=1840 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
Apr 12 18:45:23.278000 audit: PATH item=47 name=(null) inode=1842 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
Apr 12 18:45:23.278000 audit: PATH item=48 name=(null) inode=1840 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
Apr 12 18:45:23.278000 audit: PATH item=49 name=(null) inode=1843 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
Apr 12 18:45:23.278000 audit: PATH item=50 name=(null) inode=1840 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
Apr 12 18:45:23.278000 audit: PATH item=51 name=(null) inode=1844 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
Apr 12 18:45:23.278000 audit: PATH item=52 name=(null) inode=1840 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
Apr 12 18:45:23.278000 audit: PATH item=53 name=(null) inode=1845 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
Apr 12 18:45:23.278000 audit: PATH item=54 name=(null) inode=50 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
Apr 12 18:45:23.278000 audit: PATH item=55 name=(null) inode=1846 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
Apr 12 18:45:23.278000 audit: PATH item=56 name=(null) inode=1846 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
Apr 12 18:45:23.278000 audit: PATH item=57 name=(null) inode=1847 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
Apr 12 18:45:23.278000 audit: PATH item=58 name=(null) inode=1846 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
Apr 12 18:45:23.278000 audit: PATH item=59 name=(null) inode=1848 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
Apr 12 18:45:23.278000 audit: PATH item=60 name=(null) inode=1846 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
Apr 12 18:45:23.278000 audit: PATH item=61 name=(null) inode=1849 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
Apr 12 18:45:23.278000 audit: PATH item=62 name=(null) inode=1849 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
Apr 12 18:45:23.278000 audit: PATH item=63 name=(null) inode=1850 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
Apr 12 18:45:23.278000 audit: PATH item=64 name=(null) inode=1849 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
Apr 12 18:45:23.278000 audit: PATH item=65 name=(null) inode=1851 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
Apr 12 18:45:23.278000 audit: PATH item=66 name=(null) inode=1849 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
Apr 12 18:45:23.278000 audit: PATH item=67 name=(null) inode=1852 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
Apr 12 18:45:23.278000 audit: PATH item=68 name=(null) inode=1849 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
Apr 12 18:45:23.278000 audit: PATH item=69 name=(null) inode=1853 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
Apr 12 18:45:23.278000 audit: PATH item=70 name=(null) inode=1849 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
Apr 12 18:45:23.278000 audit: PATH item=71 name=(null) inode=1854 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
Apr 12 18:45:23.278000 audit: PATH item=72 name=(null) inode=1846 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
Apr 12 18:45:23.278000 audit: PATH item=73 name=(null) inode=1855 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
Apr 12 18:45:23.278000 audit: PATH item=74 name=(null) inode=1855 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
Apr 12 18:45:23.278000 audit: PATH item=75 name=(null) inode=1856 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
Apr 12 18:45:23.278000 audit: PATH item=76 name=(null) inode=1855 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
Apr 12 18:45:23.278000 audit: PATH item=77 name=(null) inode=1857 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
Apr 12 18:45:23.278000 audit: PATH item=78 name=(null) inode=1855 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
Apr 12 18:45:23.278000 audit: PATH item=79 name=(null) inode=1858 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
Apr 12 18:45:23.278000 audit: PATH item=80 name=(null) inode=1855 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
Apr 12 18:45:23.278000 audit: PATH item=81 name=(null) inode=1859 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
Apr 12 18:45:23.278000 audit: PATH item=82 name=(null) inode=1855 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
Apr 12 18:45:23.278000 audit: PATH item=83 name=(null) inode=1860 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
Apr 12 18:45:23.278000 audit: PATH item=84 name=(null) inode=1846 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
Apr 12 18:45:23.278000 audit: PATH item=85 name=(null) inode=1861 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
Apr 12 18:45:23.278000 audit: PATH item=86 name=(null) inode=1861 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
Apr 12 18:45:23.278000 audit: PATH item=87 name=(null) inode=1862 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
Apr 12 18:45:23.278000 audit: PATH item=88 name=(null) inode=1861 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
Apr 12 18:45:23.278000 audit: PATH item=89 name=(null) inode=1863 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
Apr 12 18:45:23.278000 audit: PATH item=90 name=(null) inode=1861 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
Apr 12 18:45:23.278000 audit: PATH item=91 name=(null) inode=1864 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
Apr 12 18:45:23.278000 audit: PATH item=92 name=(null) inode=1861 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
Apr 12 18:45:23.278000 audit: PATH item=93 name=(null) inode=1865 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
Apr 12 18:45:23.278000 audit: PATH item=94 name=(null) inode=1861 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
Apr 12 18:45:23.278000 audit: PATH item=95 name=(null) inode=1866 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
Apr 12 18:45:23.278000 audit: PATH item=96 name=(null) inode=1846 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
Apr 12 18:45:23.278000 audit: PATH item=97 name=(null) inode=1867 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
Apr 12 18:45:23.278000 audit: PATH item=98 name=(null) inode=1867 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
Apr 12 18:45:23.278000 audit: PATH item=99 name=(null) inode=1868 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
Apr 12 18:45:23.278000 audit: PATH item=100 name=(null) inode=1867 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
Apr 12 18:45:23.278000 audit: PATH item=101 name=(null) inode=1869 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
Apr 12 18:45:23.278000 audit: PATH item=102 name=(null) inode=1867 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
Apr 12 18:45:23.278000 audit: PATH item=103 name=(null) inode=1870 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
Apr 12 18:45:23.278000 audit: PATH item=104 name=(null) inode=1867 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
Apr 12 18:45:23.278000 audit: PATH item=105 name=(null) inode=1871 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
Apr 12 18:45:23.278000 audit: PATH item=106 name=(null) inode=1867 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
Apr 12 18:45:23.278000 audit: PATH item=107 name=(null) inode=1872 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
Apr 12 18:45:23.278000 audit: PROCTITLE proctitle="(udev-worker)"
Apr 12 18:45:23.288190 systemd-networkd[1008]: lo: Link UP
Apr 12 18:45:23.288201 systemd-networkd[1008]: lo: Gained carrier
Apr 12 18:45:23.288761 systemd-networkd[1008]: Enumeration completed
Apr 12 18:45:23.288862 systemd[1]: Started systemd-networkd.service.
Apr 12 18:45:23.288887 systemd-networkd[1008]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
Apr 12 18:45:23.289000 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'
Apr 12 18:45:23.292067 systemd[1]: Starting systemd-networkd-wait-online.service...
Apr 12 18:45:23.293480 kernel: piix4_smbus 0000:00:01.3: SMBus Host Controller at 0xb100, revision 0
Apr 12 18:45:23.293803 systemd-networkd[1008]: eth0: Link UP
Apr 12 18:45:23.293807 systemd-networkd[1008]: eth0: Gained carrier
Apr 12 18:45:23.298635 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3
Apr 12 18:45:23.310746 systemd-networkd[1008]: eth0: DHCPv4 address 10.0.0.53/16, gateway 10.0.0.1 acquired from 10.0.0.1
Apr 12 18:45:23.322643 kernel: mousedev: PS/2 mouse device common for all mice
Apr 12 18:45:23.377644 kernel: kvm: Nested Virtualization enabled
Apr 12 18:45:23.377802 kernel: SVM: kvm: Nested Paging enabled
Apr 12 18:45:23.377819 kernel: SVM: Virtual VMLOAD VMSAVE supported
Apr 12 18:45:23.379135 kernel: SVM: Virtual GIF supported
Apr 12 18:45:23.394641 kernel: EDAC MC: Ver: 3.0.0
Apr 12 18:45:23.412031 systemd[1]: Finished systemd-udev-settle.service.
Apr 12 18:45:23.412000 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'
Apr 12 18:45:23.414270 systemd[1]: Starting lvm2-activation-early.service...
Apr 12 18:45:23.420921 lvm[1034]: WARNING: Failed to connect to lvmetad. Falling back to device scanning.
Apr 12 18:45:23.448722 systemd[1]: Finished lvm2-activation-early.service.
Apr 12 18:45:23.449000 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'
Apr 12 18:45:23.449912 systemd[1]: Reached target cryptsetup.target.
Apr 12 18:45:23.451868 systemd[1]: Starting lvm2-activation.service...
Apr 12 18:45:23.455059 lvm[1035]: WARNING: Failed to connect to lvmetad. Falling back to device scanning.
Apr 12 18:45:23.478927 systemd[1]: Finished lvm2-activation.service.
Apr 12 18:45:23.479000 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'
Apr 12 18:45:23.480028 systemd[1]: Reached target local-fs-pre.target.
Apr 12 18:45:23.480978 systemd[1]: var-lib-machines.mount was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw).
Apr 12 18:45:23.481003 systemd[1]: Reached target local-fs.target.
Apr 12 18:45:23.481982 systemd[1]: Reached target machines.target.
Apr 12 18:45:23.484015 systemd[1]: Starting ldconfig.service...
Apr 12 18:45:23.485122 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met.
Apr 12 18:45:23.485185 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).
Apr 12 18:45:23.486198 systemd[1]: Starting systemd-boot-update.service...
Apr 12 18:45:23.488225 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service...
Apr 12 18:45:23.490630 systemd[1]: Starting systemd-machine-id-commit.service...
Apr 12 18:45:23.491766 systemd[1]: systemd-sysext.service was skipped because no trigger condition checks were met.
Apr 12 18:45:23.491836 systemd[1]: ensure-sysext.service was skipped because no trigger condition checks were met.
Apr 12 18:45:23.492903 systemd[1]: Starting systemd-tmpfiles-setup.service...
Apr 12 18:45:23.493251 systemd[1]: boot.automount: Got automount request for /boot, triggered by 1037 (bootctl)
Apr 12 18:45:23.494491 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service...
Apr 12 18:45:23.498000 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'
Apr 12 18:45:23.498890 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service.
Apr 12 18:45:23.516000 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'
Apr 12 18:45:23.516351 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
Apr 12 18:45:23.517066 systemd[1]: Finished systemd-machine-id-commit.service.
Apr 12 18:45:23.518692 systemd-tmpfiles[1040]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring.
Apr 12 18:45:23.520784 systemd-tmpfiles[1040]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring.
Apr 12 18:45:23.523644 systemd-tmpfiles[1040]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring.
Apr 12 18:45:23.538800 systemd-fsck[1045]: fsck.fat 4.2 (2021-01-31)
Apr 12 18:45:23.538800 systemd-fsck[1045]: /dev/vda1: 790 files, 119263/258078 clusters
Apr 12 18:45:23.540895 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service.
Apr 12 18:45:23.541000 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'
Apr 12 18:45:23.543469 systemd[1]: Mounting boot.mount...
Apr 12 18:45:23.549865 systemd[1]: Mounted boot.mount.
Apr 12 18:45:23.562319 systemd[1]: Finished systemd-boot-update.service.
Apr 12 18:45:23.562000 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'
Apr 12 18:45:23.595270 ldconfig[1036]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start.
Apr 12 18:45:23.602533 systemd[1]: Finished ldconfig.service.
Apr 12 18:45:23.602000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=ldconfig comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Apr 12 18:45:23.627818 systemd[1]: Finished systemd-tmpfiles-setup.service.
Apr 12 18:45:23.628000 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'
Apr 12 18:45:23.630405 systemd[1]: Starting audit-rules.service...
Apr 12 18:45:23.632258 systemd[1]: Starting clean-ca-certificates.service...
Apr 12 18:45:23.634414 systemd[1]: Starting systemd-journal-catalog-update.service...
Apr 12 18:45:23.635000 audit: BPF prog-id=24 op=LOAD
Apr 12 18:45:23.637472 systemd[1]: Starting systemd-resolved.service...
Apr 12 18:45:23.638000 audit: BPF prog-id=25 op=LOAD
Apr 12 18:45:23.639876 systemd[1]: Starting systemd-timesyncd.service...
Apr 12 18:45:23.641419 systemd[1]: Starting systemd-update-utmp.service...
Apr 12 18:45:23.642767 systemd[1]: Finished clean-ca-certificates.service.
Apr 12 18:45:23.643000 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'
Apr 12 18:45:23.643937 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt).
Apr 12 18:45:23.645000 audit[1058]: SYSTEM_BOOT pid=1058 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'
Apr 12 18:45:23.650716 systemd[1]: Finished systemd-update-utmp.service.
Apr 12 18:45:23.651000 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'
Apr 12 18:45:23.656000 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'
Apr 12 18:45:23.655829 systemd[1]: Finished systemd-journal-catalog-update.service.
Apr 12 18:45:23.658072 systemd[1]: Starting systemd-update-done.service...
Apr 12 18:45:23.662955 augenrules[1070]: No rules
Apr 12 18:45:23.663350 systemd[1]: Finished systemd-update-done.service.
Apr 12 18:45:23.662000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1
Apr 12 18:45:23.662000 audit[1070]: SYSCALL arch=c000003e syscall=44 success=yes exit=1056 a0=3 a1=7ffe5a079830 a2=420 a3=0 items=0 ppid=1049 pid=1070 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)
Apr 12 18:45:23.662000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573
Apr 12 18:45:23.664484 systemd[1]: Finished audit-rules.service.
Apr 12 18:45:23.690556 systemd[1]: Started systemd-timesyncd.service.
Apr 12 18:45:23.691601 systemd[1]: Reached target time-set.target.
Apr 12 18:45:24.217057 systemd-timesyncd[1057]: Contacted time server 10.0.0.1:123 (10.0.0.1).
Apr 12 18:45:24.217108 systemd-timesyncd[1057]: Initial clock synchronization to Fri 2024-04-12 18:45:24.216967 UTC.
Apr 12 18:45:24.217741 systemd-resolved[1053]: Positive Trust Anchors:
Apr 12 18:45:24.217751 systemd-resolved[1053]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
Apr 12 18:45:24.217778 systemd-resolved[1053]: 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
Apr 12 18:45:24.224021 systemd-resolved[1053]: Defaulting to hostname 'linux'.
Apr 12 18:45:24.225367 systemd[1]: Started systemd-resolved.service.
Apr 12 18:45:24.226371 systemd[1]: Reached target network.target.
Apr 12 18:45:24.227240 systemd[1]: Reached target nss-lookup.target.
Apr 12 18:45:24.228112 systemd[1]: Reached target sysinit.target.
Apr 12 18:45:24.229021 systemd[1]: Started motdgen.path.
Apr 12 18:45:24.229774 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path.
Apr 12 18:45:24.231093 systemd[1]: Started logrotate.timer.
Apr 12 18:45:24.231953 systemd[1]: Started mdadm.timer.
Apr 12 18:45:24.232672 systemd[1]: Started systemd-tmpfiles-clean.timer.
Apr 12 18:45:24.233794 systemd[1]: update-engine-stub.timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate).
Apr 12 18:45:24.233842 systemd[1]: Reached target paths.target.
Apr 12 18:45:24.234636 systemd[1]: Reached target timers.target.
Apr 12 18:45:24.235846 systemd[1]: Listening on dbus.socket.
Apr 12 18:45:24.238025 systemd[1]: Starting docker.socket...
Apr 12 18:45:24.241059 systemd[1]: Listening on sshd.socket.
Apr 12 18:45:24.241967 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).
Apr 12 18:45:24.242443 systemd[1]: Listening on docker.socket.
Apr 12 18:45:24.243313 systemd[1]: Reached target sockets.target.
Apr 12 18:45:24.244125 systemd[1]: Reached target basic.target.
Apr 12 18:45:24.245005 systemd[1]: addon-config@usr-share-oem.service was skipped because no trigger condition checks were met.
Apr 12 18:45:24.245035 systemd[1]: addon-run@usr-share-oem.service was skipped because no trigger condition checks were met.
Apr 12 18:45:24.246199 systemd[1]: Starting containerd.service...
Apr 12 18:45:24.248149 systemd[1]: Starting dbus.service...
Apr 12 18:45:24.249999 systemd[1]: Starting enable-oem-cloudinit.service...
Apr 12 18:45:24.252084 systemd[1]: Starting extend-filesystems.service...
Apr 12 18:45:24.253060 systemd[1]: flatcar-setup-environment.service was skipped because of an unmet condition check (ConditionPathExists=/usr/share/oem/bin/flatcar-setup-environment).
Apr 12 18:45:24.254366 systemd[1]: Starting motdgen.service...
Apr 12 18:45:24.254728 jq[1080]: false
Apr 12 18:45:24.256247 systemd[1]: Starting ssh-key-proc-cmdline.service...
Apr 12 18:45:24.258099 systemd[1]: Starting sshd-keygen.service...
Apr 12 18:45:24.262114 systemd[1]: Starting systemd-logind.service...
Apr 12 18:45:24.263005 systemd[1]: systemd-pcrphase.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f).
Apr 12 18:45:24.263079 systemd[1]: tcsd.service was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0).
Apr 12 18:45:24.264197 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details.
Apr 12 18:45:24.264996 systemd[1]: Starting update-engine.service...
Apr 12 18:45:24.267248 systemd[1]: Starting update-ssh-keys-after-ignition.service...
Apr 12 18:45:24.270943 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'.
Apr 12 18:45:24.271166 systemd[1]: Condition check resulted in enable-oem-cloudinit.service being skipped.
Apr 12 18:45:24.271557 systemd[1]: motdgen.service: Deactivated successfully.
Apr 12 18:45:24.271770 systemd[1]: Finished motdgen.service.
Apr 12 18:45:24.273512 dbus-daemon[1079]: [system] SELinux support is enabled
Apr 12 18:45:24.273767 jq[1097]: true
Apr 12 18:45:24.274058 systemd[1]: Started dbus.service.
Apr 12 18:45:24.275945 extend-filesystems[1081]: Found sr0
Apr 12 18:45:24.277429 extend-filesystems[1081]: Found vda
Apr 12 18:45:24.277429 extend-filesystems[1081]: Found vda1
Apr 12 18:45:24.277429 extend-filesystems[1081]: Found vda2
Apr 12 18:45:24.277429 extend-filesystems[1081]: Found vda3
Apr 12 18:45:24.277429 extend-filesystems[1081]: Found usr
Apr 12 18:45:24.277429 extend-filesystems[1081]: Found vda4
Apr 12 18:45:24.277429 extend-filesystems[1081]: Found vda6
Apr 12 18:45:24.277429 extend-filesystems[1081]: Found vda7
Apr 12 18:45:24.277429 extend-filesystems[1081]: Found vda9
Apr 12 18:45:24.277429 extend-filesystems[1081]: Checking size of /dev/vda9
Apr 12 18:45:24.277354 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully.
Apr 12 18:45:24.330093 update_engine[1095]: I0412 18:45:24.308792 1095 main.cc:92] Flatcar Update Engine starting
Apr 12 18:45:24.330093 update_engine[1095]: I0412 18:45:24.311775 1095 update_check_scheduler.cc:74] Next update check in 8m20s
Apr 12 18:45:24.330264 extend-filesystems[1081]: Old size kept for /dev/vda9
Apr 12 18:45:24.277737 systemd[1]: Finished ssh-key-proc-cmdline.service.
Apr 12 18:45:24.331682 env[1103]: time="2024-04-12T18:45:24.304432755Z" level=info msg="starting containerd" revision=92b3a9d6f1b3bcc6dc74875cfdea653fe39f09c2 version=1.6.16
Apr 12 18:45:24.281920 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).
Apr 12 18:45:24.331936 jq[1101]: true
Apr 12 18:45:24.281956 systemd[1]: Reached target system-config.target.
Apr 12 18:45:24.283458 systemd[1]: user-cloudinit-proc-cmdline.service was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url).
Apr 12 18:45:24.332176 bash[1123]: Updated "/home/core/.ssh/authorized_keys"
Apr 12 18:45:24.283478 systemd[1]: Reached target user-config.target.
Apr 12 18:45:24.310486 systemd[1]: Started update-engine.service.
Apr 12 18:45:24.312412 systemd[1]: Started locksmithd.service.
Apr 12 18:45:24.314385 systemd-logind[1091]: Watching system buttons on /dev/input/event1 (Power Button)
Apr 12 18:45:24.314404 systemd-logind[1091]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)
Apr 12 18:45:24.314642 systemd-logind[1091]: New seat seat0.
Apr 12 18:45:24.316329 systemd[1]: Started systemd-logind.service.
Apr 12 18:45:24.318334 systemd[1]: Finished update-ssh-keys-after-ignition.service.
Apr 12 18:45:24.328628 systemd[1]: extend-filesystems.service: Deactivated successfully.
Apr 12 18:45:24.328770 systemd[1]: Finished extend-filesystems.service.
Apr 12 18:45:24.348588 env[1103]: time="2024-04-12T18:45:24.348549313Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Apr 12 18:45:24.349081 env[1103]: time="2024-04-12T18:45:24.349064579Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Apr 12 18:45:24.350021 env[1103]: time="2024-04-12T18:45:24.349995495Z" 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.154-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1
Apr 12 18:45:24.350092 env[1103]: time="2024-04-12T18:45:24.350073842Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Apr 12 18:45:24.350324 env[1103]: time="2024-04-12T18:45:24.350304945Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1
Apr 12 18:45:24.350405 env[1103]: time="2024-04-12T18:45:24.350386488Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Apr 12 18:45:24.350478 env[1103]: time="2024-04-12T18:45:24.350459024Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured"
Apr 12 18:45:24.350554 env[1103]: time="2024-04-12T18:45:24.350533163Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Apr 12 18:45:24.350718 env[1103]: time="2024-04-12T18:45:24.350702310Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Apr 12 18:45:24.350986 env[1103]: time="2024-04-12T18:45:24.350969842Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Apr 12 18:45:24.351153 env[1103]: time="2024-04-12T18:45:24.351133699Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1
Apr 12 18:45:24.351229 env[1103]: time="2024-04-12T18:45:24.351210302Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Apr 12 18:45:24.351335 env[1103]: time="2024-04-12T18:45:24.351316992Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured"
Apr 12 18:45:24.351405 env[1103]: time="2024-04-12T18:45:24.351386713Z" level=info msg="metadata content store policy set" policy=shared
Apr 12 18:45:24.359679 env[1103]: time="2024-04-12T18:45:24.357096619Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Apr 12 18:45:24.359679 env[1103]: time="2024-04-12T18:45:24.357122057Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Apr 12 18:45:24.359679 env[1103]: time="2024-04-12T18:45:24.357133769Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Apr 12 18:45:24.359679 env[1103]: time="2024-04-12T18:45:24.357168724Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Apr 12 18:45:24.359679 env[1103]: time="2024-04-12T18:45:24.357183983Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Apr 12 18:45:24.359679 env[1103]: time="2024-04-12T18:45:24.357195935Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Apr 12 18:45:24.359679 env[1103]: time="2024-04-12T18:45:24.357207587Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Apr 12 18:45:24.359679 env[1103]: time="2024-04-12T18:45:24.357249917Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Apr 12 18:45:24.359679 env[1103]: time="2024-04-12T18:45:24.357261849Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1
Apr 12 18:45:24.359679 env[1103]: time="2024-04-12T18:45:24.357273481Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Apr 12 18:45:24.359679 env[1103]: time="2024-04-12T18:45:24.357284652Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Apr 12 18:45:24.359679 env[1103]: time="2024-04-12T18:45:24.357295192Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Apr 12 18:45:24.359679 env[1103]: time="2024-04-12T18:45:24.357379269Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Apr 12 18:45:24.359679 env[1103]: time="2024-04-12T18:45:24.357451825Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Apr 12 18:45:24.359077 systemd[1]: Started containerd.service.
Apr 12 18:45:24.360047 env[1103]: time="2024-04-12T18:45:24.357687207Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Apr 12 18:45:24.360047 env[1103]: time="2024-04-12T18:45:24.357711232Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Apr 12 18:45:24.360047 env[1103]: time="2024-04-12T18:45:24.357723425Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Apr 12 18:45:24.360047 env[1103]: time="2024-04-12T18:45:24.357762327Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Apr 12 18:45:24.360047 env[1103]: time="2024-04-12T18:45:24.357773258Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Apr 12 18:45:24.360047 env[1103]: time="2024-04-12T18:45:24.357784419Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Apr 12 18:45:24.360047 env[1103]: time="2024-04-12T18:45:24.357794247Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Apr 12 18:45:24.360047 env[1103]: time="2024-04-12T18:45:24.357805248Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Apr 12 18:45:24.360047 env[1103]: time="2024-04-12T18:45:24.357825516Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Apr 12 18:45:24.360047 env[1103]: time="2024-04-12T18:45:24.357835445Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Apr 12 18:45:24.360047 env[1103]: time="2024-04-12T18:45:24.357845634Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Apr 12 18:45:24.360047 env[1103]: time="2024-04-12T18:45:24.357857145Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Apr 12 18:45:24.360047 env[1103]: time="2024-04-12T18:45:24.357959277Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Apr 12 18:45:24.360047 env[1103]: time="2024-04-12T18:45:24.357973363Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Apr 12 18:45:24.360047 env[1103]: time="2024-04-12T18:45:24.357983763Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Apr 12 18:45:24.360316 env[1103]: time="2024-04-12T18:45:24.357993391Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Apr 12 18:45:24.360316 env[1103]: time="2024-04-12T18:45:24.358004862Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1
Apr 12 18:45:24.360316 env[1103]: time="2024-04-12T18:45:24.358013889Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Apr 12 18:45:24.360316 env[1103]: time="2024-04-12T18:45:24.358030891Z" level=error msg="failed to initialize a tracing processor \"otlp\"" error="no OpenTelemetry endpoint: skip plugin"
Apr 12 18:45:24.360316 env[1103]: time="2024-04-12T18:45:24.358061458Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1
Apr 12 18:45:24.360347 locksmithd[1127]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot"
Apr 12 18:45:24.360598 env[1103]: time="2024-04-12T18:45:24.358234873Z" 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}"
Apr 12 18:45:24.360598 env[1103]: time="2024-04-12T18:45:24.358281240Z" level=info msg="Connect containerd service"
Apr 12 18:45:24.360598 env[1103]: time="2024-04-12T18:45:24.358308371Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\""
Apr 12 18:45:24.360598 env[1103]: time="2024-04-12T18:45:24.358758135Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config"
Apr 12 18:45:24.360598 env[1103]: time="2024-04-12T18:45:24.358948722Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc
Apr 12 18:45:24.360598 env[1103]: time="2024-04-12T18:45:24.358977957Z" level=info msg=serving... address=/run/containerd/containerd.sock
Apr 12 18:45:24.360598 env[1103]: time="2024-04-12T18:45:24.359012211Z" level=info msg="containerd successfully booted in 0.055623s"
Apr 12 18:45:24.360598 env[1103]: time="2024-04-12T18:45:24.360125418Z" level=info msg="Start subscribing containerd event"
Apr 12 18:45:24.360598 env[1103]: time="2024-04-12T18:45:24.360163009Z" level=info msg="Start recovering state"
Apr 12 18:45:24.360598 env[1103]: time="2024-04-12T18:45:24.360209115Z" level=info msg="Start event monitor"
Apr 12 18:45:24.360598 env[1103]: time="2024-04-12T18:45:24.360221348Z" level=info msg="Start snapshots syncer"
Apr 12 18:45:24.360598 env[1103]: time="2024-04-12T18:45:24.360228492Z" level=info msg="Start cni network conf syncer for default"
Apr 12 18:45:24.360598 env[1103]: time="2024-04-12T18:45:24.360234453Z" level=info msg="Start streaming server"
Apr 12 18:45:25.200958 systemd-networkd[1008]: eth0: Gained IPv6LL
Apr 12 18:45:25.202625 systemd[1]: Finished systemd-networkd-wait-online.service.
Apr 12 18:45:25.203970 systemd[1]: Reached target network-online.target.
Apr 12 18:45:25.205964 systemd[1]: Starting coreos-metadata.service...
Apr 12 18:45:25.212111 systemd[1]: coreos-metadata.service: Deactivated successfully.
Apr 12 18:45:25.212277 systemd[1]: Finished coreos-metadata.service.
Apr 12 18:45:25.214602 systemd[1]: Starting etcd-member.service...
Apr 12 18:45:25.238638 systemd[1]: Starting docker.service...
Apr 12 18:45:25.269358 env[1152]: time="2024-04-12T18:45:25.269306803Z" level=info msg="Starting up"
Apr 12 18:45:25.271024 env[1152]: time="2024-04-12T18:45:25.270986903Z" level=info msg="parsed scheme: \"unix\"" module=grpc
Apr 12 18:45:25.271024 env[1152]: time="2024-04-12T18:45:25.271006991Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
Apr 12 18:45:25.271120 env[1152]: time="2024-04-12T18:45:25.271030445Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/libcontainerd/docker-containerd.sock <nil> 0 <nil>}] <nil> <nil>}" module=grpc
Apr 12 18:45:25.271120 env[1152]: time="2024-04-12T18:45:25.271041816Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
Apr 12 18:45:25.272813 env[1152]: time="2024-04-12T18:45:25.272797037Z" level=info msg="parsed scheme: \"unix\"" module=grpc
Apr 12 18:45:25.272904 env[1152]: time="2024-04-12T18:45:25.272884311Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
Apr 12 18:45:25.272992 env[1152]: time="2024-04-12T18:45:25.272969200Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/libcontainerd/docker-containerd.sock <nil> 0 <nil>}] <nil> <nil>}" module=grpc
Apr 12 18:45:25.273077 env[1152]: time="2024-04-12T18:45:25.273057155Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
Apr 12 18:45:25.310269 env[1152]: time="2024-04-12T18:45:25.310233250Z" level=info msg="Loading containers: start."
Apr 12 18:45:25.405682 kernel: Initializing XFRM netlink socket
Apr 12 18:45:25.433602 env[1152]: time="2024-04-12T18:45:25.433551812Z" 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"
Apr 12 18:45:25.478643 systemd-networkd[1008]: docker0: Link UP
Apr 12 18:45:25.487324 env[1152]: time="2024-04-12T18:45:25.487278340Z" level=info msg="Loading containers: done."
Apr 12 18:45:25.499590 env[1152]: time="2024-04-12T18:45:25.499535011Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2
Apr 12 18:45:25.499818 env[1152]: time="2024-04-12T18:45:25.499758821Z" level=info msg="Docker daemon" commit=112bdf3343 graphdriver(s)=overlay2 version=20.10.23
Apr 12 18:45:25.499899 env[1152]: time="2024-04-12T18:45:25.499874407Z" level=info msg="Daemon has completed initialization"
Apr 12 18:45:25.516719 systemd[1]: Started docker.service.
Apr 12 18:45:25.522153 env[1152]: time="2024-04-12T18:45:25.522102565Z" level=info msg="API listen on /run/docker.sock"
Apr 12 18:45:25.523017 etcd-wrapper[1145]: Error response from daemon: No such container: etcd-member
Apr 12 18:45:25.541855 etcd-wrapper[1264]: Error: No such container: etcd-member
Apr 12 18:45:25.569360 etcd-wrapper[1282]: Unable to find image 'quay.io/coreos/etcd:v3.5.0' locally
Apr 12 18:45:25.963965 sshd_keygen[1099]: ssh-keygen: generating new host keys: RSA ECDSA ED25519
Apr 12 18:45:25.981405 systemd[1]: Finished sshd-keygen.service.
Apr 12 18:45:25.983883 systemd[1]: Starting issuegen.service...
Apr 12 18:45:25.989575 systemd[1]: issuegen.service: Deactivated successfully.
Apr 12 18:45:25.989748 systemd[1]: Finished issuegen.service.
Apr 12 18:45:25.992149 systemd[1]: Starting systemd-user-sessions.service...
Apr 12 18:45:25.998993 systemd[1]: Finished systemd-user-sessions.service.
Apr 12 18:45:26.001690 systemd[1]: Started getty@tty1.service.
Apr 12 18:45:26.003879 systemd[1]: Started serial-getty@ttyS0.service.
Apr 12 18:45:26.005148 systemd[1]: Reached target getty.target.
Apr 12 18:45:27.175187 etcd-wrapper[1282]: v3.5.0: Pulling from coreos/etcd
Apr 12 18:45:27.573829 etcd-wrapper[1282]: 1813d21adc01: Pulling fs layer
Apr 12 18:45:27.573829 etcd-wrapper[1282]: 6e96907ab677: Pulling fs layer
Apr 12 18:45:27.573829 etcd-wrapper[1282]: 444ed0ea8673: Pulling fs layer
Apr 12 18:45:27.573829 etcd-wrapper[1282]: 0fd2df5633f0: Pulling fs layer
Apr 12 18:45:27.573829 etcd-wrapper[1282]: 8cc22b9456bb: Pulling fs layer
Apr 12 18:45:27.573829 etcd-wrapper[1282]: 7ac70aecd290: Pulling fs layer
Apr 12 18:45:27.573829 etcd-wrapper[1282]: 4b376c64dfe4: Pulling fs layer
Apr 12 18:45:27.573829 etcd-wrapper[1282]: 8cc22b9456bb: Waiting
Apr 12 18:45:27.573829 etcd-wrapper[1282]: 7ac70aecd290: Waiting
Apr 12 18:45:27.573829 etcd-wrapper[1282]: 4b376c64dfe4: Waiting
Apr 12 18:45:27.574174 etcd-wrapper[1282]: 0fd2df5633f0: Waiting
Apr 12 18:45:27.945943 etcd-wrapper[1282]: 6e96907ab677: Verifying Checksum
Apr 12 18:45:27.945943 etcd-wrapper[1282]: 6e96907ab677: Download complete
Apr 12 18:45:27.976215 etcd-wrapper[1282]: 444ed0ea8673: Verifying Checksum
Apr 12 18:45:27.976215 etcd-wrapper[1282]: 444ed0ea8673: Download complete
Apr 12 18:45:28.045476 etcd-wrapper[1282]: 1813d21adc01: Verifying Checksum
Apr 12 18:45:28.045476 etcd-wrapper[1282]: 1813d21adc01: Download complete
Apr 12 18:45:28.271573 etcd-wrapper[1282]: 8cc22b9456bb: Verifying Checksum
Apr 12 18:45:28.271573 etcd-wrapper[1282]: 8cc22b9456bb: Download complete
Apr 12 18:45:28.293525 etcd-wrapper[1282]: 0fd2df5633f0: Verifying Checksum
Apr 12 18:45:28.293525 etcd-wrapper[1282]: 0fd2df5633f0: Download complete
Apr 12 18:45:28.338319 etcd-wrapper[1282]: 7ac70aecd290: Verifying Checksum
Apr 12 18:45:28.338319 etcd-wrapper[1282]: 7ac70aecd290: Download complete
Apr 12 18:45:28.575396 etcd-wrapper[1282]: 4b376c64dfe4: Verifying Checksum
Apr 12 18:45:28.575396 etcd-wrapper[1282]: 4b376c64dfe4: Download complete
Apr 12 18:45:28.988828 etcd-wrapper[1282]: 1813d21adc01: Pull complete
Apr 12 18:45:29.306156 systemd[1]: var-lib-docker-overlay2-b02ca9a8810dd163e2c987f0b7beb73bc2043bc0ae4209393d4a82bad1f98523-merged.mount: Deactivated successfully.
Apr 12 18:45:29.763051 etcd-wrapper[1282]: 6e96907ab677: Pull complete
Apr 12 18:45:29.983842 systemd[1]: var-lib-docker-overlay2-3c9413b0d83f2219f32e87520b64080aed985ca00e2e03ebbd345fef3f48145e-merged.mount: Deactivated successfully.
Apr 12 18:45:30.064100 etcd-wrapper[1282]: 444ed0ea8673: Pull complete
Apr 12 18:45:30.274336 systemd[1]: var-lib-docker-overlay2-2e45045d38c54b4958cd6ef5c0d592724d9bd0632d157f3ac329e62c3a03fe6c-merged.mount: Deactivated successfully.
Apr 12 18:45:30.342338 etcd-wrapper[1282]: 0fd2df5633f0: Pull complete
Apr 12 18:45:30.376232 systemd[1]: var-lib-docker-overlay2-1eb5f73ee44103eabaecfe97f0abb75ab78ef4997aaa66145050591081b1edc8-merged.mount: Deactivated successfully.
Apr 12 18:45:30.388913 etcd-wrapper[1282]: 8cc22b9456bb: Pull complete
Apr 12 18:45:30.438741 etcd-wrapper[1282]: 7ac70aecd290: Pull complete
Apr 12 18:45:30.490993 etcd-wrapper[1282]: 4b376c64dfe4: Pull complete
Apr 12 18:45:30.500114 etcd-wrapper[1282]: Digest: sha256:28759af54acd6924b2191dc1a1d096e2fa2e219717a21b9d8edf89717db3631b
Apr 12 18:45:30.503439 etcd-wrapper[1282]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.0
Apr 12 18:45:30.761384 env[1103]: time="2024-04-12T18:45:30.761246649Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Apr 12 18:45:30.761384 env[1103]: time="2024-04-12T18:45:30.761279360Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Apr 12 18:45:30.761384 env[1103]: time="2024-04-12T18:45:30.761288968Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Apr 12 18:45:30.761837 env[1103]: time="2024-04-12T18:45:30.761403032Z" level=info msg="starting signal loop" namespace=moby path=/run/containerd/io.containerd.runtime.v2.task/moby/fe8ad86aa83a5cb5a850533e058337219671d1d3b75890fade774bdcbb0a0675 pid=1394 runtime=io.containerd.runc.v2
Apr 12 18:45:30.771516 systemd[1]: Started docker-fe8ad86aa83a5cb5a850533e058337219671d1d3b75890fade774bdcbb0a0675.scope.
Apr 12 18:45:30.783822 systemd-resolved[1053]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address
Apr 12 18:45:30.815273 etcd-wrapper[1282]: {"level":"info","ts":1712947530.815083,"caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"}
Apr 12 18:45:30.815273 etcd-wrapper[1282]: {"level":"info","ts":1712947530.8151586,"caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"ed3f747e97df4da6bf539c0b55b8958a"}
Apr 12 18:45:30.815844 etcd-wrapper[1282]: {"level":"warn","ts":1712947530.815192,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"}
Apr 12 18:45:30.815844 etcd-wrapper[1282]: {"level":"warn","ts":1712947530.8152046,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.0"}
Apr 12 18:45:30.815844 etcd-wrapper[1282]: {"level":"warn","ts":1712947530.8152108,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"}
Apr 12 18:45:30.815844 etcd-wrapper[1282]: {"level":"warn","ts":1712947530.8152156,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"}
Apr 12 18:45:30.815844 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.815Z","caller":"etcdmain/etcd.go:72","msg":"Running: ","args":["/usr/local/bin/etcd","--listen-client-urls=http://0.0.0.0:2379","--advertise-client-urls=http://10.0.0.53:2379"]}
Apr 12 18:45:30.815844 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.815Z","caller":"embed/etcd.go:131","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]}
Apr 12 18:45:30.816100 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.815Z","caller":"embed/etcd.go:139","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]}
Apr 12 18:45:30.816259 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.816Z","caller":"embed/etcd.go:307","msg":"starting an etcd server","etcd-version":"3.5.0","git-sha":"946a5a6f2","go-version":"go1.16.3","go-os":"linux","go-arch":"amd64","max-cpu-set":4,"max-cpu-available":4,"member-initialized":false,"name":"ed3f747e97df4da6bf539c0b55b8958a","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,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["http://localhost:2380"],"listen-peer-urls":["http://localhost:2380"],"advertise-client-urls":["http://10.0.0.53:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"ed3f747e97df4da6bf539c0b55b8958a=http://localhost:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-size-bytes":2147483648,"pre-vote":true,"initial-corrupt-check":false,"corrupt-check-time-interval":"0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"}
Apr 12 18:45:30.819058 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.818Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.492623ms"}
Apr 12 18:45:30.822870 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.822Z","caller":"etcdserver/raft.go:448","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"}
Apr 12 18:45:30.822870 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"}
Apr 12 18:45:30.822870 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"}
Apr 12 18:45:30.822870 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
Apr 12 18:45:30.822870 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"}
Apr 12 18:45:30.823031 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"}
Apr 12 18:45:30.825953 etcd-wrapper[1282]: {"level":"warn","ts":"2024-04-12T18:45:30.825Z","caller":"auth/store.go:1220","msg":"simple token is not cryptographically signed"}
Apr 12 18:45:30.827231 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.827Z","caller":"mvcc/kvstore.go:415","msg":"kvstore restored","current-rev":1}
Apr 12 18:45:30.828012 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.827Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"}
Apr 12 18:45:30.829140 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.828Z","caller":"etcdserver/server.go:843","msg":"starting etcd server","local-member-id":"8e9e05c52164694d","local-server-version":"3.5.0","cluster-version":"to_be_decided"}
Apr 12 18:45:30.829140 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.828Z","caller":"etcdserver/server.go:728","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8e9e05c52164694d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
Apr 12 18:45:30.830079 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"}
Apr 12 18:45:30.830234 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.830Z","caller":"membership/cluster.go:393","msg":"added member","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","added-peer-id":"8e9e05c52164694d","added-peer-peer-urls":["http://localhost:2380"]}
Apr 12 18:45:30.831604 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.831Z","caller":"embed/etcd.go:276","msg":"now serving peer/client/metrics","local-member-id":"8e9e05c52164694d","initial-advertise-peer-urls":["http://localhost:2380"],"listen-peer-urls":["http://localhost:2380"],"advertise-client-urls":["http://10.0.0.53:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]}
Apr 12 18:45:30.831738 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.831Z","caller":"embed/etcd.go:580","msg":"serving peer traffic","address":"127.0.0.1:2380"}
Apr 12 18:45:30.831738 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.831Z","caller":"embed/etcd.go:552","msg":"cmux::serve","address":"127.0.0.1:2380"}
Apr 12 18:45:30.924160 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"}
Apr 12 18:45:30.924160 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"}
Apr 12 18:45:30.924160 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"}
Apr 12 18:45:30.924160 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"}
Apr 12 18:45:30.924160 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"}
Apr 12 18:45:30.924160 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"}
Apr 12 18:45:30.924160 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"}
Apr 12 18:45:30.924430 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.924Z","caller":"etcdserver/server.go:2027","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:ed3f747e97df4da6bf539c0b55b8958a ClientURLs:[http://10.0.0.53:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"}
Apr 12 18:45:30.924430 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.924Z","caller":"etcdserver/server.go:2476","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
Apr 12 18:45:30.924430 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.924Z","caller":"embed/serve.go:98","msg":"ready to serve client requests"}
Apr 12 18:45:30.924734 systemd[1]: Started etcd-member.service.
Apr 12 18:45:30.924863 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.924Z","caller":"etcdmain/main.go:47","msg":"notifying init daemon"}
Apr 12 18:45:30.924863 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.924Z","caller":"membership/cluster.go:531","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"}
Apr 12 18:45:30.924863 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.924Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
Apr 12 18:45:30.924863 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.924Z","caller":"etcdserver/server.go:2500","msg":"cluster version is updated","cluster-version":"3.5"}
Apr 12 18:45:30.925012 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.924Z","caller":"etcdmain/main.go:53","msg":"successfully notified init daemon"}
Apr 12 18:45:30.926030 systemd[1]: Reached target multi-user.target.
Apr 12 18:45:30.927277 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:45:30.927Z","caller":"embed/serve.go:140","msg":"serving client traffic insecurely; this is strongly discouraged!","address":"[::]:2379"}
Apr 12 18:45:30.928471 systemd[1]: Starting systemd-update-utmp-runlevel.service...
Apr 12 18:45:30.933925 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully.
Apr 12 18:45:30.934100 systemd[1]: Finished systemd-update-utmp-runlevel.service.
Apr 12 18:45:30.935309 systemd[1]: Startup finished in 562ms (kernel) + 3.620s (initrd) + 10.128s (userspace) = 14.311s.
Apr 12 18:45:31.070577 systemd[1]: var-lib-docker-overlay2-f52b9f01afa7586a5b874c8d7f2551f59b7d32e3cbfa0ce5891d5445da092a71-merged.mount: Deactivated successfully.
Apr 12 18:45:34.238042 systemd[1]: Created slice system-sshd.slice.
Apr 12 18:45:34.239244 systemd[1]: Started sshd@0-10.0.0.53:22-10.0.0.1:42480.service.
Apr 12 18:45:34.280607 sshd[1436]: Accepted publickey for core from 10.0.0.1 port 42480 ssh2: RSA SHA256:oFTmhZVjs8bjXH/lYpDQZ+WL9oh5tEY90V+L3H6oLsU
Apr 12 18:45:34.281917 sshd[1436]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Apr 12 18:45:34.289395 systemd[1]: Created slice user-500.slice.
Apr 12 18:45:34.290367 systemd[1]: Starting user-runtime-dir@500.service...
Apr 12 18:45:34.291774 systemd-logind[1091]: New session 1 of user core.
Apr 12 18:45:34.299786 systemd[1]: Finished user-runtime-dir@500.service.
Apr 12 18:45:34.301592 systemd[1]: Starting user@500.service...
Apr 12 18:45:34.303912 (systemd)[1439]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0)
Apr 12 18:45:34.373080 systemd[1439]: Queued start job for default target default.target.
Apr 12 18:45:34.373513 systemd[1439]: Reached target paths.target.
Apr 12 18:45:34.373540 systemd[1439]: Reached target sockets.target.
Apr 12 18:45:34.373554 systemd[1439]: Reached target timers.target.
Apr 12 18:45:34.373564 systemd[1439]: Reached target basic.target.
Apr 12 18:45:34.373597 systemd[1439]: Reached target default.target.
Apr 12 18:45:34.373618 systemd[1439]: Startup finished in 64ms.
Apr 12 18:45:34.373747 systemd[1]: Started user@500.service.
Apr 12 18:45:34.375225 systemd[1]: Started session-1.scope.
Apr 12 18:45:34.425177 systemd[1]: Started sshd@1-10.0.0.53:22-10.0.0.1:42496.service.
Apr 12 18:45:34.464414 sshd[1448]: Accepted publickey for core from 10.0.0.1 port 42496 ssh2: RSA SHA256:oFTmhZVjs8bjXH/lYpDQZ+WL9oh5tEY90V+L3H6oLsU
Apr 12 18:45:34.465602 sshd[1448]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Apr 12 18:45:34.468979 systemd-logind[1091]: New session 2 of user core.
Apr 12 18:45:34.469740 systemd[1]: Started session-2.scope.
Apr 12 18:45:34.522723 sshd[1448]: pam_unix(sshd:session): session closed for user core
Apr 12 18:45:34.525357 systemd[1]: sshd@1-10.0.0.53:22-10.0.0.1:42496.service: Deactivated successfully.
Apr 12 18:45:34.525909 systemd[1]: session-2.scope: Deactivated successfully.
Apr 12 18:45:34.526357 systemd-logind[1091]: Session 2 logged out. Waiting for processes to exit.
Apr 12 18:45:34.527214 systemd[1]: Started sshd@2-10.0.0.53:22-10.0.0.1:42502.service.
Apr 12 18:45:34.527808 systemd-logind[1091]: Removed session 2.
Apr 12 18:45:34.564364 sshd[1454]: Accepted publickey for core from 10.0.0.1 port 42502 ssh2: RSA SHA256:oFTmhZVjs8bjXH/lYpDQZ+WL9oh5tEY90V+L3H6oLsU
Apr 12 18:45:34.565344 sshd[1454]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Apr 12 18:45:34.568688 systemd-logind[1091]: New session 3 of user core.
Apr 12 18:45:34.569334 systemd[1]: Started session-3.scope.
Apr 12 18:45:34.617948 sshd[1454]: pam_unix(sshd:session): session closed for user core
Apr 12 18:45:34.620805 systemd[1]: sshd@2-10.0.0.53:22-10.0.0.1:42502.service: Deactivated successfully.
Apr 12 18:45:34.621309 systemd[1]: session-3.scope: Deactivated successfully.
Apr 12 18:45:34.621777 systemd-logind[1091]: Session 3 logged out. Waiting for processes to exit.
Apr 12 18:45:34.622758 systemd[1]: Started sshd@3-10.0.0.53:22-10.0.0.1:42504.service.
Apr 12 18:45:34.623365 systemd-logind[1091]: Removed session 3.
Apr 12 18:45:34.660418 sshd[1460]: Accepted publickey for core from 10.0.0.1 port 42504 ssh2: RSA SHA256:oFTmhZVjs8bjXH/lYpDQZ+WL9oh5tEY90V+L3H6oLsU
Apr 12 18:45:34.661517 sshd[1460]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Apr 12 18:45:34.664583 systemd-logind[1091]: New session 4 of user core.
Apr 12 18:45:34.665555 systemd[1]: Started session-4.scope.
Apr 12 18:45:34.718428 sshd[1460]: pam_unix(sshd:session): session closed for user core
Apr 12 18:45:34.721007 systemd[1]: sshd@3-10.0.0.53:22-10.0.0.1:42504.service: Deactivated successfully.
Apr 12 18:45:34.721558 systemd[1]: session-4.scope: Deactivated successfully.
Apr 12 18:45:34.722006 systemd-logind[1091]: Session 4 logged out. Waiting for processes to exit.
Apr 12 18:45:34.722870 systemd[1]: Started sshd@4-10.0.0.53:22-10.0.0.1:42510.service.
Apr 12 18:45:34.723408 systemd-logind[1091]: Removed session 4.
Apr 12 18:45:34.761718 sshd[1466]: Accepted publickey for core from 10.0.0.1 port 42510 ssh2: RSA SHA256:oFTmhZVjs8bjXH/lYpDQZ+WL9oh5tEY90V+L3H6oLsU
Apr 12 18:45:34.762798 sshd[1466]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Apr 12 18:45:34.766126 systemd-logind[1091]: New session 5 of user core.
Apr 12 18:45:34.766899 systemd[1]: Started session-5.scope.
Apr 12 18:45:34.816707 sshd[1466]: pam_unix(sshd:session): session closed for user core
Apr 12 18:45:34.819418 systemd[1]: sshd@4-10.0.0.53:22-10.0.0.1:42510.service: Deactivated successfully.
Apr 12 18:45:34.819947 systemd[1]: session-5.scope: Deactivated successfully.
Apr 12 18:45:34.820428 systemd-logind[1091]: Session 5 logged out. Waiting for processes to exit.
Apr 12 18:45:34.821488 systemd[1]: Started sshd@5-10.0.0.53:22-10.0.0.1:42516.service.
Apr 12 18:45:34.822197 systemd-logind[1091]: Removed session 5.
Apr 12 18:45:34.858691 sshd[1474]: Accepted publickey for core from 10.0.0.1 port 42516 ssh2: RSA SHA256:oFTmhZVjs8bjXH/lYpDQZ+WL9oh5tEY90V+L3H6oLsU
Apr 12 18:45:34.859626 sshd[1474]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Apr 12 18:45:34.862500 systemd-logind[1091]: New session 6 of user core.
Apr 12 18:45:34.863268 systemd[1]: Started session-6.scope.
Apr 12 18:45:34.955849 sshd[1474]: pam_unix(sshd:session): session closed for user core
Apr 12 18:45:34.958128 systemd[1]: sshd@5-10.0.0.53:22-10.0.0.1:42516.service: Deactivated successfully.
Apr 12 18:45:34.958786 systemd[1]: session-6.scope: Deactivated successfully.
Apr 12 18:45:34.959220 systemd-logind[1091]: Session 6 logged out. Waiting for processes to exit.
Apr 12 18:45:34.959834 systemd-logind[1091]: Removed session 6.
Apr 12 18:46:09.654807 update_engine[1095]: I0412 18:46:09.654731 1095 update_attempter.cc:509] Updating boot flags...
Apr 12 18:46:28.821184 etcd-wrapper[1282]: {"level":"warn","ts":"2024-04-12T18:46:28.817Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"182.234303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"}
Apr 12 18:46:28.821184 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:28.817Z","caller":"traceutil/trace.go:171","msg":"trace[360879277] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"186.409235ms","start":"2024-04-12T18:46:28.631Z","end":"2024-04-12T18:46:28.817Z","steps":["trace[360879277] 'agreement among raft nodes before linearized reading' (duration: 72.210895ms)","trace[360879277] 'range keys from in-memory index tree' (duration: 110.00841ms)"],"step_count":2}
Apr 12 18:46:28.821184 etcd-wrapper[1282]: {"level":"warn","ts":"2024-04-12T18:46:28.817Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"182.671388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"}
Apr 12 18:46:28.821184 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:28.818Z","caller":"traceutil/trace.go:171","msg":"trace[982851662] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"185.899868ms","start":"2024-04-12T18:46:28.632Z","end":"2024-04-12T18:46:28.818Z","steps":["trace[982851662] 'agreement among raft nodes before linearized reading' (duration: 71.096087ms)","trace[982851662] 'range keys from in-memory index tree' (duration: 111.563509ms)"],"step_count":2}
Apr 12 18:46:28.821853 etcd-wrapper[1282]: {"level":"warn","ts":"2024-04-12T18:46:28.819Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"111.79819ms","expected-duration":"100ms","prefix":"","request":"header:<ID:7587877986780333705 > txn:<compare:<target:MOD key:\"/registry/apiregistration.k8s.io/apiservices/v1.apps\" mod_revision:0 > success:<request_put:<key:\"/registry/apiregistration.k8s.io/apiservices/v1.apps\" value_size:860 >> failure:<>>","response":"size:14"}
Apr 12 18:46:28.821853 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:28.820Z","caller":"traceutil/trace.go:171","msg":"trace[1915790074] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"169.58276ms","start":"2024-04-12T18:46:28.650Z","end":"2024-04-12T18:46:28.820Z","steps":["trace[1915790074] 'process raft request' (duration: 169.536874ms)"],"step_count":1}
Apr 12 18:46:28.821853 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:28.820Z","caller":"traceutil/trace.go:171","msg":"trace[1033267003] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"181.787082ms","start":"2024-04-12T18:46:28.638Z","end":"2024-04-12T18:46:28.820Z","steps":["trace[1033267003] 'process raft request' (duration: 64.835088ms)","trace[1033267003] 'compare' (duration: 108.667315ms)"],"step_count":2}
Apr 12 18:46:28.821853 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:28.820Z","caller":"traceutil/trace.go:171","msg":"trace[614338333] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"181.538194ms","start":"2024-04-12T18:46:28.638Z","end":"2024-04-12T18:46:28.820Z","steps":["trace[614338333] 'process raft request' (duration: 180.832125ms)"],"step_count":1}
Apr 12 18:46:28.821853 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:28.820Z","caller":"traceutil/trace.go:171","msg":"trace[880015810] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"173.305341ms","start":"2024-04-12T18:46:28.647Z","end":"2024-04-12T18:46:28.820Z","steps":["trace[880015810] 'process raft request' (duration: 172.631523ms)"],"step_count":1}
Apr 12 18:46:28.822009 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:28.820Z","caller":"traceutil/trace.go:171","msg":"trace[1606702846] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"178.117753ms","start":"2024-04-12T18:46:28.642Z","end":"2024-04-12T18:46:28.820Z","steps":["trace[1606702846] 'process raft request' (duration: 177.353533ms)"],"step_count":1}
Apr 12 18:46:28.822009 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:28.820Z","caller":"traceutil/trace.go:171","msg":"trace[1891685421] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"173.435556ms","start":"2024-04-12T18:46:28.647Z","end":"2024-04-12T18:46:28.820Z","steps":["trace[1891685421] 'process raft request' (duration: 172.629529ms)"],"step_count":1}
Apr 12 18:46:28.822009 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:28.820Z","caller":"traceutil/trace.go:171","msg":"trace[1458318953] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"173.383287ms","start":"2024-04-12T18:46:28.647Z","end":"2024-04-12T18:46:28.820Z","steps":["trace[1458318953] 'process raft request' (duration: 172.593139ms)"],"step_count":1}
Apr 12 18:46:28.828452 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:28.828Z","caller":"traceutil/trace.go:171","msg":"trace[154113217] linearizableReadLoop","detail":"{readStateIndex:22; appliedIndex:22; }","duration":"124.789373ms","start":"2024-04-12T18:46:28.703Z","end":"2024-04-12T18:46:28.828Z","steps":["trace[154113217] 'read index received' (duration: 124.782861ms)","trace[154113217] 'applied index is now lower than readState.Index' (duration: 5.671µs)"],"step_count":2}
Apr 12 18:46:28.828913 etcd-wrapper[1282]: {"level":"warn","ts":"2024-04-12T18:46:28.828Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"175.453876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"}
Apr 12 18:46:28.829315 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:28.829Z","caller":"traceutil/trace.go:171","msg":"trace[1176431667] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:18; }","duration":"175.758871ms","start":"2024-04-12T18:46:28.653Z","end":"2024-04-12T18:46:28.829Z","steps":["trace[1176431667] 'agreement among raft nodes before linearized reading' (duration: 175.44539ms)"],"step_count":1}
Apr 12 18:46:28.829578 etcd-wrapper[1282]: {"level":"warn","ts":"2024-04-12T18:46:28.828Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"137.362178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"}
Apr 12 18:46:28.829578 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:28.829Z","caller":"traceutil/trace.go:171","msg":"trace[371131678] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:18; }","duration":"137.847543ms","start":"2024-04-12T18:46:28.691Z","end":"2024-04-12T18:46:28.829Z","steps":["trace[371131678] 'agreement among raft nodes before linearized reading' (duration: 137.340498ms)"],"step_count":1}
Apr 12 18:46:28.830036 etcd-wrapper[1282]: {"level":"warn","ts":"2024-04-12T18:46:28.829Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"181.907559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/localhost\" ","response":"range_response_count:1 size:3208"}
Apr 12 18:46:28.830036 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:28.829Z","caller":"traceutil/trace.go:171","msg":"trace[686716226] range","detail":"{range_begin:/registry/minions/localhost; range_end:; response_count:1; response_revision:18; }","duration":"182.556692ms","start":"2024-04-12T18:46:28.647Z","end":"2024-04-12T18:46:28.829Z","steps":["trace[686716226] 'agreement among raft nodes before linearized reading' (duration: 181.883855ms)"],"step_count":1}
Apr 12 18:46:29.007429 etcd-wrapper[1282]: {"level":"warn","ts":"2024-04-12T18:46:29.006Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"166.900738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-mftq8\" ","response":"range_response_count:1 size:862"}
Apr 12 18:46:29.007429 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:29.007Z","caller":"traceutil/trace.go:171","msg":"trace[1613020986] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-mftq8; range_end:; response_count:1; response_revision:27; }","duration":"167.055459ms","start":"2024-04-12T18:46:28.840Z","end":"2024-04-12T18:46:29.007Z","steps":["trace[1613020986] 'agreement among raft nodes before linearized reading' (duration: 91.07612ms)","trace[1613020986] 'range keys from in-memory index tree' (duration: 75.80462ms)"],"step_count":2}
Apr 12 18:46:29.007429 etcd-wrapper[1282]: {"level":"warn","ts":"2024-04-12T18:46:29.006Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"166.893724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"}
Apr 12 18:46:29.007429 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:29.007Z","caller":"traceutil/trace.go:171","msg":"trace[2010214428] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:27; }","duration":"167.212755ms","start":"2024-04-12T18:46:28.840Z","end":"2024-04-12T18:46:29.007Z","steps":["trace[2010214428] 'agreement among raft nodes before linearized reading' (duration: 91.072983ms)","trace[2010214428] 'range keys from in-memory index tree' (duration: 75.802867ms)"],"step_count":2}
Apr 12 18:46:29.008265 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:29.007Z","caller":"traceutil/trace.go:171","msg":"trace[1290034743] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"147.158051ms","start":"2024-04-12T18:46:28.860Z","end":"2024-04-12T18:46:29.007Z","steps":["trace[1290034743] 'process raft request' (duration: 146.978823ms)"],"step_count":1}
Apr 12 18:46:29.008265 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:29.007Z","caller":"traceutil/trace.go:171","msg":"trace[32814690] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"159.825154ms","start":"2024-04-12T18:46:28.847Z","end":"2024-04-12T18:46:29.007Z","steps":["trace[32814690] 'process raft request' (duration: 83.537143ms)","trace[32814690] 'compare' (duration: 75.626255ms)"],"step_count":2}
Apr 12 18:46:29.008265 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:29.007Z","caller":"traceutil/trace.go:171","msg":"trace[978370512] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"147.214548ms","start":"2024-04-12T18:46:28.860Z","end":"2024-04-12T18:46:29.007Z","steps":["trace[978370512] 'process raft request' (duration: 146.943647ms)"],"step_count":1}
Apr 12 18:46:29.008265 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:29.007Z","caller":"traceutil/trace.go:171","msg":"trace[1501705700] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"146.844511ms","start":"2024-04-12T18:46:28.860Z","end":"2024-04-12T18:46:29.007Z","steps":["trace[1501705700] 'process raft request' (duration: 146.526693ms)"],"step_count":1}
Apr 12 18:46:29.008265 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:29.007Z","caller":"traceutil/trace.go:171","msg":"trace[1492452727] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"145.576854ms","start":"2024-04-12T18:46:28.861Z","end":"2024-04-12T18:46:29.007Z","steps":["trace[1492452727] 'process raft request' (duration: 145.546918ms)"],"step_count":1}
Apr 12 18:46:29.008616 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:29.007Z","caller":"traceutil/trace.go:171","msg":"trace[1395691255] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"157.03549ms","start":"2024-04-12T18:46:28.850Z","end":"2024-04-12T18:46:29.007Z","steps":["trace[1395691255] 'process raft request' (duration: 156.548764ms)"],"step_count":1}
Apr 12 18:46:29.008616 etcd-wrapper[1282]: {"level":"warn","ts":"2024-04-12T18:46:29.007Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"166.579553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"}
Apr 12 18:46:29.008616 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:29.007Z","caller":"traceutil/trace.go:171","msg":"trace[42510088] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:27; }","duration":"167.268851ms","start":"2024-04-12T18:46:28.840Z","end":"2024-04-12T18:46:29.007Z","steps":["trace[42510088] 'agreement among raft nodes before linearized reading' (duration: 90.658724ms)","trace[42510088] 'range keys from in-memory index tree' (duration: 75.892696ms)"],"step_count":2}
Apr 12 18:46:29.008616 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:29.007Z","caller":"traceutil/trace.go:171","msg":"trace[1541190988] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"149.041247ms","start":"2024-04-12T18:46:28.858Z","end":"2024-04-12T18:46:29.007Z","steps":["trace[1541190988] 'process raft request' (duration: 148.865997ms)"],"step_count":1}
Apr 12 18:46:29.008616 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:29.007Z","caller":"traceutil/trace.go:171","msg":"trace[699916029] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"148.473537ms","start":"2024-04-12T18:46:28.858Z","end":"2024-04-12T18:46:29.007Z","steps":["trace[699916029] 'process raft request' (duration: 148.301093ms)"],"step_count":1}
Apr 12 18:46:29.028037 etcd-wrapper[1282]: {"level":"warn","ts":"2024-04-12T18:46:29.022Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"122.696812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"}
Apr 12 18:46:29.028037 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:29.022Z","caller":"traceutil/trace.go:171","msg":"trace[1908338653] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:35; }","duration":"122.7856ms","start":"2024-04-12T18:46:28.899Z","end":"2024-04-12T18:46:29.022Z","steps":["trace[1908338653] 'agreement among raft nodes before linearized reading' (duration: 122.65305ms)"],"step_count":1}
Apr 12 18:46:29.532725 etcd-wrapper[1282]: {"level":"warn","ts":"2024-04-12T18:46:29.531Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"228.565154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/localhost.17c59cc031baaac3\" ","response":"range_response_count:1 size:588"}
Apr 12 18:46:29.532725 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:29.531Z","caller":"traceutil/trace.go:171","msg":"trace[1247429815] range","detail":"{range_begin:/registry/events/default/localhost.17c59cc031baaac3; range_end:; response_count:1; response_revision:88; }","duration":"228.670551ms","start":"2024-04-12T18:46:29.302Z","end":"2024-04-12T18:46:29.531Z","steps":["trace[1247429815] 'range keys from in-memory index tree' (duration: 227.250347ms)"],"step_count":1}
Apr 12 18:46:46.874886 etcd-wrapper[1282]: {"level":"warn","ts":"2024-04-12T18:46:46.874Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"108.268546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"}
Apr 12 18:46:46.874886 etcd-wrapper[1282]: {"level":"warn","ts":"2024-04-12T18:46:46.874Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"108.330623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"}
Apr 12 18:46:46.874886 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:46.874Z","caller":"traceutil/trace.go:171","msg":"trace[1250239338] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:365; }","duration":"108.386659ms","start":"2024-04-12T18:46:46.766Z","end":"2024-04-12T18:46:46.874Z","steps":["trace[1250239338] 'agreement among raft nodes before linearized reading' (duration: 46.922359ms)","trace[1250239338] 'range keys from in-memory index tree' (duration: 61.30972ms)"],"step_count":2}
Apr 12 18:46:46.874886 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:46.874Z","caller":"traceutil/trace.go:171","msg":"trace[29566560] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:365; }","duration":"108.417386ms","start":"2024-04-12T18:46:46.766Z","end":"2024-04-12T18:46:46.874Z","steps":["trace[29566560] 'agreement among raft nodes before linearized reading' (duration: 46.93911ms)","trace[29566560] 'range keys from in-memory index tree' (duration: 61.335057ms)"],"step_count":2}
Apr 12 18:46:46.875491 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:46.874Z","caller":"traceutil/trace.go:171","msg":"trace[90785176] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"108.170723ms","start":"2024-04-12T18:46:46.766Z","end":"2024-04-12T18:46:46.874Z","steps":["trace[90785176] 'process raft request' (duration: 46.665988ms)","trace[90785176] 'compare' (duration: 61.222685ms)"],"step_count":2}
Apr 12 18:46:46.875491 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:46.874Z","caller":"traceutil/trace.go:171","msg":"trace[1012567382] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"105.667433ms","start":"2024-04-12T18:46:46.768Z","end":"2024-04-12T18:46:46.874Z","steps":["trace[1012567382] 'process raft request' (duration: 105.574569ms)"],"step_count":1}
Apr 12 18:46:46.978080 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:46.977Z","caller":"traceutil/trace.go:171","msg":"trace[1757456406] linearizableReadLoop","detail":"{readStateIndex:379; appliedIndex:379; }","duration":"100.581754ms","start":"2024-04-12T18:46:46.877Z","end":"2024-04-12T18:46:46.977Z","steps":["trace[1757456406] 'read index received' (duration: 100.573869ms)","trace[1757456406] 'applied index is now lower than readState.Index' (duration: 6.893µs)"],"step_count":2}
Apr 12 18:46:46.984021 etcd-wrapper[1282]: {"level":"warn","ts":"2024-04-12T18:46:46.983Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"168.246624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"}
Apr 12 18:46:46.984021 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:46.983Z","caller":"traceutil/trace.go:171","msg":"trace[1656701783] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:368; }","duration":"168.303411ms","start":"2024-04-12T18:46:46.815Z","end":"2024-04-12T18:46:46.983Z","steps":["trace[1656701783] 'agreement among raft nodes before linearized reading' (duration: 162.414545ms)"],"step_count":1}
Apr 12 18:46:46.984021 etcd-wrapper[1282]: {"level":"warn","ts":"2024-04-12T18:46:46.983Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"163.529279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"}
Apr 12 18:46:46.984150 etcd-wrapper[1282]: {"level":"warn","ts":"2024-04-12T18:46:46.983Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"128.815173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"}
Apr 12 18:46:46.984150 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:46.983Z","caller":"traceutil/trace.go:171","msg":"trace[657205332] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:368; }","duration":"163.627362ms","start":"2024-04-12T18:46:46.820Z","end":"2024-04-12T18:46:46.983Z","steps":["trace[657205332] 'agreement among raft nodes before linearized reading' (duration: 157.646403ms)"],"step_count":1}
Apr 12 18:46:46.984395 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:46.984Z","caller":"traceutil/trace.go:171","msg":"trace[896460408] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"102.999403ms","start":"2024-04-12T18:46:46.881Z","end":"2024-04-12T18:46:46.984Z","steps":["trace[896460408] 'process raft request' (duration: 96.710575ms)"],"step_count":1}
Apr 12 18:46:46.984395 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:46.984Z","caller":"traceutil/trace.go:171","msg":"trace[643352264] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:368; }","duration":"129.02716ms","start":"2024-04-12T18:46:46.854Z","end":"2024-04-12T18:46:46.984Z","steps":["trace[643352264] 'agreement among raft nodes before linearized reading' (duration: 122.926737ms)"],"step_count":1}
Apr 12 18:46:46.984507 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:46.984Z","caller":"traceutil/trace.go:171","msg":"trace[290134966] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"101.809509ms","start":"2024-04-12T18:46:46.882Z","end":"2024-04-12T18:46:46.984Z","steps":["trace[290134966] 'process raft request' (duration: 101.759485ms)"],"step_count":1}
Apr 12 18:46:46.984777 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:46.984Z","caller":"traceutil/trace.go:171","msg":"trace[342547864] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"102.471823ms","start":"2024-04-12T18:46:46.882Z","end":"2024-04-12T18:46:46.984Z","steps":["trace[342547864] 'process raft request' (duration: 101.880632ms)"],"step_count":1}
Apr 12 18:46:46.984848 etcd-wrapper[1282]: {"level":"warn","ts":"2024-04-12T18:46:46.984Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"104.607813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"}
Apr 12 18:46:46.984883 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:46.984Z","caller":"traceutil/trace.go:171","msg":"trace[546764709] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:373; }","duration":"104.715705ms","start":"2024-04-12T18:46:46.880Z","end":"2024-04-12T18:46:46.984Z","steps":["trace[546764709] 'agreement among raft nodes before linearized reading' (duration: 104.553802ms)"],"step_count":1}
Apr 12 18:46:46.984914 etcd-wrapper[1282]: {"level":"warn","ts":"2024-04-12T18:46:46.984Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"102.432809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"}
Apr 12 18:46:46.985067 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:46.984Z","caller":"traceutil/trace.go:171","msg":"trace[1682558935] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:373; }","duration":"102.474128ms","start":"2024-04-12T18:46:46.882Z","end":"2024-04-12T18:46:46.984Z","steps":["trace[1682558935] 'agreement among raft nodes before linearized reading' (duration: 102.419825ms)"],"step_count":1}
Apr 12 18:46:46.985175 etcd-wrapper[1282]: {"level":"warn","ts":"2024-04-12T18:46:46.984Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"103.805486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"}
Apr 12 18:46:46.985175 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:46.984Z","caller":"traceutil/trace.go:171","msg":"trace[2077256436] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"101.867908ms","start":"2024-04-12T18:46:46.882Z","end":"2024-04-12T18:46:46.984Z","steps":["trace[2077256436] 'process raft request' (duration: 101.523692ms)"],"step_count":1}
Apr 12 18:46:46.985175 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:46:46.984Z","caller":"traceutil/trace.go:171","msg":"trace[118429819] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:373; }","duration":"103.826837ms","start":"2024-04-12T18:46:46.881Z","end":"2024-04-12T18:46:46.984Z","steps":["trace[118429819] 'agreement among raft nodes before linearized reading' (duration: 103.793104ms)"],"step_count":1}
Apr 12 18:47:03.308851 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:47:03.308Z","caller":"traceutil/trace.go:171","msg":"trace[1132668486] linearizableReadLoop","detail":"{readStateIndex:524; appliedIndex:524; }","duration":"120.433295ms","start":"2024-04-12T18:47:03.188Z","end":"2024-04-12T18:47:03.308Z","steps":["trace[1132668486] 'read index received' (duration: 120.425569ms)","trace[1132668486] 'applied index is now lower than readState.Index' (duration: 6.574µs)"],"step_count":2}
Apr 12 18:47:03.349747 etcd-wrapper[1282]: {"level":"warn","ts":"2024-04-12T18:47:03.349Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"161.50124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-flannel/kube-flannel-ds-9sklt\" ","response":"range_response_count:1 size:5288"}
Apr 12 18:47:03.349747 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:47:03.349Z","caller":"traceutil/trace.go:171","msg":"trace[1251684001] range","detail":"{range_begin:/registry/pods/kube-flannel/kube-flannel-ds-9sklt; range_end:; response_count:1; response_revision:508; }","duration":"161.564643ms","start":"2024-04-12T18:47:03.187Z","end":"2024-04-12T18:47:03.349Z","steps":["trace[1251684001] 'agreement among raft nodes before linearized reading' (duration: 120.54429ms)","trace[1251684001] 'range keys from in-memory index tree' (duration: 40.915971ms)"],"step_count":2}
Apr 12 18:47:03.669281 etcd-wrapper[1282]: {"level":"warn","ts":"2024-04-12T18:47:03.668Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"176.772277ms","expected-duration":"100ms","prefix":"","request":"header:<ID:7587877986780334715 > txn:<compare:<target:MOD key:\"/registry/events/kube-system/kube-proxy-gfkn2.17c59cc9b90f38cf\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-system/kube-proxy-gfkn2.17c59cc9b90f38cf\" value_size:638 lease:7587877986780333762 >> failure:<>>","response":"size:16"}
Apr 12 18:47:03.669281 etcd-wrapper[1282]: {"level":"info","ts":"2024-04-12T18:47:03.668Z","caller":"traceutil/trace.go:171","msg":"trace[820312584] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"177.031839ms","start":"2024-04-12T18:47:03.491Z","end":"2024-04-12T18:47:03.668Z","steps":["trace[820312584] 'compare' (duration: 176.685549ms)"],"step_count":1}