[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-10.fc44 06/10/2025 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 478564243 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001010] APIC: Switch to symmetric I/O mode setup [ 0.002317] x2apic enabled [ 0.003009] Switched APIC routing to physical x2apic. [ 0.004019] kvm-guest: setup PV IPIs [ 0.008000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008026] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009018] pid_max: default: 32768 minimum: 301 [ 0.011126] LSM: Security Framework initializing [ 0.012067] Yama: becoming mindful. [ 0.013042] SELinux: Initializing. [ 0.014099] *** VALIDATE selinux *** [ 0.023340] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.028197] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.029153] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030138] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031185] *** VALIDATE tmpfs *** [ 0.033604] *** VALIDATE proc *** [ 0.035205] *** VALIDATE cgroup *** [ 0.036014] *** VALIDATE cgroup2 *** [ 0.038100] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.039198] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.040010] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.041047] Spectre V2 : User space: Vulnerable [ 0.042013] Speculative Store Bypass: Vulnerable [ 0.045381] debug: unmapping init [mem 0xffffffffaae59000-0xffffffffaae60fff] [ 0.048000] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.048845] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.049024] ... version: 2 [ 0.050009] ... bit width: 48 [ 0.051013] ... generic registers: 4 [ 0.052014] ... value mask: 0000ffffffffffff [ 0.053014] ... max period: 00007fffffffffff [ 0.054012] ... fixed-purpose events: 3 [ 0.055011] ... event mask: 000000070000000f [ 0.056323] rcu: Hierarchical SRCU implementation. [ 0.058469] smp: Bringing up secondary CPUs ... [ 0.059588] x86: Booting SMP configuration: [ 0.060031] .... node #0, CPUs: #1 #2 #3 [ 0.068020] smp: Brought up 1 node, 4 CPUs [ 0.070017] smpboot: Max logical packages: 1 [ 0.071015] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.140560] node 0 deferred pages initialised in 68ms [ 0.143471] devtmpfs: initialized [ 0.144148] x86/mm: Memory block size: 128MB [ 0.146176] gcov: version magic: 0x41383552 [ 0.148037] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.149061] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.150238] pinctrl core: initialized pinctrl subsystem [ 0.151161] [ 0.151434] ************************************************************* [ 0.152012] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.153008] ** ** [ 0.154007] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.155008] ** ** [ 0.156007] ** This means that this kernel is built to expose internal ** [ 0.157010] ** IOMMU data structures, which may compromise security on ** [ 0.158008] ** your system. ** [ 0.159018] ** ** [ 0.160011] ** If you see this message and you are not debugging the ** [ 0.161009] ** kernel, report this immediately to your vendor! ** [ 0.162007] ** ** [ 0.163012] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.164008] ************************************************************* [ 0.165585] NET: Registered protocol family 16 [ 0.166317] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.167057] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.168039] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.169367] cpuidle: using governor menu [ 0.170606] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.173394] PCI: Using configuration type 1 for base access [ 0.175135] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.183077] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.184025] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.187160] cryptd: max_cpu_qlen set to 1000 [ 0.189176] ACPI: Added _OSI(Module Device) [ 0.190010] ACPI: Added _OSI(Processor Device) [ 0.190893] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.192008] ACPI: Added _OSI(Processor Aggregator Device) [ 0.194942] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.199300] ACPI: Interpreter enabled [ 0.201053] ACPI: PM: (supports S0 S3 S4 S5) [ 0.202008] ACPI: Using IOAPIC for interrupt routing [ 0.203087] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.206620] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.216662] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.219034] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.220012] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.222069] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.226304] acpiphp: Slot [2] registered [ 0.227099] acpiphp: Slot [5] registered [ 0.228084] acpiphp: Slot [6] registered [ 0.230092] acpiphp: Slot [3] registered [ 0.231094] acpiphp: Slot [4] registered [ 0.232158] acpiphp: Slot [7] registered [ 0.233070] acpiphp: Slot [8] registered [ 0.234066] acpiphp: Slot [9] registered [ 0.235066] acpiphp: Slot [10] registered [ 0.236067] acpiphp: Slot [11] registered [ 0.237072] acpiphp: Slot [12] registered [ 0.238099] acpiphp: Slot [13] registered [ 0.239069] acpiphp: Slot [14] registered [ 0.240085] acpiphp: Slot [15] registered [ 0.241067] acpiphp: Slot [16] registered [ 0.242067] acpiphp: Slot [17] registered [ 0.243071] acpiphp: Slot [18] registered [ 0.244097] acpiphp: Slot [19] registered [ 0.245073] acpiphp: Slot [20] registered [ 0.246070] acpiphp: Slot [21] registered [ 0.247068] acpiphp: Slot [22] registered [ 0.248072] acpiphp: Slot [23] registered [ 0.249053] acpiphp: Slot [24] registered [ 0.250056] acpiphp: Slot [25] registered [ 0.251054] acpiphp: Slot [26] registered [ 0.252034] acpiphp: Slot [27] registered [ 0.253071] acpiphp: Slot [28] registered [ 0.254026] acpiphp: Slot [29] registered [ 0.255071] acpiphp: Slot [30] registered [ 0.257077] acpiphp: Slot [31] registered [ 0.258044] PCI host bridge to bus 0000:00 [ 0.259014] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.260017] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.262015] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.263016] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.265017] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.267019] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.268146] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.270806] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.273067] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.278920] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.282518] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.284012] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.286013] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.288015] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.289563] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.291847] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.294045] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.297793] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.301983] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.312017] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.318012] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.323212] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.328013] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.332016] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.351025] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.367919] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.375019] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.380016] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.393023] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.400757] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.402259] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.404317] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.406317] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.408187] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.412181] iommu: Default domain type: Passthrough [ 0.414501] SCSI subsystem initialized [ 0.415151] ACPI: bus type USB registered [ 0.417087] usbcore: registered new interface driver usbfs [ 0.418074] usbcore: registered new interface driver hub [ 0.420095] usbcore: registered new device driver usb [ 0.421157] pps_core: LinuxPPS API ver. 1 registered [ 0.422050] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.425057] PTP clock support registered [ 0.427145] EDAC MC: Ver: 3.0.0 [ 0.428450] PCI: Using ACPI for IRQ routing [ 0.429828] NetLabel: Initializing [ 0.431010] NetLabel: domain hash size = 128 [ 0.432008] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.434078] NetLabel: unlabeled traffic allowed by default [ 0.436041] vgaarb: loaded [ 0.437264] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.438013] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.446000] clocksource: Switched to clocksource kvm-clock [ 0.556528] VFS: Disk quotas dquot_6.6.0 [ 0.557717] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.559429] *** VALIDATE ramfs *** [ 0.560234] *** VALIDATE hugetlbfs *** [ 0.561413] pnp: PnP ACPI init [ 0.563905] pnp: PnP ACPI: found 6 devices [ 0.578206] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.580551] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.581990] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.583484] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.585127] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.586724] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.588687] NET: Registered protocol family 2 [ 0.590822] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.594837] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.597811] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.601809] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.605964] TCP: Hash tables configured (established 65536 bind 65536) [ 0.609425] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.612751] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.615825] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.618917] NET: Registered protocol family 1 [ 0.621328] RPC: Registered named UNIX socket transport module. [ 0.623309] RPC: Registered udp transport module. [ 0.624319] RPC: Registered tcp transport module. [ 0.625856] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.627623] NET: Registered protocol family 44 [ 0.628766] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.630391] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.631831] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.633411] PCI: CLS 0 bytes, default 64 [ 0.634790] Unpacking initramfs... [ 2.007154] debug: unmapping init [mem 0xffff8ae0fcc64000-0xffff8ae0fffcffff] [ 2.010323] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.012426] software IO TLB: mapped [mem 0x00000000a0000000-0x00000000a4000000] (64MB) [ 2.014473] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.490566] Initialise system trusted keyrings [ 2.492481] Key type blacklist registered [ 2.494775] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.505619] zbud: loaded [ 2.508793] *** VALIDATE nfs *** [ 2.510257] *** VALIDATE nfs4 *** [ 2.511930] pstore: using deflate compression [ 2.515708] Platform Keyring initialized [ 2.632803] NET: Registered protocol family 38 [ 2.634702] Key type asymmetric registered [ 2.636631] Asymmetric key parser 'x509' registered [ 2.639124] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.642898] io scheduler mq-deadline registered [ 2.644860] io scheduler kyber registered [ 2.646658] io scheduler bfq registered [ 2.648705] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.652114] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.655203] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.658380] ACPI: Power Button [PWRF] [ 2.665402] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.672903] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.685785] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.715077] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.744870] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.748893] Non-volatile memory driver v1.3 [ 2.750603] Linux agpgart interface v0.103 [ 2.779552] virtio_blk virtio1: [vda] 142768 512-byte logical blocks (73.1 MB/69.7 MiB) [ 2.781269] vda: detected capacity change from 0 to 73097216 [ 2.795927] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.797941] vdb: detected capacity change from 0 to 1073741824 [ 2.803975] libphy: Fixed MDIO Bus: probed [ 2.812031] usbcore: registered new interface driver usbserial_generic [ 2.813697] usbserial: USB Serial support registered for generic [ 2.815318] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.818033] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.819149] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.820867] mousedev: PS/2 mouse device common for all mice [ 2.823247] rtc_cmos 00:05: RTC can wake from S4 [ 2.825519] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.825723] rtc_cmos 00:05: registered as rtc0 [ 2.830962] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.833741] intel_pstate: CPU model not supported [ 2.836365] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.840140] hid: raw HID events driver (C) Jiri Kosina [ 2.841967] usbcore: registered new interface driver usbhid [ 2.843657] usbhid: USB HID core driver [ 2.845120] drop_monitor: Initializing network drop monitor service [ 2.848104] Initializing XFRM netlink socket [ 2.848356] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.849686] NET: Registered protocol family 10 [ 2.854180] Segment Routing with IPv6 [ 2.856782] NET: Registered protocol family 17 [ 2.859555] mpls_gso: MPLS GSO support [ 2.865059] RAS: Correctable Errors collector initialized. [ 2.868084] AVX version of gcm_enc/dec engaged. [ 2.870298] AES CTR mode by8 optimization enabled [ 2.948230] sched_clock: Marking stable (2948203921, 0)->(3848615731, -900411810) [ 2.950645] registered taskstats version 1 [ 2.952316] Loading compiled-in X.509 certificates [ 2.954784] zswap: loaded using pool lzo/zbud [ 2.985165] Key type big_key registered [ 3.001741] Key type encrypted registered [ 3.003564] ima: No TPM chip found, activating TPM-bypass! [ 3.007212] ima: Allocated hash algorithm: sha1 [ 3.008924] ima: No architecture policies found [ 3.010886] evm: Initialising EVM extended attributes: [ 3.012959] evm: security.selinux [ 3.014327] evm: security.ima [ 3.015471] evm: security.capability [ 3.017099] evm: HMAC attrs: 0x1 [ 3.019683] rtc_cmos 00:05: setting system clock to 2026-09-10 16:35:07 UTC (1789058107) [ 3.026724] debug: unmapping init [mem 0xffffffffabe03000-0xffffffffabffffff] [ 3.030222] debug: unmapping init [mem 0xffffffffaab82000-0xffffffffaae58fff] [ 3.039116] Write protecting the kernel read-only data: 28672k [ 3.042702] debug: unmapping init [mem 0xffffffffa9203000-0xffffffffa93fffff] [ 3.045685] debug: unmapping init [mem 0xffffffffa9b14000-0xffffffffa9bfffff] [ 3.078513] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 3.086808] systemd[1]: Detected virtualization kvm. [ 3.089417] systemd[1]: Detected architecture x86-64. [ 3.091864] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.120114] systemd[1]: No hostname configured. [ 3.121860] systemd[1]: Set hostname to . [ 3.123902] random: systemd: uninitialized urandom read (16 bytes read) [ 3.126778] systemd[1]: Initializing machine ID from random generator. [ 3.182351] random: ln: uninitialized urandom read (6 bytes read) [ 3.263440] random: systemd: uninitialized urandom read (16 bytes read) [ 3.266024] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 3.269819] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ 3.273555] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Initrd Root Device. [ OK ] Listening on Journal Socket. Starting Apply Kernel Variables... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Slices. [ OK ] Reached target Paths. [ OK ] Started Memstrack Anylazing Service. Starting Journal Service... [ OK ] Reached target Sockets. [ OK ] Reached target Swap. Starting Setup Virtual Console... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Create Static Device Nodes in /dev... [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 3.917707] device-mapper: uevent: version 1.0.3 [ 3.919915] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] [ 4.168587] random: fast init done Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 4.713528] virtio_net virtio0 ens2: renamed from eth0 [ 4.721384] scsi host0: ata_piix [ 4.740426] scsi host1: ata_piix [ 4.742332] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.744211] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.408951] dracut-initqueue[580]: RTNETLINK answers: File exists [ 9.525932] random: crng init done [ 9.526864] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 10.047737] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Swap. [ OK ] Stopped target Local File Systems. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.355463] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.648512] SELinux: Disabled at runtime. [ 11.712258] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 11.722397] systemd[1]: Detected virtualization kvm. [ 11.724546] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.273804] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.277201] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.283484] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.287577] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.291107] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.299531] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.309285] systemd[1]: Mounting Huge Pages File System... Mounting Huge Pages File System... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target rpc_pipefs.target. Mounting Kernel Debug File System... Mounting POSIX Message Queue File System... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Stopped target Initrd File Systems. [ OK ] Created slice system-getty.slice. [ OK ] Listening on udev Control Socket. [ 12.421929] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Starting Apply Kernel Variables... [ OK ] Listening on Process Core Dump Socket. Starting Remount Root and Kernel File Systems... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target RPC Port Mapper. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Started Journal Service. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Apply Kernel Variables. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... Starting udev Kernel Device Manager... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 12.893874] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 13.234516] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.275366] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.375438] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.396145] EDAC sbridge: Ver: 1.1.2 [ 14.570771] Key type dns_resolver registered [ 14.874165] NFS: Registering the id_resolver key type [ 14.876385] Key type id_resolver registered [ 14.878258] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... Starting Mark the need to relabel after reboot... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started dnf makecache --timer. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Login Service... Starting Restore /run/initramfs on shutdown... [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Started irqbalance daemon. [ OK ] Reached target sshd-keygen.target. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg231-client login: [ 40.714882] libcfs: loading out-of-tree module taints kernel. [ 40.762225] Key type ._llcrypt registered [ 40.764840] Key type .llcrypt registered [ 41.063209] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 41.071698] alg: No test for adler32 (adler32-zlib) [ 42.112525] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 42.462625] LNet: Added LNI 192.168.202.31@tcp [8/256/0/180] [ 44.095266] Key type lgssc registered [ 44.921860] Lustre: Echo OBD driver; http://www.lustre.org/ [ 48.328298] alg: No test for zstdfast (zstdfast-lustre-generic) [ 48.351654] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 84.720629] hrtimer: interrupt took 5767350 ns [ 200.957495] Lustre: Mounted lustre-client [ 206.787302] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 222.837820] Lustre: DEBUG MARKER: oleg231-client.virtnet: executing check_logdir /tmp/testlogs/ [ 226.788050] Lustre: lustre-OST0000-osc-ffff8ae143ea4000: disconnect after 24s idle [ 228.647760] Lustre: DEBUG MARKER: oleg231-client.virtnet: executing yml_node [ 233.514798] Lustre: DEBUG MARKER: Client: 2.17.53.26 [ 236.366859] Lustre: DEBUG MARKER: MDS: 2.17.53.26 [ 239.576667] Lustre: DEBUG MARKER: OSS: 2.17.53.26 [ 241.435084] Lustre: DEBUG MARKER: -----============= acceptance-small: sanity ============----- Thu Sep 10 12:39:04 EDT 2026 [ 264.407159] Lustre: DEBUG MARKER: - need MDS1_VERSION <= 2.16.61-1-g89cf292a8c2 (34682138 <= 34618625) for LU-18938, skip 360 [ 266.060846] Lustre: DEBUG MARKER: - need MDS1_VERSION < v2_14_55-100-g8a84c7f9c7 (34682138 < 34486116) for LU-14927, skip 0f [ 267.891483] Lustre: DEBUG MARKER: excepting tests: 42a 42c 42b 118c 118d 407 119i 817 411a [ 269.656555] Lustre: DEBUG MARKER: skipping tests SLOW=no: 27m 60i 64b 68 71 135 136 230d 300o 842 51c 51e 834 [ 271.418603] Lustre: DEBUG MARKER: === sanity: start setup 12:39:34 (1789058374) === [ 275.740390] Lustre: DEBUG MARKER: oleg231-client.virtnet: executing check_config_client /mnt/lustre [ 294.656890] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 308.250977] Lustre: DEBUG MARKER: === sanity: finish setup 12:40:11 (1789058411) === [ 316.584837] Lustre: DEBUG MARKER: == sanity test 84: lz4/lz4hc/lzstd compression/decompression kernel module ========================================================== 12:40:19 (1789058419) [ 317.196316] lustre_kcompr_25715: ***** [ 317.198690] lustre_kcompr_25715: (de)compression test on random binary data [ 317.209918] lustre_kcompr_25715: ***** [ 317.245102] lustre_kcompr_25715: compr lz4(1) in 64kB chunks took 8668 us (461 MB/s), compress ratio: 99.24 [ 317.262610] lustre_kcompr_25715: compr lz4(1) in 128kB chunks took 4033 us (991 MB/s), compress ratio: 104.39 [ 317.292774] lustre_kcompr_25715: compr lz4(1) in 256kB chunks took 8314 us (481 MB/s), compress ratio: 107.57 [ 317.312532] lustre_kcompr_25715: compr lz4(1) in 512kB chunks took 5591 us (715 MB/s), compress ratio: 109.55 [ 317.327281] lustre_kcompr_25715: compr lz4(1) in 1024kB chunks took 1348 us (2967 MB/s), compress ratio: 110.81 [ 317.345594] lustre_kcompr_25715: compr lz4(1) in 2048kB chunks took 2186 us (1829 MB/s), compress ratio: 110.91 [ 317.370534] lustre_kcompr_25715: compr lz4(1) in 4096kB chunks took 9394 us (425 MB/s), compress ratio: 111.27 [ 317.396379] lustre_kcompr_25715: decompr lz4(1) took 3348 us (10 MB/s) [ 317.407018] lustre_kcompr_25715: test_comp_compress_decompress(lz4,1) ret 0 [ 317.421723] lustre_kcompr_25715: acompr lz4(1) of 128kB chunks took 95 us (1315 MB/s), compress ratio: 103.12 [ 317.437368] lustre_kcompr_25715: adecompr lz4(1) took 991 us (1 MB/s) [ 317.474813] lustre_kcompr_25715: test_acomp_compress_decompress(lz4,1) ret 0 [ 317.503609] lustre_kcompr_25715: compr lz4(4) in 64kB chunks took 6899 us (579 MB/s), compress ratio: 84.80 [ 317.522656] lustre_kcompr_25715: compr lz4(4) in 128kB chunks took 4112 us (972 MB/s), compress ratio: 94.79 [ 317.540732] lustre_kcompr_25715: compr lz4(4) in 256kB chunks took 4987 us (802 MB/s), compress ratio: 100.99 [ 317.556629] lustre_kcompr_25715: compr lz4(4) in 512kB chunks took 6228 us (642 MB/s), compress ratio: 104.97 [ 317.572346] lustre_kcompr_25715: compr lz4(4) in 1024kB chunks took 5198 us (769 MB/s), compress ratio: 107.43 [ 317.603750] lustre_kcompr_25715: compr lz4(4) in 2048kB chunks took 6345 us (630 MB/s), compress ratio: 108.78 [ 317.623630] lustre_kcompr_25715: compr lz4(4) in 4096kB chunks took 4441 us (900 MB/s), compress ratio: 108.99 [ 317.659549] lustre_kcompr_25715: decompr lz4(4) took 9264 us (3 MB/s) [ 317.671243] lustre_kcompr_25715: test_comp_compress_decompress(lz4,4) ret 0 [ 317.685611] lustre_kcompr_25715: acompr lz4(4) of 128kB chunks took 91 us (1373 MB/s), compress ratio: 91.53 [ 317.697165] lustre_kcompr_25715: adecompr lz4(4) took 248 us (5 MB/s) [ 317.708034] lustre_kcompr_25715: test_acomp_compress_decompress(lz4,4) ret 0 [ 317.725867] lustre_kcompr_25715: compr lz4(9) in 64kB chunks took 3160 us (1265 MB/s), compress ratio: 62.68 [ 317.735907] lustre_kcompr_25715: compr lz4(9) in 128kB chunks took 3474 us (1151 MB/s), compress ratio: 77.37 [ 317.745678] lustre_kcompr_25715: compr lz4(9) in 256kB chunks took 2312 us (1730 MB/s), compress ratio: 89.72 [ 317.762325] lustre_kcompr_25715: compr lz4(9) in 512kB chunks took 1364 us (2932 MB/s), compress ratio: 96.60 [ 317.777860] lustre_kcompr_25715: compr lz4(9) in 1024kB chunks took 2750 us (1454 MB/s), compress ratio: 99.12 [ 317.789478] lustre_kcompr_25715: compr lz4(9) in 2048kB chunks took 1546 us (2587 MB/s), compress ratio: 102.05 [ 317.802402] lustre_kcompr_25715: compr lz4(9) in 4096kB chunks took 3250 us (1230 MB/s), compress ratio: 103.23 [ 317.832341] lustre_kcompr_25715: decompr lz4(9) took 3693 us (10 MB/s) [ 317.839187] lustre_kcompr_25715: test_comp_compress_decompress(lz4,9) ret 0 [ 317.846342] lustre_kcompr_25715: acompr lz4(9) of 128kB chunks took 146 us (856 MB/s), compress ratio: 75.24 [ 317.854612] lustre_kcompr_25715: adecompr lz4(9) took 205 us (8 MB/s) [ 317.863407] lustre_kcompr_25715: test_acomp_compress_decompress(lz4,9) ret 0 [ 317.894544] lustre_kcompr_25715: compr lz4(15) in 64kB chunks took 6969 us (573 MB/s), compress ratio: 29.56 [ 317.918099] lustre_kcompr_25715: compr lz4(15) in 128kB chunks took 7457 us (536 MB/s), compress ratio: 44.23 [ 317.936532] lustre_kcompr_25715: compr lz4(15) in 256kB chunks took 3866 us (1034 MB/s), compress ratio: 60.14 [ 317.944633] lustre_kcompr_25715: compr lz4(15) in 512kB chunks took 3215 us (1244 MB/s), compress ratio: 72.98 [ 317.957694] lustre_kcompr_25715: compr lz4(15) in 1024kB chunks took 3668 us (1090 MB/s), compress ratio: 80.73 [ 317.978587] lustre_kcompr_25715: compr lz4(15) in 2048kB chunks took 6098 us (655 MB/s), compress ratio: 85.98 [ 317.999551] lustre_kcompr_25715: compr lz4(15) in 4096kB chunks took 2046 us (1955 MB/s), compress ratio: 89.99 [ 318.038375] lustre_kcompr_25715: decompr lz4(15) took 7733 us (5 MB/s) [ 318.043862] lustre_kcompr_25715: test_comp_compress_decompress(lz4,15) ret 0 [ 318.054926] lustre_kcompr_25715: acompr lz4(15) of 128kB chunks took 91 us (1373 MB/s), compress ratio: 41.17 [ 318.062172] lustre_kcompr_25715: adecompr lz4(15) took 110 us (27 MB/s) [ 318.081801] lustre_kcompr_25715: test_acomp_compress_decompress(lz4,15) ret 0 [ 318.215560] lustre_kcompr_25715: compr lz4hc(1) in 64kB chunks took 101880 us (39 MB/s), compress ratio: 100.64 [ 318.297665] lustre_kcompr_25715: compr lz4hc(1) in 128kB chunks took 70105 us (57 MB/s), compress ratio: 106.96 [ 318.382524] lustre_kcompr_25715: compr lz4hc(1) in 256kB chunks took 69864 us (57 MB/s), compress ratio: 110.43 [ 318.478562] lustre_kcompr_25715: compr lz4hc(1) in 512kB chunks took 69653 us (57 MB/s), compress ratio: 112.26 [ 318.553604] lustre_kcompr_25715: compr lz4hc(1) in 1024kB chunks took 65095 us (61 MB/s), compress ratio: 113.19 [ 318.649109] lustre_kcompr_25715: compr lz4hc(1) in 2048kB chunks took 83912 us (47 MB/s), compress ratio: 113.66 [ 318.692784] lustre_kcompr_25715: compr lz4hc(1) in 4096kB chunks took 32935 us (121 MB/s), compress ratio: 113.90 [ 318.722883] lustre_kcompr_25715: decompr lz4hc(1) took 2054 us (17 MB/s) [ 318.742195] lustre_kcompr_25715: test_comp_compress_decompress(lz4hc,1) ret 0 [ 318.758122] lustre_kcompr_25715: acompr lz4hc(1) of 128kB chunks took 604 us (206 MB/s), compress ratio: 106.47 [ 318.772821] lustre_kcompr_25715: adecompr lz4hc(1) took 171 us (6 MB/s) [ 318.780883] lustre_kcompr_25715: test_acomp_compress_decompress(lz4hc,1) ret 0 [ 318.893651] lustre_kcompr_25715: compr lz4hc(3) in 64kB chunks took 95974 us (41 MB/s), compress ratio: 101.02 [ 319.017670] lustre_kcompr_25715: compr lz4hc(3) in 128kB chunks took 97233 us (41 MB/s), compress ratio: 107.37 [ 319.080849] lustre_kcompr_25715: compr lz4hc(3) in 256kB chunks took 53770 us (74 MB/s), compress ratio: 110.86 [ 319.143673] lustre_kcompr_25715: compr lz4hc(3) in 512kB chunks took 55192 us (72 MB/s), compress ratio: 112.68 [ 319.208111] lustre_kcompr_25715: compr lz4hc(3) in 1024kB chunks took 58455 us (68 MB/s), compress ratio: 113.63 [ 319.300671] lustre_kcompr_25715: compr lz4hc(3) in 2048kB chunks took 86063 us (46 MB/s), compress ratio: 114.10 [ 319.456609] lustre_kcompr_25715: compr lz4hc(3) in 4096kB chunks took 123005 us (32 MB/s), compress ratio: 114.34 [ 319.480519] lustre_kcompr_25715: decompr lz4hc(3) took 2581 us (13 MB/s) [ 319.486081] lustre_kcompr_25715: test_comp_compress_decompress(lz4hc,3) ret 0 [ 319.495325] lustre_kcompr_25715: acompr lz4hc(3) of 128kB chunks took 2143 us (58 MB/s), compress ratio: 106.47 [ 319.520149] lustre_kcompr_25715: adecompr lz4hc(3) took 130 us (9 MB/s) [ 319.574479] lustre_kcompr_25715: test_acomp_compress_decompress(lz4hc,3) ret 0 [ 321.187602] lustre_kcompr_25715: compr lz4hc(8) in 64kB chunks took 1557958 us (2 MB/s), compress ratio: 107.10 [ 322.242464] lustre_kcompr_25715: compr lz4hc(8) in 128kB chunks took 1043506 us (3 MB/s), compress ratio: 114.61 [ 324.073470] lustre_kcompr_25715: compr lz4hc(8) in 256kB chunks took 1827294 us (2 MB/s), compress ratio: 118.81 [ 325.548291] lustre_kcompr_25715: compr lz4hc(8) in 512kB chunks took 1449782 us (2 MB/s), compress ratio: 120.99 [ 327.372103] lustre_kcompr_25715: compr lz4hc(8) in 1024kB chunks took 1814642 us (2 MB/s), compress ratio: 122.08 [ 329.188622] Lustre: lustre-OST0001-osc-ffff8ae143ea4000: disconnect after 20s idle [ 329.199124] Lustre: Skipped 1 previous similar message [ 329.388556] lustre_kcompr_25715: compr lz4hc(8) in 2048kB chunks took 1992966 us (2 MB/s), compress ratio: 122.64 [ 331.282965] lustre_kcompr_25715: compr lz4hc(8) in 4096kB chunks took 1888184 us (2 MB/s), compress ratio: 122.93 [ 331.307437] lustre_kcompr_25715: decompr lz4hc(8) took 6576 us (4 MB/s) [ 331.310913] lustre_kcompr_25715: test_comp_compress_decompress(lz4hc,8) ret 0 [ 331.330635] lustre_kcompr_25715: acompr lz4hc(8) of 128kB chunks took 14366 us (8 MB/s), compress ratio: 113.48 [ 331.333403] lustre_kcompr_25715: adecompr lz4hc(8) took 268 us (4 MB/s) [ 331.337356] lustre_kcompr_25715: test_acomp_compress_decompress(lz4hc,8) ret 0 [ 332.419214] lustre_kcompr_25715: compr lz4hc(15) in 64kB chunks took 1068744 us (3 MB/s), compress ratio: 107.76 [ 334.701769] lustre_kcompr_25715: compr lz4hc(15) in 128kB chunks took 2269174 us (1 MB/s), compress ratio: 115.84 [ 337.077730] lustre_kcompr_25715: compr lz4hc(15) in 256kB chunks took 2351663 us (1 MB/s), compress ratio: 120.42 [ 338.960980] lustre_kcompr_25715: compr lz4hc(15) in 512kB chunks took 1852529 us (2 MB/s), compress ratio: 122.91 [ 340.643558] lustre_kcompr_25715: compr lz4hc(15) in 1024kB chunks took 1663232 us (2 MB/s), compress ratio: 124.12 [ 342.376474] lustre_kcompr_25715: compr lz4hc(15) in 2048kB chunks took 1722744 us (2 MB/s), compress ratio: 124.73 [ 343.531819] lustre_kcompr_25715: compr lz4hc(15) in 4096kB chunks took 1133944 us (3 MB/s), compress ratio: 125.05 [ 343.552912] lustre_kcompr_25715: decompr lz4hc(15) took 2201 us (14 MB/s) [ 343.560656] lustre_kcompr_25715: test_comp_compress_decompress(lz4hc,15) ret 0 [ 343.616840] lustre_kcompr_25715: acompr lz4hc(15) of 128kB chunks took 44726 us (2 MB/s), compress ratio: 114.67 [ 343.630854] lustre_kcompr_25715: adecompr lz4hc(15) took 104 us (10 MB/s) [ 343.677024] lustre_kcompr_25715: test_acomp_compress_decompress(lz4hc,15) ret 0 [ 343.694650] lustre_kcompr_25715: compr lzo(-1) in 64kB chunks took 6834 us (585 MB/s), compress ratio: 83.49 [ 343.717927] lustre_kcompr_25715: compr lzo(-1) in 128kB chunks took 1841 us (2172 MB/s), compress ratio: 88.66 [ 343.737078] lustre_kcompr_25715: compr lzo(-1) in 256kB chunks took 3826 us (1045 MB/s), compress ratio: 89.09 [ 343.750058] lustre_kcompr_25715: compr lzo(-1) in 512kB chunks took 4144 us (965 MB/s), compress ratio: 90.09 [ 343.757711] lustre_kcompr_25715: compr lzo(-1) in 1024kB chunks took 2257 us (1772 MB/s), compress ratio: 90.37 [ 343.766440] lustre_kcompr_25715: compr lzo(-1) in 2048kB chunks took 2629 us (1521 MB/s), compress ratio: 90.28 [ 343.779386] lustre_kcompr_25715: compr lzo(-1) in 4096kB chunks took 7459 us (536 MB/s), compress ratio: 90.59 [ 343.807244] lustre_kcompr_25715: decompr lzo(-1) took 2635 us (16 MB/s) [ 343.819282] lustre_kcompr_25715: test_comp_compress_decompress(lzo,-1) ret 0 [ 343.834768] lustre_kcompr_25715: acompr lzo(-1) of 128kB chunks took 114 us (1096 MB/s), compress ratio: 86.91 [ 343.838903] lustre_kcompr_25715: adecompr lzo(-1) took 101 us (14 MB/s) [ 343.871115] lustre_kcompr_25715: test_acomp_compress_decompress(lzo,-1) ret 0 [ 344.263219] lustre_kcompr_25715: compr deflate(1) in 64kB chunks took 362221 us (11 MB/s), compress ratio: 100.68 [ 344.494690] lustre_kcompr_25715: compr deflate(1) in 128kB chunks took 226171 us (17 MB/s), compress ratio: 111.41 [ 344.820300] lustre_kcompr_25715: compr deflate(1) in 256kB chunks took 321114 us (12 MB/s), compress ratio: 118.30 [ 345.188297] lustre_kcompr_25715: compr deflate(1) in 512kB chunks took 362878 us (11 MB/s), compress ratio: 122.47 [ 345.539212] lustre_kcompr_25715: compr deflate(1) in 1024kB chunks took 342629 us (11 MB/s), compress ratio: 124.65 [ 346.023582] lustre_kcompr_25715: compr deflate(1) in 2048kB chunks took 463227 us (8 MB/s), compress ratio: 125.63 [ 347.109408] lustre_kcompr_25715: compr deflate(1) in 4096kB chunks took 1070037 us (3 MB/s), compress ratio: 125.93 [ 347.139866] lustre_kcompr_25715: decompr deflate(1) took 9786 us (3 MB/s) [ 347.173160] lustre_kcompr_25715: test_comp_compress_decompress(deflate,1) ret 0 [ 347.204299] lustre_kcompr_25715: acompr deflate(1) of 128kB chunks took 19523 us (6 MB/s), compress ratio: 110.42 [ 347.215181] lustre_kcompr_25715: adecompr deflate(1) took 207 us (5 MB/s) [ 347.241584] lustre_kcompr_25715: test_acomp_compress_decompress(deflate,1) ret 0 [ 347.602825] lustre_kcompr_25715: compr deflate(6) in 64kB chunks took 342780 us (11 MB/s), compress ratio: 100.68 [ 348.170980] lustre_kcompr_25715: compr deflate(6) in 128kB chunks took 557514 us (7 MB/s), compress ratio: 111.41 [ 348.787238] lustre_kcompr_25715: compr deflate(6) in 256kB chunks took 599395 us (6 MB/s), compress ratio: 118.30 [ 349.479321] lustre_kcompr_25715: compr deflate(6) in 512kB chunks took 678782 us (5 MB/s), compress ratio: 122.47 [ 349.945674] lustre_kcompr_25715: compr deflate(6) in 1024kB chunks took 452917 us (8 MB/s), compress ratio: 124.65 [ 350.201303] lustre_kcompr_25715: compr deflate(6) in 2048kB chunks took 251311 us (15 MB/s), compress ratio: 125.63 [ 350.550280] lustre_kcompr_25715: compr deflate(6) in 4096kB chunks took 344436 us (11 MB/s), compress ratio: 125.93 [ 350.564697] lustre_kcompr_25715: decompr deflate(6) took 4369 us (7 MB/s) [ 350.572891] lustre_kcompr_25715: test_comp_compress_decompress(deflate,6) ret 0 [ 350.585977] lustre_kcompr_25715: acompr deflate(6) of 128kB chunks took 7627 us (16 MB/s), compress ratio: 110.42 [ 350.591666] lustre_kcompr_25715: adecompr deflate(6) took 1663 us (0 MB/s) [ 350.600719] lustre_kcompr_25715: test_acomp_compress_decompress(deflate,6) ret 0 [ 350.948389] lustre_kcompr_25715: compr deflate(9) in 64kB chunks took 336895 us (11 MB/s), compress ratio: 100.68 [ 351.241849] lustre_kcompr_25715: compr deflate(9) in 128kB chunks took 287300 us (13 MB/s), compress ratio: 111.41 [ 351.591720] lustre_kcompr_25715: compr deflate(9) in 256kB chunks took 345919 us (11 MB/s), compress ratio: 118.30 [ 352.399056] lustre_kcompr_25715: compr deflate(9) in 512kB chunks took 786429 us (5 MB/s), compress ratio: 122.47 [ 353.051482] lustre_kcompr_25715: compr deflate(9) in 1024kB chunks took 626011 us (6 MB/s), compress ratio: 124.65 [ 353.534343] lustre_kcompr_25715: compr deflate(9) in 2048kB chunks took 471115 us (8 MB/s), compress ratio: 125.63 [ 353.890844] lustre_kcompr_25715: compr deflate(9) in 4096kB chunks took 351110 us (11 MB/s), compress ratio: 125.93 [ 353.905452] lustre_kcompr_25715: decompr deflate(9) took 4659 us (6 MB/s) [ 353.921430] lustre_kcompr_25715: test_comp_compress_decompress(deflate,9) ret 0 [ 353.941364] lustre_kcompr_25715: acompr deflate(9) of 128kB chunks took 11758 us (10 MB/s), compress ratio: 110.42 [ 353.954672] lustre_kcompr_25715: adecompr deflate(9) took 191 us (5 MB/s) [ 353.959907] lustre_kcompr_25715: test_acomp_compress_decompress(deflate,9) ret 0 [ 353.983938] lustre_kcompr_25715: compr zstd(0) in 64kB chunks took 7631 us (524 MB/s), compress ratio: 166.06 [ 354.002936] lustre_kcompr_25715: compr zstd(0) in 128kB chunks took 15101 us (264 MB/s), compress ratio: 201.84 [ 354.017705] lustre_kcompr_25715: compr zstd(0) in 256kB chunks took 11699 us (341 MB/s), compress ratio: 215.71 [ 354.039128] lustre_kcompr_25715: compr zstd(0) in 512kB chunks took 18522 us (215 MB/s), compress ratio: 225.06 [ 354.051001] lustre_kcompr_25715: compr zstd(0) in 1024kB chunks took 2845 us (1405 MB/s), compress ratio: 228.22 [ 354.057367] lustre_kcompr_25715: compr zstd(0) in 2048kB chunks took 3291 us (1215 MB/s), compress ratio: 228.94 [ 354.074965] lustre_kcompr_25715: compr zstd(0) in 4096kB chunks took 8876 us (450 MB/s), compress ratio: 228.93 [ 354.112026] lustre_kcompr_25715: decompr zstd(0) took 10012 us (1 MB/s) [ 354.133765] lustre_kcompr_25715: test_comp_compress_decompress(zstd,0) ret 0 [ 354.155772] lustre_kcompr_25715: acompr zstd(0) of 128kB chunks took 792 us (157 MB/s), compress ratio: 197.10 [ 354.166188] lustre_kcompr_25715: adecompr zstd(0) took 154 us (4 MB/s) [ 354.195031] lustre_kcompr_25715: test_acomp_compress_decompress(zstd,0) ret 0 [ 354.238851] lustre_kcompr_25715: compr zstd(1) in 64kB chunks took 7903 us (506 MB/s), compress ratio: 164.68 [ 354.251287] lustre_kcompr_25715: compr zstd(1) in 128kB chunks took 3993 us (1001 MB/s), compress ratio: 199.01 [ 354.270821] lustre_kcompr_25715: compr zstd(1) in 256kB chunks took 2064 us (1937 MB/s), compress ratio: 212.43 [ 354.280668] lustre_kcompr_25715: compr zstd(1) in 512kB chunks took 2285 us (1750 MB/s), compress ratio: 218.85 [ 354.301756] lustre_kcompr_25715: compr zstd(1) in 1024kB chunks took 6040 us (662 MB/s), compress ratio: 224.43 [ 354.317422] lustre_kcompr_25715: compr zstd(1) in 2048kB chunks took 2153 us (1857 MB/s), compress ratio: 225.19 [ 354.337528] lustre_kcompr_25715: compr zstd(1) in 4096kB chunks took 8691 us (460 MB/s), compress ratio: 224.05 [ 354.363523] lustre_kcompr_25715: decompr zstd(1) took 6392 us (2 MB/s) [ 354.376789] lustre_kcompr_25715: test_comp_compress_decompress(zstd,1) ret 0 [ 354.388866] lustre_kcompr_25715: acompr zstd(1) of 128kB chunks took 168 us (744 MB/s), compress ratio: 197.10 [ 354.399428] lustre_kcompr_25715: adecompr zstd(1) took 157 us (4 MB/s) [ 354.437708] lustre_kcompr_25715: test_acomp_compress_decompress(zstd,1) ret 0 [ 354.839246] lustre_kcompr_25715: compr zstd(10) in 64kB chunks took 377049 us (10 MB/s), compress ratio: 167.96 [ 355.151948] lustre_kcompr_25715: compr zstd(10) in 128kB chunks took 301015 us (13 MB/s), compress ratio: 192.82 [ 355.456654] lustre_kcompr_25715: compr zstd(10) in 256kB chunks took 280820 us (14 MB/s), compress ratio: 205.70 [ 355.669801] lustre_kcompr_25715: compr zstd(10) in 512kB chunks took 206362 us (19 MB/s), compress ratio: 228.51 [ 356.052914] lustre_kcompr_25715: compr zstd(10) in 1024kB chunks took 372472 us (10 MB/s), compress ratio: 233.28 [ 356.635375] lustre_kcompr_25715: compr zstd(10) in 2048kB chunks took 566526 us (7 MB/s), compress ratio: 235.72 [ 356.712729] lustre_kcompr_25715: compr zstd(10) in 4096kB chunks took 66031 us (60 MB/s), compress ratio: 236.81 [ 356.732112] lustre_kcompr_25715: decompr zstd(10) took 2982 us (5 MB/s) [ 356.743470] lustre_kcompr_25715: test_comp_compress_decompress(zstd,10) ret 0 [ 356.755331] lustre_kcompr_25715: acompr zstd(10) of 128kB chunks took 3976 us (31 MB/s), compress ratio: 189.41 [ 356.760701] lustre_kcompr_25715: adecompr zstd(10) took 118 us (5 MB/s) [ 356.766523] lustre_kcompr_25715: test_acomp_compress_decompress(zstd,10) ret 0 [ 357.911191] lustre_kcompr_25715: compr zstd(15) in 64kB chunks took 1134656 us (3 MB/s), compress ratio: 176.99 [ 359.447205] lustre_kcompr_25715: compr zstd(15) in 128kB chunks took 1530366 us (2 MB/s), compress ratio: 204.33 [ 360.663120] lustre_kcompr_25715: compr zstd(15) in 256kB chunks took 1194990 us (3 MB/s), compress ratio: 220.10 [ 361.785371] lustre_kcompr_25715: compr zstd(15) in 512kB chunks took 1093039 us (3 MB/s), compress ratio: 227.45 [ 362.789446] lustre_kcompr_25715: compr zstd(15) in 1024kB chunks took 990619 us (4 MB/s), compress ratio: 230.64 [ 363.611445] lustre_kcompr_25715: compr zstd(15) in 2048kB chunks took 803943 us (4 MB/s), compress ratio: 230.82 [ 364.831642] lustre_kcompr_25715: compr zstd(15) in 4096kB chunks took 1211262 us (3 MB/s), compress ratio: 232.28 [ 364.851803] lustre_kcompr_25715: decompr zstd(15) took 2870 us (6 MB/s) [ 364.860947] lustre_kcompr_25715: test_comp_compress_decompress(zstd,15) ret 0 [ 364.881133] lustre_kcompr_25715: acompr zstd(15) of 128kB chunks took 16466 us (7 MB/s), compress ratio: 199.50 [ 364.885887] lustre_kcompr_25715: adecompr zstd(15) took 191 us (3 MB/s) [ 364.892457] lustre_kcompr_25715: test_acomp_compress_decompress(zstd,15) ret 0 [ 364.908290] lustre_kcompr_25715: compr zstdfast(1) in 64kB chunks took 4413 us (906 MB/s), compress ratio: 164.68 [ 364.920753] lustre_kcompr_25715: compr zstdfast(1) in 128kB chunks took 4315 us (926 MB/s), compress ratio: 199.01 [ 364.929174] lustre_kcompr_25715: compr zstdfast(1) in 256kB chunks took 2991 us (1337 MB/s), compress ratio: 212.43 [ 364.949185] lustre_kcompr_25715: compr zstdfast(1) in 512kB chunks took 9778 us (409 MB/s), compress ratio: 218.85 [ 364.968454] lustre_kcompr_25715: compr zstdfast(1) in 1024kB chunks took 7847 us (509 MB/s), compress ratio: 224.43 [ 364.995195] lustre_kcompr_25715: compr zstdfast(1) in 2048kB chunks took 12711 us (314 MB/s), compress ratio: 225.19 [ 365.029723] lustre_kcompr_25715: compr zstdfast(1) in 4096kB chunks took 12745 us (313 MB/s), compress ratio: 224.05 [ 365.057839] lustre_kcompr_25715: decompr zstdfast(1) took 1709 us (10 MB/s) [ 365.064301] lustre_kcompr_25715: test_comp_compress_decompress(zstdfast,1) ret 0 [ 365.071645] lustre_kcompr_25715: acompr zstdfast(1) of 128kB chunks took 181 us (690 MB/s), compress ratio: 197.10 [ 365.079298] lustre_kcompr_25715: adecompr zstdfast(1) took 214 us (2 MB/s) [ 365.085943] lustre_kcompr_25715: test_acomp_compress_decompress(zstdfast,1) ret 0 [ 365.161842] lustre_kcompr_25715: compr zstdfast(5) in 64kB chunks took 17971 us (222 MB/s), compress ratio: 145.13 [ 365.183645] lustre_kcompr_25715: compr zstdfast(5) in 128kB chunks took 8942 us (447 MB/s), compress ratio: 172.07 [ 365.200281] lustre_kcompr_25715: compr zstdfast(5) in 256kB chunks took 5800 us (689 MB/s), compress ratio: 181.72 [ 365.226575] lustre_kcompr_25715: compr zstdfast(5) in 512kB chunks took 8573 us (466 MB/s), compress ratio: 188.45 [ 365.245591] lustre_kcompr_25715: compr zstdfast(5) in 1024kB chunks took 6869 us (582 MB/s), compress ratio: 190.20 [ 365.269930] lustre_kcompr_25715: compr zstdfast(5) in 2048kB chunks took 5980 us (668 MB/s), compress ratio: 190.02 [ 365.284764] lustre_kcompr_25715: compr zstdfast(5) in 4096kB chunks took 5113 us (782 MB/s), compress ratio: 190.47 [ 365.315558] lustre_kcompr_25715: decompr zstdfast(5) took 3728 us (5 MB/s) [ 365.326873] lustre_kcompr_25715: test_comp_compress_decompress(zstdfast,5) ret 0 [ 365.334668] lustre_kcompr_25715: acompr zstdfast(5) of 128kB chunks took 139 us (899 MB/s), compress ratio: 160.23 [ 365.348062] lustre_kcompr_25715: adecompr zstdfast(5) took 98 us (7 MB/s) [ 365.372240] lustre_kcompr_25715: test_acomp_compress_decompress(zstdfast,5) ret 0 [ 365.435102] lustre_kcompr_25715: compr zstdfast(12) in 64kB chunks took 7030 us (568 MB/s), compress ratio: 39.60 [ 365.460111] lustre_kcompr_25715: compr zstdfast(12) in 128kB chunks took 16936 us (236 MB/s), compress ratio: 44.71 [ 365.476690] lustre_kcompr_25715: compr zstdfast(12) in 256kB chunks took 7455 us (536 MB/s), compress ratio: 47.47 [ 365.497928] lustre_kcompr_25715: compr zstdfast(12) in 512kB chunks took 4602 us (869 MB/s), compress ratio: 48.97 [ 365.541351] lustre_kcompr_25715: compr zstdfast(12) in 1024kB chunks took 4354 us (918 MB/s), compress ratio: 49.77 [ 365.566967] lustre_kcompr_25715: compr zstdfast(12) in 2048kB chunks took 8123 us (492 MB/s), compress ratio: 50.18 [ 365.590055] lustre_kcompr_25715: compr zstdfast(12) in 4096kB chunks took 8155 us (490 MB/s), compress ratio: 50.39 [ 365.632022] lustre_kcompr_25715: decompr zstdfast(12) took 7004 us (11 MB/s) [ 365.662567] lustre_kcompr_25715: test_comp_compress_decompress(zstdfast,12) ret 0 [ 365.677987] lustre_kcompr_25715: acompr zstdfast(12) of 128kB chunks took 142 us (880 MB/s), compress ratio: 44.40 [ 365.689897] lustre_kcompr_25715: adecompr zstdfast(12) took 450 us (6 MB/s) [ 365.740151] lustre_kcompr_25715: test_acomp_compress_decompress(zstdfast,12) ret 0 [ 365.777098] lustre_kcompr_25715: compr zstdfast(15) in 64kB chunks took 2510 us (1593 MB/s), compress ratio: 20.13 [ 365.804306] lustre_kcompr_25715: compr zstdfast(15) in 128kB chunks took 7735 us (517 MB/s), compress ratio: 21.17 [ 365.815792] lustre_kcompr_25715: compr zstdfast(15) in 256kB chunks took 2729 us (1465 MB/s), compress ratio: 22.11 [ 365.832803] lustre_kcompr_25715: compr zstdfast(15) in 512kB chunks took 7818 us (511 MB/s), compress ratio: 22.92 [ 365.843347] lustre_kcompr_25715: compr zstdfast(15) in 1024kB chunks took 4243 us (942 MB/s), compress ratio: 24.10 [ 365.870209] lustre_kcompr_25715: compr zstdfast(15) in 2048kB chunks took 16150 us (247 MB/s), compress ratio: 24.06 [ 365.897665] lustre_kcompr_25715: compr zstdfast(15) in 4096kB chunks took 6797 us (588 MB/s), compress ratio: 30.80 [ 365.926935] lustre_kcompr_25715: decompr zstdfast(15) took 4744 us (27 MB/s) [ 365.938667] lustre_kcompr_25715: test_comp_compress_decompress(zstdfast,15) ret 0 [ 365.950148] lustre_kcompr_25715: acompr zstdfast(15) of 128kB chunks took 177 us (706 MB/s), compress ratio: 18.78 [ 365.957888] lustre_kcompr_25715: adecompr zstdfast(15) took 125 us (53 MB/s) [ 365.970349] lustre_kcompr_25715: test_acomp_compress_decompress(zstdfast,15) ret 0 [ 365.976314] lustre_kcompr_25715: SUCCESS [ 375.260355] lustre_kcompr_18885: ***** [ 375.262675] lustre_kcompr_18885: (de)compression test on provided file /tmp/f84.sanity [ 375.267494] lustre_kcompr_18885: ***** [ 375.311672] lustre_kcompr_18885: compr lz4(1) in 64kB chunks took 29635 us (134 MB/s), compress ratio: 2.93 [ 375.354583] lustre_kcompr_18885: compr lz4(1) in 128kB chunks took 35855 us (111 MB/s), compress ratio: 3.04 [ 375.398194] lustre_kcompr_18885: compr lz4(1) in 256kB chunks took 37575 us (106 MB/s), compress ratio: 3.08 [ 375.438410] lustre_kcompr_18885: compr lz4(1) in 512kB chunks took 33211 us (120 MB/s), compress ratio: 3.10 [ 375.480810] lustre_kcompr_18885: compr lz4(1) in 1024kB chunks took 36410 us (109 MB/s), compress ratio: 3.11 [ 375.532586] lustre_kcompr_18885: compr lz4(1) in 2048kB chunks took 43770 us (91 MB/s), compress ratio: 3.11 [ 375.574677] lustre_kcompr_18885: compr lz4(1) in 4096kB chunks took 32690 us (122 MB/s), compress ratio: 3.12 [ 375.603247] lustre_kcompr_18885: decompr lz4(1) took 10088 us (127 MB/s) [ 375.610696] lustre_kcompr_18885: test_comp_compress_decompress(lz4,1) ret 0 [ 375.618645] lustre_kcompr_18885: acompr lz4(1) of 128kB chunks took 934 us (133 MB/s), compress ratio: 2.68 [ 375.624214] lustre_kcompr_18885: adecompr lz4(1) took 442 us (105 MB/s) [ 375.631460] lustre_kcompr_18885: test_acomp_compress_decompress(lz4,1) ret 0 [ 375.666533] lustre_kcompr_18885: compr lz4(4) in 64kB chunks took 22030 us (181 MB/s), compress ratio: 2.67 [ 375.706321] lustre_kcompr_18885: compr lz4(4) in 128kB chunks took 26051 us (153 MB/s), compress ratio: 2.63 [ 375.750732] lustre_kcompr_18885: compr lz4(4) in 256kB chunks took 36041 us (110 MB/s), compress ratio: 2.68 [ 375.807806] lustre_kcompr_18885: compr lz4(4) in 512kB chunks took 48973 us (81 MB/s), compress ratio: 2.71 [ 375.870721] lustre_kcompr_18885: compr lz4(4) in 1024kB chunks took 47505 us (84 MB/s), compress ratio: 2.72 [ 375.927010] lustre_kcompr_18885: compr lz4(4) in 2048kB chunks took 45430 us (88 MB/s), compress ratio: 2.72 [ 376.047953] lustre_kcompr_18885: compr lz4(4) in 4096kB chunks took 104457 us (38 MB/s), compress ratio: 2.73 [ 376.104574] lustre_kcompr_18885: decompr lz4(4) took 28544 us (51 MB/s) [ 376.111974] lustre_kcompr_18885: test_comp_compress_decompress(lz4,4) ret 0 [ 376.124952] lustre_kcompr_18885: acompr lz4(4) of 128kB chunks took 1013 us (123 MB/s), compress ratio: 2.30 [ 376.135978] lustre_kcompr_18885: adecompr lz4(4) took 2157 us (25 MB/s) [ 376.152657] lustre_kcompr_18885: test_acomp_compress_decompress(lz4,4) ret 0 [ 376.207504] lustre_kcompr_18885: compr lz4(9) in 64kB chunks took 28225 us (141 MB/s), compress ratio: 2.30 [ 376.261989] lustre_kcompr_18885: compr lz4(9) in 128kB chunks took 48577 us (82 MB/s), compress ratio: 2.15 [ 376.335439] lustre_kcompr_18885: compr lz4(9) in 256kB chunks took 50893 us (78 MB/s), compress ratio: 2.20 [ 376.428507] lustre_kcompr_18885: compr lz4(9) in 512kB chunks took 66541 us (60 MB/s), compress ratio: 2.23 [ 376.498516] lustre_kcompr_18885: compr lz4(9) in 1024kB chunks took 43478 us (92 MB/s), compress ratio: 2.25 [ 376.558407] lustre_kcompr_18885: compr lz4(9) in 2048kB chunks took 42550 us (94 MB/s), compress ratio: 2.25 [ 376.590571] lustre_kcompr_18885: compr lz4(9) in 4096kB chunks took 27337 us (146 MB/s), compress ratio: 2.26 [ 376.631056] lustre_kcompr_18885: decompr lz4(9) took 22985 us (76 MB/s) [ 376.640646] lustre_kcompr_18885: test_comp_compress_decompress(lz4,9) ret 0 [ 376.654942] lustre_kcompr_18885: acompr lz4(9) of 128kB chunks took 1985 us (62 MB/s), compress ratio: 1.87 [ 376.665652] lustre_kcompr_18885: adecompr lz4(9) took 229 us (291 MB/s) [ 376.693361] lustre_kcompr_18885: test_acomp_compress_decompress(lz4,9) ret 0 [ 376.760850] lustre_kcompr_18885: compr lz4(15) in 64kB chunks took 33347 us (119 MB/s), compress ratio: 1.63 [ 376.829286] lustre_kcompr_18885: compr lz4(15) in 128kB chunks took 57282 us (69 MB/s), compress ratio: 1.52 [ 376.871986] lustre_kcompr_18885: compr lz4(15) in 256kB chunks took 27575 us (145 MB/s), compress ratio: 1.56 [ 376.913198] lustre_kcompr_18885: compr lz4(15) in 512kB chunks took 25484 us (156 MB/s), compress ratio: 1.58 [ 376.936841] lustre_kcompr_18885: compr lz4(15) in 1024kB chunks took 17205 us (232 MB/s), compress ratio: 1.60 [ 376.951667] lustre_kcompr_18885: compr lz4(15) in 2048kB chunks took 9693 us (412 MB/s), compress ratio: 1.60 [ 376.964240] lustre_kcompr_18885: compr lz4(15) in 4096kB chunks took 9265 us (431 MB/s), compress ratio: 1.60 [ 376.974216] lustre_kcompr_18885: decompr lz4(15) took 2550 us (975 MB/s) [ 376.977624] lustre_kcompr_18885: test_comp_compress_decompress(lz4,15) ret 0 [ 376.994167] lustre_kcompr_18885: acompr lz4(15) of 128kB chunks took 447 us (279 MB/s), compress ratio: 1.33 [ 377.002753] lustre_kcompr_18885: adecompr lz4(15) took 476 us (197 MB/s) [ 377.012773] lustre_kcompr_18885: test_acomp_compress_decompress(lz4,15) ret 0 [ 377.277092] lustre_kcompr_18885: compr lz4hc(1) in 64kB chunks took 245134 us (16 MB/s), compress ratio: 3.58 [ 377.598746] lustre_kcompr_18885: compr lz4hc(1) in 128kB chunks took 317418 us (12 MB/s), compress ratio: 3.77 [ 378.001261] lustre_kcompr_18885: compr lz4hc(1) in 256kB chunks took 391918 us (10 MB/s), compress ratio: 3.88 [ 378.282392] lustre_kcompr_18885: compr lz4hc(1) in 512kB chunks took 260700 us (15 MB/s), compress ratio: 3.93 [ 378.555759] lustre_kcompr_18885: compr lz4hc(1) in 1024kB chunks took 260431 us (15 MB/s), compress ratio: 3.96 [ 378.924956] lustre_kcompr_18885: compr lz4hc(1) in 2048kB chunks took 344774 us (11 MB/s), compress ratio: 3.97 [ 379.164726] lustre_kcompr_18885: compr lz4hc(1) in 4096kB chunks took 212174 us (18 MB/s), compress ratio: 3.98 [ 379.178879] lustre_kcompr_18885: decompr lz4hc(1) took 5050 us (198 MB/s) [ 379.241345] lustre_kcompr_18885: test_comp_compress_decompress(lz4hc,1) ret 0 [ 379.267412] lustre_kcompr_18885: acompr lz4hc(1) of 128kB chunks took 10239 us (12 MB/s), compress ratio: 3.27 [ 379.275514] lustre_kcompr_18885: adecompr lz4hc(1) took 167 us (228 MB/s) [ 379.290823] lustre_kcompr_18885: test_acomp_compress_decompress(lz4hc,1) ret 0 [ 379.466839] lustre_kcompr_18885: compr lz4hc(3) in 64kB chunks took 155404 us (25 MB/s), compress ratio: 3.68 [ 379.754619] lustre_kcompr_18885: compr lz4hc(3) in 128kB chunks took 282566 us (14 MB/s), compress ratio: 3.91 [ 380.124447] lustre_kcompr_18885: compr lz4hc(3) in 256kB chunks took 364308 us (10 MB/s), compress ratio: 4.04 [ 380.277444] lustre_kcompr_18885: compr lz4hc(3) in 512kB chunks took 148457 us (26 MB/s), compress ratio: 4.10 [ 380.402759] lustre_kcompr_18885: compr lz4hc(3) in 1024kB chunks took 122848 us (32 MB/s), compress ratio: 4.13 [ 380.649840] lustre_kcompr_18885: compr lz4hc(3) in 2048kB chunks took 240607 us (16 MB/s), compress ratio: 4.15 [ 380.887713] lustre_kcompr_18885: compr lz4hc(3) in 4096kB chunks took 229188 us (17 MB/s), compress ratio: 4.16 [ 380.945295] lustre_kcompr_18885: decompr lz4hc(3) took 25803 us (37 MB/s) [ 380.951392] lustre_kcompr_18885: test_comp_compress_decompress(lz4hc,3) ret 0 [ 380.973811] lustre_kcompr_18885: acompr lz4hc(3) of 128kB chunks took 7365 us (16 MB/s), compress ratio: 3.38 [ 380.980811] lustre_kcompr_18885: adecompr lz4hc(3) took 320 us (115 MB/s) [ 380.986868] lustre_kcompr_18885: test_acomp_compress_decompress(lz4hc,3) ret 0 [ 381.352858] lustre_kcompr_18885: compr lz4hc(8) in 64kB chunks took 356592 us (11 MB/s), compress ratio: 3.72 [ 381.781908] lustre_kcompr_18885: compr lz4hc(8) in 128kB chunks took 423902 us (9 MB/s), compress ratio: 3.96 [ 382.151299] lustre_kcompr_18885: compr lz4hc(8) in 256kB chunks took 364861 us (10 MB/s), compress ratio: 4.10 [ 382.573387] lustre_kcompr_18885: compr lz4hc(8) in 512kB chunks took 419098 us (9 MB/s), compress ratio: 4.17 [ 383.999837] lustre_kcompr_18885: compr lz4hc(8) in 1024kB chunks took 1421000 us (2 MB/s), compress ratio: 4.21 [ 385.495062] lustre_kcompr_18885: compr lz4hc(8) in 2048kB chunks took 1486097 us (2 MB/s), compress ratio: 4.22 [ 386.687978] lustre_kcompr_18885: compr lz4hc(8) in 4096kB chunks took 1185278 us (3 MB/s), compress ratio: 4.23 [ 386.771613] lustre_kcompr_18885: decompr lz4hc(8) took 33823 us (27 MB/s) [ 386.797444] lustre_kcompr_18885: test_comp_compress_decompress(lz4hc,8) ret 0 [ 386.830621] lustre_kcompr_18885: acompr lz4hc(8) of 128kB chunks took 11344 us (11 MB/s), compress ratio: 3.42 [ 386.834482] lustre_kcompr_18885: adecompr lz4hc(8) took 215 us (169 MB/s) [ 386.848407] lustre_kcompr_18885: test_acomp_compress_decompress(lz4hc,8) ret 0 [ 387.209394] lustre_kcompr_18885: compr lz4hc(15) in 64kB chunks took 338522 us (11 MB/s), compress ratio: 3.72 [ 387.532359] lustre_kcompr_18885: compr lz4hc(15) in 128kB chunks took 314153 us (12 MB/s), compress ratio: 3.96 [ 387.974501] lustre_kcompr_18885: compr lz4hc(15) in 256kB chunks took 430654 us (9 MB/s), compress ratio: 4.10 [ 388.300086] lustre_kcompr_18885: compr lz4hc(15) in 512kB chunks took 322220 us (12 MB/s), compress ratio: 4.17 [ 388.686390] lustre_kcompr_18885: compr lz4hc(15) in 1024kB chunks took 380363 us (10 MB/s), compress ratio: 4.21 [ 389.204476] lustre_kcompr_18885: compr lz4hc(15) in 2048kB chunks took 512638 us (7 MB/s), compress ratio: 4.22 [ 390.405197] lustre_kcompr_18885: compr lz4hc(15) in 4096kB chunks took 1195091 us (3 MB/s), compress ratio: 4.23 [ 390.432050] lustre_kcompr_18885: decompr lz4hc(15) took 13582 us (69 MB/s) [ 390.444040] lustre_kcompr_18885: test_comp_compress_decompress(lz4hc,15) ret 0 [ 390.507257] lustre_kcompr_18885: acompr lz4hc(15) of 128kB chunks took 52978 us (2 MB/s), compress ratio: 3.42 [ 390.526587] lustre_kcompr_18885: adecompr lz4hc(15) took 179 us (203 MB/s) [ 390.575710] lustre_kcompr_18885: test_acomp_compress_decompress(lz4hc,15) ret 0 [ 390.682446] lustre_kcompr_18885: compr lzo(-1) in 64kB chunks took 81419 us (49 MB/s), compress ratio: 2.90 [ 390.755294] lustre_kcompr_18885: compr lzo(-1) in 128kB chunks took 66763 us (59 MB/s), compress ratio: 2.95 [ 390.912047] lustre_kcompr_18885: compr lzo(-1) in 256kB chunks took 125806 us (31 MB/s), compress ratio: 2.96 [ 391.034403] lustre_kcompr_18885: compr lzo(-1) in 512kB chunks took 88120 us (45 MB/s), compress ratio: 2.98 [ 391.104505] lustre_kcompr_18885: compr lzo(-1) in 1024kB chunks took 45013 us (88 MB/s), compress ratio: 2.97 [ 391.147292] lustre_kcompr_18885: compr lzo(-1) in 2048kB chunks took 31762 us (125 MB/s), compress ratio: 2.97 [ 391.180919] lustre_kcompr_18885: compr lzo(-1) in 4096kB chunks took 28496 us (140 MB/s), compress ratio: 2.96 [ 391.211981] lustre_kcompr_18885: decompr lzo(-1) took 14987 us (89 MB/s) [ 391.217649] lustre_kcompr_18885: test_comp_compress_decompress(lzo,-1) ret 0 [ 391.224246] lustre_kcompr_18885: acompr lzo(-1) of 128kB chunks took 913 us (136 MB/s), compress ratio: 2.62 [ 391.240611] lustre_kcompr_18885: adecompr lzo(-1) took 270 us (176 MB/s) [ 391.291515] lustre_kcompr_18885: test_acomp_compress_decompress(lzo,-1) ret 0 [ 392.935882] lustre_kcompr_18885: compr deflate(1) in 64kB chunks took 1621993 us (2 MB/s), compress ratio: 3.51 [ 393.573124] lustre_kcompr_18885: compr deflate(1) in 128kB chunks took 631724 us (6 MB/s), compress ratio: 3.53 [ 394.850601] lustre_kcompr_18885: compr deflate(1) in 256kB chunks took 1273824 us (3 MB/s), compress ratio: 3.53 [ 396.591556] lustre_kcompr_18885: compr deflate(1) in 512kB chunks took 1728669 us (2 MB/s), compress ratio: 3.54 [ 397.526224] lustre_kcompr_18885: compr deflate(1) in 1024kB chunks took 922431 us (4 MB/s), compress ratio: 3.54 [ 398.280615] lustre_kcompr_18885: compr deflate(1) in 2048kB chunks took 738501 us (5 MB/s), compress ratio: 3.54 [ 399.366573] lustre_kcompr_18885: compr deflate(1) in 4096kB chunks took 1078402 us (3 MB/s), compress ratio: 3.54 [ 399.434092] lustre_kcompr_18885: decompr deflate(1) took 54986 us (20 MB/s) [ 399.461961] lustre_kcompr_18885: test_comp_compress_decompress(deflate,1) ret 0 [ 399.513946] lustre_kcompr_18885: acompr deflate(1) of 128kB chunks took 25151 us (4 MB/s), compress ratio: 3.09 [ 399.526477] lustre_kcompr_18885: adecompr deflate(1) took 2986 us (13 MB/s) [ 399.534713] lustre_kcompr_18885: test_acomp_compress_decompress(deflate,1) ret 0 [ 400.178451] lustre_kcompr_18885: compr deflate(6) in 64kB chunks took 633316 us (6 MB/s), compress ratio: 3.51 [ 400.546206] lustre_kcompr_18885: compr deflate(6) in 128kB chunks took 361196 us (11 MB/s), compress ratio: 3.53 [ 401.146550] lustre_kcompr_18885: compr deflate(6) in 256kB chunks took 595194 us (6 MB/s), compress ratio: 3.53 [ 401.982717] lustre_kcompr_18885: compr deflate(6) in 512kB chunks took 821827 us (4 MB/s), compress ratio: 3.54 [ 402.477790] lustre_kcompr_18885: compr deflate(6) in 1024kB chunks took 478096 us (8 MB/s), compress ratio: 3.54 [ 403.266758] lustre_kcompr_18885: compr deflate(6) in 2048kB chunks took 781956 us (5 MB/s), compress ratio: 3.54 [ 403.781826] lustre_kcompr_18885: compr deflate(6) in 4096kB chunks took 503670 us (7 MB/s), compress ratio: 3.54 [ 403.846532] lustre_kcompr_18885: decompr deflate(6) took 50303 us (22 MB/s) [ 403.852631] lustre_kcompr_18885: test_comp_compress_decompress(deflate,6) ret 0 [ 403.876827] lustre_kcompr_18885: acompr deflate(6) of 128kB chunks took 17911 us (6 MB/s), compress ratio: 3.09 [ 403.884843] lustre_kcompr_18885: adecompr deflate(6) took 2042 us (19 MB/s) [ 403.892370] lustre_kcompr_18885: test_acomp_compress_decompress(deflate,6) ret 0 [ 404.377036] lustre_kcompr_18885: compr deflate(9) in 64kB chunks took 472040 us (8 MB/s), compress ratio: 3.51 [ 405.165984] lustre_kcompr_18885: compr deflate(9) in 128kB chunks took 782039 us (5 MB/s), compress ratio: 3.53 [ 406.190565] lustre_kcompr_18885: compr deflate(9) in 256kB chunks took 1012523 us (3 MB/s), compress ratio: 3.53 [ 406.859128] lustre_kcompr_18885: compr deflate(9) in 512kB chunks took 659135 us (6 MB/s), compress ratio: 3.54 [ 407.343237] lustre_kcompr_18885: compr deflate(9) in 1024kB chunks took 479785 us (8 MB/s), compress ratio: 3.54 [ 407.940556] lustre_kcompr_18885: compr deflate(9) in 2048kB chunks took 585217 us (6 MB/s), compress ratio: 3.54 [ 408.434260] lustre_kcompr_18885: compr deflate(9) in 4096kB chunks took 476773 us (8 MB/s), compress ratio: 3.54 [ 408.468357] lustre_kcompr_18885: decompr deflate(9) took 26554 us (42 MB/s) [ 408.471841] lustre_kcompr_18885: test_comp_compress_decompress(deflate,9) ret 0 [ 408.497435] lustre_kcompr_18885: acompr deflate(9) of 128kB chunks took 19884 us (6 MB/s), compress ratio: 3.09 [ 408.508604] lustre_kcompr_18885: adecompr deflate(9) took 2219 us (18 MB/s) [ 408.516757] lustre_kcompr_18885: test_acomp_compress_decompress(deflate,9) ret 0 [ 408.586811] lustre_kcompr_18885: compr zstd(0) in 64kB chunks took 58568 us (68 MB/s), compress ratio: 4.28 [ 408.672955] lustre_kcompr_18885: compr zstd(0) in 128kB chunks took 79205 us (50 MB/s), compress ratio: 4.46 [ 408.772658] lustre_kcompr_18885: compr zstd(0) in 256kB chunks took 95806 us (41 MB/s), compress ratio: 4.61 [ 408.852457] lustre_kcompr_18885: compr zstd(0) in 512kB chunks took 75180 us (53 MB/s), compress ratio: 4.72 [ 408.984221] lustre_kcompr_18885: compr zstd(0) in 1024kB chunks took 128572 us (31 MB/s), compress ratio: 4.80 [ 409.279989] lustre_kcompr_18885: compr zstd(0) in 2048kB chunks took 288541 us (13 MB/s), compress ratio: 4.85 [ 409.393775] lustre_kcompr_18885: compr zstd(0) in 4096kB chunks took 108023 us (37 MB/s), compress ratio: 4.88 [ 409.461992] lustre_kcompr_18885: decompr zstd(0) took 57908 us (14 MB/s) [ 409.472875] lustre_kcompr_18885: test_comp_compress_decompress(zstd,0) ret 0 [ 409.495936] lustre_kcompr_18885: acompr zstd(0) of 128kB chunks took 5845 us (21 MB/s), compress ratio: 3.98 [ 409.512620] lustre_kcompr_18885: adecompr zstd(0) took 3068 us (10 MB/s) [ 409.539770] lustre_kcompr_18885: test_acomp_compress_decompress(zstd,0) ret 0 [ 409.601746] lustre_kcompr_18885: compr zstd(1) in 64kB chunks took 42720 us (93 MB/s), compress ratio: 4.06 [ 409.655730] lustre_kcompr_18885: compr zstd(1) in 128kB chunks took 49862 us (80 MB/s), compress ratio: 4.20 [ 409.711494] lustre_kcompr_18885: compr zstd(1) in 256kB chunks took 51600 us (77 MB/s), compress ratio: 4.34 [ 409.750796] lustre_kcompr_18885: compr zstd(1) in 512kB chunks took 36265 us (110 MB/s), compress ratio: 4.34 [ 409.795323] lustre_kcompr_18885: compr zstd(1) in 1024kB chunks took 40399 us (99 MB/s), compress ratio: 4.37 [ 409.854433] lustre_kcompr_18885: compr zstd(1) in 2048kB chunks took 54648 us (73 MB/s), compress ratio: 4.39 [ 409.910060] lustre_kcompr_18885: compr zstd(1) in 4096kB chunks took 47097 us (84 MB/s), compress ratio: 4.40 [ 409.970995] lustre_kcompr_18885: decompr zstd(1) took 40271 us (22 MB/s) [ 409.975980] lustre_kcompr_18885: test_comp_compress_decompress(zstd,1) ret 0 [ 409.981305] lustre_kcompr_18885: acompr zstd(1) of 128kB chunks took 1388 us (90 MB/s), compress ratio: 3.73 [ 409.989284] lustre_kcompr_18885: adecompr zstd(1) took 739 us (45 MB/s) [ 409.995196] lustre_kcompr_18885: test_acomp_compress_decompress(zstd,1) ret 0 [ 411.315980] lustre_kcompr_18885: compr zstd(10) in 64kB chunks took 1307445 us (3 MB/s), compress ratio: 4.80 [ 413.403167] lustre_kcompr_18885: compr zstd(10) in 128kB chunks took 2080286 us (1 MB/s), compress ratio: 5.09 [ 415.749852] lustre_kcompr_18885: compr zstd(10) in 256kB chunks took 2336036 us (1 MB/s), compress ratio: 5.34 [ 417.343475] lustre_kcompr_18885: compr zstd(10) in 512kB chunks took 1586076 us (2 MB/s), compress ratio: 5.45 [ 419.127294] lustre_kcompr_18885: compr zstd(10) in 1024kB chunks took 1760863 us (2 MB/s), compress ratio: 5.62 [ 420.781110] lustre_kcompr_18885: compr zstd(10) in 2048kB chunks took 1640000 us (2 MB/s), compress ratio: 5.76 [ 422.669624] lustre_kcompr_18885: compr zstd(10) in 4096kB chunks took 1875644 us (2 MB/s), compress ratio: 5.89 [ 422.724191] lustre_kcompr_18885: decompr zstd(10) took 42959 us (15 MB/s) [ 422.728559] lustre_kcompr_18885: test_comp_compress_decompress(zstd,10) ret 0 [ 422.760671] lustre_kcompr_18885: acompr zstd(10) of 128kB chunks took 27038 us (4 MB/s), compress ratio: 4.46 [ 422.773216] lustre_kcompr_18885: adecompr zstd(10) took 2871 us (9 MB/s) [ 422.779974] lustre_kcompr_18885: test_acomp_compress_decompress(zstd,10) ret 0 [ 437.476417] lustre_kcompr_18885: compr zstd(15) in 64kB chunks took 14678723 us (0 MB/s), compress ratio: 4.96 [ 460.066009] watchdog: BUG: soft lockup - CPU#2 stuck for 22s! [insmod:11408] [ 460.074023] Modules linked in: kcompr(O+) lzstd(O) llz4hc(O) llz4(O) lustre(O) osp(O) ofd(O) lod(O) mdt(O) mdd(O) mgs(O) lquota(O) lfsck(O) obdecho(O) mgc(O) mdc(O) lov(O) osc(O) lmv(O) ec(O) fid(O) fld(O) ptlrpc_gss(O) ptlrpc(O) obdclass(O) ksocklnd(O) lnet(O) libcfs(O) rpcsec_gss_krb5 auth_rpcgss nfsv4 dns_resolver intel_rapl_msr intel_rapl_common sb_edac rapl i2c_piix4 pcspkr squashfs ata_generic crct10dif_pclmul crc32_pclmul crc32c_intel ghash_clmulni_intel ata_piix serio_raw libata dm_mirror dm_region_hash dm_log dm_mod sha512_ssse3 sha512_generic [last unloaded: kcompr] [ 460.127156] CPU: 2 PID: 11408 Comm: insmod Kdump: loaded Tainted: G O -------- - - 4.18.0rh8.10-debug #2 [ 460.133809] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-10.fc44 06/10/2025 [ 460.148710] RIP: 0010:ZSTD_btGetAllMatches_noDict_3+0xa4b/0xa90 [lzstd] [ 460.153199] Code: e9 74 fe ff ff 48 8b 05 2b 11 06 00 4c 8d 48 01 48 83 c2 08 4c 89 0d 1c 11 06 00 49 83 c0 08 48 39 d1 76 2c 49 8b 00 48 8b 32 <49> 83 c1 01 48 39 f0 0f 85 ec fc ff ff eb d7 8b 84 24 8c 00 00 00 [ 460.168811] RSP: 0018:ffffa3a6023534e8 EFLAGS: 00000202 ORIG_RAX: ffffffffffffff13 [ 460.172764] RAX: 7420732774692065 RBX: 0000000000007502 RCX: ffff8ae151bdfff9 [ 460.177973] RDX: ffff8ae151bc7997 RSI: 7420732774692065 RDI: 000000000000043f [ 460.180450] RBP: ffff8ae151bc793f R08: ffff8ae151bc7558 R09: 00000000119d96cc [ 460.184049] R10: ffff8ae151bbfffe R11: 0000000000000200 R12: ffffa3a605a1ec08 [ 460.187664] R13: 0000000000007941 R14: ffff8ae151be0000 R15: 0000000000000002 [ 460.191476] FS: 00007f66dd407740(0000) GS:ffff8ae182100000(0000) knlGS:0000000000000000 [ 460.197062] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 460.201577] CR2: 00007fcf08384000 CR3: 00000001033b3001 CR4: 0000000000170ee0 [ 460.205915] Call Trace: [ 460.207607] [ 460.208178] ? show_regs.cold.9+0x22/0x2f [ 460.209944] ? watchdog_timer_fn.cold.6+0x62/0x10a [ 460.213234] ? __hrtimer_run_queues+0x1c2/0x4d0 [ 460.221976] ? watchdog+0x40/0x40 [ 460.224500] ? hrtimer_interrupt+0x128/0x2c0 [ 460.227872] ? smp_apic_timer_interrupt+0xa6/0x270 [ 460.230644] ? apic_timer_interrupt+0xf/0x20 [ 460.235564] [ 460.236472] ? ZSTD_btGetAllMatches_noDict_3+0xa4b/0xa90 [lzstd] [ 460.238470] ? ZSTD_btGetAllMatches_noDict_3+0x6cf/0xa90 [lzstd] [ 460.241063] ZSTD_compressBlock_opt2+0xd6d/0x15a0 [lzstd] [ 460.243991] ? ZSTD_btGetAllMatches_dictMatchState_6+0xfa0/0xfa0 [lzstd] [ 460.250523] ? ZSTD_btGetAllMatches_noDict_4+0x850/0x850 [lzstd] [ 460.258047] ? ZSTD_btGetAllMatches_noDict_5+0x860/0x860 [lzstd] [ 460.266565] ? ZSTD_btGetAllMatches_extDict_5+0x1160/0x1160 [lzstd] [ 460.271041] ? ZSTD_btGetAllMatches_dictMatchState_3+0x11b0/0x11b0 [lzstd] [ 460.276623] ? ZSTD_btGetAllMatches_extDict_6+0x1160/0x1160 [lzstd] [ 460.289143] ? ZSTD_btGetAllMatches_extDict_4+0x1160/0x1160 [lzstd] [ 460.292969] ? ZSTD_btGetAllMatches_noDict_3+0xa90/0xa90 [lzstd] [ 460.294754] ? ZSTD_btGetAllMatches_dictMatchState_5+0xfa0/0xfa0 [lzstd] [ 460.303921] ? ZSTD_btGetAllMatches_noDict_6+0x860/0x860 [lzstd] [ 460.313214] ? ZSTD_btGetAllMatches_dictMatchState_4+0xf80/0xf80 [lzstd] [ 460.316513] ? ZSTD_compressBlock_btultra2+0xfd/0x140 [lzstd] [ 460.322206] ZSTD_compressBlock_btultra2+0xfd/0x140 [lzstd] [ 460.326773] ZSTD_buildSeqStore+0x28a/0x3b0 [lzstd] [ 460.331977] ZSTD_compress_frameChunk+0x3b8/0xd80 [lzstd] [ 460.334196] ? ZSTD_getCParams_internal+0xfa/0x230 [lzstd] [ 460.336259] ZSTD_compressContinue_internal+0x1b8/0x3e0 [lzstd] [ 460.338086] ZSTD_compressEnd+0x2b/0x200 [lzstd] [ 460.339865] ZSTD_compressStream2+0x8bf/0x9c0 [lzstd] [ 460.341200] ZSTD_compress2+0x9e/0x110 [lzstd] [ 460.342878] zstd_compress_cctx+0x59/0x70 [lzstd] [ 460.344112] __zstd_compress.isra.2+0xef/0x2c0 [lzstd] [ 460.345388] ? __schedule+0x369/0xcb0 [ 460.347987] zstd_compress+0x2f/0x60 [lzstd] [ 460.349153] crypto_compress+0x17/0x20 [ 460.350090] test_comp_compress_decompress+0x2c6/0x77a [kcompr] [ 460.352064] kcompr_init+0x2f7/0x1000 [kcompr] [ 460.354923] ? slab_post_alloc_hook+0x66/0x380 [ 460.356301] ? kmalloc_array.constprop.6+0x23/0x40 [ 460.360950] ? __kmalloc+0x1b4/0x4a0 [ 460.365161] ? 0xffffffffc05fd000 [ 460.366377] do_one_initcall+0x73/0x360 [ 460.370036] ? gcov_event+0x73/0x679 [ 460.370890] do_init_module+0xa4/0x350 [ 460.371747] load_module+0x1c42/0x2260 [ 460.375271] ? security_kernel_post_read_file+0x88/0xc0 [ 460.379996] ? __do_sys_finit_module+0x141/0x1a0 [ 460.384237] __do_sys_finit_module+0x141/0x1a0 [ 460.387843] __x64_sys_finit_module+0x1e/0x30 [ 460.391109] do_syscall_64+0xc1/0x440 [ 460.393936] entry_SYSCALL_64_after_hwframe+0x49/0xae [ 460.397544] RIP: 0033:0x7f66dc30541d [ 460.399912] Code: ff c3 66 2e 0f 1f 84 00 00 00 00 00 90 f3 0f 1e fa 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 3b 7a 39 00 f7 d8 64 89 01 48 [ 460.417252] RSP: 002b:00007ffe4fe3ed28 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 460.421939] RAX: ffffffffffffffda RBX: 000055dc71255830 RCX: 00007f66dc30541d [ 460.425939] RDX: 0000000000000000 RSI: 000055dc712552a0 RDI: 0000000000000003 [ 460.429932] RBP: 000055dc712552a0 R08: 0000000000000000 R09: 00007ffe4fe3eec8 [ 460.434227] R10: 0000000000000003 R11: 0000000000000246 R12: 0000000000000000 [ 460.440937] R13: 000055dc712557f0 R14: 0000000000000000 R15: 000055dc712552a0 [ 461.018361] lustre_kcompr_18885: compr zstd(15) in 128kB chunks took 23522362 us (0 MB/s), compress ratio: 5.28 [ 471.502634] lustre_kcompr_18885: compr zstd(15) in 256kB chunks took 10479626 us (0 MB/s), compress ratio: 5.57 [ 483.922033] lustre_kcompr_18885: compr zstd(15) in 512kB chunks took 12409230 us (0 MB/s), compress ratio: 5.83 [ 498.506904] lustre_kcompr_18885: compr zstd(15) in 1024kB chunks took 14575860 us (0 MB/s), compress ratio: 6.06 [ 513.437867] lustre_kcompr_18885: compr zstd(15) in 2048kB chunks took 14922242 us (0 MB/s), compress ratio: 6.26 [ 531.791741] lustre_kcompr_18885: compr zstd(15) in 4096kB chunks took 18350110 us (0 MB/s), compress ratio: 6.46 [ 531.836188] lustre_kcompr_18885: decompr zstd(15) took 29245 us (21 MB/s) [ 531.850610] lustre_kcompr_18885: test_comp_compress_decompress(zstd,15) ret 0 [ 532.542654] lustre_kcompr_18885: acompr zstd(15) of 128kB chunks took 671368 us (0 MB/s), compress ratio: 4.64 [ 532.561095] lustre_kcompr_18885: adecompr zstd(15) took 578 us (46 MB/s) [ 532.599431] lustre_kcompr_18885: test_acomp_compress_decompress(zstd,15) ret 0 [ 532.722884] lustre_kcompr_18885: compr zstdfast(1) in 64kB chunks took 73031 us (54 MB/s), compress ratio: 3.49 [ 532.813725] lustre_kcompr_18885: compr zstdfast(1) in 128kB chunks took 78479 us (50 MB/s), compress ratio: 3.62 [ 532.910637] lustre_kcompr_18885: compr zstdfast(1) in 256kB chunks took 86196 us (46 MB/s), compress ratio: 3.84 [ 532.975504] lustre_kcompr_18885: compr zstdfast(1) in 512kB chunks took 56704 us (70 MB/s), compress ratio: 3.73 [ 533.067786] lustre_kcompr_18885: compr zstdfast(1) in 1024kB chunks took 80310 us (49 MB/s), compress ratio: 3.76 [ 533.156304] lustre_kcompr_18885: compr zstdfast(1) in 2048kB chunks took 70247 us (56 MB/s), compress ratio: 3.77 [ 533.217686] lustre_kcompr_18885: compr zstdfast(1) in 4096kB chunks took 55492 us (72 MB/s), compress ratio: 3.78 [ 533.246152] lustre_kcompr_18885: decompr zstdfast(1) took 15994 us (66 MB/s) [ 533.250309] lustre_kcompr_18885: test_comp_compress_decompress(zstdfast,1) ret 0 [ 533.257099] lustre_kcompr_18885: acompr zstdfast(1) of 128kB chunks took 1403 us (89 MB/s), compress ratio: 3.19 [ 533.263302] lustre_kcompr_18885: adecompr zstdfast(1) took 2001 us (19 MB/s) [ 533.268312] lustre_kcompr_18885: test_acomp_compress_decompress(zstdfast,1) ret 0 [ 533.358309] lustre_kcompr_18885: compr zstdfast(5) in 64kB chunks took 69878 us (57 MB/s), compress ratio: 2.81 [ 533.441309] lustre_kcompr_18885: compr zstdfast(5) in 128kB chunks took 73957 us (54 MB/s), compress ratio: 2.93 [ 533.513535] lustre_kcompr_18885: compr zstdfast(5) in 256kB chunks took 62048 us (64 MB/s), compress ratio: 3.16 [ 533.577270] lustre_kcompr_18885: compr zstdfast(5) in 512kB chunks took 48078 us (83 MB/s), compress ratio: 3.04 [ 533.650987] lustre_kcompr_18885: compr zstdfast(5) in 1024kB chunks took 67241 us (59 MB/s), compress ratio: 3.07 [ 533.725927] lustre_kcompr_18885: compr zstdfast(5) in 2048kB chunks took 61753 us (64 MB/s), compress ratio: 3.08 [ 533.810427] lustre_kcompr_18885: compr zstdfast(5) in 4096kB chunks took 75618 us (52 MB/s), compress ratio: 3.09 [ 533.876756] lustre_kcompr_18885: decompr zstdfast(5) took 41617 us (31 MB/s) [ 533.886888] lustre_kcompr_18885: test_comp_compress_decompress(zstdfast,5) ret 0 [ 533.894747] lustre_kcompr_18885: acompr zstdfast(5) of 128kB chunks took 1944 us (64 MB/s), compress ratio: 2.56 [ 533.908592] lustre_kcompr_18885: adecompr zstdfast(5) took 1107 us (44 MB/s) [ 533.919887] lustre_kcompr_18885: test_acomp_compress_decompress(zstdfast,5) ret 0 [ 533.978651] lustre_kcompr_18885: compr zstdfast(12) in 64kB chunks took 39837 us (100 MB/s), compress ratio: 1.43 [ 534.021866] lustre_kcompr_18885: compr zstdfast(12) in 128kB chunks took 35346 us (113 MB/s), compress ratio: 1.50 [ 534.067675] lustre_kcompr_18885: compr zstdfast(12) in 256kB chunks took 34095 us (117 MB/s), compress ratio: 1.62 [ 534.114994] lustre_kcompr_18885: compr zstdfast(12) in 512kB chunks took 39280 us (101 MB/s), compress ratio: 1.55 [ 534.188372] lustre_kcompr_18885: compr zstdfast(12) in 1024kB chunks took 54785 us (73 MB/s), compress ratio: 1.58 [ 534.239519] lustre_kcompr_18885: compr zstdfast(12) in 2048kB chunks took 45695 us (87 MB/s), compress ratio: 1.60 [ 534.288339] lustre_kcompr_18885: compr zstdfast(12) in 4096kB chunks took 37667 us (106 MB/s), compress ratio: 1.60 [ 534.331686] lustre_kcompr_18885: decompr zstdfast(12) took 23002 us (108 MB/s) [ 534.344452] lustre_kcompr_18885: test_comp_compress_decompress(zstdfast,12) ret 0 [ 534.363589] lustre_kcompr_18885: acompr zstdfast(12) of 128kB chunks took 3708 us (33 MB/s), compress ratio: 1.37 [ 534.395733] lustre_kcompr_18885: adecompr zstdfast(12) took 879 us (103 MB/s) [ 534.426661] lustre_kcompr_18885: test_acomp_compress_decompress(zstdfast,12) ret 0 [ 534.473117] lustre_kcompr_18885: compr zstdfast(15) in 64kB chunks took 16488 us (242 MB/s), compress ratio: 1.12 [ 534.514599] lustre_kcompr_18885: compr zstdfast(15) in 128kB chunks took 29016 us (137 MB/s), compress ratio: 1.16 [ 534.581776] lustre_kcompr_18885: compr zstdfast(15) in 256kB chunks took 51740 us (77 MB/s), compress ratio: 1.22 [ 534.643153] lustre_kcompr_18885: compr zstdfast(15) in 512kB chunks took 41504 us (96 MB/s), compress ratio: 1.20 [ 534.706318] lustre_kcompr_18885: compr zstdfast(15) in 1024kB chunks took 32010 us (124 MB/s), compress ratio: 1.22 [ 534.761765] lustre_kcompr_18885: compr zstdfast(15) in 2048kB chunks took 33114 us (120 MB/s), compress ratio: 1.23 [ 534.812234] lustre_kcompr_18885: compr zstdfast(15) in 4096kB chunks took 40938 us (97 MB/s), compress ratio: 1.23 [ 534.848535] lustre_kcompr_18885: decompr zstdfast(15) took 15357 us (210 MB/s) [ 534.866997] lustre_kcompr_18885: test_comp_compress_decompress(zstdfast,15) ret 0 [ 534.871736] lustre_kcompr_18885: acompr zstdfast(15) of 128kB chunks took 413 us (302 MB/s), compress ratio: 1.08 [ 534.886567] lustre_kcompr_18885: adecompr zstdfast(15) took 398 us (290 MB/s) [ 534.893845] lustre_kcompr_18885: test_acomp_compress_decompress(zstdfast,15) ret 0 [ 534.898534] lustre_kcompr_18885: SUCCESS [ 541.534866] lustre_kcompr_12345: ***** [ 541.544277] lustre_kcompr_12345: (de)compression test on provided file /tmp/f84.sanity [ 541.554738] lustre_kcompr_12345: ***** [ 541.611721] lustre_kcompr_12345: compr lz4(1) in 64kB chunks took 43207 us (92 MB/s), compress ratio: 2.45 [ 541.644184] lustre_kcompr_12345: compr lz4(1) in 128kB chunks took 24949 us (160 MB/s), compress ratio: 2.39 [ 541.697543] lustre_kcompr_12345: compr lz4(1) in 256kB chunks took 41154 us (97 MB/s), compress ratio: 2.39 [ 541.748423] lustre_kcompr_12345: compr lz4(1) in 512kB chunks took 42867 us (93 MB/s), compress ratio: 2.39 [ 541.815169] lustre_kcompr_12345: compr lz4(1) in 1024kB chunks took 51978 us (76 MB/s), compress ratio: 2.39 [ 541.902089] lustre_kcompr_12345: compr lz4(1) in 2048kB chunks took 58799 us (68 MB/s), compress ratio: 2.39 [ 541.969549] lustre_kcompr_12345: compr lz4(1) in 4096kB chunks took 57261 us (69 MB/s), compress ratio: 2.39 [ 541.997592] lustre_kcompr_12345: decompr lz4(1) took 13627 us (122 MB/s) [ 542.001891] lustre_kcompr_12345: test_comp_compress_decompress(lz4,1) ret 0 [ 542.019594] lustre_kcompr_12345: acompr lz4(1) of 128kB chunks took 181 us (690 MB/s), compress ratio: 15.67 [ 542.026725] lustre_kcompr_12345: adecompr lz4(1) took 143 us (55 MB/s) [ 542.049760] lustre_kcompr_12345: test_acomp_compress_decompress(lz4,1) ret 0 [ 542.100800] lustre_kcompr_12345: compr lz4(4) in 64kB chunks took 21124 us (189 MB/s), compress ratio: 2.36 [ 542.147765] lustre_kcompr_12345: compr lz4(4) in 128kB chunks took 31685 us (126 MB/s), compress ratio: 2.35 [ 542.179091] lustre_kcompr_12345: compr lz4(4) in 256kB chunks took 23540 us (169 MB/s), compress ratio: 2.35 [ 542.223755] lustre_kcompr_12345: compr lz4(4) in 512kB chunks took 31801 us (125 MB/s), compress ratio: 2.35 [ 542.266151] lustre_kcompr_12345: compr lz4(4) in 1024kB chunks took 30441 us (131 MB/s), compress ratio: 2.35 [ 542.301371] lustre_kcompr_12345: compr lz4(4) in 2048kB chunks took 16664 us (240 MB/s), compress ratio: 2.35 [ 542.341183] lustre_kcompr_12345: compr lz4(4) in 4096kB chunks took 32119 us (124 MB/s), compress ratio: 2.35 [ 542.369736] lustre_kcompr_12345: decompr lz4(4) took 10892 us (156 MB/s) [ 542.375132] lustre_kcompr_12345: test_comp_compress_decompress(lz4,4) ret 0 [ 542.388037] lustre_kcompr_12345: acompr lz4(4) of 128kB chunks took 199 us (628 MB/s), compress ratio: 15.30 [ 542.398445] lustre_kcompr_12345: adecompr lz4(4) took 1214 us (6 MB/s) [ 542.411356] lustre_kcompr_12345: test_acomp_compress_decompress(lz4,4) ret 0 [ 542.458720] lustre_kcompr_12345: compr lz4(9) in 64kB chunks took 22902 us (174 MB/s), compress ratio: 2.32 [ 542.497822] lustre_kcompr_12345: compr lz4(9) in 128kB chunks took 26688 us (149 MB/s), compress ratio: 2.31 [ 542.528286] lustre_kcompr_12345: compr lz4(9) in 256kB chunks took 21838 us (183 MB/s), compress ratio: 2.31 [ 542.552658] lustre_kcompr_12345: compr lz4(9) in 512kB chunks took 14727 us (271 MB/s), compress ratio: 2.31 [ 542.584112] lustre_kcompr_12345: compr lz4(9) in 1024kB chunks took 24480 us (163 MB/s), compress ratio: 2.31 [ 542.613475] lustre_kcompr_12345: compr lz4(9) in 2048kB chunks took 17206 us (232 MB/s), compress ratio: 2.32 [ 542.631145] lustre_kcompr_12345: compr lz4(9) in 4096kB chunks took 11895 us (336 MB/s), compress ratio: 2.32 [ 542.647912] lustre_kcompr_12345: decompr lz4(9) took 3781 us (455 MB/s) [ 542.657484] lustre_kcompr_12345: test_comp_compress_decompress(lz4,9) ret 0 [ 542.669672] lustre_kcompr_12345: acompr lz4(9) of 128kB chunks took 109 us (1146 MB/s), compress ratio: 14.81 [ 542.676478] lustre_kcompr_12345: adecompr lz4(9) took 244 us (34 MB/s) [ 542.707130] lustre_kcompr_12345: test_acomp_compress_decompress(lz4,9) ret 0 [ 542.772437] lustre_kcompr_12345: compr lz4(15) in 64kB chunks took 23947 us (167 MB/s), compress ratio: 2.23 [ 542.795776] lustre_kcompr_12345: compr lz4(15) in 128kB chunks took 15245 us (262 MB/s), compress ratio: 2.23 [ 542.822921] lustre_kcompr_12345: compr lz4(15) in 256kB chunks took 19590 us (204 MB/s), compress ratio: 2.23 [ 542.850994] lustre_kcompr_12345: compr lz4(15) in 512kB chunks took 17246 us (231 MB/s), compress ratio: 2.23 [ 542.866944] lustre_kcompr_12345: compr lz4(15) in 1024kB chunks took 9259 us (432 MB/s), compress ratio: 2.23 [ 542.891715] lustre_kcompr_12345: compr lz4(15) in 2048kB chunks took 19019 us (210 MB/s), compress ratio: 2.23 [ 542.919778] lustre_kcompr_12345: compr lz4(15) in 4096kB chunks took 12615 us (317 MB/s), compress ratio: 2.23 [ 542.954472] lustre_kcompr_12345: decompr lz4(15) took 4215 us (424 MB/s) [ 542.968305] lustre_kcompr_12345: test_comp_compress_decompress(lz4,15) ret 0 [ 542.973462] lustre_kcompr_12345: acompr lz4(15) of 128kB chunks took 114 us (1096 MB/s), compress ratio: 14.18 [ 542.986482] lustre_kcompr_12345: adecompr lz4(15) took 392 us (22 MB/s) [ 543.013387] lustre_kcompr_12345: test_acomp_compress_decompress(lz4,15) ret 0 [ 543.233956] lustre_kcompr_12345: compr lz4hc(1) in 64kB chunks took 185234 us (21 MB/s), compress ratio: 2.54 [ 543.536045] lustre_kcompr_12345: compr lz4hc(1) in 128kB chunks took 286048 us (13 MB/s), compress ratio: 2.61 [ 543.865243] lustre_kcompr_12345: compr lz4hc(1) in 256kB chunks took 321113 us (12 MB/s), compress ratio: 2.64 [ 544.299732] lustre_kcompr_12345: compr lz4hc(1) in 512kB chunks took 417283 us (9 MB/s), compress ratio: 2.66 [ 544.626409] lustre_kcompr_12345: compr lz4hc(1) in 1024kB chunks took 318777 us (12 MB/s), compress ratio: 2.67 [ 544.903428] lustre_kcompr_12345: compr lz4hc(1) in 2048kB chunks took 267855 us (14 MB/s), compress ratio: 2.67 [ 545.448632] lustre_kcompr_12345: compr lz4hc(1) in 4096kB chunks took 521194 us (7 MB/s), compress ratio: 2.67 [ 545.491098] lustre_kcompr_12345: decompr lz4hc(1) took 13520 us (110 MB/s) [ 545.505842] lustre_kcompr_12345: test_comp_compress_decompress(lz4hc,1) ret 0 [ 545.525632] lustre_kcompr_12345: acompr lz4hc(1) of 128kB chunks took 3012 us (41 MB/s), compress ratio: 15.97 [ 545.537654] lustre_kcompr_12345: adecompr lz4hc(1) took 208 us (37 MB/s) [ 545.559811] lustre_kcompr_12345: test_acomp_compress_decompress(lz4hc,1) ret 0 [ 545.847691] lustre_kcompr_12345: compr lz4hc(3) in 64kB chunks took 246790 us (16 MB/s), compress ratio: 2.57 [ 546.111986] lustre_kcompr_12345: compr lz4hc(3) in 128kB chunks took 259339 us (15 MB/s), compress ratio: 2.64 [ 546.542645] lustre_kcompr_12345: compr lz4hc(3) in 256kB chunks took 416499 us (9 MB/s), compress ratio: 2.68 [ 546.838547] lustre_kcompr_12345: compr lz4hc(3) in 512kB chunks took 285100 us (14 MB/s), compress ratio: 2.69 [ 547.100947] lustre_kcompr_12345: compr lz4hc(3) in 1024kB chunks took 255539 us (15 MB/s), compress ratio: 2.70 [ 547.494233] lustre_kcompr_12345: compr lz4hc(3) in 2048kB chunks took 379272 us (10 MB/s), compress ratio: 2.71 [ 547.897507] lustre_kcompr_12345: compr lz4hc(3) in 4096kB chunks took 393092 us (10 MB/s), compress ratio: 2.71 [ 547.914828] lustre_kcompr_12345: decompr lz4hc(3) took 5613 us (262 MB/s) [ 547.919657] lustre_kcompr_12345: test_comp_compress_decompress(lz4hc,3) ret 0 [ 547.931256] lustre_kcompr_12345: acompr lz4hc(3) of 128kB chunks took 990 us (126 MB/s), compress ratio: 16.00 [ 547.937298] lustre_kcompr_12345: adecompr lz4hc(3) took 478 us (16 MB/s) [ 547.943372] lustre_kcompr_12345: test_acomp_compress_decompress(lz4hc,3) ret 0 [ 548.606757] lustre_kcompr_12345: compr lz4hc(8) in 64kB chunks took 646124 us (6 MB/s), compress ratio: 2.60 [ 549.039976] lustre_kcompr_12345: compr lz4hc(8) in 128kB chunks took 417420 us (9 MB/s), compress ratio: 2.66 [ 549.425216] lustre_kcompr_12345: compr lz4hc(8) in 256kB chunks took 381686 us (10 MB/s), compress ratio: 2.70 [ 550.587759] lustre_kcompr_12345: compr lz4hc(8) in 512kB chunks took 1156408 us (3 MB/s), compress ratio: 2.71 [ 551.565900] lustre_kcompr_12345: compr lz4hc(8) in 1024kB chunks took 967791 us (4 MB/s), compress ratio: 2.72 [ 552.074829] lustre_kcompr_12345: compr lz4hc(8) in 2048kB chunks took 497289 us (8 MB/s), compress ratio: 2.73 [ 552.976906] lustre_kcompr_12345: compr lz4hc(8) in 4096kB chunks took 897534 us (4 MB/s), compress ratio: 2.73 [ 553.019863] lustre_kcompr_12345: decompr lz4hc(8) took 11007 us (132 MB/s) [ 553.028402] lustre_kcompr_12345: test_comp_compress_decompress(lz4hc,8) ret 0 [ 553.055325] lustre_kcompr_12345: acompr lz4hc(8) of 128kB chunks took 19631 us (6 MB/s), compress ratio: 16.17 [ 553.066951] lustre_kcompr_12345: adecompr lz4hc(8) took 160 us (48 MB/s) [ 553.082712] lustre_kcompr_12345: test_acomp_compress_decompress(lz4hc,8) ret 0 [ 559.463440] lustre_kcompr_12345: compr lz4hc(15) in 64kB chunks took 6364784 us (0 MB/s), compress ratio: 2.61 [ 573.693502] lustre_kcompr_12345: compr lz4hc(15) in 128kB chunks took 14225648 us (0 MB/s), compress ratio: 2.67 [ 586.289075] lustre_kcompr_12345: compr lz4hc(15) in 256kB chunks took 12565447 us (0 MB/s), compress ratio: 2.71 [ 601.641765] lustre_kcompr_12345: compr lz4hc(15) in 512kB chunks took 15348147 us (0 MB/s), compress ratio: 2.72 [ 618.879761] lustre_kcompr_12345: compr lz4hc(15) in 1024kB chunks took 17227365 us (0 MB/s), compress ratio: 2.73 [ 633.958557] lustre_kcompr_12345: compr lz4hc(15) in 2048kB chunks took 15073287 us (0 MB/s), compress ratio: 2.74 [ 651.254694] lustre_kcompr_12345: compr lz4hc(15) in 4096kB chunks took 17291014 us (0 MB/s), compress ratio: 2.74 [ 651.274876] lustre_kcompr_12345: decompr lz4hc(15) took 5829 us (249 MB/s) [ 651.280443] lustre_kcompr_12345: test_comp_compress_decompress(lz4hc,15) ret 0 [ 651.330236] lustre_kcompr_12345: acompr lz4hc(15) of 128kB chunks took 45015 us (2 MB/s), compress ratio: 16.30 [ 651.342200] lustre_kcompr_12345: adecompr lz4hc(15) took 329 us (23 MB/s) [ 651.347564] lustre_kcompr_12345: test_acomp_compress_decompress(lz4hc,15) ret 0 [ 651.370233] lustre_kcompr_12345: compr lzo(-1) in 64kB chunks took 17530 us (228 MB/s), compress ratio: 2.35 [ 651.387995] lustre_kcompr_12345: compr lzo(-1) in 128kB chunks took 13378 us (298 MB/s), compress ratio: 2.35 [ 651.416021] lustre_kcompr_12345: compr lzo(-1) in 256kB chunks took 22106 us (180 MB/s), compress ratio: 2.35 [ 651.452608] lustre_kcompr_12345: compr lzo(-1) in 512kB chunks took 30925 us (129 MB/s), compress ratio: 2.36 [ 651.477806] lustre_kcompr_12345: compr lzo(-1) in 1024kB chunks took 21551 us (185 MB/s), compress ratio: 2.36 [ 651.511487] lustre_kcompr_12345: compr lzo(-1) in 2048kB chunks took 23914 us (167 MB/s), compress ratio: 2.36 [ 651.536695] lustre_kcompr_12345: compr lzo(-1) in 4096kB chunks took 18924 us (211 MB/s), compress ratio: 2.36 [ 651.553783] lustre_kcompr_12345: decompr lzo(-1) took 6136 us (276 MB/s) [ 651.558506] lustre_kcompr_12345: test_comp_compress_decompress(lzo,-1) ret 0 [ 651.563207] lustre_kcompr_12345: acompr lzo(-1) of 128kB chunks took 1230 us (101 MB/s), compress ratio: 15.45 [ 651.567409] lustre_kcompr_12345: adecompr lzo(-1) took 332 us (24 MB/s) [ 651.573996] lustre_kcompr_12345: test_acomp_compress_decompress(lzo,-1) ret 0 [ 652.219036] lustre_kcompr_12345: compr deflate(1) in 64kB chunks took 634768 us (6 MB/s), compress ratio: 3.27 [ 652.894987] lustre_kcompr_12345: compr deflate(1) in 128kB chunks took 668537 us (5 MB/s), compress ratio: 3.28 [ 653.645545] lustre_kcompr_12345: compr deflate(1) in 256kB chunks took 740209 us (5 MB/s), compress ratio: 3.28 [ 654.552387] lustre_kcompr_12345: compr deflate(1) in 512kB chunks took 892948 us (4 MB/s), compress ratio: 3.28 [ 655.410196] lustre_kcompr_12345: compr deflate(1) in 1024kB chunks took 845984 us (4 MB/s), compress ratio: 3.28 [ 656.196635] lustre_kcompr_12345: compr deflate(1) in 2048kB chunks took 776380 us (5 MB/s), compress ratio: 3.28 [ 656.976479] lustre_kcompr_12345: compr deflate(1) in 4096kB chunks took 771880 us (5 MB/s), compress ratio: 3.28 [ 657.043263] lustre_kcompr_12345: decompr deflate(1) took 46556 us (26 MB/s) [ 657.059177] lustre_kcompr_12345: test_comp_compress_decompress(deflate,1) ret 0 [ 657.095331] lustre_kcompr_12345: acompr deflate(1) of 128kB chunks took 26186 us (4 MB/s), compress ratio: 21.43 [ 657.118739] lustre_kcompr_12345: adecompr deflate(1) took 2054 us (2 MB/s) [ 657.148498] lustre_kcompr_12345: test_acomp_compress_decompress(deflate,1) ret 0 [ 657.939446] lustre_kcompr_12345: compr deflate(6) in 64kB chunks took 772040 us (5 MB/s), compress ratio: 3.27 [ 658.845628] lustre_kcompr_12345: compr deflate(6) in 128kB chunks took 897308 us (4 MB/s), compress ratio: 3.28 [ 659.884424] lustre_kcompr_12345: compr deflate(6) in 256kB chunks took 1027628 us (3 MB/s), compress ratio: 3.28 [ 660.509293] lustre_kcompr_12345: compr deflate(6) in 512kB chunks took 609913 us (6 MB/s), compress ratio: 3.28 [ 661.522396] lustre_kcompr_12345: compr deflate(6) in 1024kB chunks took 999482 us (4 MB/s), compress ratio: 3.28 [ 661.997681] lustre_kcompr_12345: compr deflate(6) in 2048kB chunks took 469454 us (8 MB/s), compress ratio: 3.28 [ 662.352188] lustre_kcompr_12345: compr deflate(6) in 4096kB chunks took 350041 us (11 MB/s), compress ratio: 3.28 [ 662.437989] lustre_kcompr_12345: decompr deflate(6) took 65585 us (18 MB/s) [ 662.456362] lustre_kcompr_12345: test_comp_compress_decompress(deflate,6) ret 0 [ 662.483639] lustre_kcompr_12345: acompr deflate(6) of 128kB chunks took 19398 us (6 MB/s), compress ratio: 21.43 [ 662.491441] lustre_kcompr_12345: adecompr deflate(6) took 930 us (6 MB/s) [ 662.500680] lustre_kcompr_12345: test_acomp_compress_decompress(deflate,6) ret 0 [ 663.093215] lustre_kcompr_12345: compr deflate(9) in 64kB chunks took 547897 us (7 MB/s), compress ratio: 3.27 [ 663.546918] lustre_kcompr_12345: compr deflate(9) in 128kB chunks took 447298 us (8 MB/s), compress ratio: 3.28 [ 663.947550] lustre_kcompr_12345: compr deflate(9) in 256kB chunks took 397021 us (10 MB/s), compress ratio: 3.28 [ 664.316429] lustre_kcompr_12345: compr deflate(9) in 512kB chunks took 364999 us (10 MB/s), compress ratio: 3.28 [ 664.713348] lustre_kcompr_12345: compr deflate(9) in 1024kB chunks took 391424 us (10 MB/s), compress ratio: 3.28 [ 665.053261] lustre_kcompr_12345: compr deflate(9) in 2048kB chunks took 335537 us (11 MB/s), compress ratio: 3.28 [ 665.541883] lustre_kcompr_12345: compr deflate(9) in 4096kB chunks took 485777 us (8 MB/s), compress ratio: 3.28 [ 665.618837] lustre_kcompr_12345: decompr deflate(9) took 53010 us (22 MB/s) [ 665.626817] lustre_kcompr_12345: test_comp_compress_decompress(deflate,9) ret 0 [ 665.650870] lustre_kcompr_12345: acompr deflate(9) of 128kB chunks took 17540 us (7 MB/s), compress ratio: 21.43 [ 665.656809] lustre_kcompr_12345: adecompr deflate(9) took 400 us (14 MB/s) [ 665.667952] lustre_kcompr_12345: test_acomp_compress_decompress(deflate,9) ret 0 [ 665.842483] lustre_kcompr_12345: compr zstd(0) in 64kB chunks took 160609 us (24 MB/s), compress ratio: 3.28 [ 665.983264] lustre_kcompr_12345: compr zstd(0) in 128kB chunks took 133640 us (29 MB/s), compress ratio: 3.26 [ 666.048771] lustre_kcompr_12345: compr zstd(0) in 256kB chunks took 58411 us (68 MB/s), compress ratio: 3.31 [ 666.123943] lustre_kcompr_12345: compr zstd(0) in 512kB chunks took 71867 us (55 MB/s), compress ratio: 3.32 [ 666.252742] lustre_kcompr_12345: compr zstd(0) in 1024kB chunks took 125128 us (31 MB/s), compress ratio: 3.32 [ 666.462275] lustre_kcompr_12345: compr zstd(0) in 2048kB chunks took 203860 us (19 MB/s), compress ratio: 3.33 [ 666.778854] lustre_kcompr_12345: compr zstd(0) in 4096kB chunks took 313071 us (12 MB/s), compress ratio: 3.33 [ 666.844342] lustre_kcompr_12345: decompr zstd(0) took 50897 us (23 MB/s) [ 666.856864] lustre_kcompr_12345: test_comp_compress_decompress(zstd,0) ret 0 [ 666.867938] lustre_kcompr_12345: acompr zstd(0) of 128kB chunks took 964 us (129 MB/s), compress ratio: 22.64 [ 666.877021] lustre_kcompr_12345: adecompr zstd(0) took 204 us (27 MB/s) [ 666.895894] lustre_kcompr_12345: test_acomp_compress_decompress(zstd,0) ret 0 [ 666.952827] lustre_kcompr_12345: compr zstd(1) in 64kB chunks took 43818 us (91 MB/s), compress ratio: 3.22 [ 667.009144] lustre_kcompr_12345: compr zstd(1) in 128kB chunks took 51258 us (78 MB/s), compress ratio: 3.18 [ 667.066332] lustre_kcompr_12345: compr zstd(1) in 256kB chunks took 50958 us (78 MB/s), compress ratio: 3.19 [ 667.136030] lustre_kcompr_12345: compr zstd(1) in 512kB chunks took 65578 us (60 MB/s), compress ratio: 3.19 [ 667.185791] lustre_kcompr_12345: compr zstd(1) in 1024kB chunks took 34534 us (115 MB/s), compress ratio: 3.19 [ 667.230735] lustre_kcompr_12345: compr zstd(1) in 2048kB chunks took 37671 us (106 MB/s), compress ratio: 3.19 [ 667.302359] lustre_kcompr_12345: compr zstd(1) in 4096kB chunks took 66012 us (60 MB/s), compress ratio: 3.19 [ 667.337412] lustre_kcompr_12345: decompr zstd(1) took 17320 us (72 MB/s) [ 667.342249] lustre_kcompr_12345: test_comp_compress_decompress(zstd,1) ret 0 [ 667.356569] lustre_kcompr_12345: acompr zstd(1) of 128kB chunks took 3669 us (34 MB/s), compress ratio: 22.79 [ 667.368159] lustre_kcompr_12345: adecompr zstd(1) took 379 us (14 MB/s) [ 667.375813] lustre_kcompr_12345: test_acomp_compress_decompress(zstd,1) ret 0 [ 668.627589] lustre_kcompr_12345: compr zstd(10) in 64kB chunks took 1238168 us (3 MB/s), compress ratio: 3.33 [ 669.531556] lustre_kcompr_12345: compr zstd(10) in 128kB chunks took 897608 us (4 MB/s), compress ratio: 3.33 [ 671.420461] lustre_kcompr_12345: compr zstd(10) in 256kB chunks took 1875651 us (2 MB/s), compress ratio: 3.37 [ 672.139294] lustre_kcompr_12345: compr zstd(10) in 512kB chunks took 702374 us (5 MB/s), compress ratio: 3.40 [ 673.459541] lustre_kcompr_12345: compr zstd(10) in 1024kB chunks took 1317005 us (3 MB/s), compress ratio: 3.42 [ 674.422267] lustre_kcompr_12345: compr zstd(10) in 2048kB chunks took 955795 us (4 MB/s), compress ratio: 3.46 [ 675.200112] lustre_kcompr_12345: compr zstd(10) in 4096kB chunks took 770305 us (5 MB/s), compress ratio: 3.50 [ 675.254955] lustre_kcompr_12345: decompr zstd(10) took 41708 us (27 MB/s) [ 675.260117] lustre_kcompr_12345: test_comp_compress_decompress(zstd,10) ret 0 [ 675.269306] lustre_kcompr_12345: acompr zstd(10) of 128kB chunks took 1576 us (79 MB/s), compress ratio: 22.78 [ 675.274046] lustre_kcompr_12345: adecompr zstd(10) took 175 us (31 MB/s) [ 675.278407] lustre_kcompr_12345: test_acomp_compress_decompress(zstd,10) ret 0 [ 686.968367] lustre_kcompr_12345: compr zstd(15) in 64kB chunks took 11670370 us (0 MB/s), compress ratio: 3.36 [ 698.957404] lustre_kcompr_12345: compr zstd(15) in 128kB chunks took 11950647 us (0 MB/s), compress ratio: 3.39 [ 703.492366] lustre_kcompr_12345: compr zstd(15) in 256kB chunks took 4526360 us (0 MB/s), compress ratio: 3.40 [ 707.883831] lustre_kcompr_12345: compr zstd(15) in 512kB chunks took 4384209 us (0 MB/s), compress ratio: 3.42 [ 712.289370] lustre_kcompr_12345: compr zstd(15) in 1024kB chunks took 4399056 us (0 MB/s), compress ratio: 3.43 [ 717.863774] lustre_kcompr_12345: compr zstd(15) in 2048kB chunks took 5559359 us (0 MB/s), compress ratio: 3.48 [ 723.458183] lustre_kcompr_12345: compr zstd(15) in 4096kB chunks took 5587917 us (0 MB/s), compress ratio: 3.56 [ 723.554362] lustre_kcompr_12345: decompr zstd(15) took 66707 us (16 MB/s) [ 723.588371] lustre_kcompr_12345: test_comp_compress_decompress(zstd,15) ret 0 [ 723.691199] lustre_kcompr_12345: acompr zstd(15) of 128kB chunks took 92210 us (1 MB/s), compress ratio: 23.92 [ 723.698541] lustre_kcompr_12345: adecompr zstd(15) took 189 us (27 MB/s) [ 723.725382] lustre_kcompr_12345: test_acomp_compress_decompress(zstd,15) ret 0 [ 723.823371] lustre_kcompr_12345: compr zstdfast(1) in 64kB chunks took 62370 us (64 MB/s), compress ratio: 2.47 [ 723.852952] lustre_kcompr_12345: compr zstdfast(1) in 128kB chunks took 24324 us (164 MB/s), compress ratio: 2.48 [ 723.936541] lustre_kcompr_12345: compr zstdfast(1) in 256kB chunks took 77283 us (51 MB/s), compress ratio: 2.53 [ 724.003933] lustre_kcompr_12345: compr zstdfast(1) in 512kB chunks took 57375 us (69 MB/s), compress ratio: 2.42 [ 724.067468] lustre_kcompr_12345: compr zstdfast(1) in 1024kB chunks took 45159 us (88 MB/s), compress ratio: 2.42 [ 724.143532] lustre_kcompr_12345: compr zstdfast(1) in 2048kB chunks took 65225 us (61 MB/s), compress ratio: 2.42 [ 724.205837] lustre_kcompr_12345: compr zstdfast(1) in 4096kB chunks took 34641 us (115 MB/s), compress ratio: 2.42 [ 724.222483] lustre_kcompr_12345: decompr zstdfast(1) took 5610 us (294 MB/s) [ 724.228496] lustre_kcompr_12345: test_comp_compress_decompress(zstdfast,1) ret 0 [ 724.233551] lustre_kcompr_12345: acompr zstdfast(1) of 128kB chunks took 330 us (378 MB/s), compress ratio: 17.28 [ 724.247209] lustre_kcompr_12345: adecompr zstdfast(1) took 531 us (13 MB/s) [ 724.257432] lustre_kcompr_12345: test_acomp_compress_decompress(zstdfast,1) ret 0 [ 724.329532] lustre_kcompr_12345: compr zstdfast(5) in 64kB chunks took 40179 us (99 MB/s), compress ratio: 2.38 [ 724.351669] lustre_kcompr_12345: compr zstdfast(5) in 128kB chunks took 14473 us (276 MB/s), compress ratio: 2.38 [ 724.370749] lustre_kcompr_12345: compr zstdfast(5) in 256kB chunks took 14095 us (283 MB/s), compress ratio: 2.40 [ 724.389094] lustre_kcompr_12345: compr zstdfast(5) in 512kB chunks took 13892 us (287 MB/s), compress ratio: 2.36 [ 724.423754] lustre_kcompr_12345: compr zstdfast(5) in 1024kB chunks took 29163 us (137 MB/s), compress ratio: 2.36 [ 724.489723] lustre_kcompr_12345: compr zstdfast(5) in 2048kB chunks took 54241 us (73 MB/s), compress ratio: 2.36 [ 724.559423] lustre_kcompr_12345: compr zstdfast(5) in 4096kB chunks took 56757 us (70 MB/s), compress ratio: 2.36 [ 724.596076] lustre_kcompr_12345: decompr zstdfast(5) took 6872 us (245 MB/s) [ 724.602723] lustre_kcompr_12345: test_comp_compress_decompress(zstdfast,5) ret 0 [ 724.612987] lustre_kcompr_12345: acompr zstdfast(5) of 128kB chunks took 189 us (661 MB/s), compress ratio: 16.12 [ 724.637924] lustre_kcompr_12345: adecompr zstdfast(5) took 564 us (13 MB/s) [ 724.647548] lustre_kcompr_12345: test_acomp_compress_decompress(zstdfast,5) ret 0 [ 724.689568] lustre_kcompr_12345: compr zstdfast(12) in 64kB chunks took 21171 us (188 MB/s), compress ratio: 2.07 [ 724.729649] lustre_kcompr_12345: compr zstdfast(12) in 128kB chunks took 31159 us (128 MB/s), compress ratio: 2.07 [ 724.761675] lustre_kcompr_12345: compr zstdfast(12) in 256kB chunks took 16373 us (244 MB/s), compress ratio: 2.08 [ 724.789471] lustre_kcompr_12345: compr zstdfast(12) in 512kB chunks took 18155 us (220 MB/s), compress ratio: 2.07 [ 724.817411] lustre_kcompr_12345: compr zstdfast(12) in 1024kB chunks took 19452 us (205 MB/s), compress ratio: 2.07 [ 724.839321] lustre_kcompr_12345: compr zstdfast(12) in 2048kB chunks took 9158 us (436 MB/s), compress ratio: 2.07 [ 724.859794] lustre_kcompr_12345: compr zstdfast(12) in 4096kB chunks took 10110 us (395 MB/s), compress ratio: 2.08 [ 724.886213] lustre_kcompr_12345: decompr zstdfast(12) took 5631 us (341 MB/s) [ 724.896781] lustre_kcompr_12345: test_comp_compress_decompress(zstdfast,12) ret 0 [ 724.909659] lustre_kcompr_12345: acompr zstdfast(12) of 128kB chunks took 193 us (647 MB/s), compress ratio: 13.13 [ 724.928514] lustre_kcompr_12345: adecompr zstdfast(12) took 110 us (86 MB/s) [ 724.971085] lustre_kcompr_12345: test_acomp_compress_decompress(zstdfast,12) ret 0 [ 725.035916] lustre_kcompr_12345: compr zstdfast(15) in 64kB chunks took 13925 us (287 MB/s), compress ratio: 1.82 [ 725.066477] lustre_kcompr_12345: compr zstdfast(15) in 128kB chunks took 14094 us (283 MB/s), compress ratio: 1.83 [ 725.083484] lustre_kcompr_12345: compr zstdfast(15) in 256kB chunks took 7126 us (561 MB/s), compress ratio: 1.83 [ 725.106763] lustre_kcompr_12345: compr zstdfast(15) in 512kB chunks took 15248 us (262 MB/s), compress ratio: 1.83 [ 725.137457] lustre_kcompr_12345: compr zstdfast(15) in 1024kB chunks took 18410 us (217 MB/s), compress ratio: 1.83 [ 725.162921] lustre_kcompr_12345: compr zstdfast(15) in 2048kB chunks took 16433 us (243 MB/s), compress ratio: 1.83 [ 725.187214] lustre_kcompr_12345: compr zstdfast(15) in 4096kB chunks took 9890 us (404 MB/s), compress ratio: 1.84 [ 725.234510] lustre_kcompr_12345: decompr zstdfast(15) took 6065 us (358 MB/s) [ 725.244568] lustre_kcompr_12345: test_comp_compress_decompress(zstdfast,15) ret 0 [ 725.250381] lustre_kcompr_12345: acompr zstdfast(15) of 128kB chunks took 135 us (925 MB/s), compress ratio: 10.73 [ 725.258315] lustre_kcompr_12345: adecompr zstdfast(15) took 100 us (116 MB/s) [ 725.267837] lustre_kcompr_12345: test_acomp_compress_decompress(zstdfast,15) ret 0 [ 725.272167] lustre_kcompr_12345: SUCCESS [ 750.192862] Lustre: DEBUG MARKER: == sanity test complete, duration 506 sec ================ 12:47:32 (1789058852) [ 752.377563] Lustre: DEBUG MARKER: === sanity: start cleanup 12:47:34 (1789058854) === [ 757.000574] Lustre: DEBUG MARKER: === sanity: finish cleanup 12:47:39 (1789058859) === [ 760.409398] LustreError: 13325:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8ae143ea4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 760.463828] Lustre: Unmounted lustre-client [ 802.760235] Key type lgssc unregistered [ 803.174684] LNet: 13917:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 803.191267] LNetError: 13917:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 803.214679] LNet: Removed LNI 192.168.202.31@tcp [ 804.178264] Key type .llcrypt unregistered [ 804.184894] Key type ._llcrypt unregistered