[ 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 487361568 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 2512MB 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, 524588K 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.001013] APIC: Switch to symmetric I/O mode setup [ 0.003141] x2apic enabled [ 0.004008] Switched APIC routing to physical x2apic. [ 0.006007] kvm-guest: setup PV IPIs [ 0.008600] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.009022] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.011007] pid_max: default: 32768 minimum: 301 [ 0.012127] LSM: Security Framework initializing [ 0.013057] Yama: becoming mindful. [ 0.014045] SELinux: Initializing. [ 0.015081] *** VALIDATE selinux *** [ 0.023531] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.028632] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.029158] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030115] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031135] *** VALIDATE tmpfs *** [ 0.032484] *** VALIDATE proc *** [ 0.034099] *** VALIDATE cgroup *** [ 0.034904] *** VALIDATE cgroup2 *** [ 0.036041] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.038021] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.039012] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.041031] Spectre V2 : User space: Vulnerable [ 0.043005] Speculative Store Bypass: Vulnerable [ 0.045913] debug: unmapping init [mem 0xffffffffac459000-0xffffffffac460fff] [ 0.047954] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.048751] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.049024] ... version: 2 [ 0.050014] ... bit width: 48 [ 0.051015] ... generic registers: 4 [ 0.052010] ... value mask: 0000ffffffffffff [ 0.053012] ... max period: 00007fffffffffff [ 0.054015] ... fixed-purpose events: 3 [ 0.055009] ... event mask: 000000070000000f [ 0.056234] rcu: Hierarchical SRCU implementation. [ 0.058315] smp: Bringing up secondary CPUs ... [ 0.059604] x86: Booting SMP configuration: [ 0.060034] .... node #0, CPUs: #1 #2 #3 [ 0.063318] smp: Brought up 1 node, 4 CPUs [ 0.065013] smpboot: Max logical packages: 1 [ 0.066016] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.154492] node 0 deferred pages initialised in 86ms [ 0.158105] devtmpfs: initialized [ 0.159300] x86/mm: Memory block size: 128MB [ 0.161790] gcov: version magic: 0x41383552 [ 0.165304] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.168136] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.171346] pinctrl core: initialized pinctrl subsystem [ 0.173215] [ 0.173742] ************************************************************* [ 0.176014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.180013] ** ** [ 0.182013] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.185013] ** ** [ 0.187012] ** This means that this kernel is built to expose internal ** [ 0.190014] ** IOMMU data structures, which may compromise security on ** [ 0.192011] ** your system. ** [ 0.194013] ** ** [ 0.197015] ** If you see this message and you are not debugging the ** [ 0.198013] ** kernel, report this immediately to your vendor! ** [ 0.201012] ** ** [ 0.203011] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.206010] ************************************************************* [ 0.208642] NET: Registered protocol family 16 [ 0.210411] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.213061] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.216063] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.219140] cpuidle: using governor menu [ 0.220874] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.223636] PCI: Using configuration type 1 for base access [ 0.226126] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.235161] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.238043] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.242421] cryptd: max_cpu_qlen set to 1000 [ 0.245299] ACPI: Added _OSI(Module Device) [ 0.247021] ACPI: Added _OSI(Processor Device) [ 0.249014] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.250013] ACPI: Added _OSI(Processor Aggregator Device) [ 0.254619] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.260453] ACPI: Interpreter enabled [ 0.262060] ACPI: PM: (supports S0 S3 S4 S5) [ 0.263013] ACPI: Using IOAPIC for interrupt routing [ 0.265106] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.268418] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.278311] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.281044] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.284021] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.288089] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.293393] acpiphp: Slot [2] registered [ 0.294128] acpiphp: Slot [5] registered [ 0.296110] acpiphp: Slot [6] registered [ 0.297097] acpiphp: Slot [3] registered [ 0.298096] acpiphp: Slot [4] registered [ 0.300110] acpiphp: Slot [7] registered [ 0.301100] acpiphp: Slot [8] registered [ 0.302064] acpiphp: Slot [9] registered [ 0.304139] acpiphp: Slot [10] registered [ 0.306107] acpiphp: Slot [11] registered [ 0.307099] acpiphp: Slot [12] registered [ 0.309104] acpiphp: Slot [13] registered [ 0.310201] acpiphp: Slot [14] registered [ 0.312106] acpiphp: Slot [15] registered [ 0.313152] acpiphp: Slot [16] registered [ 0.315132] acpiphp: Slot [17] registered [ 0.316104] acpiphp: Slot [18] registered [ 0.318107] acpiphp: Slot [19] registered [ 0.320102] acpiphp: Slot [20] registered [ 0.321102] acpiphp: Slot [21] registered [ 0.323119] acpiphp: Slot [22] registered [ 0.325124] acpiphp: Slot [23] registered [ 0.326107] acpiphp: Slot [24] registered [ 0.328136] acpiphp: Slot [25] registered [ 0.329108] acpiphp: Slot [26] registered [ 0.331139] acpiphp: Slot [27] registered [ 0.332121] acpiphp: Slot [28] registered [ 0.334115] acpiphp: Slot [29] registered [ 0.336110] acpiphp: Slot [30] registered [ 0.337118] acpiphp: Slot [31] registered [ 0.339070] PCI host bridge to bus 0000:00 [ 0.340026] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.343022] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.345023] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.348025] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.351032] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.354031] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.356223] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.358997] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.362408] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.369543] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.374060] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.377021] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.379017] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.381027] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.384655] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.387856] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.391046] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.393836] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.397947] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.408013] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.411014] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.417157] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.426017] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.433019] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.448018] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.457377] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.467032] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.475016] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.490018] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.500711] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.503390] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.505382] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.508399] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.511238] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.516004] iommu: Default domain type: Passthrough [ 0.517554] SCSI subsystem initialized [ 0.519102] ACPI: bus type USB registered [ 0.521130] usbcore: registered new interface driver usbfs [ 0.523093] usbcore: registered new interface driver hub [ 0.524082] usbcore: registered new device driver usb [ 0.526195] pps_core: LinuxPPS API ver. 1 registered [ 0.528012] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.530067] PTP clock support registered [ 0.532126] EDAC MC: Ver: 3.0.0 [ 0.533314] PCI: Using ACPI for IRQ routing [ 0.534633] NetLabel: Initializing [ 0.536018] NetLabel: domain hash size = 128 [ 0.537010] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.539104] NetLabel: unlabeled traffic allowed by default [ 0.541169] vgaarb: loaded [ 0.542266] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.544011] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.551012] clocksource: Switched to clocksource kvm-clock [ 0.651390] VFS: Disk quotas dquot_6.6.0 [ 0.652352] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.653821] *** VALIDATE ramfs *** [ 0.654629] *** VALIDATE hugetlbfs *** [ 0.655854] pnp: PnP ACPI init [ 0.657401] pnp: PnP ACPI: found 6 devices [ 0.675025] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.678341] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.680724] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.683057] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.685622] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.688216] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.691246] NET: Registered protocol family 2 [ 0.693794] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.698154] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.701728] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.706877] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.709882] TCP: Hash tables configured (established 65536 bind 65536) [ 0.713284] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.716485] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.719354] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.722284] NET: Registered protocol family 1 [ 0.725881] RPC: Registered named UNIX socket transport module. [ 0.728664] RPC: Registered udp transport module. [ 0.730595] RPC: Registered tcp transport module. [ 0.732447] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.735017] NET: Registered protocol family 44 [ 0.736907] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.739034] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.741085] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.743476] PCI: CLS 0 bytes, default 64 [ 0.745048] Unpacking initramfs... [ 2.151198] debug: unmapping init [mem 0xffff9828bcc64000-0xffff9828bffcffff] [ 2.155409] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.157454] software IO TLB: mapped [mem 0x00000000b8c64000-0x00000000bcc64000] (64MB) [ 2.159946] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.621621] Initialise system trusted keyrings [ 2.622915] Key type blacklist registered [ 2.624365] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.633841] zbud: loaded [ 2.637533] *** VALIDATE nfs *** [ 2.638850] *** VALIDATE nfs4 *** [ 2.640635] pstore: using deflate compression [ 2.645086] Platform Keyring initialized [ 2.752730] NET: Registered protocol family 38 [ 2.754883] Key type asymmetric registered [ 2.756524] Asymmetric key parser 'x509' registered [ 2.758651] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.761703] io scheduler mq-deadline registered [ 2.763263] io scheduler kyber registered [ 2.764620] io scheduler bfq registered [ 2.766447] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.769613] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.772152] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.774650] ACPI: Power Button [PWRF] [ 2.780448] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.786845] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.803375] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.830797] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.859964] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.864227] Non-volatile memory driver v1.3 [ 2.865696] Linux agpgart interface v0.103 [ 2.899270] virtio_blk virtio1: [vda] 142768 512-byte logical blocks (73.1 MB/69.7 MiB) [ 2.902420] vda: detected capacity change from 0 to 73097216 [ 2.920764] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.924028] vdb: detected capacity change from 0 to 1073741824 [ 2.940837] libphy: Fixed MDIO Bus: probed [ 2.946800] usbcore: registered new interface driver usbserial_generic [ 2.949778] usbserial: USB Serial support registered for generic [ 2.952352] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.956962] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.958982] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.961598] mousedev: PS/2 mouse device common for all mice [ 2.964664] rtc_cmos 00:05: RTC can wake from S4 [ 2.968502] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.968828] rtc_cmos 00:05: registered as rtc0 [ 2.974413] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.976501] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.978284] intel_pstate: CPU model not supported [ 2.984316] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.989052] hid: raw HID events driver (C) Jiri Kosina [ 2.991194] usbcore: registered new interface driver usbhid [ 2.993294] usbhid: USB HID core driver [ 2.995040] drop_monitor: Initializing network drop monitor service [ 2.997573] Initializing XFRM netlink socket [ 2.999707] NET: Registered protocol family 10 [ 3.003278] Segment Routing with IPv6 [ 3.004712] NET: Registered protocol family 17 [ 3.006824] mpls_gso: MPLS GSO support [ 3.012145] RAS: Correctable Errors collector initialized. [ 3.015871] AVX version of gcm_enc/dec engaged. [ 3.018352] AES CTR mode by8 optimization enabled [ 3.106551] sched_clock: Marking stable (3106519800, 0)->(4013165674, -906645874) [ 3.111039] registered taskstats version 1 [ 3.114246] Loading compiled-in X.509 certificates [ 3.116869] zswap: loaded using pool lzo/zbud [ 3.139519] Key type big_key registered [ 3.152119] Key type encrypted registered [ 3.153781] ima: No TPM chip found, activating TPM-bypass! [ 3.155853] ima: Allocated hash algorithm: sha1 [ 3.157695] ima: No architecture policies found [ 3.159443] evm: Initialising EVM extended attributes: [ 3.161361] evm: security.selinux [ 3.162524] evm: security.ima [ 3.163541] evm: security.capability [ 3.164745] evm: HMAC attrs: 0x1 [ 3.166895] rtc_cmos 00:05: setting system clock to 2026-09-10 16:30:06 UTC (1789057806) [ 3.172995] debug: unmapping init [mem 0xffffffffad403000-0xffffffffad5fffff] [ 3.176225] debug: unmapping init [mem 0xffffffffac182000-0xffffffffac458fff] [ 3.185248] Write protecting the kernel read-only data: 28672k [ 3.188595] debug: unmapping init [mem 0xffffffffaa803000-0xffffffffaa9fffff] [ 3.191452] debug: unmapping init [mem 0xffffffffab114000-0xffffffffab1fffff] [ 3.225468] 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.234783] systemd[1]: Detected virtualization kvm. [ 3.236743] systemd[1]: Detected architecture x86-64. [ 3.238639] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.266134] systemd[1]: No hostname configured. [ 3.267895] systemd[1]: Set hostname to . [ 3.269553] random: systemd: uninitialized urandom read (16 bytes read) [ 3.271633] systemd[1]: Initializing machine ID from random generator. [ 3.320582] random: ln: uninitialized urandom read (6 bytes read) [ 3.391259] random: systemd: uninitialized urandom read (16 bytes read) [ 3.394212] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 3.399082] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 3.407944] systemd[1]: Starting Setup Virtual Console... Starting Setup Virtual Console... [ OK ] Reached target Timers. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Reached target Initrd Root Device. [ OK ] Listening on Journal Socket (/dev/log). Starting Apply Kernel Variables... [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Swap. Starting Journal Service... [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Slices. [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 3.972084] device-mapper: uevent: version 1.0.3 [ 3.973774] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] 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. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 4.562663] virtio_net virtio0 ens2: renamed from eth0 [ 4.602516] scsi host0: ata_piix [ 4.618304] scsi host1: ata_piix [ 4.619825] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.622196] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.884801] dracut-initqueue[583]: RTNETLINK answers: File exists [ 9.670154] random: crng init done [ 9.672311] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 9.969991] 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... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped Apply Kernel Variables. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Slices. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.142213] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.409309] SELinux: Disabled at runtime. [ 11.469036] 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.477889] systemd[1]: Detected virtualization kvm. [ 11.479846] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 11.996432] systemd[1]: initrd-switch-root.service: Succeeded. [ 11.999475] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.003902] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.007461] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.010583] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.018456] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.022657] systemd[1]: proc-sys-fs-binfmt_misc.automount: Refusing to start, unit to trigger not loaded. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Stopped target Switch Root. [ OK ] Reached target rpc_pipefs.target. [ OK ] Created slice system-getty.slice. [ OK ] Listening on Process Core Dump Socket. Starting Remount Root and Kernel File Systems... Mounting Kernel Debug File System... [ OK ] Created slice User and Session Slice. Starting Create list of required st…ce nodes for the current kernel... Mounting POSIX Message Queue File System... [ OK ] Listening on udev Control Socket. [ OK ] Stopped target Initrd Root File System. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Initrd File Systems. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Slices. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... Mounting Huge Pages File System... Starting Apply Kernel Variables... [ OK ] Reached target Paths. Activating swap /dev/disk/by-label/SWAP... [ OK ] Started Journal Service. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Kernel Debug File System. [ 12.184902] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Huge Pages File System. [ OK ] Started Apply Kernel Variables. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... 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 ] Mounted /mnt. [ 12.398948] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Coldplug all Devices. [ OK ] Started udev Kernel Device Manager. [ 12.737962] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 12.750989] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 12.903041] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 12.913459] EDAC sbridge: Ver: 1.1.2 [ 14.125275] Key type dns_resolver registered [ 14.440421] NFS: Registering the id_resolver key type [ 14.442328] Key type id_resolver registered [ 14.443881] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ 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 ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Basic System. Starting Login Service... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Started irqbalance daemon. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting Network Manager Wait Online... [ 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... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ 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 System Logging Service... Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... [ 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 oleg243-client login: [ 45.266466] libcfs: loading out-of-tree module taints kernel. [ 45.324224] Key type ._llcrypt registered [ 45.325986] Key type .llcrypt registered [ 45.563195] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 45.570259] alg: No test for adler32 (adler32-zlib) [ 47.336229] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 48.149148] LNet: Added LNI 192.168.202.43@tcp [8/256/0/180] [ 49.879177] Key type lgssc registered [ 51.739537] Lustre: Echo OBD driver; http://www.lustre.org/ [ 55.994682] hrtimer: interrupt took 2748918 ns [ 59.169780] alg: No test for zstdfast (zstdfast-lustre-generic) [ 59.192522] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 217.883718] Lustre: Mounted lustre-client [ 224.034263] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 237.806194] Lustre: DEBUG MARKER: oleg243-client.virtnet: executing check_logdir /tmp/testlogs/ [ 243.681460] Lustre: lustre-OST0000-osc-ffff982911000000: disconnect after 24s idle [ 245.488387] Lustre: DEBUG MARKER: oleg243-client.virtnet: executing yml_node [ 250.888181] Lustre: DEBUG MARKER: Client: 2.17.53.26 [ 253.984975] Lustre: DEBUG MARKER: MDS: 2.17.53.26 [ 257.452449] Lustre: DEBUG MARKER: OSS: 2.17.53.26 [ 259.768504] Lustre: DEBUG MARKER: -----============= acceptance-small: sanity ============----- Thu Sep 10 12:34:21 EDT 2026 [ 281.850177] Lustre: DEBUG MARKER: - need MDS1_VERSION <= 2.16.61-1-g89cf292a8c2 (34682138 <= 34618625) for LU-18938, skip 360 [ 284.301994] Lustre: DEBUG MARKER: - need MDS1_VERSION < v2_14_55-100-g8a84c7f9c7 (34682138 < 34486116) for LU-14927, skip 0f [ 286.436901] Lustre: DEBUG MARKER: excepting tests: 42a 42c 42b 118c 118d 407 119i 817 411a 130b 130c 130d 130e 130f 130g [ 288.134749] Lustre: DEBUG MARKER: skipping tests SLOW=no: 27m 60i 64b 68 71 135 136 230d 300o 842 51b 51c 51e 834 [ 290.327310] Lustre: DEBUG MARKER: === sanity: start setup 12:34:51 (1789058091) === [ 296.007161] Lustre: DEBUG MARKER: oleg243-client.virtnet: executing check_config_client /mnt/lustre [ 322.198161] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 337.445681] Lustre: DEBUG MARKER: === sanity: finish setup 12:35:38 (1789058138) === [ 346.016240] Lustre: DEBUG MARKER: == sanity test 84: lz4/lz4hc/lzstd compression/decompression kernel module ========================================================== 12:35:47 (1789058147) [ 346.486237] lustre_kcompr_21935: ***** [ 346.487805] lustre_kcompr_21935: (de)compression test on random binary data [ 346.491382] lustre_kcompr_21935: ***** [ 346.500702] lustre_kcompr_21935: compr lz4(1) in 64kB chunks took 2153 us (1857 MB/s), compress ratio: 96.25 [ 346.512263] lustre_kcompr_21935: compr lz4(1) in 128kB chunks took 6801 us (588 MB/s), compress ratio: 101.50 [ 346.523783] lustre_kcompr_21935: compr lz4(1) in 256kB chunks took 3094 us (1292 MB/s), compress ratio: 105.33 [ 346.535373] lustre_kcompr_21935: compr lz4(1) in 512kB chunks took 2661 us (1503 MB/s), compress ratio: 107.35 [ 346.549773] lustre_kcompr_21935: compr lz4(1) in 1024kB chunks took 2835 us (1410 MB/s), compress ratio: 108.34 [ 346.575139] lustre_kcompr_21935: compr lz4(1) in 2048kB chunks took 7997 us (500 MB/s), compress ratio: 108.58 [ 346.594773] lustre_kcompr_21935: compr lz4(1) in 4096kB chunks took 5894 us (678 MB/s), compress ratio: 108.57 [ 346.623563] lustre_kcompr_21935: decompr lz4(1) took 4446 us (8 MB/s) [ 346.633829] lustre_kcompr_21935: test_comp_compress_decompress(lz4,1) ret 0 [ 346.643303] lustre_kcompr_21935: acompr lz4(1) of 128kB chunks took 103 us (1213 MB/s), compress ratio: 109.13 [ 346.654537] lustre_kcompr_21935: adecompr lz4(1) took 222 us (5 MB/s) [ 346.679402] lustre_kcompr_21935: test_acomp_compress_decompress(lz4,1) ret 0 [ 346.712238] lustre_kcompr_21935: compr lz4(4) in 64kB chunks took 6097 us (656 MB/s), compress ratio: 83.25 [ 346.730639] lustre_kcompr_21935: compr lz4(4) in 128kB chunks took 1632 us (2450 MB/s), compress ratio: 93.11 [ 346.749176] lustre_kcompr_21935: compr lz4(4) in 256kB chunks took 4769 us (838 MB/s), compress ratio: 99.58 [ 346.761372] lustre_kcompr_21935: compr lz4(4) in 512kB chunks took 1586 us (2522 MB/s), compress ratio: 103.33 [ 346.780162] lustre_kcompr_21935: compr lz4(4) in 1024kB chunks took 6322 us (632 MB/s), compress ratio: 104.78 [ 346.789361] lustre_kcompr_21935: compr lz4(4) in 2048kB chunks took 3954 us (1011 MB/s), compress ratio: 105.59 [ 346.796889] lustre_kcompr_21935: compr lz4(4) in 4096kB chunks took 2171 us (1842 MB/s), compress ratio: 106.12 [ 346.808152] lustre_kcompr_21935: decompr lz4(4) took 1941 us (19 MB/s) [ 346.811960] lustre_kcompr_21935: test_comp_compress_decompress(lz4,4) ret 0 [ 346.816503] lustre_kcompr_21935: acompr lz4(4) of 128kB chunks took 102 us (1225 MB/s), compress ratio: 96.09 [ 346.821461] lustre_kcompr_21935: adecompr lz4(4) took 116 us (11 MB/s) [ 346.827069] lustre_kcompr_21935: test_acomp_compress_decompress(lz4,4) ret 0 [ 346.841347] lustre_kcompr_21935: compr lz4(9) in 64kB chunks took 2390 us (1673 MB/s), compress ratio: 62.01 [ 346.849177] lustre_kcompr_21935: compr lz4(9) in 128kB chunks took 2671 us (1497 MB/s), compress ratio: 75.76 [ 346.857113] lustre_kcompr_21935: compr lz4(9) in 256kB chunks took 2036 us (1964 MB/s), compress ratio: 87.26 [ 346.865947] lustre_kcompr_21935: compr lz4(9) in 512kB chunks took 2356 us (1697 MB/s), compress ratio: 94.44 [ 346.874495] lustre_kcompr_21935: compr lz4(9) in 1024kB chunks took 4329 us (924 MB/s), compress ratio: 97.65 [ 346.886528] lustre_kcompr_21935: compr lz4(9) in 2048kB chunks took 2936 us (1362 MB/s), compress ratio: 99.60 [ 346.896663] lustre_kcompr_21935: compr lz4(9) in 4096kB chunks took 1909 us (2095 MB/s), compress ratio: 100.90 [ 346.913979] lustre_kcompr_21935: decompr lz4(9) took 3350 us (11 MB/s) [ 346.918800] lustre_kcompr_21935: test_comp_compress_decompress(lz4,9) ret 0 [ 346.923171] lustre_kcompr_21935: acompr lz4(9) of 128kB chunks took 103 us (1213 MB/s), compress ratio: 79.92 [ 346.928677] lustre_kcompr_21935: adecompr lz4(9) took 129 us (12 MB/s) [ 346.936135] lustre_kcompr_21935: test_acomp_compress_decompress(lz4,9) ret 0 [ 346.981784] lustre_kcompr_21935: compr lz4(15) in 64kB chunks took 13133 us (304 MB/s), compress ratio: 29.46 [ 347.001167] lustre_kcompr_21935: compr lz4(15) in 128kB chunks took 4785 us (835 MB/s), compress ratio: 43.67 [ 347.019613] lustre_kcompr_21935: compr lz4(15) in 256kB chunks took 3351 us (1193 MB/s), compress ratio: 58.92 [ 347.052670] lustre_kcompr_21935: compr lz4(15) in 512kB chunks took 6906 us (579 MB/s), compress ratio: 72.60 [ 347.094185] lustre_kcompr_21935: compr lz4(15) in 1024kB chunks took 11367 us (351 MB/s), compress ratio: 80.19 [ 347.118704] lustre_kcompr_21935: compr lz4(15) in 2048kB chunks took 2448 us (1633 MB/s), compress ratio: 85.08 [ 347.143165] lustre_kcompr_21935: compr lz4(15) in 4096kB chunks took 5647 us (708 MB/s), compress ratio: 88.68 [ 347.172981] lustre_kcompr_21935: decompr lz4(15) took 2869 us (15 MB/s) [ 347.182583] lustre_kcompr_21935: test_comp_compress_decompress(lz4,15) ret 0 [ 347.207707] lustre_kcompr_21935: acompr lz4(15) of 128kB chunks took 113 us (1106 MB/s), compress ratio: 45.10 [ 347.219095] lustre_kcompr_21935: adecompr lz4(15) took 175 us (15 MB/s) [ 347.246132] lustre_kcompr_21935: test_acomp_compress_decompress(lz4,15) ret 0 [ 347.389451] lustre_kcompr_21935: compr lz4hc(1) in 64kB chunks took 83628 us (47 MB/s), compress ratio: 98.21 [ 347.513635] lustre_kcompr_21935: compr lz4hc(1) in 128kB chunks took 102750 us (38 MB/s), compress ratio: 104.25 [ 347.618253] lustre_kcompr_21935: compr lz4hc(1) in 256kB chunks took 78982 us (50 MB/s), compress ratio: 107.56 [ 347.718718] lustre_kcompr_21935: compr lz4hc(1) in 512kB chunks took 79645 us (50 MB/s), compress ratio: 109.31 [ 347.852418] lustre_kcompr_21935: compr lz4hc(1) in 1024kB chunks took 110439 us (36 MB/s), compress ratio: 110.20 [ 347.977543] lustre_kcompr_21935: compr lz4hc(1) in 2048kB chunks took 103558 us (38 MB/s), compress ratio: 110.64 [ 348.124690] lustre_kcompr_21935: compr lz4hc(1) in 4096kB chunks took 130070 us (30 MB/s), compress ratio: 110.86 [ 348.156862] lustre_kcompr_21935: decompr lz4hc(1) took 1614 us (22 MB/s) [ 348.159610] lustre_kcompr_21935: test_comp_compress_decompress(lz4hc,1) ret 0 [ 348.162670] lustre_kcompr_21935: acompr lz4hc(1) of 128kB chunks took 706 us (177 MB/s), compress ratio: 111.64 [ 348.167301] lustre_kcompr_21935: adecompr lz4hc(1) took 332 us (3 MB/s) [ 348.172333] lustre_kcompr_21935: test_acomp_compress_decompress(lz4hc,1) ret 0 [ 348.225316] lustre_kcompr_21935: compr lz4hc(3) in 64kB chunks took 41498 us (96 MB/s), compress ratio: 98.72 [ 348.284151] lustre_kcompr_21935: compr lz4hc(3) in 128kB chunks took 47167 us (84 MB/s), compress ratio: 104.80 [ 348.403801] lustre_kcompr_21935: compr lz4hc(3) in 256kB chunks took 108630 us (36 MB/s), compress ratio: 108.13 [ 348.475771] lustre_kcompr_21935: compr lz4hc(3) in 512kB chunks took 51729 us (77 MB/s), compress ratio: 109.88 [ 348.519509] lustre_kcompr_21935: compr lz4hc(3) in 1024kB chunks took 39678 us (100 MB/s), compress ratio: 110.78 [ 348.562984] lustre_kcompr_21935: compr lz4hc(3) in 2048kB chunks took 38645 us (103 MB/s), compress ratio: 111.23 [ 348.606630] lustre_kcompr_21935: compr lz4hc(3) in 4096kB chunks took 39090 us (102 MB/s), compress ratio: 111.45 [ 348.624264] lustre_kcompr_21935: decompr lz4hc(3) took 2762 us (12 MB/s) [ 348.628880] lustre_kcompr_21935: test_comp_compress_decompress(lz4hc,3) ret 0 [ 348.634878] lustre_kcompr_21935: acompr lz4hc(3) of 128kB chunks took 990 us (126 MB/s), compress ratio: 111.93 [ 348.645339] lustre_kcompr_21935: adecompr lz4hc(3) took 215 us (5 MB/s) [ 348.650952] lustre_kcompr_21935: test_acomp_compress_decompress(lz4hc,3) ret 0 [ 349.402805] lustre_kcompr_21935: compr lz4hc(8) in 64kB chunks took 741418 us (5 MB/s), compress ratio: 105.07 [ 349.955695] lustre_kcompr_21935: compr lz4hc(8) in 128kB chunks took 548898 us (7 MB/s), compress ratio: 112.36 [ 350.613553] lustre_kcompr_21935: compr lz4hc(8) in 256kB chunks took 653967 us (6 MB/s), compress ratio: 116.41 [ 351.474129] lustre_kcompr_21935: compr lz4hc(8) in 512kB chunks took 845528 us (4 MB/s), compress ratio: 118.52 [ 352.333129] lustre_kcompr_21935: compr lz4hc(8) in 1024kB chunks took 853981 us (4 MB/s), compress ratio: 119.60 [ 353.763550] lustre_kcompr_21935: compr lz4hc(8) in 2048kB chunks took 1421584 us (2 MB/s), compress ratio: 120.11 [ 355.475324] lustre_kcompr_21935: compr lz4hc(8) in 4096kB chunks took 1693360 us (2 MB/s), compress ratio: 120.38 [ 355.508357] lustre_kcompr_21935: decompr lz4hc(8) took 5186 us (6 MB/s) [ 355.514457] lustre_kcompr_21935: test_comp_compress_decompress(lz4hc,8) ret 0 [ 355.558491] lustre_kcompr_21935: acompr lz4hc(8) of 128kB chunks took 36755 us (3 MB/s), compress ratio: 119.59 [ 355.566309] lustre_kcompr_21935: adecompr lz4hc(8) took 248 us (4 MB/s) [ 355.574189] lustre_kcompr_21935: test_acomp_compress_decompress(lz4hc,8) ret 0 [ 357.435256] lustre_kcompr_21935: compr lz4hc(15) in 64kB chunks took 1842946 us (2 MB/s), compress ratio: 105.62 [ 359.930341] lustre_kcompr_21935: compr lz4hc(15) in 128kB chunks took 2474710 us (1 MB/s), compress ratio: 113.29 [ 361.307866] lustre_kcompr_21935: compr lz4hc(15) in 256kB chunks took 1357910 us (2 MB/s), compress ratio: 117.72 [ 361.443423] Lustre: lustre-OST0000-osc-ffff982911000000: disconnect after 23s idle [ 361.451055] Lustre: Skipped 1 previous similar message [ 362.999207] lustre_kcompr_21935: compr lz4hc(15) in 512kB chunks took 1688203 us (2 MB/s), compress ratio: 120.07 [ 363.898088] lustre_kcompr_21935: compr lz4hc(15) in 1024kB chunks took 888972 us (4 MB/s), compress ratio: 121.26 [ 364.934569] lustre_kcompr_21935: compr lz4hc(15) in 2048kB chunks took 1028679 us (3 MB/s), compress ratio: 121.83 [ 366.101836] lustre_kcompr_21935: compr lz4hc(15) in 4096kB chunks took 1164032 us (3 MB/s), compress ratio: 122.10 [ 366.132580] lustre_kcompr_21935: decompr lz4hc(15) took 1772 us (18 MB/s) [ 366.139715] lustre_kcompr_21935: test_comp_compress_decompress(lz4hc,15) ret 0 [ 366.176118] lustre_kcompr_21935: acompr lz4hc(15) of 128kB chunks took 24040 us (5 MB/s), compress ratio: 121.25 [ 366.194167] lustre_kcompr_21935: adecompr lz4hc(15) took 136 us (7 MB/s) [ 366.217857] lustre_kcompr_21935: test_acomp_compress_decompress(lz4hc,15) ret 0 [ 366.237647] lustre_kcompr_21935: compr lzo(-1) in 64kB chunks took 6970 us (573 MB/s), compress ratio: 81.44 [ 366.258119] lustre_kcompr_21935: compr lzo(-1) in 128kB chunks took 6548 us (610 MB/s), compress ratio: 86.71 [ 366.271423] lustre_kcompr_21935: compr lzo(-1) in 256kB chunks took 5497 us (727 MB/s), compress ratio: 87.23 [ 366.295646] lustre_kcompr_21935: compr lzo(-1) in 512kB chunks took 12560 us (318 MB/s), compress ratio: 88.27 [ 366.308622] lustre_kcompr_21935: compr lzo(-1) in 1024kB chunks took 4919 us (813 MB/s), compress ratio: 88.74 [ 366.324811] lustre_kcompr_21935: compr lzo(-1) in 2048kB chunks took 7729 us (517 MB/s), compress ratio: 88.70 [ 366.338886] lustre_kcompr_21935: compr lzo(-1) in 4096kB chunks took 3781 us (1057 MB/s), compress ratio: 89.05 [ 366.357222] lustre_kcompr_21935: decompr lzo(-1) took 3599 us (12 MB/s) [ 366.361198] lustre_kcompr_21935: test_comp_compress_decompress(lzo,-1) ret 0 [ 366.366941] lustre_kcompr_21935: acompr lzo(-1) of 128kB chunks took 118 us (1059 MB/s), compress ratio: 96.80 [ 366.375184] lustre_kcompr_21935: adecompr lzo(-1) took 125 us (10 MB/s) [ 366.395869] lustre_kcompr_21935: test_acomp_compress_decompress(lzo,-1) ret 0 [ 367.012327] lustre_kcompr_21935: compr deflate(1) in 64kB chunks took 594762 us (6 MB/s), compress ratio: 98.66 [ 367.764855] lustre_kcompr_21935: compr deflate(1) in 128kB chunks took 746123 us (5 MB/s), compress ratio: 108.97 [ 368.524521] lustre_kcompr_21935: compr deflate(1) in 256kB chunks took 736288 us (5 MB/s), compress ratio: 115.57 [ 369.386582] lustre_kcompr_21935: compr deflate(1) in 512kB chunks took 846979 us (4 MB/s), compress ratio: 119.53 [ 370.134905] lustre_kcompr_21935: compr deflate(1) in 1024kB chunks took 725405 us (5 MB/s), compress ratio: 121.46 [ 370.997787] lustre_kcompr_21935: compr deflate(1) in 2048kB chunks took 840818 us (4 MB/s), compress ratio: 122.43 [ 371.834466] lustre_kcompr_21935: compr deflate(1) in 4096kB chunks took 822948 us (4 MB/s), compress ratio: 122.70 [ 371.872283] lustre_kcompr_21935: decompr deflate(1) took 10970 us (2 MB/s) [ 371.883987] lustre_kcompr_21935: test_comp_compress_decompress(deflate,1) ret 0 [ 371.917190] lustre_kcompr_21935: acompr deflate(1) of 128kB chunks took 22287 us (5 MB/s), compress ratio: 116.40 [ 371.935472] lustre_kcompr_21935: adecompr deflate(1) took 10148 us (0 MB/s) [ 371.970942] lustre_kcompr_21935: test_acomp_compress_decompress(deflate,1) ret 0 [ 372.623125] lustre_kcompr_21935: compr deflate(6) in 64kB chunks took 584103 us (6 MB/s), compress ratio: 98.66 [ 373.385485] lustre_kcompr_21935: compr deflate(6) in 128kB chunks took 735125 us (5 MB/s), compress ratio: 108.97 [ 374.002775] lustre_kcompr_21935: compr deflate(6) in 256kB chunks took 596877 us (6 MB/s), compress ratio: 115.57 [ 374.473547] lustre_kcompr_21935: compr deflate(6) in 512kB chunks took 464670 us (8 MB/s), compress ratio: 119.53 [ 374.837572] lustre_kcompr_21935: compr deflate(6) in 1024kB chunks took 356034 us (11 MB/s), compress ratio: 121.46 [ 375.414951] lustre_kcompr_21935: compr deflate(6) in 2048kB chunks took 566338 us (7 MB/s), compress ratio: 122.43 [ 375.827358] lustre_kcompr_21935: compr deflate(6) in 4096kB chunks took 400654 us (9 MB/s), compress ratio: 122.70 [ 375.844174] lustre_kcompr_21935: decompr deflate(6) took 4011 us (8 MB/s) [ 375.849212] lustre_kcompr_21935: test_comp_compress_decompress(deflate,6) ret 0 [ 375.865185] lustre_kcompr_21935: acompr deflate(6) of 128kB chunks took 12030 us (10 MB/s), compress ratio: 116.40 [ 375.868947] lustre_kcompr_21935: adecompr deflate(6) took 335 us (3 MB/s) [ 375.878948] lustre_kcompr_21935: test_acomp_compress_decompress(deflate,6) ret 0 [ 376.219519] lustre_kcompr_21935: compr deflate(9) in 64kB chunks took 325184 us (12 MB/s), compress ratio: 98.66 [ 376.512638] lustre_kcompr_21935: compr deflate(9) in 128kB chunks took 288360 us (13 MB/s), compress ratio: 108.97 [ 376.766588] lustre_kcompr_21935: compr deflate(9) in 256kB chunks took 249668 us (16 MB/s), compress ratio: 115.57 [ 377.011556] lustre_kcompr_21935: compr deflate(9) in 512kB chunks took 239761 us (16 MB/s), compress ratio: 119.53 [ 377.274206] lustre_kcompr_21935: compr deflate(9) in 1024kB chunks took 258497 us (15 MB/s), compress ratio: 121.46 [ 377.595636] lustre_kcompr_21935: compr deflate(9) in 2048kB chunks took 317793 us (12 MB/s), compress ratio: 122.43 [ 378.158744] lustre_kcompr_21935: compr deflate(9) in 4096kB chunks took 553406 us (7 MB/s), compress ratio: 122.70 [ 378.214918] lustre_kcompr_21935: decompr deflate(9) took 20396 us (1 MB/s) [ 378.237796] lustre_kcompr_21935: test_comp_compress_decompress(deflate,9) ret 0 [ 378.261391] lustre_kcompr_21935: acompr deflate(9) of 128kB chunks took 13055 us (9 MB/s), compress ratio: 116.40 [ 378.276080] lustre_kcompr_21935: adecompr deflate(9) took 187 us (5 MB/s) [ 378.285364] lustre_kcompr_21935: test_acomp_compress_decompress(deflate,9) ret 0 [ 378.330757] lustre_kcompr_21935: compr zstd(0) in 64kB chunks took 16392 us (244 MB/s), compress ratio: 160.20 [ 378.359995] lustre_kcompr_21935: compr zstd(0) in 128kB chunks took 23725 us (168 MB/s), compress ratio: 194.79 [ 378.402668] lustre_kcompr_21935: compr zstd(0) in 256kB chunks took 37179 us (107 MB/s), compress ratio: 207.78 [ 378.459992] lustre_kcompr_21935: compr zstd(0) in 512kB chunks took 42008 us (95 MB/s), compress ratio: 214.55 [ 378.484365] lustre_kcompr_21935: compr zstd(0) in 1024kB chunks took 7072 us (565 MB/s), compress ratio: 215.83 [ 378.525150] lustre_kcompr_21935: compr zstd(0) in 2048kB chunks took 23876 us (167 MB/s), compress ratio: 215.61 [ 378.549694] lustre_kcompr_21935: compr zstd(0) in 4096kB chunks took 7403 us (540 MB/s), compress ratio: 216.61 [ 378.578747] lustre_kcompr_21935: decompr zstd(0) took 8671 us (2 MB/s) [ 378.591380] lustre_kcompr_21935: test_comp_compress_decompress(zstd,0) ret 0 [ 378.599327] lustre_kcompr_21935: acompr zstd(0) of 128kB chunks took 851 us (146 MB/s), compress ratio: 217.36 [ 378.622080] lustre_kcompr_21935: adecompr zstd(0) took 1753 us (0 MB/s) [ 378.649719] lustre_kcompr_21935: test_acomp_compress_decompress(zstd,0) ret 0 [ 378.675786] lustre_kcompr_21935: compr zstd(1) in 64kB chunks took 5070 us (788 MB/s), compress ratio: 159.27 [ 378.694782] lustre_kcompr_21935: compr zstd(1) in 128kB chunks took 10767 us (371 MB/s), compress ratio: 192.46 [ 378.723689] lustre_kcompr_21935: compr zstd(1) in 256kB chunks took 11231 us (356 MB/s), compress ratio: 205.69 [ 378.749261] lustre_kcompr_21935: compr zstd(1) in 512kB chunks took 14178 us (282 MB/s), compress ratio: 211.86 [ 378.777221] lustre_kcompr_21935: compr zstd(1) in 1024kB chunks took 18106 us (220 MB/s), compress ratio: 214.79 [ 378.801718] lustre_kcompr_21935: compr zstd(1) in 2048kB chunks took 12588 us (317 MB/s), compress ratio: 214.47 [ 378.819247] lustre_kcompr_21935: compr zstd(1) in 4096kB chunks took 3181 us (1257 MB/s), compress ratio: 216.14 [ 378.846981] lustre_kcompr_21935: decompr zstd(1) took 3128 us (5 MB/s) [ 378.861499] lustre_kcompr_21935: test_comp_compress_decompress(zstd,1) ret 0 [ 378.869302] lustre_kcompr_21935: acompr zstd(1) of 128kB chunks took 159 us (786 MB/s), compress ratio: 202.27 [ 378.878799] lustre_kcompr_21935: adecompr zstd(1) took 138 us (4 MB/s) [ 378.933859] lustre_kcompr_21935: test_acomp_compress_decompress(zstd,1) ret 0 [ 379.349194] lustre_kcompr_21935: compr zstd(10) in 64kB chunks took 388902 us (10 MB/s), compress ratio: 162.62 [ 379.807653] lustre_kcompr_21935: compr zstd(10) in 128kB chunks took 436315 us (9 MB/s), compress ratio: 186.13 [ 380.240734] lustre_kcompr_21935: compr zstd(10) in 256kB chunks took 422768 us (9 MB/s), compress ratio: 198.02 [ 380.497880] lustre_kcompr_21935: compr zstd(10) in 512kB chunks took 236759 us (16 MB/s), compress ratio: 219.57 [ 380.799046] lustre_kcompr_21935: compr zstd(10) in 1024kB chunks took 297162 us (13 MB/s), compress ratio: 224.19 [ 381.559982] lustre_kcompr_21935: compr zstd(10) in 2048kB chunks took 738585 us (5 MB/s), compress ratio: 226.59 [ 381.670966] lustre_kcompr_21935: compr zstd(10) in 4096kB chunks took 95731 us (41 MB/s), compress ratio: 227.95 [ 381.701909] lustre_kcompr_21935: decompr zstd(10) took 4341 us (4 MB/s) [ 381.723757] lustre_kcompr_21935: test_comp_compress_decompress(zstd,10) ret 0 [ 381.761809] lustre_kcompr_21935: acompr zstd(10) of 128kB chunks took 19426 us (6 MB/s), compress ratio: 205.44 [ 381.777365] lustre_kcompr_21935: adecompr zstd(10) took 106 us (5 MB/s) [ 381.833126] lustre_kcompr_21935: test_acomp_compress_decompress(zstd,10) ret 0 [ 382.938952] lustre_kcompr_21935: compr zstd(15) in 64kB chunks took 1059196 us (3 MB/s), compress ratio: 171.44 [ 384.172408] lustre_kcompr_21935: compr zstd(15) in 128kB chunks took 1226578 us (3 MB/s), compress ratio: 196.95 [ 384.778825] lustre_kcompr_21935: compr zstd(15) in 256kB chunks took 602273 us (6 MB/s), compress ratio: 211.81 [ 385.144283] lustre_kcompr_21935: compr zstd(15) in 512kB chunks took 362425 us (11 MB/s), compress ratio: 219.21 [ 385.572121] lustre_kcompr_21935: compr zstd(15) in 1024kB chunks took 414043 us (9 MB/s), compress ratio: 221.11 [ 386.220711] lustre_kcompr_21935: compr zstd(15) in 2048kB chunks took 637183 us (6 MB/s), compress ratio: 223.68 [ 387.185220] lustre_kcompr_21935: compr zstd(15) in 4096kB chunks took 944112 us (4 MB/s), compress ratio: 222.79 [ 387.196702] lustre_kcompr_21935: decompr zstd(15) took 1954 us (9 MB/s) [ 387.201326] lustre_kcompr_21935: test_comp_compress_decompress(zstd,15) ret 0 [ 387.212610] lustre_kcompr_21935: acompr zstd(15) of 128kB chunks took 7819 us (15 MB/s), compress ratio: 221.40 [ 387.217186] lustre_kcompr_21935: adecompr zstd(15) took 136 us (4 MB/s) [ 387.224772] lustre_kcompr_21935: test_acomp_compress_decompress(zstd,15) ret 0 [ 387.241116] lustre_kcompr_21935: compr zstdfast(1) in 64kB chunks took 2751 us (1454 MB/s), compress ratio: 159.27 [ 387.256430] lustre_kcompr_21935: compr zstdfast(1) in 128kB chunks took 4259 us (939 MB/s), compress ratio: 192.46 [ 387.266152] lustre_kcompr_21935: compr zstdfast(1) in 256kB chunks took 4040 us (990 MB/s), compress ratio: 205.69 [ 387.278953] lustre_kcompr_21935: compr zstdfast(1) in 512kB chunks took 3994 us (1001 MB/s), compress ratio: 211.86 [ 387.295498] lustre_kcompr_21935: compr zstdfast(1) in 1024kB chunks took 4387 us (911 MB/s), compress ratio: 214.79 [ 387.304408] lustre_kcompr_21935: compr zstdfast(1) in 2048kB chunks took 3201 us (1249 MB/s), compress ratio: 214.47 [ 387.313543] lustre_kcompr_21935: compr zstdfast(1) in 4096kB chunks took 2036 us (1964 MB/s), compress ratio: 216.14 [ 387.339646] lustre_kcompr_21935: decompr zstdfast(1) took 5060 us (3 MB/s) [ 387.347719] lustre_kcompr_21935: test_comp_compress_decompress(zstdfast,1) ret 0 [ 387.357976] lustre_kcompr_21935: acompr zstdfast(1) of 128kB chunks took 2606 us (47 MB/s), compress ratio: 202.27 [ 387.364189] lustre_kcompr_21935: adecompr zstdfast(1) took 123 us (5 MB/s) [ 387.371267] lustre_kcompr_21935: test_acomp_compress_decompress(zstdfast,1) ret 0 [ 387.384234] lustre_kcompr_21935: compr zstdfast(5) in 64kB chunks took 4744 us (843 MB/s), compress ratio: 141.01 [ 387.389738] lustre_kcompr_21935: compr zstdfast(5) in 128kB chunks took 1933 us (2069 MB/s), compress ratio: 166.76 [ 387.396650] lustre_kcompr_21935: compr zstdfast(5) in 256kB chunks took 3289 us (1216 MB/s), compress ratio: 176.49 [ 387.402200] lustre_kcompr_21935: compr zstdfast(5) in 512kB chunks took 2761 us (1448 MB/s), compress ratio: 179.85 [ 387.408741] lustre_kcompr_21935: compr zstdfast(5) in 1024kB chunks took 3694 us (1082 MB/s), compress ratio: 181.35 [ 387.415162] lustre_kcompr_21935: compr zstdfast(5) in 2048kB chunks took 3054 us (1309 MB/s), compress ratio: 181.77 [ 387.421772] lustre_kcompr_21935: compr zstdfast(5) in 4096kB chunks took 2791 us (1433 MB/s), compress ratio: 183.40 [ 387.449925] lustre_kcompr_21935: decompr zstdfast(5) took 4005 us (5 MB/s) [ 387.454428] lustre_kcompr_21935: test_comp_compress_decompress(zstdfast,5) ret 0 [ 387.466508] lustre_kcompr_21935: acompr zstdfast(5) of 128kB chunks took 184 us (679 MB/s), compress ratio: 175.46 [ 387.471942] lustre_kcompr_21935: adecompr zstdfast(5) took 366 us (1 MB/s) [ 387.481324] lustre_kcompr_21935: test_acomp_compress_decompress(zstdfast,5) ret 0 [ 387.492450] lustre_kcompr_21935: compr zstdfast(12) in 64kB chunks took 2314 us (1728 MB/s), compress ratio: 38.50 [ 387.500096] lustre_kcompr_21935: compr zstdfast(12) in 128kB chunks took 3775 us (1059 MB/s), compress ratio: 43.35 [ 387.505963] lustre_kcompr_21935: compr zstdfast(12) in 256kB chunks took 2181 us (1834 MB/s), compress ratio: 47.00 [ 387.521704] lustre_kcompr_21935: compr zstdfast(12) in 512kB chunks took 8732 us (458 MB/s), compress ratio: 49.84 [ 387.535071] lustre_kcompr_21935: compr zstdfast(12) in 1024kB chunks took 1632 us (2450 MB/s), compress ratio: 54.08 [ 387.542443] lustre_kcompr_21935: compr zstdfast(12) in 2048kB chunks took 3129 us (1278 MB/s), compress ratio: 59.64 [ 387.556680] lustre_kcompr_21935: compr zstdfast(12) in 4096kB chunks took 3929 us (1018 MB/s), compress ratio: 82.79 [ 387.572312] lustre_kcompr_21935: decompr zstdfast(12) took 2594 us (18 MB/s) [ 387.588495] lustre_kcompr_21935: test_comp_compress_decompress(zstdfast,12) ret 0 [ 387.600329] lustre_kcompr_21935: acompr zstdfast(12) of 128kB chunks took 454 us (275 MB/s), compress ratio: 48.63 [ 387.605602] lustre_kcompr_21935: adecompr zstdfast(12) took 146 us (17 MB/s) [ 387.620363] lustre_kcompr_21935: test_acomp_compress_decompress(zstdfast,12) ret 0 [ 387.636375] lustre_kcompr_21935: compr zstdfast(15) in 64kB chunks took 4382 us (912 MB/s), compress ratio: 19.18 [ 387.644871] lustre_kcompr_21935: compr zstdfast(15) in 128kB chunks took 2021 us (1979 MB/s), compress ratio: 20.01 [ 387.650743] lustre_kcompr_21935: compr zstdfast(15) in 256kB chunks took 2054 us (1947 MB/s), compress ratio: 20.79 [ 387.658728] lustre_kcompr_21935: compr zstdfast(15) in 512kB chunks took 3812 us (1049 MB/s), compress ratio: 22.09 [ 387.666894] lustre_kcompr_21935: compr zstdfast(15) in 1024kB chunks took 2975 us (1344 MB/s), compress ratio: 24.58 [ 387.672642] lustre_kcompr_21935: compr zstdfast(15) in 2048kB chunks took 1952 us (2049 MB/s), compress ratio: 29.95 [ 387.677515] lustre_kcompr_21935: compr zstdfast(15) in 4096kB chunks took 2040 us (1960 MB/s), compress ratio: 31.64 [ 387.695951] lustre_kcompr_21935: decompr zstdfast(15) took 3593 us (35 MB/s) [ 387.699629] lustre_kcompr_21935: test_comp_compress_decompress(zstdfast,15) ret 0 [ 387.706080] lustre_kcompr_21935: acompr zstdfast(15) of 128kB chunks took 218 us (573 MB/s), compress ratio: 20.62 [ 387.710991] lustre_kcompr_21935: adecompr zstdfast(15) took 149 us (40 MB/s) [ 387.718621] lustre_kcompr_21935: test_acomp_compress_decompress(zstdfast,15) ret 0 [ 387.721957] lustre_kcompr_21935: SUCCESS [ 395.547578] lustre_kcompr_1806: ***** [ 395.550508] lustre_kcompr_1806: (de)compression test on provided file /tmp/f84.sanity [ 395.554573] lustre_kcompr_1806: ***** [ 395.596019] lustre_kcompr_1806: compr lz4(1) in 64kB chunks took 29293 us (136 MB/s), compress ratio: 2.93 [ 395.681755] lustre_kcompr_1806: compr lz4(1) in 128kB chunks took 76968 us (51 MB/s), compress ratio: 3.04 [ 395.741786] lustre_kcompr_1806: compr lz4(1) in 256kB chunks took 43165 us (92 MB/s), compress ratio: 3.08 [ 395.798547] lustre_kcompr_1806: compr lz4(1) in 512kB chunks took 52648 us (75 MB/s), compress ratio: 3.10 [ 395.867360] lustre_kcompr_1806: compr lz4(1) in 1024kB chunks took 62361 us (64 MB/s), compress ratio: 3.11 [ 395.940509] lustre_kcompr_1806: compr lz4(1) in 2048kB chunks took 58512 us (68 MB/s), compress ratio: 3.11 [ 396.031513] lustre_kcompr_1806: compr lz4(1) in 4096kB chunks took 74449 us (53 MB/s), compress ratio: 3.12 [ 396.075041] lustre_kcompr_1806: decompr lz4(1) took 17005 us (75 MB/s) [ 396.090576] lustre_kcompr_1806: test_comp_compress_decompress(lz4,1) ret 0 [ 396.097064] lustre_kcompr_1806: acompr lz4(1) of 128kB chunks took 657 us (190 MB/s), compress ratio: 2.68 [ 396.109278] lustre_kcompr_1806: adecompr lz4(1) took 587 us (79 MB/s) [ 396.129492] lustre_kcompr_1806: test_acomp_compress_decompress(lz4,1) ret 0 [ 396.205826] lustre_kcompr_1806: compr lz4(4) in 64kB chunks took 52809 us (75 MB/s), compress ratio: 2.67 [ 396.264149] lustre_kcompr_1806: compr lz4(4) in 128kB chunks took 47391 us (84 MB/s), compress ratio: 2.63 [ 396.288304] lustre_kcompr_1806: compr lz4(4) in 256kB chunks took 19535 us (204 MB/s), compress ratio: 2.68 [ 396.311519] lustre_kcompr_1806: compr lz4(4) in 512kB chunks took 19066 us (209 MB/s), compress ratio: 2.71 [ 396.338516] lustre_kcompr_1806: compr lz4(4) in 1024kB chunks took 22558 us (177 MB/s), compress ratio: 2.72 [ 396.355567] lustre_kcompr_1806: compr lz4(4) in 2048kB chunks took 14174 us (282 MB/s), compress ratio: 2.72 [ 396.371936] lustre_kcompr_1806: compr lz4(4) in 4096kB chunks took 13290 us (300 MB/s), compress ratio: 2.73 [ 396.389434] lustre_kcompr_1806: decompr lz4(4) took 5005 us (292 MB/s) [ 396.403119] lustre_kcompr_1806: test_comp_compress_decompress(lz4,4) ret 0 [ 396.410788] lustre_kcompr_1806: acompr lz4(4) of 128kB chunks took 626 us (199 MB/s), compress ratio: 2.30 [ 396.422969] lustre_kcompr_1806: adecompr lz4(4) took 184 us (294 MB/s) [ 396.430077] lustre_kcompr_1806: test_acomp_compress_decompress(lz4,4) ret 0 [ 396.513877] lustre_kcompr_1806: compr lz4(9) in 64kB chunks took 45090 us (88 MB/s), compress ratio: 2.30 [ 396.573806] lustre_kcompr_1806: compr lz4(9) in 128kB chunks took 50808 us (78 MB/s), compress ratio: 2.15 [ 396.644460] lustre_kcompr_1806: compr lz4(9) in 256kB chunks took 42758 us (93 MB/s), compress ratio: 2.20 [ 396.692337] lustre_kcompr_1806: compr lz4(9) in 512kB chunks took 33770 us (118 MB/s), compress ratio: 2.23 [ 396.733658] lustre_kcompr_1806: compr lz4(9) in 1024kB chunks took 28655 us (139 MB/s), compress ratio: 2.25 [ 396.778842] lustre_kcompr_1806: compr lz4(9) in 2048kB chunks took 33802 us (118 MB/s), compress ratio: 2.25 [ 396.835253] lustre_kcompr_1806: compr lz4(9) in 4096kB chunks took 33083 us (120 MB/s), compress ratio: 2.26 [ 396.867378] lustre_kcompr_1806: decompr lz4(9) took 11966 us (147 MB/s) [ 396.881260] lustre_kcompr_1806: test_comp_compress_decompress(lz4,9) ret 0 [ 396.899163] lustre_kcompr_1806: acompr lz4(9) of 128kB chunks took 1175 us (106 MB/s), compress ratio: 1.87 [ 396.906779] lustre_kcompr_1806: adecompr lz4(9) took 280 us (238 MB/s) [ 396.928702] lustre_kcompr_1806: test_acomp_compress_decompress(lz4,9) ret 0 [ 397.000244] lustre_kcompr_1806: compr lz4(15) in 64kB chunks took 26867 us (148 MB/s), compress ratio: 1.63 [ 397.048058] lustre_kcompr_1806: compr lz4(15) in 128kB chunks took 35708 us (112 MB/s), compress ratio: 1.52 [ 397.090056] lustre_kcompr_1806: compr lz4(15) in 256kB chunks took 31055 us (128 MB/s), compress ratio: 1.56 [ 397.118830] lustre_kcompr_1806: compr lz4(15) in 512kB chunks took 24644 us (162 MB/s), compress ratio: 1.58 [ 397.159332] lustre_kcompr_1806: compr lz4(15) in 1024kB chunks took 26283 us (152 MB/s), compress ratio: 1.60 [ 397.197955] lustre_kcompr_1806: compr lz4(15) in 2048kB chunks took 32055 us (124 MB/s), compress ratio: 1.60 [ 397.229185] lustre_kcompr_1806: compr lz4(15) in 4096kB chunks took 23158 us (172 MB/s), compress ratio: 1.60 [ 397.269443] lustre_kcompr_1806: decompr lz4(15) took 6103 us (407 MB/s) [ 397.275268] lustre_kcompr_1806: test_comp_compress_decompress(lz4,15) ret 0 [ 397.290552] lustre_kcompr_1806: acompr lz4(15) of 128kB chunks took 493 us (253 MB/s), compress ratio: 1.33 [ 397.300920] lustre_kcompr_1806: adecompr lz4(15) took 193 us (486 MB/s) [ 397.316888] lustre_kcompr_1806: test_acomp_compress_decompress(lz4,15) ret 0 [ 397.532650] lustre_kcompr_1806: compr lz4hc(1) in 64kB chunks took 194926 us (20 MB/s), compress ratio: 3.58 [ 397.923410] lustre_kcompr_1806: compr lz4hc(1) in 128kB chunks took 372384 us (10 MB/s), compress ratio: 3.77 [ 398.167765] lustre_kcompr_1806: compr lz4hc(1) in 256kB chunks took 231562 us (17 MB/s), compress ratio: 3.88 [ 398.528745] lustre_kcompr_1806: compr lz4hc(1) in 512kB chunks took 349034 us (11 MB/s), compress ratio: 3.93 [ 398.787419] lustre_kcompr_1806: compr lz4hc(1) in 1024kB chunks took 251590 us (15 MB/s), compress ratio: 3.96 [ 399.156804] lustre_kcompr_1806: compr lz4hc(1) in 2048kB chunks took 358691 us (11 MB/s), compress ratio: 3.97 [ 399.699312] lustre_kcompr_1806: compr lz4hc(1) in 4096kB chunks took 522802 us (7 MB/s), compress ratio: 3.98 [ 399.732842] lustre_kcompr_1806: decompr lz4hc(1) took 12939 us (77 MB/s) [ 399.746866] lustre_kcompr_1806: test_comp_compress_decompress(lz4hc,1) ret 0 [ 399.764722] lustre_kcompr_1806: acompr lz4hc(1) of 128kB chunks took 8346 us (14 MB/s), compress ratio: 3.27 [ 399.777475] lustre_kcompr_1806: adecompr lz4hc(1) took 475 us (80 MB/s) [ 399.797295] lustre_kcompr_1806: test_acomp_compress_decompress(lz4hc,1) ret 0 [ 400.028745] lustre_kcompr_1806: compr lz4hc(3) in 64kB chunks took 196146 us (20 MB/s), compress ratio: 3.68 [ 400.235808] lustre_kcompr_1806: compr lz4hc(3) in 128kB chunks took 202496 us (19 MB/s), compress ratio: 3.91 [ 400.430511] lustre_kcompr_1806: compr lz4hc(3) in 256kB chunks took 189663 us (21 MB/s), compress ratio: 4.04 [ 400.788622] lustre_kcompr_1806: compr lz4hc(3) in 512kB chunks took 352066 us (11 MB/s), compress ratio: 4.10 [ 401.148786] lustre_kcompr_1806: compr lz4hc(3) in 1024kB chunks took 353980 us (11 MB/s), compress ratio: 4.13 [ 401.561597] lustre_kcompr_1806: compr lz4hc(3) in 2048kB chunks took 397931 us (10 MB/s), compress ratio: 4.15 [ 401.948933] lustre_kcompr_1806: compr lz4hc(3) in 4096kB chunks took 373982 us (10 MB/s), compress ratio: 4.16 [ 401.983335] lustre_kcompr_1806: decompr lz4hc(3) took 14723 us (65 MB/s) [ 401.992583] lustre_kcompr_1806: test_comp_compress_decompress(lz4hc,3) ret 0 [ 402.009798] lustre_kcompr_1806: acompr lz4hc(3) of 128kB chunks took 11052 us (11 MB/s), compress ratio: 3.38 [ 402.020765] lustre_kcompr_1806: adecompr lz4hc(3) took 233 us (158 MB/s) [ 402.050637] lustre_kcompr_1806: test_acomp_compress_decompress(lz4hc,3) ret 0 [ 402.494798] lustre_kcompr_1806: compr lz4hc(8) in 64kB chunks took 420549 us (9 MB/s), compress ratio: 3.72 [ 403.078473] lustre_kcompr_1806: compr lz4hc(8) in 128kB chunks took 577462 us (6 MB/s), compress ratio: 3.96 [ 403.843172] lustre_kcompr_1806: compr lz4hc(8) in 256kB chunks took 754278 us (5 MB/s), compress ratio: 4.10 [ 404.601909] lustre_kcompr_1806: compr lz4hc(8) in 512kB chunks took 747404 us (5 MB/s), compress ratio: 4.17 [ 405.427519] lustre_kcompr_1806: compr lz4hc(8) in 1024kB chunks took 815321 us (4 MB/s), compress ratio: 4.21 [ 406.336902] lustre_kcompr_1806: compr lz4hc(8) in 2048kB chunks took 896619 us (4 MB/s), compress ratio: 4.22 [ 407.262969] lustre_kcompr_1806: compr lz4hc(8) in 4096kB chunks took 911552 us (4 MB/s), compress ratio: 4.23 [ 407.289489] lustre_kcompr_1806: decompr lz4hc(8) took 10458 us (90 MB/s) [ 407.295713] lustre_kcompr_1806: test_comp_compress_decompress(lz4hc,8) ret 0 [ 407.337207] lustre_kcompr_1806: acompr lz4hc(8) of 128kB chunks took 34704 us (3 MB/s), compress ratio: 3.42 [ 407.348967] lustre_kcompr_1806: adecompr lz4hc(8) took 190 us (191 MB/s) [ 407.368604] lustre_kcompr_1806: test_acomp_compress_decompress(lz4hc,8) ret 0 [ 407.844840] lustre_kcompr_1806: compr lz4hc(15) in 64kB chunks took 461560 us (8 MB/s), compress ratio: 3.72 [ 408.707615] lustre_kcompr_1806: compr lz4hc(15) in 128kB chunks took 854970 us (4 MB/s), compress ratio: 3.96 [ 409.294478] lustre_kcompr_1806: compr lz4hc(15) in 256kB chunks took 564366 us (7 MB/s), compress ratio: 4.10 [ 409.819877] lustre_kcompr_1806: compr lz4hc(15) in 512kB chunks took 520297 us (7 MB/s), compress ratio: 4.17 [ 410.280676] lustre_kcompr_1806: compr lz4hc(15) in 1024kB chunks took 455865 us (8 MB/s), compress ratio: 4.21 [ 410.913337] lustre_kcompr_1806: compr lz4hc(15) in 2048kB chunks took 622548 us (6 MB/s), compress ratio: 4.22 [ 411.641786] lustre_kcompr_1806: compr lz4hc(15) in 4096kB chunks took 720652 us (5 MB/s), compress ratio: 4.23 [ 411.659577] lustre_kcompr_1806: decompr lz4hc(15) took 4842 us (195 MB/s) [ 411.665551] lustre_kcompr_1806: test_comp_compress_decompress(lz4hc,15) ret 0 [ 411.690559] lustre_kcompr_1806: acompr lz4hc(15) of 128kB chunks took 19591 us (6 MB/s), compress ratio: 3.42 [ 411.700657] lustre_kcompr_1806: adecompr lz4hc(15) took 406 us (89 MB/s) [ 411.707478] lustre_kcompr_1806: test_acomp_compress_decompress(lz4hc,15) ret 0 [ 411.736614] lustre_kcompr_1806: compr lzo(-1) in 64kB chunks took 24098 us (165 MB/s), compress ratio: 2.90 [ 411.761617] lustre_kcompr_1806: compr lzo(-1) in 128kB chunks took 20094 us (199 MB/s), compress ratio: 2.95 [ 411.796376] lustre_kcompr_1806: compr lzo(-1) in 256kB chunks took 29396 us (136 MB/s), compress ratio: 2.96 [ 411.827452] lustre_kcompr_1806: compr lzo(-1) in 512kB chunks took 25255 us (158 MB/s), compress ratio: 2.98 [ 411.863478] lustre_kcompr_1806: compr lzo(-1) in 1024kB chunks took 29968 us (133 MB/s), compress ratio: 2.97 [ 411.900937] lustre_kcompr_1806: compr lzo(-1) in 2048kB chunks took 30709 us (130 MB/s), compress ratio: 2.97 [ 411.944982] lustre_kcompr_1806: compr lzo(-1) in 4096kB chunks took 36461 us (109 MB/s), compress ratio: 2.96 [ 411.973439] lustre_kcompr_1806: decompr lzo(-1) took 12752 us (105 MB/s) [ 411.980925] lustre_kcompr_1806: test_comp_compress_decompress(lzo,-1) ret 0 [ 411.990515] lustre_kcompr_1806: acompr lzo(-1) of 128kB chunks took 716 us (174 MB/s), compress ratio: 2.62 [ 412.001766] lustre_kcompr_1806: adecompr lzo(-1) took 291 us (163 MB/s) [ 412.035879] lustre_kcompr_1806: test_acomp_compress_decompress(lzo,-1) ret 0 [ 412.846514] lustre_kcompr_1806: compr deflate(1) in 64kB chunks took 773659 us (5 MB/s), compress ratio: 3.51 [ 413.763844] lustre_kcompr_1806: compr deflate(1) in 128kB chunks took 908994 us (4 MB/s), compress ratio: 3.53 [ 414.546570] lustre_kcompr_1806: compr deflate(1) in 256kB chunks took 774202 us (5 MB/s), compress ratio: 3.53 [ 415.486857] lustre_kcompr_1806: compr deflate(1) in 512kB chunks took 929139 us (4 MB/s), compress ratio: 3.54 [ 416.423161] lustre_kcompr_1806: compr deflate(1) in 1024kB chunks took 927238 us (4 MB/s), compress ratio: 3.54 [ 417.237890] lustre_kcompr_1806: compr deflate(1) in 2048kB chunks took 809937 us (4 MB/s), compress ratio: 3.54 [ 418.181723] lustre_kcompr_1806: compr deflate(1) in 4096kB chunks took 931358 us (4 MB/s), compress ratio: 3.54 [ 418.286053] lustre_kcompr_1806: decompr deflate(1) took 86384 us (13 MB/s) [ 418.298637] lustre_kcompr_1806: test_comp_compress_decompress(deflate,1) ret 0 [ 418.339322] lustre_kcompr_1806: acompr deflate(1) of 128kB chunks took 31015 us (4 MB/s), compress ratio: 3.09 [ 418.353735] lustre_kcompr_1806: adecompr deflate(1) took 3158 us (12 MB/s) [ 418.393778] lustre_kcompr_1806: test_acomp_compress_decompress(deflate,1) ret 0 [ 419.289630] lustre_kcompr_1806: compr deflate(6) in 64kB chunks took 844755 us (4 MB/s), compress ratio: 3.51 [ 420.316183] lustre_kcompr_1806: compr deflate(6) in 128kB chunks took 1016789 us (3 MB/s), compress ratio: 3.53 [ 421.110741] lustre_kcompr_1806: compr deflate(6) in 256kB chunks took 781741 us (5 MB/s), compress ratio: 3.53 [ 421.751361] lustre_kcompr_1806: compr deflate(6) in 512kB chunks took 617464 us (6 MB/s), compress ratio: 3.54 [ 422.622184] lustre_kcompr_1806: compr deflate(6) in 1024kB chunks took 862650 us (4 MB/s), compress ratio: 3.54 [ 423.655538] lustre_kcompr_1806: compr deflate(6) in 2048kB chunks took 1026993 us (3 MB/s), compress ratio: 3.54 [ 424.715939] lustre_kcompr_1806: compr deflate(6) in 4096kB chunks took 1029804 us (3 MB/s), compress ratio: 3.54 [ 424.781711] lustre_kcompr_1806: decompr deflate(6) took 50350 us (22 MB/s) [ 424.801814] lustre_kcompr_1806: test_comp_compress_decompress(deflate,6) ret 0 [ 424.858853] lustre_kcompr_1806: acompr deflate(6) of 128kB chunks took 41887 us (2 MB/s), compress ratio: 3.09 [ 424.873173] lustre_kcompr_1806: adecompr deflate(6) took 2657 us (15 MB/s) [ 424.923304] lustre_kcompr_1806: test_acomp_compress_decompress(deflate,6) ret 0 [ 425.735057] lustre_kcompr_1806: compr deflate(9) in 64kB chunks took 778028 us (5 MB/s), compress ratio: 3.51 [ 426.884239] lustre_kcompr_1806: compr deflate(9) in 128kB chunks took 1143875 us (3 MB/s), compress ratio: 3.53 [ 427.766287] lustre_kcompr_1806: compr deflate(9) in 256kB chunks took 873269 us (4 MB/s), compress ratio: 3.53 [ 428.646243] lustre_kcompr_1806: compr deflate(9) in 512kB chunks took 873261 us (4 MB/s), compress ratio: 3.54 [ 429.510324] lustre_kcompr_1806: compr deflate(9) in 1024kB chunks took 857624 us (4 MB/s), compress ratio: 3.54 [ 430.493456] lustre_kcompr_1806: compr deflate(9) in 2048kB chunks took 968628 us (4 MB/s), compress ratio: 3.54 [ 431.237725] lustre_kcompr_1806: compr deflate(9) in 4096kB chunks took 738494 us (5 MB/s), compress ratio: 3.54 [ 431.328891] lustre_kcompr_1806: decompr deflate(9) took 69214 us (16 MB/s) [ 431.362078] lustre_kcompr_1806: test_comp_compress_decompress(deflate,9) ret 0 [ 431.420208] lustre_kcompr_1806: acompr deflate(9) of 128kB chunks took 39126 us (3 MB/s), compress ratio: 3.09 [ 431.436991] lustre_kcompr_1806: adecompr deflate(9) took 1559 us (25 MB/s) [ 431.461212] lustre_kcompr_1806: test_acomp_compress_decompress(deflate,9) ret 0 [ 431.629611] lustre_kcompr_1806: compr zstd(0) in 64kB chunks took 149082 us (26 MB/s), compress ratio: 4.28 [ 431.847493] lustre_kcompr_1806: compr zstd(0) in 128kB chunks took 212817 us (18 MB/s), compress ratio: 4.46 [ 432.103869] lustre_kcompr_1806: compr zstd(0) in 256kB chunks took 243642 us (16 MB/s), compress ratio: 4.61 [ 432.412762] lustre_kcompr_1806: compr zstd(0) in 512kB chunks took 303871 us (13 MB/s), compress ratio: 4.72 [ 432.816525] lustre_kcompr_1806: compr zstd(0) in 1024kB chunks took 392988 us (10 MB/s), compress ratio: 4.80 [ 433.273512] lustre_kcompr_1806: compr zstd(0) in 2048kB chunks took 436357 us (9 MB/s), compress ratio: 4.85 [ 433.590114] lustre_kcompr_1806: compr zstd(0) in 4096kB chunks took 308240 us (12 MB/s), compress ratio: 4.88 [ 433.664743] lustre_kcompr_1806: decompr zstd(0) took 59467 us (13 MB/s) [ 433.674990] lustre_kcompr_1806: test_comp_compress_decompress(zstd,0) ret 0 [ 433.691169] lustre_kcompr_1806: acompr zstd(0) of 128kB chunks took 8606 us (14 MB/s), compress ratio: 3.98 [ 433.710280] lustre_kcompr_1806: adecompr zstd(0) took 4025 us (7 MB/s) [ 433.723525] lustre_kcompr_1806: test_acomp_compress_decompress(zstd,0) ret 0 [ 433.846201] lustre_kcompr_1806: compr zstd(1) in 64kB chunks took 102869 us (38 MB/s), compress ratio: 4.06 [ 433.997659] lustre_kcompr_1806: compr zstd(1) in 128kB chunks took 125641 us (31 MB/s), compress ratio: 4.20 [ 434.118415] lustre_kcompr_1806: compr zstd(1) in 256kB chunks took 113927 us (35 MB/s), compress ratio: 4.34 [ 434.205562] lustre_kcompr_1806: compr zstd(1) in 512kB chunks took 78074 us (51 MB/s), compress ratio: 4.34 [ 434.326168] lustre_kcompr_1806: compr zstd(1) in 1024kB chunks took 116330 us (34 MB/s), compress ratio: 4.37 [ 434.414110] lustre_kcompr_1806: compr zstd(1) in 2048kB chunks took 70010 us (57 MB/s), compress ratio: 4.39 [ 434.453738] lustre_kcompr_1806: compr zstd(1) in 4096kB chunks took 35539 us (112 MB/s), compress ratio: 4.40 [ 434.476498] lustre_kcompr_1806: decompr zstd(1) took 14006 us (64 MB/s) [ 434.480336] lustre_kcompr_1806: test_comp_compress_decompress(zstd,1) ret 0 [ 434.498743] lustre_kcompr_1806: acompr zstd(1) of 128kB chunks took 3775 us (33 MB/s), compress ratio: 3.73 [ 434.506407] lustre_kcompr_1806: adecompr zstd(1) took 790 us (42 MB/s) [ 434.512877] lustre_kcompr_1806: test_acomp_compress_decompress(zstd,1) ret 0 [ 436.493758] lustre_kcompr_1806: compr zstd(10) in 64kB chunks took 1951992 us (2 MB/s), compress ratio: 4.80 [ 438.546735] lustre_kcompr_1806: compr zstd(10) in 128kB chunks took 2043272 us (1 MB/s), compress ratio: 5.09 [ 440.911697] lustre_kcompr_1806: compr zstd(10) in 256kB chunks took 2359911 us (1 MB/s), compress ratio: 5.34 [ 442.728194] lustre_kcompr_1806: compr zstd(10) in 512kB chunks took 1804111 us (2 MB/s), compress ratio: 5.45 [ 444.923231] lustre_kcompr_1806: compr zstd(10) in 1024kB chunks took 2181152 us (1 MB/s), compress ratio: 5.62 [ 447.765193] lustre_kcompr_1806: compr zstd(10) in 2048kB chunks took 2825094 us (1 MB/s), compress ratio: 5.76 [ 450.232329] lustre_kcompr_1806: compr zstd(10) in 4096kB chunks took 2438447 us (1 MB/s), compress ratio: 5.89 [ 450.291398] lustre_kcompr_1806: decompr zstd(10) took 32521 us (20 MB/s) [ 450.304787] lustre_kcompr_1806: test_comp_compress_decompress(zstd,10) ret 0 [ 450.418959] lustre_kcompr_1806: acompr zstd(10) of 128kB chunks took 105010 us (1 MB/s), compress ratio: 4.46 [ 450.428336] lustre_kcompr_1806: adecompr zstd(10) took 636 us (44 MB/s) [ 450.438535] lustre_kcompr_1806: test_acomp_compress_decompress(zstd,10) ret 0 [ 469.753627] lustre_kcompr_1806: compr zstd(15) in 64kB chunks took 19285988 us (0 MB/s), compress ratio: 4.96 [ 492.058771] watchdog: BUG: soft lockup - CPU#0 stuck for 23s! [insmod:11439] [ 492.063689] 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] [ 492.100219] CPU: 0 PID: 11439 Comm: insmod Kdump: loaded Tainted: G O -------- - - 4.18.0rh8.10-debug #2 [ 492.107977] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-10.fc44 06/10/2025 [ 492.113153] RIP: 0010:ZSTD_btGetAllMatches_noDict_3+0x656/0xa90 [lzstd] [ 492.119857] Code: 4c 89 05 25 15 06 00 49 83 c7 08 4c 39 f0 0f 83 b8 01 00 00 4d 8b 27 49 83 c0 01 4c 89 64 24 58 4c 8b 20 4c 39 64 24 58 74 d2 <4c> 8b 7c 24 60 4c 8b 44 24 58 eb 8f 41 8b 13 48 83 05 bb 18 06 00 [ 492.131364] RSP: 0018:ffffb96d814a34e8 EFLAGS: 00000287 ORIG_RAX: ffffffffffffff13 [ 492.137051] RAX: ffff9829097eaceb RBX: 000000000000aa10 RCX: ffff9829097eac33 [ 492.142754] RDX: ffff9829097eaa0e RSI: 0000000000000005 RDI: ffffb96d9bccf340 [ 492.145787] RBP: ffff9829097eac2e R08: 00000000119dc5cf R09: 0000000000000005 [ 492.149170] R10: 000000000000000c R11: 00000000000001fc R12: 2067363120747365 [ 492.152189] R13: 0000000000000004 R14: ffff9829097ffff9 R15: ffff9829097eaacb [ 492.155332] FS: 00007f6606c29740(0000) GS:ffff982942000000(0000) knlGS:0000000000000000 [ 492.159954] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 492.165162] CR2: 00007f8eb368e000 CR3: 000000012c8d8006 CR4: 0000000000170ef0 [ 492.168324] Call Trace: [ 492.169370] [ 492.170930] ? show_regs.cold.9+0x22/0x2f [ 492.172868] ? watchdog_timer_fn.cold.6+0x62/0x10a [ 492.176594] ? __hrtimer_run_queues+0x1c2/0x4d0 [ 492.178247] ? watchdog+0x40/0x40 [ 492.185180] ? hrtimer_interrupt+0x128/0x2c0 [ 492.187173] ? smp_apic_timer_interrupt+0xa6/0x270 [ 492.189972] ? apic_timer_interrupt+0xf/0x20 [ 492.192130] [ 492.193106] ? ZSTD_btGetAllMatches_noDict_3+0x656/0xa90 [lzstd] [ 492.196759] ? ZSTD_btGetAllMatches_noDict_3+0x6cf/0xa90 [lzstd] [ 492.204163] ZSTD_compressBlock_opt2+0xd6d/0x15a0 [lzstd] [ 492.208685] ? ZSTD_btGetAllMatches_dictMatchState_6+0xfa0/0xfa0 [lzstd] [ 492.218285] ? ZSTD_btGetAllMatches_noDict_4+0x850/0x850 [lzstd] [ 492.225632] ? ZSTD_btGetAllMatches_noDict_5+0x860/0x860 [lzstd] [ 492.229984] ? ZSTD_btGetAllMatches_extDict_5+0x1160/0x1160 [lzstd] [ 492.234418] ? ZSTD_btGetAllMatches_dictMatchState_3+0x11b0/0x11b0 [lzstd] [ 492.239882] ? ZSTD_btGetAllMatches_extDict_6+0x1160/0x1160 [lzstd] [ 492.242974] ? ZSTD_btGetAllMatches_extDict_4+0x1160/0x1160 [lzstd] [ 492.246144] ? ZSTD_btGetAllMatches_noDict_3+0xa90/0xa90 [lzstd] [ 492.249805] ? ZSTD_btGetAllMatches_dictMatchState_5+0xfa0/0xfa0 [lzstd] [ 492.255044] ? ZSTD_btGetAllMatches_noDict_6+0x860/0x860 [lzstd] [ 492.262331] ? ZSTD_btGetAllMatches_dictMatchState_4+0xf80/0xf80 [lzstd] [ 492.267358] ? ZSTD_compressBlock_btultra2+0xfd/0x140 [lzstd] [ 492.270781] ZSTD_compressBlock_btultra2+0xfd/0x140 [lzstd] [ 492.275266] ZSTD_buildSeqStore+0x28a/0x3b0 [lzstd] [ 492.277868] ZSTD_compress_frameChunk+0x3b8/0xd80 [lzstd] [ 492.281154] ? ZSTD_getCParams_internal+0xfa/0x230 [lzstd] [ 492.283733] ZSTD_compressContinue_internal+0x1b8/0x3e0 [lzstd] [ 492.286925] ZSTD_compressEnd+0x2b/0x200 [lzstd] [ 492.289275] ZSTD_compressStream2+0x8bf/0x9c0 [lzstd] [ 492.291839] ZSTD_compress2+0x9e/0x110 [lzstd] [ 492.293365] zstd_compress_cctx+0x59/0x70 [lzstd] [ 492.295226] __zstd_compress.isra.2+0xef/0x2c0 [lzstd] [ 492.297401] ? __schedule+0x369/0xcb0 [ 492.299099] zstd_compress+0x2f/0x60 [lzstd] [ 492.301023] crypto_compress+0x17/0x20 [ 492.303006] test_comp_compress_decompress+0x2c6/0x77a [kcompr] [ 492.306920] kcompr_init+0x2f7/0x1000 [kcompr] [ 492.308742] ? slab_post_alloc_hook+0x66/0x380 [ 492.310286] ? kmalloc_array.constprop.6+0x23/0x40 [ 492.313150] ? __kmalloc+0x1b4/0x4a0 [ 492.314908] ? 0xffffffffc1e32000 [ 492.316811] do_one_initcall+0x73/0x360 [ 492.318708] ? gcov_event+0x73/0x679 [ 492.320329] do_init_module+0xa4/0x350 [ 492.322001] load_module+0x1c42/0x2260 [ 492.323800] ? security_kernel_post_read_file+0x88/0xc0 [ 492.326542] ? __do_sys_finit_module+0x141/0x1a0 [ 492.328587] __do_sys_finit_module+0x141/0x1a0 [ 492.330428] __x64_sys_finit_module+0x1e/0x30 [ 492.332276] do_syscall_64+0xc1/0x440 [ 492.333931] entry_SYSCALL_64_after_hwframe+0x49/0xae [ 492.336348] RIP: 0033:0x7f6605b2741d [ 492.337967] 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 [ 492.349633] RSP: 002b:00007fff77c8dcd8 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 492.354117] RAX: ffffffffffffffda RBX: 000055b168e07830 RCX: 00007f6605b2741d [ 492.357852] RDX: 0000000000000000 RSI: 000055b168e072a0 RDI: 0000000000000003 [ 492.361726] RBP: 000055b168e072a0 R08: 0000000000000000 R09: 00007fff77c8de78 [ 492.365616] R10: 0000000000000003 R11: 0000000000000246 R12: 0000000000000000 [ 492.368989] R13: 000055b168e077f0 R14: 0000000000000000 R15: 000055b168e072a0 [ 493.241767] lustre_kcompr_1806: compr zstd(15) in 128kB chunks took 23480130 us (0 MB/s), compress ratio: 5.28 [ 507.108694] lustre_kcompr_1806: compr zstd(15) in 256kB chunks took 13852201 us (0 MB/s), compress ratio: 5.57 [ 522.628734] lustre_kcompr_1806: compr zstd(15) in 512kB chunks took 15508542 us (0 MB/s), compress ratio: 5.83 [ 535.429858] lustre_kcompr_1806: compr zstd(15) in 1024kB chunks took 12785295 us (0 MB/s), compress ratio: 6.06 [ 549.861932] lustre_kcompr_1806: compr zstd(15) in 2048kB chunks took 14422029 us (0 MB/s), compress ratio: 6.26 [ 564.924733] lustre_kcompr_1806: compr zstd(15) in 4096kB chunks took 15051156 us (0 MB/s), compress ratio: 6.46 [ 564.966383] lustre_kcompr_1806: decompr zstd(15) took 28888 us (21 MB/s) [ 564.974074] lustre_kcompr_1806: test_comp_compress_decompress(zstd,15) ret 0 [ 565.432299] lustre_kcompr_1806: acompr zstd(15) of 128kB chunks took 449098 us (0 MB/s), compress ratio: 4.64 [ 565.446817] lustre_kcompr_1806: adecompr zstd(15) took 594 us (45 MB/s) [ 565.465370] lustre_kcompr_1806: test_acomp_compress_decompress(zstd,15) ret 0 [ 565.630535] lustre_kcompr_1806: compr zstdfast(1) in 64kB chunks took 121110 us (33 MB/s), compress ratio: 3.49 [ 565.748844] lustre_kcompr_1806: compr zstdfast(1) in 128kB chunks took 95663 us (41 MB/s), compress ratio: 3.62 [ 565.807611] lustre_kcompr_1806: compr zstdfast(1) in 256kB chunks took 54066 us (73 MB/s), compress ratio: 3.84 [ 565.850735] lustre_kcompr_1806: compr zstdfast(1) in 512kB chunks took 38773 us (103 MB/s), compress ratio: 3.73 [ 565.894570] lustre_kcompr_1806: compr zstdfast(1) in 1024kB chunks took 39087 us (102 MB/s), compress ratio: 3.76 [ 565.948927] lustre_kcompr_1806: compr zstdfast(1) in 2048kB chunks took 49827 us (80 MB/s), compress ratio: 3.77 [ 565.989824] lustre_kcompr_1806: compr zstdfast(1) in 4096kB chunks took 36394 us (109 MB/s), compress ratio: 3.78 [ 566.034347] lustre_kcompr_1806: decompr zstdfast(1) took 18601 us (56 MB/s) [ 566.039652] lustre_kcompr_1806: test_comp_compress_decompress(zstdfast,1) ret 0 [ 566.054212] lustre_kcompr_1806: acompr zstdfast(1) of 128kB chunks took 3451 us (36 MB/s), compress ratio: 3.19 [ 566.061608] lustre_kcompr_1806: adecompr zstdfast(1) took 317 us (123 MB/s) [ 566.084251] lustre_kcompr_1806: test_acomp_compress_decompress(zstdfast,1) ret 0 [ 566.177182] lustre_kcompr_1806: compr zstdfast(5) in 64kB chunks took 63470 us (63 MB/s), compress ratio: 2.81 [ 566.296948] lustre_kcompr_1806: compr zstdfast(5) in 128kB chunks took 100444 us (39 MB/s), compress ratio: 2.93 [ 566.460517] lustre_kcompr_1806: compr zstdfast(5) in 256kB chunks took 150313 us (26 MB/s), compress ratio: 3.16 [ 566.547845] lustre_kcompr_1806: compr zstdfast(5) in 512kB chunks took 75354 us (53 MB/s), compress ratio: 3.04 [ 566.640043] lustre_kcompr_1806: compr zstdfast(5) in 1024kB chunks took 85018 us (47 MB/s), compress ratio: 3.07 [ 566.744318] lustre_kcompr_1806: compr zstdfast(5) in 2048kB chunks took 92424 us (43 MB/s), compress ratio: 3.08 [ 566.847768] lustre_kcompr_1806: compr zstdfast(5) in 4096kB chunks took 93818 us (42 MB/s), compress ratio: 3.09 [ 566.907130] lustre_kcompr_1806: decompr zstdfast(5) took 36227 us (35 MB/s) [ 566.924333] lustre_kcompr_1806: test_comp_compress_decompress(zstdfast,5) ret 0 [ 566.936883] lustre_kcompr_1806: acompr zstdfast(5) of 128kB chunks took 1108 us (112 MB/s), compress ratio: 2.56 [ 566.953852] lustre_kcompr_1806: adecompr zstdfast(5) took 356 us (136 MB/s) [ 566.974184] lustre_kcompr_1806: test_acomp_compress_decompress(zstdfast,5) ret 0 [ 567.057555] lustre_kcompr_1806: compr zstdfast(12) in 64kB chunks took 43777 us (91 MB/s), compress ratio: 1.43 [ 567.133282] lustre_kcompr_1806: compr zstdfast(12) in 128kB chunks took 55329 us (72 MB/s), compress ratio: 1.50 [ 567.193439] lustre_kcompr_1806: compr zstdfast(12) in 256kB chunks took 43969 us (90 MB/s), compress ratio: 1.62 [ 567.253928] lustre_kcompr_1806: compr zstdfast(12) in 512kB chunks took 53251 us (75 MB/s), compress ratio: 1.55 [ 567.310842] lustre_kcompr_1806: compr zstdfast(12) in 1024kB chunks took 43071 us (92 MB/s), compress ratio: 1.58 [ 567.364210] lustre_kcompr_1806: compr zstdfast(12) in 2048kB chunks took 47493 us (84 MB/s), compress ratio: 1.60 [ 567.409977] lustre_kcompr_1806: compr zstdfast(12) in 4096kB chunks took 37813 us (105 MB/s), compress ratio: 1.60 [ 567.450661] lustre_kcompr_1806: decompr zstdfast(12) took 19741 us (126 MB/s) [ 567.474156] lustre_kcompr_1806: test_comp_compress_decompress(zstdfast,12) ret 0 [ 567.483574] lustre_kcompr_1806: acompr zstdfast(12) of 128kB chunks took 646 us (193 MB/s), compress ratio: 1.37 [ 567.496731] lustre_kcompr_1806: adecompr zstdfast(12) took 875 us (104 MB/s) [ 567.527171] lustre_kcompr_1806: test_acomp_compress_decompress(zstdfast,12) ret 0 [ 567.564464] lustre_kcompr_1806: compr zstdfast(15) in 64kB chunks took 13756 us (290 MB/s), compress ratio: 1.12 [ 567.587315] lustre_kcompr_1806: compr zstdfast(15) in 128kB chunks took 16803 us (238 MB/s), compress ratio: 1.16 [ 567.615701] lustre_kcompr_1806: compr zstdfast(15) in 256kB chunks took 21399 us (186 MB/s), compress ratio: 1.22 [ 567.647523] lustre_kcompr_1806: compr zstdfast(15) in 512kB chunks took 25590 us (156 MB/s), compress ratio: 1.20 [ 567.669181] lustre_kcompr_1806: compr zstdfast(15) in 1024kB chunks took 15790 us (253 MB/s), compress ratio: 1.22 [ 567.697203] lustre_kcompr_1806: compr zstdfast(15) in 2048kB chunks took 21738 us (184 MB/s), compress ratio: 1.23 [ 567.725329] lustre_kcompr_1806: compr zstdfast(15) in 4096kB chunks took 21710 us (184 MB/s), compress ratio: 1.23 [ 567.767485] lustre_kcompr_1806: decompr zstdfast(15) took 24908 us (130 MB/s) [ 567.778192] lustre_kcompr_1806: test_comp_compress_decompress(zstdfast,15) ret 0 [ 567.791506] lustre_kcompr_1806: acompr zstdfast(15) of 128kB chunks took 1810 us (69 MB/s), compress ratio: 1.08 [ 567.797757] lustre_kcompr_1806: adecompr zstdfast(15) took 180 us (641 MB/s) [ 567.821218] lustre_kcompr_1806: test_acomp_compress_decompress(zstdfast,15) ret 0 [ 567.836349] lustre_kcompr_1806: SUCCESS [ 573.089089] lustre_kcompr_16003: ***** [ 573.094242] lustre_kcompr_16003: (de)compression test on provided file /tmp/f84.sanity [ 573.101689] lustre_kcompr_16003: ***** [ 573.151541] lustre_kcompr_16003: compr lz4(1) in 64kB chunks took 31931 us (125 MB/s), compress ratio: 2.45 [ 573.182753] lustre_kcompr_16003: compr lz4(1) in 128kB chunks took 20203 us (197 MB/s), compress ratio: 2.39 [ 573.219648] lustre_kcompr_16003: compr lz4(1) in 256kB chunks took 30148 us (132 MB/s), compress ratio: 2.39 [ 573.259470] lustre_kcompr_16003: compr lz4(1) in 512kB chunks took 35881 us (111 MB/s), compress ratio: 2.39 [ 573.322659] lustre_kcompr_16003: compr lz4(1) in 1024kB chunks took 43058 us (92 MB/s), compress ratio: 2.39 [ 573.374664] lustre_kcompr_16003: compr lz4(1) in 2048kB chunks took 33460 us (119 MB/s), compress ratio: 2.39 [ 573.431761] lustre_kcompr_16003: compr lz4(1) in 4096kB chunks took 46031 us (86 MB/s), compress ratio: 2.39 [ 573.451988] lustre_kcompr_16003: decompr lz4(1) took 5576 us (299 MB/s) [ 573.456681] lustre_kcompr_16003: test_comp_compress_decompress(lz4,1) ret 0 [ 573.462170] lustre_kcompr_16003: acompr lz4(1) of 128kB chunks took 292 us (428 MB/s), compress ratio: 15.67 [ 573.473559] lustre_kcompr_16003: adecompr lz4(1) took 104 us (76 MB/s) [ 573.502168] lustre_kcompr_16003: test_acomp_compress_decompress(lz4,1) ret 0 [ 573.534407] lustre_kcompr_16003: compr lz4(4) in 64kB chunks took 17573 us (227 MB/s), compress ratio: 2.36 [ 573.572990] lustre_kcompr_16003: compr lz4(4) in 128kB chunks took 27195 us (147 MB/s), compress ratio: 2.35 [ 573.597101] lustre_kcompr_16003: compr lz4(4) in 256kB chunks took 19114 us (209 MB/s), compress ratio: 2.35 [ 573.632644] lustre_kcompr_16003: compr lz4(4) in 512kB chunks took 28491 us (140 MB/s), compress ratio: 2.35 [ 573.667307] lustre_kcompr_16003: compr lz4(4) in 1024kB chunks took 14783 us (270 MB/s), compress ratio: 2.35 [ 573.693262] lustre_kcompr_16003: compr lz4(4) in 2048kB chunks took 20825 us (192 MB/s), compress ratio: 2.35 [ 573.728497] lustre_kcompr_16003: compr lz4(4) in 4096kB chunks took 21644 us (184 MB/s), compress ratio: 2.35 [ 573.758642] lustre_kcompr_16003: decompr lz4(4) took 8653 us (196 MB/s) [ 573.765496] lustre_kcompr_16003: test_comp_compress_decompress(lz4,4) ret 0 [ 573.772606] lustre_kcompr_16003: acompr lz4(4) of 128kB chunks took 114 us (1096 MB/s), compress ratio: 15.30 [ 573.780076] lustre_kcompr_16003: adecompr lz4(4) took 114 us (71 MB/s) [ 573.793168] lustre_kcompr_16003: test_acomp_compress_decompress(lz4,4) ret 0 [ 573.824545] lustre_kcompr_16003: compr lz4(9) in 64kB chunks took 6575 us (608 MB/s), compress ratio: 2.32 [ 573.838544] lustre_kcompr_16003: compr lz4(9) in 128kB chunks took 10037 us (398 MB/s), compress ratio: 2.31 [ 573.858364] lustre_kcompr_16003: compr lz4(9) in 256kB chunks took 15728 us (254 MB/s), compress ratio: 2.31 [ 573.874452] lustre_kcompr_16003: compr lz4(9) in 512kB chunks took 11287 us (354 MB/s), compress ratio: 2.31 [ 573.898509] lustre_kcompr_16003: compr lz4(9) in 1024kB chunks took 13628 us (293 MB/s), compress ratio: 2.31 [ 573.926422] lustre_kcompr_16003: compr lz4(9) in 2048kB chunks took 14384 us (278 MB/s), compress ratio: 2.32 [ 573.947537] lustre_kcompr_16003: compr lz4(9) in 4096kB chunks took 11497 us (347 MB/s), compress ratio: 2.32 [ 573.970185] lustre_kcompr_16003: decompr lz4(9) took 8244 us (209 MB/s) [ 573.978914] lustre_kcompr_16003: test_comp_compress_decompress(lz4,9) ret 0 [ 573.995887] lustre_kcompr_16003: acompr lz4(9) of 128kB chunks took 281 us (444 MB/s), compress ratio: 14.81 [ 574.006848] lustre_kcompr_16003: adecompr lz4(9) took 100 us (84 MB/s) [ 574.021299] lustre_kcompr_16003: test_acomp_compress_decompress(lz4,9) ret 0 [ 574.056542] lustre_kcompr_16003: compr lz4(15) in 64kB chunks took 7032 us (568 MB/s), compress ratio: 2.23 [ 574.084287] lustre_kcompr_16003: compr lz4(15) in 128kB chunks took 21538 us (185 MB/s), compress ratio: 2.23 [ 574.104869] lustre_kcompr_16003: compr lz4(15) in 256kB chunks took 12700 us (314 MB/s), compress ratio: 2.23 [ 574.126866] lustre_kcompr_16003: compr lz4(15) in 512kB chunks took 11039 us (362 MB/s), compress ratio: 2.23 [ 574.156113] lustre_kcompr_16003: compr lz4(15) in 1024kB chunks took 14272 us (280 MB/s), compress ratio: 2.23 [ 574.175770] lustre_kcompr_16003: compr lz4(15) in 2048kB chunks took 10277 us (389 MB/s), compress ratio: 2.23 [ 574.208679] lustre_kcompr_16003: compr lz4(15) in 4096kB chunks took 22734 us (175 MB/s), compress ratio: 2.23 [ 574.234859] lustre_kcompr_16003: decompr lz4(15) took 1982 us (902 MB/s) [ 574.243558] lustre_kcompr_16003: test_comp_compress_decompress(lz4,15) ret 0 [ 574.253357] lustre_kcompr_16003: acompr lz4(15) of 128kB chunks took 165 us (757 MB/s), compress ratio: 14.18 [ 574.262168] lustre_kcompr_16003: adecompr lz4(15) took 106 us (83 MB/s) [ 574.285492] lustre_kcompr_16003: test_acomp_compress_decompress(lz4,15) ret 0 [ 574.579774] lustre_kcompr_16003: compr lz4hc(1) in 64kB chunks took 264189 us (15 MB/s), compress ratio: 2.54 [ 574.939526] lustre_kcompr_16003: compr lz4hc(1) in 128kB chunks took 353880 us (11 MB/s), compress ratio: 2.61 [ 575.277096] lustre_kcompr_16003: compr lz4hc(1) in 256kB chunks took 323890 us (12 MB/s), compress ratio: 2.64 [ 575.618854] lustre_kcompr_16003: compr lz4hc(1) in 512kB chunks took 320204 us (12 MB/s), compress ratio: 2.66 [ 575.982757] lustre_kcompr_16003: compr lz4hc(1) in 1024kB chunks took 352902 us (11 MB/s), compress ratio: 2.67 [ 576.304251] lustre_kcompr_16003: compr lz4hc(1) in 2048kB chunks took 306394 us (13 MB/s), compress ratio: 2.67 [ 576.665380] lustre_kcompr_16003: compr lz4hc(1) in 4096kB chunks took 349656 us (11 MB/s), compress ratio: 2.67 [ 576.693877] lustre_kcompr_16003: decompr lz4hc(1) took 7289 us (204 MB/s) [ 576.701164] lustre_kcompr_16003: test_comp_compress_decompress(lz4hc,1) ret 0 [ 576.711684] lustre_kcompr_16003: acompr lz4hc(1) of 128kB chunks took 1025 us (121 MB/s), compress ratio: 15.97 [ 576.731986] lustre_kcompr_16003: adecompr lz4hc(1) took 100 us (78 MB/s) [ 576.756327] lustre_kcompr_16003: test_acomp_compress_decompress(lz4hc,1) ret 0 [ 577.137239] lustre_kcompr_16003: compr lz4hc(3) in 64kB chunks took 344327 us (11 MB/s), compress ratio: 2.57 [ 577.474244] lustre_kcompr_16003: compr lz4hc(3) in 128kB chunks took 327354 us (12 MB/s), compress ratio: 2.64 [ 577.730673] lustre_kcompr_16003: compr lz4hc(3) in 256kB chunks took 248096 us (16 MB/s), compress ratio: 2.68 [ 578.016496] lustre_kcompr_16003: compr lz4hc(3) in 512kB chunks took 276729 us (14 MB/s), compress ratio: 2.69 [ 578.415644] lustre_kcompr_16003: compr lz4hc(3) in 1024kB chunks took 381967 us (10 MB/s), compress ratio: 2.70 [ 578.723143] lustre_kcompr_16003: compr lz4hc(3) in 2048kB chunks took 298295 us (13 MB/s), compress ratio: 2.71 [ 579.119194] lustre_kcompr_16003: compr lz4hc(3) in 4096kB chunks took 387745 us (10 MB/s), compress ratio: 2.71 [ 579.156268] lustre_kcompr_16003: decompr lz4hc(3) took 8471 us (173 MB/s) [ 579.169949] lustre_kcompr_16003: test_comp_compress_decompress(lz4hc,3) ret 0 [ 579.181411] lustre_kcompr_16003: acompr lz4hc(3) of 128kB chunks took 850 us (147 MB/s), compress ratio: 16.00 [ 579.191215] lustre_kcompr_16003: adecompr lz4hc(3) took 730 us (10 MB/s) [ 579.231679] lustre_kcompr_16003: test_acomp_compress_decompress(lz4hc,3) ret 0 [ 580.392139] lustre_kcompr_16003: compr lz4hc(8) in 64kB chunks took 1135135 us (3 MB/s), compress ratio: 2.60 [ 581.476918] lustre_kcompr_16003: compr lz4hc(8) in 128kB chunks took 1080421 us (3 MB/s), compress ratio: 2.66 [ 582.053279] lustre_kcompr_16003: compr lz4hc(8) in 256kB chunks took 572854 us (6 MB/s), compress ratio: 2.70 [ 582.643893] lustre_kcompr_16003: compr lz4hc(8) in 512kB chunks took 566247 us (7 MB/s), compress ratio: 2.71 [ 583.775810] lustre_kcompr_16003: compr lz4hc(8) in 1024kB chunks took 1116688 us (3 MB/s), compress ratio: 2.72 [ 584.537935] lustre_kcompr_16003: compr lz4hc(8) in 2048kB chunks took 750237 us (5 MB/s), compress ratio: 2.73 [ 585.564366] lustre_kcompr_16003: compr lz4hc(8) in 4096kB chunks took 1017023 us (3 MB/s), compress ratio: 2.73 [ 585.609043] lustre_kcompr_16003: decompr lz4hc(8) took 16875 us (86 MB/s) [ 585.623084] lustre_kcompr_16003: test_comp_compress_decompress(lz4hc,8) ret 0 [ 585.649734] lustre_kcompr_16003: acompr lz4hc(8) of 128kB chunks took 7066 us (17 MB/s), compress ratio: 16.17 [ 585.659343] lustre_kcompr_16003: adecompr lz4hc(8) took 344 us (22 MB/s) [ 585.665674] lustre_kcompr_16003: test_acomp_compress_decompress(lz4hc,8) ret 0 [ 593.539591] lustre_kcompr_16003: compr lz4hc(15) in 64kB chunks took 7863052 us (0 MB/s), compress ratio: 2.61 [ 604.231618] lustre_kcompr_16003: compr lz4hc(15) in 128kB chunks took 10683788 us (0 MB/s), compress ratio: 2.67 [ 616.151235] lustre_kcompr_16003: compr lz4hc(15) in 256kB chunks took 11897437 us (0 MB/s), compress ratio: 2.71 [ 635.066518] lustre_kcompr_16003: compr lz4hc(15) in 512kB chunks took 18906187 us (0 MB/s), compress ratio: 2.72 [ 651.197088] lustre_kcompr_16003: compr lz4hc(15) in 1024kB chunks took 16122370 us (0 MB/s), compress ratio: 2.73 [ 665.524396] lustre_kcompr_16003: compr lz4hc(15) in 2048kB chunks took 14316355 us (0 MB/s), compress ratio: 2.74 [ 683.157364] lustre_kcompr_16003: compr lz4hc(15) in 4096kB chunks took 17626968 us (0 MB/s), compress ratio: 2.74 [ 683.230528] lustre_kcompr_16003: decompr lz4hc(15) took 29912 us (48 MB/s) [ 683.241105] lustre_kcompr_16003: test_comp_compress_decompress(lz4hc,15) ret 0 [ 683.338543] lustre_kcompr_16003: acompr lz4hc(15) of 128kB chunks took 88579 us (1 MB/s), compress ratio: 16.30 [ 683.360099] lustre_kcompr_16003: adecompr lz4hc(15) took 190 us (40 MB/s) [ 683.386244] lustre_kcompr_16003: test_acomp_compress_decompress(lz4hc,15) ret 0 [ 683.471068] lustre_kcompr_16003: compr lzo(-1) in 64kB chunks took 64638 us (61 MB/s), compress ratio: 2.35 [ 683.548982] lustre_kcompr_16003: compr lzo(-1) in 128kB chunks took 63820 us (62 MB/s), compress ratio: 2.35 [ 683.644362] lustre_kcompr_16003: compr lzo(-1) in 256kB chunks took 66558 us (60 MB/s), compress ratio: 2.35 [ 683.685118] lustre_kcompr_16003: compr lzo(-1) in 512kB chunks took 29027 us (137 MB/s), compress ratio: 2.36 [ 683.742235] lustre_kcompr_16003: compr lzo(-1) in 1024kB chunks took 44268 us (90 MB/s), compress ratio: 2.36 [ 683.828963] lustre_kcompr_16003: compr lzo(-1) in 2048kB chunks took 58117 us (68 MB/s), compress ratio: 2.36 [ 683.899402] lustre_kcompr_16003: compr lzo(-1) in 4096kB chunks took 60942 us (65 MB/s), compress ratio: 2.36 [ 683.940448] lustre_kcompr_16003: decompr lzo(-1) took 18163 us (93 MB/s) [ 683.948378] lustre_kcompr_16003: test_comp_compress_decompress(lzo,-1) ret 0 [ 683.956404] lustre_kcompr_16003: acompr lzo(-1) of 128kB chunks took 162 us (771 MB/s), compress ratio: 15.45 [ 683.966352] lustre_kcompr_16003: adecompr lzo(-1) took 182 us (44 MB/s) [ 684.016733] lustre_kcompr_16003: test_acomp_compress_decompress(lzo,-1) ret 0 [ 684.969314] lustre_kcompr_16003: compr deflate(1) in 64kB chunks took 924519 us (4 MB/s), compress ratio: 3.27 [ 686.053794] lustre_kcompr_16003: compr deflate(1) in 128kB chunks took 1079628 us (3 MB/s), compress ratio: 3.28 [ 687.561699] lustre_kcompr_16003: compr deflate(1) in 256kB chunks took 1499406 us (2 MB/s), compress ratio: 3.28 [ 688.412265] lustre_kcompr_16003: compr deflate(1) in 512kB chunks took 838692 us (4 MB/s), compress ratio: 3.28 [ 688.970934] lustre_kcompr_16003: compr deflate(1) in 1024kB chunks took 554345 us (7 MB/s), compress ratio: 3.28 [ 689.700193] lustre_kcompr_16003: compr deflate(1) in 2048kB chunks took 712528 us (5 MB/s), compress ratio: 3.28 [ 690.495121] lustre_kcompr_16003: compr deflate(1) in 4096kB chunks took 783565 us (5 MB/s), compress ratio: 3.28 [ 690.649664] lustre_kcompr_16003: decompr deflate(1) took 103192 us (11 MB/s) [ 690.673642] lustre_kcompr_16003: test_comp_compress_decompress(deflate,1) ret 0 [ 690.709685] lustre_kcompr_16003: acompr deflate(1) of 128kB chunks took 13833 us (9 MB/s), compress ratio: 21.43 [ 690.730436] lustre_kcompr_16003: adecompr deflate(1) took 609 us (9 MB/s) [ 690.765334] lustre_kcompr_16003: test_acomp_compress_decompress(deflate,1) ret 0 [ 691.758960] lustre_kcompr_16003: compr deflate(6) in 64kB chunks took 977937 us (4 MB/s), compress ratio: 3.27 [ 692.742318] lustre_kcompr_16003: compr deflate(6) in 128kB chunks took 974869 us (4 MB/s), compress ratio: 3.28 [ 693.704553] lustre_kcompr_16003: compr deflate(6) in 256kB chunks took 957228 us (4 MB/s), compress ratio: 3.28 [ 694.417847] lustre_kcompr_16003: compr deflate(6) in 512kB chunks took 704204 us (5 MB/s), compress ratio: 3.28 [ 695.765946] lustre_kcompr_16003: compr deflate(6) in 1024kB chunks took 1336763 us (2 MB/s), compress ratio: 3.28 [ 697.462972] lustre_kcompr_16003: compr deflate(6) in 2048kB chunks took 1671331 us (2 MB/s), compress ratio: 3.28 [ 698.542411] lustre_kcompr_16003: compr deflate(6) in 4096kB chunks took 1059001 us (3 MB/s), compress ratio: 3.28 [ 698.732901] lustre_kcompr_16003: decompr deflate(6) took 150559 us (8 MB/s) [ 698.748659] lustre_kcompr_16003: test_comp_compress_decompress(deflate,6) ret 0 [ 698.801945] lustre_kcompr_16003: acompr deflate(6) of 128kB chunks took 29571 us (4 MB/s), compress ratio: 21.43 [ 698.810878] lustre_kcompr_16003: adecompr deflate(6) took 321 us (18 MB/s) [ 698.854975] lustre_kcompr_16003: test_acomp_compress_decompress(deflate,6) ret 0 [ 700.203266] lustre_kcompr_16003: compr deflate(9) in 64kB chunks took 1308316 us (3 MB/s), compress ratio: 3.27 [ 701.090570] lustre_kcompr_16003: compr deflate(9) in 128kB chunks took 860512 us (4 MB/s), compress ratio: 3.28 [ 701.662744] lustre_kcompr_16003: compr deflate(9) in 256kB chunks took 566786 us (7 MB/s), compress ratio: 3.28 [ 702.739105] lustre_kcompr_16003: compr deflate(9) in 512kB chunks took 1064432 us (3 MB/s), compress ratio: 3.28 [ 703.879997] lustre_kcompr_16003: compr deflate(9) in 1024kB chunks took 1131482 us (3 MB/s), compress ratio: 3.28 [ 704.826266] lustre_kcompr_16003: compr deflate(9) in 2048kB chunks took 934844 us (4 MB/s), compress ratio: 3.28 [ 705.350096] lustre_kcompr_16003: compr deflate(9) in 4096kB chunks took 512256 us (7 MB/s), compress ratio: 3.28 [ 705.396093] lustre_kcompr_16003: decompr deflate(9) took 28972 us (42 MB/s) [ 705.434044] lustre_kcompr_16003: test_comp_compress_decompress(deflate,9) ret 0 [ 705.472661] lustre_kcompr_16003: acompr deflate(9) of 128kB chunks took 31595 us (3 MB/s), compress ratio: 21.43 [ 705.484941] lustre_kcompr_16003: adecompr deflate(9) took 566 us (10 MB/s) [ 705.505347] lustre_kcompr_16003: test_acomp_compress_decompress(deflate,9) ret 0 [ 705.581830] lustre_kcompr_16003: compr zstd(0) in 64kB chunks took 65027 us (61 MB/s), compress ratio: 3.28 [ 705.697841] lustre_kcompr_16003: compr zstd(0) in 128kB chunks took 110764 us (36 MB/s), compress ratio: 3.26 [ 705.796418] lustre_kcompr_16003: compr zstd(0) in 256kB chunks took 92417 us (43 MB/s), compress ratio: 3.31 [ 705.950504] lustre_kcompr_16003: compr zstd(0) in 512kB chunks took 149474 us (26 MB/s), compress ratio: 3.32 [ 706.022132] lustre_kcompr_16003: compr zstd(0) in 1024kB chunks took 64409 us (62 MB/s), compress ratio: 3.32 [ 706.104202] lustre_kcompr_16003: compr zstd(0) in 2048kB chunks took 78310 us (51 MB/s), compress ratio: 3.33 [ 706.187774] lustre_kcompr_16003: compr zstd(0) in 4096kB chunks took 80828 us (49 MB/s), compress ratio: 3.33 [ 706.212926] lustre_kcompr_16003: decompr zstd(0) took 16740 us (71 MB/s) [ 706.216803] lustre_kcompr_16003: test_comp_compress_decompress(zstd,0) ret 0 [ 706.225179] lustre_kcompr_16003: acompr zstd(0) of 128kB chunks took 2096 us (59 MB/s), compress ratio: 22.64 [ 706.239285] lustre_kcompr_16003: adecompr zstd(0) took 164 us (33 MB/s) [ 706.245730] lustre_kcompr_16003: test_acomp_compress_decompress(zstd,0) ret 0 [ 706.316494] lustre_kcompr_16003: compr zstd(1) in 64kB chunks took 55121 us (72 MB/s), compress ratio: 3.22 [ 706.352256] lustre_kcompr_16003: compr zstd(1) in 128kB chunks took 31328 us (127 MB/s), compress ratio: 3.18 [ 706.388031] lustre_kcompr_16003: compr zstd(1) in 256kB chunks took 32633 us (122 MB/s), compress ratio: 3.19 [ 706.413816] lustre_kcompr_16003: compr zstd(1) in 512kB chunks took 21980 us (181 MB/s), compress ratio: 3.19 [ 706.441098] lustre_kcompr_16003: compr zstd(1) in 1024kB chunks took 24226 us (165 MB/s), compress ratio: 3.19 [ 706.475295] lustre_kcompr_16003: compr zstd(1) in 2048kB chunks took 30618 us (130 MB/s), compress ratio: 3.19 [ 706.496908] lustre_kcompr_16003: compr zstd(1) in 4096kB chunks took 18406 us (217 MB/s), compress ratio: 3.19 [ 706.511184] lustre_kcompr_16003: decompr zstd(1) took 7155 us (175 MB/s) [ 706.514411] lustre_kcompr_16003: test_comp_compress_decompress(zstd,1) ret 0 [ 706.517781] lustre_kcompr_16003: acompr zstd(1) of 128kB chunks took 193 us (647 MB/s), compress ratio: 22.79 [ 706.520903] lustre_kcompr_16003: adecompr zstd(1) took 129 us (42 MB/s) [ 706.524327] lustre_kcompr_16003: test_acomp_compress_decompress(zstd,1) ret 0 [ 707.386787] lustre_kcompr_16003: compr zstd(10) in 64kB chunks took 853375 us (4 MB/s), compress ratio: 3.33 [ 708.364834] lustre_kcompr_16003: compr zstd(10) in 128kB chunks took 975341 us (4 MB/s), compress ratio: 3.33 [ 709.423709] lustre_kcompr_16003: compr zstd(10) in 256kB chunks took 1055457 us (3 MB/s), compress ratio: 3.37 [ 709.999851] lustre_kcompr_16003: compr zstd(10) in 512kB chunks took 572424 us (6 MB/s), compress ratio: 3.40 [ 710.585097] lustre_kcompr_16003: compr zstd(10) in 1024kB chunks took 571591 us (6 MB/s), compress ratio: 3.42 [ 711.406616] lustre_kcompr_16003: compr zstd(10) in 2048kB chunks took 815793 us (4 MB/s), compress ratio: 3.46 [ 713.047733] lustre_kcompr_16003: compr zstd(10) in 4096kB chunks took 1635484 us (2 MB/s), compress ratio: 3.50 [ 713.154980] lustre_kcompr_16003: decompr zstd(10) took 86777 us (13 MB/s) [ 713.165252] lustre_kcompr_16003: test_comp_compress_decompress(zstd,10) ret 0 [ 713.181149] lustre_kcompr_16003: acompr zstd(10) of 128kB chunks took 6333 us (19 MB/s), compress ratio: 22.78 [ 713.187830] lustre_kcompr_16003: adecompr zstd(10) took 178 us (30 MB/s) [ 713.229202] lustre_kcompr_16003: test_acomp_compress_decompress(zstd,10) ret 0 [ 724.264928] lustre_kcompr_16003: compr zstd(15) in 64kB chunks took 10998969 us (0 MB/s), compress ratio: 3.36 [ 733.545358] lustre_kcompr_16003: compr zstd(15) in 128kB chunks took 9272717 us (0 MB/s), compress ratio: 3.39 [ 738.951349] lustre_kcompr_16003: compr zstd(15) in 256kB chunks took 5392207 us (0 MB/s), compress ratio: 3.40 [ 747.863188] lustre_kcompr_16003: compr zstd(15) in 512kB chunks took 8892619 us (0 MB/s), compress ratio: 3.42 [ 754.132689] lustre_kcompr_16003: compr zstd(15) in 1024kB chunks took 6259670 us (0 MB/s), compress ratio: 3.43 [ 762.784858] lustre_kcompr_16003: compr zstd(15) in 2048kB chunks took 8637977 us (0 MB/s), compress ratio: 3.48 [ 766.978637] lustre_kcompr_16003: compr zstd(15) in 4096kB chunks took 4178709 us (0 MB/s), compress ratio: 3.56 [ 767.044327] lustre_kcompr_16003: decompr zstd(15) took 41001 us (27 MB/s) [ 767.054960] lustre_kcompr_16003: test_comp_compress_decompress(zstd,15) ret 0 [ 767.155580] lustre_kcompr_16003: acompr zstd(15) of 128kB chunks took 89292 us (1 MB/s), compress ratio: 23.92 [ 767.186776] lustre_kcompr_16003: adecompr zstd(15) took 192 us (27 MB/s) [ 767.208548] lustre_kcompr_16003: test_acomp_compress_decompress(zstd,15) ret 0 [ 767.273498] lustre_kcompr_16003: compr zstdfast(1) in 64kB chunks took 43082 us (92 MB/s), compress ratio: 2.47 [ 767.335764] lustre_kcompr_16003: compr zstdfast(1) in 128kB chunks took 49885 us (80 MB/s), compress ratio: 2.48 [ 767.419815] lustre_kcompr_16003: compr zstdfast(1) in 256kB chunks took 68837 us (58 MB/s), compress ratio: 2.53 [ 767.493949] lustre_kcompr_16003: compr zstdfast(1) in 512kB chunks took 53311 us (75 MB/s), compress ratio: 2.42 [ 767.565817] lustre_kcompr_16003: compr zstdfast(1) in 1024kB chunks took 53089 us (75 MB/s), compress ratio: 2.42 [ 767.634685] lustre_kcompr_16003: compr zstdfast(1) in 2048kB chunks took 52094 us (76 MB/s), compress ratio: 2.42 [ 767.691417] lustre_kcompr_16003: compr zstdfast(1) in 4096kB chunks took 45670 us (87 MB/s), compress ratio: 2.42 [ 767.713811] lustre_kcompr_16003: decompr zstdfast(1) took 6599 us (249 MB/s) [ 767.722831] lustre_kcompr_16003: test_comp_compress_decompress(zstdfast,1) ret 0 [ 767.730492] lustre_kcompr_16003: acompr zstdfast(1) of 128kB chunks took 2656 us (47 MB/s), compress ratio: 17.28 [ 767.737857] lustre_kcompr_16003: adecompr zstdfast(1) took 130 us (55 MB/s) [ 767.745732] lustre_kcompr_16003: test_acomp_compress_decompress(zstdfast,1) ret 0 [ 767.786320] lustre_kcompr_16003: compr zstdfast(5) in 64kB chunks took 22663 us (176 MB/s), compress ratio: 2.38 [ 767.810607] lustre_kcompr_16003: compr zstdfast(5) in 128kB chunks took 16306 us (245 MB/s), compress ratio: 2.38 [ 767.842100] lustre_kcompr_16003: compr zstdfast(5) in 256kB chunks took 24303 us (164 MB/s), compress ratio: 2.40 [ 767.872584] lustre_kcompr_16003: compr zstdfast(5) in 512kB chunks took 23909 us (167 MB/s), compress ratio: 2.36 [ 767.899675] lustre_kcompr_16003: compr zstdfast(5) in 1024kB chunks took 20322 us (196 MB/s), compress ratio: 2.36 [ 767.932756] lustre_kcompr_16003: compr zstdfast(5) in 2048kB chunks took 25806 us (155 MB/s), compress ratio: 2.36 [ 767.957481] lustre_kcompr_16003: compr zstdfast(5) in 4096kB chunks took 19112 us (209 MB/s), compress ratio: 2.36 [ 767.992548] lustre_kcompr_16003: decompr zstdfast(5) took 8945 us (188 MB/s) [ 768.002676] lustre_kcompr_16003: test_comp_compress_decompress(zstdfast,5) ret 0 [ 768.008465] lustre_kcompr_16003: acompr zstdfast(5) of 128kB chunks took 198 us (631 MB/s), compress ratio: 16.12 [ 768.013895] lustre_kcompr_16003: adecompr zstdfast(5) took 181 us (42 MB/s) [ 768.045257] lustre_kcompr_16003: test_acomp_compress_decompress(zstdfast,5) ret 0 [ 768.089809] lustre_kcompr_16003: compr zstdfast(12) in 64kB chunks took 16401 us (243 MB/s), compress ratio: 2.07 [ 768.128520] lustre_kcompr_16003: compr zstdfast(12) in 128kB chunks took 22169 us (180 MB/s), compress ratio: 2.07 [ 768.156811] lustre_kcompr_16003: compr zstdfast(12) in 256kB chunks took 12757 us (313 MB/s), compress ratio: 2.08 [ 768.192837] lustre_kcompr_16003: compr zstdfast(12) in 512kB chunks took 20614 us (194 MB/s), compress ratio: 2.07 [ 768.223310] lustre_kcompr_16003: compr zstdfast(12) in 1024kB chunks took 19252 us (207 MB/s), compress ratio: 2.07 [ 768.268159] lustre_kcompr_16003: compr zstdfast(12) in 2048kB chunks took 29503 us (135 MB/s), compress ratio: 2.07 [ 768.298835] lustre_kcompr_16003: compr zstdfast(12) in 4096kB chunks took 19316 us (207 MB/s), compress ratio: 2.08 [ 768.353376] lustre_kcompr_16003: decompr zstdfast(12) took 11147 us (172 MB/s) [ 768.362156] lustre_kcompr_16003: test_comp_compress_decompress(zstdfast,12) ret 0 [ 768.366909] lustre_kcompr_16003: acompr zstdfast(12) of 128kB chunks took 153 us (816 MB/s), compress ratio: 13.13 [ 768.373862] lustre_kcompr_16003: adecompr zstdfast(12) took 98 us (97 MB/s) [ 768.413285] lustre_kcompr_16003: test_acomp_compress_decompress(zstdfast,12) ret 0 [ 768.466690] lustre_kcompr_16003: compr zstdfast(15) in 64kB chunks took 11714 us (341 MB/s), compress ratio: 1.82 [ 768.506764] lustre_kcompr_16003: compr zstdfast(15) in 128kB chunks took 17380 us (230 MB/s), compress ratio: 1.83 [ 768.540706] lustre_kcompr_16003: compr zstdfast(15) in 256kB chunks took 14198 us (281 MB/s), compress ratio: 1.83 [ 768.559827] lustre_kcompr_16003: compr zstdfast(15) in 512kB chunks took 6914 us (578 MB/s), compress ratio: 1.83 [ 768.586910] lustre_kcompr_16003: compr zstdfast(15) in 1024kB chunks took 11489 us (348 MB/s), compress ratio: 1.83 [ 768.601155] lustre_kcompr_16003: compr zstdfast(15) in 2048kB chunks took 5473 us (730 MB/s), compress ratio: 1.83 [ 768.624782] lustre_kcompr_16003: compr zstdfast(15) in 4096kB chunks took 13709 us (291 MB/s), compress ratio: 1.84 [ 768.654199] lustre_kcompr_16003: decompr zstdfast(15) took 7742 us (280 MB/s) [ 768.674370] lustre_kcompr_16003: test_comp_compress_decompress(zstdfast,15) ret 0 [ 768.701471] lustre_kcompr_16003: acompr zstdfast(15) of 128kB chunks took 162 us (771 MB/s), compress ratio: 10.73 [ 768.715516] lustre_kcompr_16003: adecompr zstdfast(15) took 103 us (113 MB/s) [ 768.748438] lustre_kcompr_16003: test_acomp_compress_decompress(zstdfast,15) ret 0 [ 768.758310] lustre_kcompr_16003: SUCCESS [ 793.636286] Lustre: DEBUG MARKER: == sanity test complete, duration 531 sec ================ 12:43:14 (1789058594) [ 796.659941] Lustre: DEBUG MARKER: === sanity: start cleanup 12:43:17 (1789058597) === [ 801.582932] Lustre: DEBUG MARKER: === sanity: finish cleanup 12:43:22 (1789058602) === [ 806.033943] LustreError: 13361:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff982911000000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 806.091165] Lustre: Unmounted lustre-client [ 830.106155] Key type lgssc unregistered [ 830.415865] LNet: 13842:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 830.421334] LNetError: 13842:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 830.450408] LNet: Removed LNI 192.168.202.43@tcp [ 831.696314] Key type .llcrypt unregistered [ 831.698523] Key type ._llcrypt unregistered