[ 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 438249614 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 2656MB 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, 524584K 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.001012] APIC: Switch to symmetric I/O mode setup [ 0.002408] x2apic enabled [ 0.003008] Switched APIC routing to physical x2apic. [ 0.004016] kvm-guest: setup PV IPIs [ 0.007273] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008018] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009014] pid_max: default: 32768 minimum: 301 [ 0.011017] LSM: Security Framework initializing [ 0.012062] Yama: becoming mindful. [ 0.013046] SELinux: Initializing. [ 0.014089] *** VALIDATE selinux *** [ 0.022822] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.027310] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.028181] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029122] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030127] *** VALIDATE tmpfs *** [ 0.032103] *** VALIDATE proc *** [ 0.033328] *** VALIDATE cgroup *** [ 0.034012] *** VALIDATE cgroup2 *** [ 0.035262] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.036167] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.037009] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.038030] Spectre V2 : User space: Vulnerable [ 0.039008] Speculative Store Bypass: Vulnerable [ 0.041834] debug: unmapping init [mem 0xffffffff8c459000-0xffffffff8c460fff] [ 0.044169] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.045615] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.046028] ... version: 2 [ 0.047028] ... bit width: 48 [ 0.048013] ... generic registers: 4 [ 0.049014] ... value mask: 0000ffffffffffff [ 0.050016] ... max period: 00007fffffffffff [ 0.051015] ... fixed-purpose events: 3 [ 0.052013] ... event mask: 000000070000000f [ 0.053334] rcu: Hierarchical SRCU implementation. [ 0.055657] smp: Bringing up secondary CPUs ... [ 0.056672] x86: Booting SMP configuration: [ 0.057027] .... node #0, CPUs: #1 #2 #3 [ 0.060325] smp: Brought up 1 node, 4 CPUs [ 0.062012] smpboot: Max logical packages: 1 [ 0.063035] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.132942] node 0 deferred pages initialised in 66ms [ 0.136115] devtmpfs: initialized [ 0.137281] x86/mm: Memory block size: 128MB [ 0.141474] gcov: version magic: 0x41383552 [ 0.144307] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.147094] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.150317] pinctrl core: initialized pinctrl subsystem [ 0.152220] [ 0.152667] ************************************************************* [ 0.155013] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.157012] ** ** [ 0.159014] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.162014] ** ** [ 0.164011] ** This means that this kernel is built to expose internal ** [ 0.166014] ** IOMMU data structures, which may compromise security on ** [ 0.169013] ** your system. ** [ 0.171019] ** ** [ 0.173014] ** If you see this message and you are not debugging the ** [ 0.176009] ** kernel, report this immediately to your vendor! ** [ 0.178012] ** ** [ 0.179012] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.181012] ************************************************************* [ 0.183798] NET: Registered protocol family 16 [ 0.185416] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.189072] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.192077] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.196110] cpuidle: using governor menu [ 0.197785] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.200444] PCI: Using configuration type 1 for base access [ 0.203129] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.214062] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.216039] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.221031] cryptd: max_cpu_qlen set to 1000 [ 0.223245] ACPI: Added _OSI(Module Device) [ 0.225019] ACPI: Added _OSI(Processor Device) [ 0.227015] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.228012] ACPI: Added _OSI(Processor Aggregator Device) [ 0.233635] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.239613] ACPI: Interpreter enabled [ 0.241060] ACPI: PM: (supports S0 S3 S4 S5) [ 0.242015] ACPI: Using IOAPIC for interrupt routing [ 0.244108] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.248458] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.259028] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.261040] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.264018] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.267096] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.271344] acpiphp: Slot [2] registered [ 0.273106] acpiphp: Slot [5] registered [ 0.275119] acpiphp: Slot [6] registered [ 0.276100] acpiphp: Slot [3] registered [ 0.278090] acpiphp: Slot [4] registered [ 0.279089] acpiphp: Slot [7] registered [ 0.280116] acpiphp: Slot [8] registered [ 0.281108] acpiphp: Slot [9] registered [ 0.283124] acpiphp: Slot [10] registered [ 0.284094] acpiphp: Slot [11] registered [ 0.285321] acpiphp: Slot [12] registered [ 0.287122] acpiphp: Slot [13] registered [ 0.289082] acpiphp: Slot [14] registered [ 0.290122] acpiphp: Slot [15] registered [ 0.292140] acpiphp: Slot [16] registered [ 0.294095] acpiphp: Slot [17] registered [ 0.295102] acpiphp: Slot [18] registered [ 0.297103] acpiphp: Slot [19] registered [ 0.298126] acpiphp: Slot [20] registered [ 0.299130] acpiphp: Slot [21] registered [ 0.301143] acpiphp: Slot [22] registered [ 0.302123] acpiphp: Slot [23] registered [ 0.304114] acpiphp: Slot [24] registered [ 0.305226] acpiphp: Slot [25] registered [ 0.307108] acpiphp: Slot [26] registered [ 0.309106] acpiphp: Slot [27] registered [ 0.310118] acpiphp: Slot [28] registered [ 0.311126] acpiphp: Slot [29] registered [ 0.312123] acpiphp: Slot [30] registered [ 0.314115] acpiphp: Slot [31] registered [ 0.315054] PCI host bridge to bus 0000:00 [ 0.316019] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.319029] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.321035] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.323028] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.326030] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.328054] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.330216] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.334176] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.337369] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.346564] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.351068] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.354022] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.356015] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.358015] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.360850] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.363617] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.366048] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.369820] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.374014] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.383014] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.386013] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.392453] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.399016] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.404015] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.421017] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.431570] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.440015] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.449014] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.469016] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.480861] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.483414] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.486267] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.488378] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.490231] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.495116] iommu: Default domain type: Passthrough [ 0.497405] SCSI subsystem initialized [ 0.499132] ACPI: bus type USB registered [ 0.500115] usbcore: registered new interface driver usbfs [ 0.502078] usbcore: registered new interface driver hub [ 0.504108] usbcore: registered new device driver usb [ 0.506177] pps_core: LinuxPPS API ver. 1 registered [ 0.508013] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.511065] PTP clock support registered [ 0.513085] EDAC MC: Ver: 3.0.0 [ 0.514678] PCI: Using ACPI for IRQ routing [ 0.515797] NetLabel: Initializing [ 0.516010] NetLabel: domain hash size = 128 [ 0.517008] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.518230] NetLabel: unlabeled traffic allowed by default [ 0.520282] vgaarb: loaded [ 0.521227] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.523012] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.528415] clocksource: Switched to clocksource kvm-clock [ 0.634986] VFS: Disk quotas dquot_6.6.0 [ 0.636807] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.639496] *** VALIDATE ramfs *** [ 0.640731] *** VALIDATE hugetlbfs *** [ 0.642493] pnp: PnP ACPI init [ 0.645584] pnp: PnP ACPI: found 6 devices [ 0.664249] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.667909] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.670920] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.673198] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.675799] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.678397] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.681474] NET: Registered protocol family 2 [ 0.684508] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.689388] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.693245] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.699101] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.702578] TCP: Hash tables configured (established 65536 bind 65536) [ 0.705646] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.708457] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.711254] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.713912] NET: Registered protocol family 1 [ 0.716090] RPC: Registered named UNIX socket transport module. [ 0.718454] RPC: Registered udp transport module. [ 0.720015] RPC: Registered tcp transport module. [ 0.721878] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.724309] NET: Registered protocol family 44 [ 0.725749] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.727295] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.728927] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.731490] PCI: CLS 0 bytes, default 64 [ 0.733063] Unpacking initramfs... [ 2.134256] debug: unmapping init [mem 0xffff92e5fcc64000-0xffff92e5fffcffff] [ 2.138916] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.141638] software IO TLB: mapped [mem 0x00000000a2000000-0x00000000a6000000] (64MB) [ 2.144517] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.637236] Initialise system trusted keyrings [ 2.638668] Key type blacklist registered [ 2.640293] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.648184] zbud: loaded [ 2.651170] *** VALIDATE nfs *** [ 2.652710] *** VALIDATE nfs4 *** [ 2.653948] pstore: using deflate compression [ 2.657040] Platform Keyring initialized [ 2.755752] NET: Registered protocol family 38 [ 2.757636] Key type asymmetric registered [ 2.759511] Asymmetric key parser 'x509' registered [ 2.761596] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.765142] io scheduler mq-deadline registered [ 2.766634] io scheduler kyber registered [ 2.768236] io scheduler bfq registered [ 2.770315] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.773463] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.776586] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.779633] ACPI: Power Button [PWRF] [ 2.785132] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.792952] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.815191] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.843316] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.872281] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.878295] Non-volatile memory driver v1.3 [ 2.879756] Linux agpgart interface v0.103 [ 2.914820] virtio_blk virtio1: [vda] 142768 512-byte logical blocks (73.1 MB/69.7 MiB) [ 2.917891] vda: detected capacity change from 0 to 73097216 [ 2.935539] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.938534] vdb: detected capacity change from 0 to 1073741824 [ 2.960175] libphy: Fixed MDIO Bus: probed [ 2.967103] usbcore: registered new interface driver usbserial_generic [ 2.969844] usbserial: USB Serial support registered for generic [ 2.972327] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.977551] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.979314] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.981834] mousedev: PS/2 mouse device common for all mice [ 2.985506] rtc_cmos 00:05: RTC can wake from S4 [ 2.987887] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.990111] rtc_cmos 00:05: registered as rtc0 [ 2.995110] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.995194] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.997840] intel_pstate: CPU model not supported [ 3.002385] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.008781] hid: raw HID events driver (C) Jiri Kosina [ 3.011292] usbcore: registered new interface driver usbhid [ 3.013407] usbhid: USB HID core driver [ 3.015160] drop_monitor: Initializing network drop monitor service [ 3.017697] Initializing XFRM netlink socket [ 3.019927] NET: Registered protocol family 10 [ 3.023632] Segment Routing with IPv6 [ 3.025293] NET: Registered protocol family 17 [ 3.027436] mpls_gso: MPLS GSO support [ 3.035380] RAS: Correctable Errors collector initialized. [ 3.037873] AVX version of gcm_enc/dec engaged. [ 3.039653] AES CTR mode by8 optimization enabled [ 3.113844] sched_clock: Marking stable (3113819243, 0)->(3996460711, -882641468) [ 3.117595] registered taskstats version 1 [ 3.119806] Loading compiled-in X.509 certificates [ 3.122132] zswap: loaded using pool lzo/zbud [ 3.149789] Key type big_key registered [ 3.162552] Key type encrypted registered [ 3.164030] ima: No TPM chip found, activating TPM-bypass! [ 3.166339] ima: Allocated hash algorithm: sha1 [ 3.168288] ima: No architecture policies found [ 3.169854] evm: Initialising EVM extended attributes: [ 3.172287] evm: security.selinux [ 3.173562] evm: security.ima [ 3.174908] evm: security.capability [ 3.176476] evm: HMAC attrs: 0x1 [ 3.179301] rtc_cmos 00:05: setting system clock to 2026-09-10 16:21:05 UTC (1789057265) [ 3.185945] debug: unmapping init [mem 0xffffffff8d403000-0xffffffff8d5fffff] [ 3.189200] debug: unmapping init [mem 0xffffffff8c182000-0xffffffff8c458fff] [ 3.197407] Write protecting the kernel read-only data: 28672k [ 3.200517] debug: unmapping init [mem 0xffffffff8a803000-0xffffffff8a9fffff] [ 3.202911] debug: unmapping init [mem 0xffffffff8b114000-0xffffffff8b1fffff] [ 3.233942] 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.244191] systemd[1]: Detected virtualization kvm. [ 3.245757] systemd[1]: Detected architecture x86-64. [ 3.247368] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.273070] systemd[1]: No hostname configured. [ 3.274589] systemd[1]: Set hostname to . [ 3.276230] random: systemd: uninitialized urandom read (16 bytes read) [ 3.278415] systemd[1]: Initializing machine ID from random generator. [ 3.333896] random: ln: uninitialized urandom read (6 bytes read) [ 3.438979] random: systemd: uninitialized urandom read (16 bytes read) [ 3.441855] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ 3.446667] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 3.450450] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket. Starting Setup Virtual Console... [ OK ] Reached target Slices. Starting Journal Service... Starting Create list of required st…ce nodes for the current kernel... Starting Apply Kernel Variables... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Reached target Initrd Root Device. [ OK ] Started Memstrack Anylazing Service. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Journal Service. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.174374] device-mapper: uevent: version 1.0.3 [ 4.176471] 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.919246] random: fast init done [ 5.027099] virtio_net virtio0 ens2: renamed from eth0 [ 5.171359] scsi host0: ata_piix [ 5.200416] scsi host1: ata_piix [ 5.205242] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.207509] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.526209] dracut-initqueue[593]: RTNETLINK answers: File exists [ 9.684383] random: crng init done [ 9.685751] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 10.277851] 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 Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Swap. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.472222] printk: systemd: 22 output lines suppressed due to ratelimiting [ 11.741152] SELinux: Disabled at runtime. [ 11.804340] 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.812299] systemd[1]: Detected virtualization kvm. [ 11.813629] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.355507] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.359438] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.364889] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.370322] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.373741] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.381226] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.387490] systemd[1]: Created slice system-getty.slice. [ OK ] Created slice system-getty.slice. Activating swap /dev/disk/by-label/SWAP... [ 12.419768] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Listening on Process Core Dump Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Created slice User and Session Slice. [ OK ] Created slice system-serial\x2dgetty.slice. Mounting Kernel Debug File System... [ OK ] Reached target Slices. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Listening on initctl Compatibility Named Pipe. Mounting POSIX Message Queue File System... Starting Create list of required st…ce nodes for the current kernel... Starting Apply Kernel Variables... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Stopped target Initrd File Systems. [ OK ] Reached target rpc_pipefs.target. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. Starting Remount Root and Kernel File Systems... [ OK ] Listening on udev Kernel Socket. Mounting Huge Pages File System... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Reached target Paths. [ OK ] Started Journal Service. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Huge Pages File System. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... [ OK ] Mounted /mnt. [ OK ] Started udev Coldplug all Devices. [ 12.904435] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 13.273700] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.311371] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.510078] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.537654] EDAC sbridge: Ver: 1.1.2 [ 14.629465] Key type dns_resolver registered [ 14.942478] NFS: Registering the id_resolver key type [ 14.944833] Key type id_resolver registered [ 14.946628] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Load/Save Random Seed... 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 RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... Starting Login Service... [ OK ] Started irqbalance daemon. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting System Logging Service... 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 oleg434-client login: [ 50.696936] libcfs: loading out-of-tree module taints kernel. [ 50.882430] Key type ._llcrypt registered [ 50.944487] Key type .llcrypt registered [ 51.608781] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 51.627331] alg: No test for adler32 (adler32-zlib) [ 53.426826] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 54.370218] LNet: Added LNI 192.168.204.34@tcp [8/256/0/180] [ 56.121642] Key type lgssc registered [ 58.336729] Lustre: Echo OBD driver; http://www.lustre.org/ [ 65.686576] alg: No test for zstdfast (zstdfast-lustre-generic) [ 65.726651] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 210.401209] Lustre: Mounted lustre-client [ 216.089602] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 226.367819] hrtimer: interrupt took 7401443 ns [ 235.703756] Lustre: DEBUG MARKER: oleg434-client.virtnet: executing check_logdir /tmp/testlogs/ [ 236.000232] Lustre: lustre-OST0000-osc-ffff92e65805f800: disconnect after 23s idle [ 242.300270] Lustre: DEBUG MARKER: oleg434-client.virtnet: executing yml_node [ 250.601895] Lustre: DEBUG MARKER: Client: 2.17.53.26 [ 254.975607] Lustre: DEBUG MARKER: MDS: 2.17.53.26 [ 258.720521] Lustre: DEBUG MARKER: OSS: 2.17.53.26 [ 261.468529] Lustre: DEBUG MARKER: -----============= acceptance-small: sanity ============----- Thu Sep 10 12:25:21 EDT 2026 [ 282.638613] Lustre: DEBUG MARKER: - need MDS1_VERSION <= 2.16.61-1-g89cf292a8c2 (34682138 <= 34618625) for LU-18938, skip 360 [ 284.920495] Lustre: DEBUG MARKER: - need MDS1_VERSION < v2_14_55-100-g8a84c7f9c7 (34682138 < 34486116) for LU-14927, skip 0f [ 287.453568] Lustre: DEBUG MARKER: excepting tests: 42a 42c 42b 118c 118d 407 119i 817 411a 130b 130c 130d 130e 130f 130g [ 289.512689] Lustre: DEBUG MARKER: skipping tests SLOW=no: 27m 60i 64b 68 71 135 136 230d 300o 842 51b 51c 51e 834 [ 291.810289] Lustre: DEBUG MARKER: === sanity: start setup 12:25:51 (1789057551) === [ 296.808856] Lustre: DEBUG MARKER: oleg434-client.virtnet: executing check_config_client /mnt/lustre [ 316.646902] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 331.327937] Lustre: DEBUG MARKER: === sanity: finish setup 12:26:32 (1789057592) === [ 342.344602] Lustre: DEBUG MARKER: == sanity test 84: lz4/lz4hc/lzstd compression/decompression kernel module ========================================================== 12:26:42 (1789057602) [ 343.060520] lustre_kcompr_7442: ***** [ 343.063276] lustre_kcompr_7442: (de)compression test on random binary data [ 343.075247] lustre_kcompr_7442: ***** [ 343.107223] lustre_kcompr_7442: compr lz4(1) in 64kB chunks took 8086 us (494 MB/s), compress ratio: 97.38 [ 343.114252] lustre_kcompr_7442: compr lz4(1) in 128kB chunks took 2258 us (1771 MB/s), compress ratio: 102.70 [ 343.123059] lustre_kcompr_7442: compr lz4(1) in 256kB chunks took 2756 us (1451 MB/s), compress ratio: 105.66 [ 343.134341] lustre_kcompr_7442: compr lz4(1) in 512kB chunks took 3345 us (1195 MB/s), compress ratio: 107.06 [ 343.146933] lustre_kcompr_7442: compr lz4(1) in 1024kB chunks took 2153 us (1857 MB/s), compress ratio: 108.39 [ 343.161251] lustre_kcompr_7442: compr lz4(1) in 2048kB chunks took 2975 us (1344 MB/s), compress ratio: 108.41 [ 343.173409] lustre_kcompr_7442: compr lz4(1) in 4096kB chunks took 3286 us (1217 MB/s), compress ratio: 108.54 [ 343.211648] lustre_kcompr_7442: decompr lz4(1) took 8953 us (4 MB/s) [ 343.220868] lustre_kcompr_7442: test_comp_compress_decompress(lz4,1) ret 0 [ 343.232144] lustre_kcompr_7442: acompr lz4(1) of 128kB chunks took 90 us (1388 MB/s), compress ratio: 100.51 [ 343.243128] lustre_kcompr_7442: adecompr lz4(1) took 92 us (13 MB/s) [ 343.293698] lustre_kcompr_7442: test_acomp_compress_decompress(lz4,1) ret 0 [ 343.331282] lustre_kcompr_7442: compr lz4(4) in 64kB chunks took 4116 us (971 MB/s), compress ratio: 83.56 [ 343.358309] lustre_kcompr_7442: compr lz4(4) in 128kB chunks took 1509 us (2650 MB/s), compress ratio: 93.46 [ 343.377093] lustre_kcompr_7442: compr lz4(4) in 256kB chunks took 1953 us (2048 MB/s), compress ratio: 99.42 [ 343.389306] lustre_kcompr_7442: compr lz4(4) in 512kB chunks took 4130 us (968 MB/s), compress ratio: 103.05 [ 343.399839] lustre_kcompr_7442: compr lz4(4) in 1024kB chunks took 1556 us (2570 MB/s), compress ratio: 104.79 [ 343.409501] lustre_kcompr_7442: compr lz4(4) in 2048kB chunks took 4162 us (961 MB/s), compress ratio: 105.64 [ 343.421734] lustre_kcompr_7442: compr lz4(4) in 4096kB chunks took 2058 us (1943 MB/s), compress ratio: 105.92 [ 343.447900] lustre_kcompr_7442: decompr lz4(4) took 3930 us (9 MB/s) [ 343.457339] lustre_kcompr_7442: test_comp_compress_decompress(lz4,4) ret 0 [ 343.474265] lustre_kcompr_7442: acompr lz4(4) of 128kB chunks took 107 us (1168 MB/s), compress ratio: 91.33 [ 343.487382] lustre_kcompr_7442: adecompr lz4(4) took 102 us (13 MB/s) [ 343.509308] lustre_kcompr_7442: test_acomp_compress_decompress(lz4,4) ret 0 [ 343.538706] lustre_kcompr_7442: compr lz4(9) in 64kB chunks took 5341 us (748 MB/s), compress ratio: 61.35 [ 343.556242] lustre_kcompr_7442: compr lz4(9) in 128kB chunks took 3786 us (1056 MB/s), compress ratio: 76.52 [ 343.573261] lustre_kcompr_7442: compr lz4(9) in 256kB chunks took 2512 us (1592 MB/s), compress ratio: 87.01 [ 343.583822] lustre_kcompr_7442: compr lz4(9) in 512kB chunks took 1591 us (2514 MB/s), compress ratio: 94.53 [ 343.600939] lustre_kcompr_7442: compr lz4(9) in 1024kB chunks took 3280 us (1219 MB/s), compress ratio: 98.05 [ 343.613049] lustre_kcompr_7442: compr lz4(9) in 2048kB chunks took 1727 us (2316 MB/s), compress ratio: 100.37 [ 343.627855] lustre_kcompr_7442: compr lz4(9) in 4096kB chunks took 2065 us (1937 MB/s), compress ratio: 101.21 [ 343.654351] lustre_kcompr_7442: decompr lz4(9) took 5372 us (7 MB/s) [ 343.663855] lustre_kcompr_7442: test_comp_compress_decompress(lz4,9) ret 0 [ 343.672403] lustre_kcompr_7442: acompr lz4(9) of 128kB chunks took 92 us (1358 MB/s), compress ratio: 74.85 [ 343.687316] lustre_kcompr_7442: adecompr lz4(9) took 3433 us (0 MB/s) [ 343.724376] lustre_kcompr_7442: test_acomp_compress_decompress(lz4,9) ret 0 [ 343.752737] lustre_kcompr_7442: compr lz4(15) in 64kB chunks took 5513 us (725 MB/s), compress ratio: 29.60 [ 343.773847] lustre_kcompr_7442: compr lz4(15) in 128kB chunks took 8440 us (473 MB/s), compress ratio: 43.93 [ 343.791621] lustre_kcompr_7442: compr lz4(15) in 256kB chunks took 2510 us (1593 MB/s), compress ratio: 58.79 [ 343.803333] lustre_kcompr_7442: compr lz4(15) in 512kB chunks took 1858 us (2152 MB/s), compress ratio: 71.68 [ 343.816239] lustre_kcompr_7442: compr lz4(15) in 1024kB chunks took 2195 us (1822 MB/s), compress ratio: 80.93 [ 343.829483] lustre_kcompr_7442: compr lz4(15) in 2048kB chunks took 2644 us (1512 MB/s), compress ratio: 87.00 [ 343.857734] lustre_kcompr_7442: compr lz4(15) in 4096kB chunks took 1280 us (3125 MB/s), compress ratio: 87.95 [ 343.901214] lustre_kcompr_7442: decompr lz4(15) took 4610 us (9 MB/s) [ 343.915780] lustre_kcompr_7442: test_comp_compress_decompress(lz4,15) ret 0 [ 343.935953] lustre_kcompr_7442: acompr lz4(15) of 128kB chunks took 90 us (1388 MB/s), compress ratio: 43.61 [ 343.945922] lustre_kcompr_7442: adecompr lz4(15) took 122 us (23 MB/s) [ 343.980265] lustre_kcompr_7442: test_acomp_compress_decompress(lz4,15) ret 0 [ 344.082164] lustre_kcompr_7442: compr lz4hc(1) in 64kB chunks took 59912 us (66 MB/s), compress ratio: 98.51 [ 344.137254] lustre_kcompr_7442: compr lz4hc(1) in 128kB chunks took 51099 us (78 MB/s), compress ratio: 104.57 [ 344.219778] lustre_kcompr_7442: compr lz4hc(1) in 256kB chunks took 67862 us (58 MB/s), compress ratio: 107.89 [ 344.275960] lustre_kcompr_7442: compr lz4hc(1) in 512kB chunks took 49128 us (81 MB/s), compress ratio: 109.64 [ 344.342607] lustre_kcompr_7442: compr lz4hc(1) in 1024kB chunks took 48930 us (81 MB/s), compress ratio: 110.52 [ 344.397836] lustre_kcompr_7442: compr lz4hc(1) in 2048kB chunks took 41585 us (96 MB/s), compress ratio: 110.96 [ 344.484425] lustre_kcompr_7442: compr lz4hc(1) in 4096kB chunks took 66725 us (59 MB/s), compress ratio: 111.18 [ 344.506444] lustre_kcompr_7442: decompr lz4hc(1) took 4443 us (8 MB/s) [ 344.516655] lustre_kcompr_7442: test_comp_compress_decompress(lz4hc,1) ret 0 [ 344.524026] lustre_kcompr_7442: acompr lz4hc(1) of 128kB chunks took 1819 us (68 MB/s), compress ratio: 106.30 [ 344.529910] lustre_kcompr_7442: adecompr lz4hc(1) took 97 us (12 MB/s) [ 344.538186] lustre_kcompr_7442: test_acomp_compress_decompress(lz4hc,1) ret 0 [ 344.604461] lustre_kcompr_7442: compr lz4hc(3) in 64kB chunks took 53746 us (74 MB/s), compress ratio: 98.97 [ 344.678937] lustre_kcompr_7442: compr lz4hc(3) in 128kB chunks took 68202 us (58 MB/s), compress ratio: 105.05 [ 344.774765] lustre_kcompr_7442: compr lz4hc(3) in 256kB chunks took 73259 us (54 MB/s), compress ratio: 108.39 [ 344.853888] lustre_kcompr_7442: compr lz4hc(3) in 512kB chunks took 68906 us (58 MB/s), compress ratio: 110.15 [ 344.995067] lustre_kcompr_7442: compr lz4hc(3) in 1024kB chunks took 134643 us (29 MB/s), compress ratio: 111.04 [ 345.105346] lustre_kcompr_7442: compr lz4hc(3) in 2048kB chunks took 101429 us (39 MB/s), compress ratio: 111.50 [ 345.215635] lustre_kcompr_7442: compr lz4hc(3) in 4096kB chunks took 95287 us (41 MB/s), compress ratio: 111.71 [ 345.259716] lustre_kcompr_7442: decompr lz4hc(3) took 12208 us (2 MB/s) [ 345.276137] lustre_kcompr_7442: test_comp_compress_decompress(lz4hc,3) ret 0 [ 345.297826] lustre_kcompr_7442: acompr lz4hc(3) of 128kB chunks took 6771 us (18 MB/s), compress ratio: 106.64 [ 345.314973] lustre_kcompr_7442: adecompr lz4hc(3) took 367 us (3 MB/s) [ 345.330680] lustre_kcompr_7442: test_acomp_compress_decompress(lz4hc,3) ret 0 [ 346.335957] lustre_kcompr_7442: compr lz4hc(8) in 64kB chunks took 962738 us (4 MB/s), compress ratio: 105.47 [ 347.265907] lustre_kcompr_7442: compr lz4hc(8) in 128kB chunks took 917829 us (4 MB/s), compress ratio: 112.80 [ 348.527105] lustre_kcompr_7442: compr lz4hc(8) in 256kB chunks took 1241868 us (3 MB/s), compress ratio: 116.72 [ 349.563196] lustre_kcompr_7442: compr lz4hc(8) in 512kB chunks took 1015444 us (3 MB/s), compress ratio: 118.85 [ 350.615794] lustre_kcompr_7442: compr lz4hc(8) in 1024kB chunks took 1044636 us (3 MB/s), compress ratio: 119.92 [ 351.740234] lustre_kcompr_7442: compr lz4hc(8) in 2048kB chunks took 1117967 us (3 MB/s), compress ratio: 120.48 [ 352.680179] lustre_kcompr_7442: compr lz4hc(8) in 4096kB chunks took 926579 us (4 MB/s), compress ratio: 120.73 [ 352.720871] lustre_kcompr_7442: decompr lz4hc(8) took 8599 us (3 MB/s) [ 352.731791] lustre_kcompr_7442: test_comp_compress_decompress(lz4hc,8) ret 0 [ 352.779653] lustre_kcompr_7442: acompr lz4hc(8) of 128kB chunks took 39033 us (3 MB/s), compress ratio: 113.58 [ 352.800792] lustre_kcompr_7442: adecompr lz4hc(8) took 212 us (5 MB/s) [ 352.845454] lustre_kcompr_7442: test_acomp_compress_decompress(lz4hc,8) ret 0 [ 353.761710] Lustre: lustre-OST0001-osc-ffff92e65805f800: disconnect after 22s idle [ 353.769983] Lustre: Skipped 1 previous similar message [ 353.985109] lustre_kcompr_7442: compr lz4hc(15) in 64kB chunks took 1104543 us (3 MB/s), compress ratio: 106.14 [ 355.153905] lustre_kcompr_7442: compr lz4hc(15) in 128kB chunks took 1158198 us (3 MB/s), compress ratio: 113.96 [ 356.779290] lustre_kcompr_7442: compr lz4hc(15) in 256kB chunks took 1614935 us (2 MB/s), compress ratio: 118.14 [ 358.419939] lustre_kcompr_7442: compr lz4hc(15) in 512kB chunks took 1631458 us (2 MB/s), compress ratio: 120.41 [ 359.667600] lustre_kcompr_7442: compr lz4hc(15) in 1024kB chunks took 1242196 us (3 MB/s), compress ratio: 121.58 [ 360.988312] lustre_kcompr_7442: compr lz4hc(15) in 2048kB chunks took 1312408 us (3 MB/s), compress ratio: 122.18 [ 362.624436] lustre_kcompr_7442: compr lz4hc(15) in 4096kB chunks took 1628893 us (2 MB/s), compress ratio: 122.45 [ 362.649749] lustre_kcompr_7442: decompr lz4hc(15) took 3133 us (10 MB/s) [ 362.660770] lustre_kcompr_7442: test_comp_compress_decompress(lz4hc,15) ret 0 [ 362.740246] lustre_kcompr_7442: acompr lz4hc(15) of 128kB chunks took 68468 us (1 MB/s), compress ratio: 114.27 [ 362.750536] lustre_kcompr_7442: adecompr lz4hc(15) took 168 us (6 MB/s) [ 362.772660] lustre_kcompr_7442: test_acomp_compress_decompress(lz4hc,15) ret 0 [ 362.789552] lustre_kcompr_7442: compr lzo(-1) in 64kB chunks took 8901 us (449 MB/s), compress ratio: 82.22 [ 362.802640] lustre_kcompr_7442: compr lzo(-1) in 128kB chunks took 3759 us (1064 MB/s), compress ratio: 87.06 [ 362.810583] lustre_kcompr_7442: compr lzo(-1) in 256kB chunks took 2482 us (1611 MB/s), compress ratio: 87.71 [ 362.819275] lustre_kcompr_7442: compr lzo(-1) in 512kB chunks took 4608 us (868 MB/s), compress ratio: 89.33 [ 362.826711] lustre_kcompr_7442: compr lzo(-1) in 1024kB chunks took 3145 us (1271 MB/s), compress ratio: 88.87 [ 362.834252] lustre_kcompr_7442: compr lzo(-1) in 2048kB chunks took 2578 us (1551 MB/s), compress ratio: 89.56 [ 362.841395] lustre_kcompr_7442: compr lzo(-1) in 4096kB chunks took 2796 us (1430 MB/s), compress ratio: 89.00 [ 362.856496] lustre_kcompr_7442: decompr lzo(-1) took 1796 us (25 MB/s) [ 362.859919] lustre_kcompr_7442: test_comp_compress_decompress(lzo,-1) ret 0 [ 362.863912] lustre_kcompr_7442: acompr lzo(-1) of 128kB chunks took 118 us (1059 MB/s), compress ratio: 83.37 [ 362.867910] lustre_kcompr_7442: adecompr lzo(-1) took 97 us (15 MB/s) [ 362.872983] lustre_kcompr_7442: test_acomp_compress_decompress(lzo,-1) ret 0 [ 363.411300] lustre_kcompr_7442: compr deflate(1) in 64kB chunks took 528814 us (7 MB/s), compress ratio: 98.96 [ 364.023551] lustre_kcompr_7442: compr deflate(1) in 128kB chunks took 594574 us (6 MB/s), compress ratio: 109.32 [ 364.563217] lustre_kcompr_7442: compr deflate(1) in 256kB chunks took 522598 us (7 MB/s), compress ratio: 115.91 [ 365.361290] lustre_kcompr_7442: compr deflate(1) in 512kB chunks took 784234 us (5 MB/s), compress ratio: 119.90 [ 365.813441] lustre_kcompr_7442: compr deflate(1) in 1024kB chunks took 439919 us (9 MB/s), compress ratio: 121.89 [ 366.345925] lustre_kcompr_7442: compr deflate(1) in 2048kB chunks took 527122 us (7 MB/s), compress ratio: 122.88 [ 367.088920] lustre_kcompr_7442: compr deflate(1) in 4096kB chunks took 726524 us (5 MB/s), compress ratio: 123.17 [ 367.133256] lustre_kcompr_7442: decompr deflate(1) took 8327 us (3 MB/s) [ 367.153459] lustre_kcompr_7442: test_comp_compress_decompress(deflate,1) ret 0 [ 367.178284] lustre_kcompr_7442: acompr deflate(1) of 128kB chunks took 19641 us (6 MB/s), compress ratio: 110.32 [ 367.186319] lustre_kcompr_7442: adecompr deflate(1) took 3418 us (0 MB/s) [ 367.191871] lustre_kcompr_7442: test_acomp_compress_decompress(deflate,1) ret 0 [ 367.782505] lustre_kcompr_7442: compr deflate(6) in 64kB chunks took 573491 us (6 MB/s), compress ratio: 98.96 [ 368.304097] lustre_kcompr_7442: compr deflate(6) in 128kB chunks took 509158 us (7 MB/s), compress ratio: 109.32 [ 368.918256] lustre_kcompr_7442: compr deflate(6) in 256kB chunks took 601274 us (6 MB/s), compress ratio: 115.91 [ 369.521542] lustre_kcompr_7442: compr deflate(6) in 512kB chunks took 596475 us (6 MB/s), compress ratio: 119.90 [ 370.009288] lustre_kcompr_7442: compr deflate(6) in 1024kB chunks took 475461 us (8 MB/s), compress ratio: 121.89 [ 370.536882] lustre_kcompr_7442: compr deflate(6) in 2048kB chunks took 518061 us (7 MB/s), compress ratio: 122.88 [ 371.079425] lustre_kcompr_7442: compr deflate(6) in 4096kB chunks took 532774 us (7 MB/s), compress ratio: 123.17 [ 371.108665] lustre_kcompr_7442: decompr deflate(6) took 14221 us (2 MB/s) [ 371.126401] lustre_kcompr_7442: test_comp_compress_decompress(deflate,6) ret 0 [ 371.159905] lustre_kcompr_7442: acompr deflate(6) of 128kB chunks took 22844 us (5 MB/s), compress ratio: 110.32 [ 371.175773] lustre_kcompr_7442: adecompr deflate(6) took 186 us (6 MB/s) [ 371.197712] lustre_kcompr_7442: test_acomp_compress_decompress(deflate,6) ret 0 [ 371.784969] lustre_kcompr_7442: compr deflate(9) in 64kB chunks took 574300 us (6 MB/s), compress ratio: 98.96 [ 372.309398] lustre_kcompr_7442: compr deflate(9) in 128kB chunks took 519725 us (7 MB/s), compress ratio: 109.32 [ 372.791898] lustre_kcompr_7442: compr deflate(9) in 256kB chunks took 473593 us (8 MB/s), compress ratio: 115.91 [ 373.208366] lustre_kcompr_7442: compr deflate(9) in 512kB chunks took 404524 us (9 MB/s), compress ratio: 119.90 [ 373.573303] lustre_kcompr_7442: compr deflate(9) in 1024kB chunks took 356321 us (11 MB/s), compress ratio: 121.89 [ 373.911586] lustre_kcompr_7442: compr deflate(9) in 2048kB chunks took 332758 us (12 MB/s), compress ratio: 122.88 [ 374.187907] lustre_kcompr_7442: compr deflate(9) in 4096kB chunks took 271874 us (14 MB/s), compress ratio: 123.17 [ 374.204778] lustre_kcompr_7442: decompr deflate(9) took 4636 us (7 MB/s) [ 374.210786] lustre_kcompr_7442: test_comp_compress_decompress(deflate,9) ret 0 [ 374.228495] lustre_kcompr_7442: acompr deflate(9) of 128kB chunks took 12581 us (9 MB/s), compress ratio: 110.32 [ 374.235867] lustre_kcompr_7442: adecompr deflate(9) took 340 us (3 MB/s) [ 374.247190] lustre_kcompr_7442: test_acomp_compress_decompress(deflate,9) ret 0 [ 374.265665] lustre_kcompr_7442: compr zstd(0) in 64kB chunks took 6253 us (639 MB/s), compress ratio: 161.06 [ 374.286296] lustre_kcompr_7442: compr zstd(0) in 128kB chunks took 15607 us (256 MB/s), compress ratio: 195.17 [ 374.306957] lustre_kcompr_7442: compr zstd(0) in 256kB chunks took 15520 us (257 MB/s), compress ratio: 207.42 [ 374.349745] lustre_kcompr_7442: compr zstd(0) in 512kB chunks took 32560 us (122 MB/s), compress ratio: 214.32 [ 374.365688] lustre_kcompr_7442: compr zstd(0) in 1024kB chunks took 11870 us (336 MB/s), compress ratio: 218.02 [ 374.380614] lustre_kcompr_7442: compr zstd(0) in 2048kB chunks took 7868 us (508 MB/s), compress ratio: 220.03 [ 374.395397] lustre_kcompr_7442: compr zstd(0) in 4096kB chunks took 5333 us (750 MB/s), compress ratio: 220.02 [ 374.408308] lustre_kcompr_7442: decompr zstd(0) took 2671 us (6 MB/s) [ 374.412742] lustre_kcompr_7442: test_comp_compress_decompress(zstd,0) ret 0 [ 374.417499] lustre_kcompr_7442: acompr zstd(0) of 128kB chunks took 237 us (527 MB/s), compress ratio: 197.99 [ 374.421728] lustre_kcompr_7442: adecompr zstd(0) took 108 us (5 MB/s) [ 374.427489] lustre_kcompr_7442: test_acomp_compress_decompress(zstd,0) ret 0 [ 374.446256] lustre_kcompr_7442: compr zstd(1) in 64kB chunks took 4961 us (806 MB/s), compress ratio: 159.92 [ 374.460919] lustre_kcompr_7442: compr zstd(1) in 128kB chunks took 5235 us (764 MB/s), compress ratio: 193.59 [ 374.476486] lustre_kcompr_7442: compr zstd(1) in 256kB chunks took 8165 us (489 MB/s), compress ratio: 204.73 [ 374.493245] lustre_kcompr_7442: compr zstd(1) in 512kB chunks took 6916 us (578 MB/s), compress ratio: 212.24 [ 374.506310] lustre_kcompr_7442: compr zstd(1) in 1024kB chunks took 4901 us (816 MB/s), compress ratio: 214.52 [ 374.522059] lustre_kcompr_7442: compr zstd(1) in 2048kB chunks took 6264 us (638 MB/s), compress ratio: 215.41 [ 374.536131] lustre_kcompr_7442: compr zstd(1) in 4096kB chunks took 2260 us (1769 MB/s), compress ratio: 215.97 [ 374.565461] lustre_kcompr_7442: decompr zstd(1) took 5121 us (3 MB/s) [ 374.577371] lustre_kcompr_7442: test_comp_compress_decompress(zstd,1) ret 0 [ 374.593716] lustre_kcompr_7442: acompr zstd(1) of 128kB chunks took 160 us (781 MB/s), compress ratio: 197.99 [ 374.605810] lustre_kcompr_7442: adecompr zstd(1) took 512 us (1 MB/s) [ 374.616793] lustre_kcompr_7442: test_acomp_compress_decompress(zstd,1) ret 0 [ 374.811313] lustre_kcompr_7442: compr zstd(10) in 64kB chunks took 168202 us (23 MB/s), compress ratio: 163.63 [ 374.972379] lustre_kcompr_7442: compr zstd(10) in 128kB chunks took 154508 us (25 MB/s), compress ratio: 187.09 [ 375.220272] lustre_kcompr_7442: compr zstd(10) in 256kB chunks took 238473 us (16 MB/s), compress ratio: 199.27 [ 375.443377] lustre_kcompr_7442: compr zstd(10) in 512kB chunks took 211835 us (18 MB/s), compress ratio: 221.16 [ 375.754039] lustre_kcompr_7442: compr zstd(10) in 1024kB chunks took 304950 us (13 MB/s), compress ratio: 226.02 [ 376.237235] lustre_kcompr_7442: compr zstd(10) in 2048kB chunks took 478344 us (8 MB/s), compress ratio: 228.47 [ 376.305140] lustre_kcompr_7442: compr zstd(10) in 4096kB chunks took 57656 us (69 MB/s), compress ratio: 229.53 [ 376.324299] lustre_kcompr_7442: decompr zstd(10) took 3366 us (5 MB/s) [ 376.333307] lustre_kcompr_7442: test_comp_compress_decompress(zstd,10) ret 0 [ 376.342118] lustre_kcompr_7442: acompr zstd(10) of 128kB chunks took 3148 us (39 MB/s), compress ratio: 189.95 [ 376.350967] lustre_kcompr_7442: adecompr zstd(10) took 111 us (5 MB/s) [ 376.379681] lustre_kcompr_7442: test_acomp_compress_decompress(zstd,10) ret 0 [ 377.217256] lustre_kcompr_7442: compr zstd(15) in 64kB chunks took 809890 us (4 MB/s), compress ratio: 172.33 [ 377.952985] lustre_kcompr_7442: compr zstd(15) in 128kB chunks took 718725 us (5 MB/s), compress ratio: 198.95 [ 378.679611] lustre_kcompr_7442: compr zstd(15) in 256kB chunks took 715970 us (5 MB/s), compress ratio: 213.29 [ 379.292819] lustre_kcompr_7442: compr zstd(15) in 512kB chunks took 607248 us (6 MB/s), compress ratio: 219.47 [ 380.014835] lustre_kcompr_7442: compr zstd(15) in 1024kB chunks took 715319 us (5 MB/s), compress ratio: 222.41 [ 381.369140] lustre_kcompr_7442: compr zstd(15) in 2048kB chunks took 1339830 us (2 MB/s), compress ratio: 224.15 [ 383.285773] lustre_kcompr_7442: compr zstd(15) in 4096kB chunks took 1908489 us (2 MB/s), compress ratio: 224.57 [ 383.317573] lustre_kcompr_7442: decompr zstd(15) took 8883 us (2 MB/s) [ 383.325755] lustre_kcompr_7442: test_comp_compress_decompress(zstd,15) ret 0 [ 383.355714] lustre_kcompr_7442: acompr zstd(15) of 128kB chunks took 24874 us (5 MB/s), compress ratio: 201.33 [ 383.364325] lustre_kcompr_7442: adecompr zstd(15) took 107 us (5 MB/s) [ 383.373115] lustre_kcompr_7442: test_acomp_compress_decompress(zstd,15) ret 0 [ 383.394785] lustre_kcompr_7442: compr zstdfast(1) in 64kB chunks took 7312 us (547 MB/s), compress ratio: 160.05 [ 383.406243] lustre_kcompr_7442: compr zstdfast(1) in 128kB chunks took 3694 us (1082 MB/s), compress ratio: 193.59 [ 383.421380] lustre_kcompr_7442: compr zstdfast(1) in 256kB chunks took 6999 us (571 MB/s), compress ratio: 204.72 [ 383.430461] lustre_kcompr_7442: compr zstdfast(1) in 512kB chunks took 2919 us (1370 MB/s), compress ratio: 212.27 [ 383.448924] lustre_kcompr_7442: compr zstdfast(1) in 1024kB chunks took 4483 us (892 MB/s), compress ratio: 214.50 [ 383.462241] lustre_kcompr_7442: compr zstdfast(1) in 2048kB chunks took 7508 us (532 MB/s), compress ratio: 214.98 [ 383.471945] lustre_kcompr_7442: compr zstdfast(1) in 4096kB chunks took 2751 us (1454 MB/s), compress ratio: 215.98 [ 383.495919] lustre_kcompr_7442: decompr zstdfast(1) took 5763 us (3 MB/s) [ 383.511907] lustre_kcompr_7442: test_comp_compress_decompress(zstdfast,1) ret 0 [ 383.523784] lustre_kcompr_7442: acompr zstdfast(1) of 128kB chunks took 215 us (581 MB/s), compress ratio: 197.99 [ 383.537294] lustre_kcompr_7442: adecompr zstdfast(1) took 105 us (6 MB/s) [ 383.547200] lustre_kcompr_7442: test_acomp_compress_decompress(zstdfast,1) ret 0 [ 383.568376] lustre_kcompr_7442: compr zstdfast(5) in 64kB chunks took 3999 us (1000 MB/s), compress ratio: 141.66 [ 383.581586] lustre_kcompr_7442: compr zstdfast(5) in 128kB chunks took 3167 us (1263 MB/s), compress ratio: 166.31 [ 383.602491] lustre_kcompr_7442: compr zstdfast(5) in 256kB chunks took 8560 us (467 MB/s), compress ratio: 176.79 [ 383.617618] lustre_kcompr_7442: compr zstdfast(5) in 512kB chunks took 3197 us (1251 MB/s), compress ratio: 180.77 [ 383.625557] lustre_kcompr_7442: compr zstdfast(5) in 1024kB chunks took 2215 us (1805 MB/s), compress ratio: 183.80 [ 383.635228] lustre_kcompr_7442: compr zstdfast(5) in 2048kB chunks took 3537 us (1130 MB/s), compress ratio: 184.20 [ 383.642568] lustre_kcompr_7442: compr zstdfast(5) in 4096kB chunks took 2100 us (1904 MB/s), compress ratio: 183.85 [ 383.663183] lustre_kcompr_7442: decompr zstdfast(5) took 2764 us (7 MB/s) [ 383.670749] lustre_kcompr_7442: test_comp_compress_decompress(zstdfast,5) ret 0 [ 383.679577] lustre_kcompr_7442: acompr zstdfast(5) of 128kB chunks took 168 us (744 MB/s), compress ratio: 174.29 [ 383.685776] lustre_kcompr_7442: adecompr zstdfast(5) took 192 us (3 MB/s) [ 383.705649] lustre_kcompr_7442: test_acomp_compress_decompress(zstdfast,5) ret 0 [ 383.744446] lustre_kcompr_7442: compr zstdfast(12) in 64kB chunks took 2439 us (1640 MB/s), compress ratio: 38.59 [ 383.755722] lustre_kcompr_7442: compr zstdfast(12) in 128kB chunks took 2401 us (1665 MB/s), compress ratio: 44.32 [ 383.764196] lustre_kcompr_7442: compr zstdfast(12) in 256kB chunks took 3099 us (1290 MB/s), compress ratio: 48.26 [ 383.780706] lustre_kcompr_7442: compr zstdfast(12) in 512kB chunks took 3834 us (1043 MB/s), compress ratio: 51.12 [ 383.790885] lustre_kcompr_7442: compr zstdfast(12) in 1024kB chunks took 4192 us (954 MB/s), compress ratio: 63.23 [ 383.798687] lustre_kcompr_7442: compr zstdfast(12) in 2048kB chunks took 2619 us (1527 MB/s), compress ratio: 74.30 [ 383.807962] lustre_kcompr_7442: compr zstdfast(12) in 4096kB chunks took 2666 us (1500 MB/s), compress ratio: 75.48 [ 383.823557] lustre_kcompr_7442: decompr zstdfast(12) took 2247 us (23 MB/s) [ 383.830280] lustre_kcompr_7442: test_comp_compress_decompress(zstdfast,12) ret 0 [ 383.838489] lustre_kcompr_7442: acompr zstdfast(12) of 128kB chunks took 149 us (838 MB/s), compress ratio: 44.47 [ 383.846608] lustre_kcompr_7442: adecompr zstdfast(12) took 949 us (2 MB/s) [ 383.857429] lustre_kcompr_7442: test_acomp_compress_decompress(zstdfast,12) ret 0 [ 383.874030] lustre_kcompr_7442: compr zstdfast(15) in 64kB chunks took 2428 us (1647 MB/s), compress ratio: 19.40 [ 383.886919] lustre_kcompr_7442: compr zstdfast(15) in 128kB chunks took 3972 us (1007 MB/s), compress ratio: 20.44 [ 383.900623] lustre_kcompr_7442: compr zstdfast(15) in 256kB chunks took 3368 us (1187 MB/s), compress ratio: 22.17 [ 383.914474] lustre_kcompr_7442: compr zstdfast(15) in 512kB chunks took 4666 us (857 MB/s), compress ratio: 24.55 [ 383.923430] lustre_kcompr_7442: compr zstdfast(15) in 1024kB chunks took 3740 us (1069 MB/s), compress ratio: 27.12 [ 383.939761] lustre_kcompr_7442: compr zstdfast(15) in 2048kB chunks took 3107 us (1287 MB/s), compress ratio: 31.25 [ 383.954557] lustre_kcompr_7442: compr zstdfast(15) in 4096kB chunks took 7941 us (503 MB/s), compress ratio: 36.58 [ 383.977705] lustre_kcompr_7442: decompr zstdfast(15) took 2607 us (41 MB/s) [ 383.986855] lustre_kcompr_7442: test_comp_compress_decompress(zstdfast,15) ret 0 [ 383.994486] lustre_kcompr_7442: acompr zstdfast(15) of 128kB chunks took 801 us (156 MB/s), compress ratio: 19.67 [ 384.001177] lustre_kcompr_7442: adecompr zstdfast(15) took 140 us (45 MB/s) [ 384.011744] lustre_kcompr_7442: test_acomp_compress_decompress(zstdfast,15) ret 0 [ 384.015676] lustre_kcompr_7442: SUCCESS [ 391.515537] lustre_kcompr_13674: ***** [ 391.520355] lustre_kcompr_13674: (de)compression test on provided file /tmp/f84.sanity [ 391.525285] lustre_kcompr_13674: ***** [ 391.557772] lustre_kcompr_13674: compr lz4(1) in 64kB chunks took 18301 us (218 MB/s), compress ratio: 2.93 [ 391.594127] lustre_kcompr_13674: compr lz4(1) in 128kB chunks took 29510 us (135 MB/s), compress ratio: 3.04 [ 391.649351] lustre_kcompr_13674: compr lz4(1) in 256kB chunks took 47190 us (84 MB/s), compress ratio: 3.08 [ 391.718413] lustre_kcompr_13674: compr lz4(1) in 512kB chunks took 56137 us (71 MB/s), compress ratio: 3.10 [ 391.770987] lustre_kcompr_13674: compr lz4(1) in 1024kB chunks took 37298 us (107 MB/s), compress ratio: 3.11 [ 391.839521] lustre_kcompr_13674: compr lz4(1) in 2048kB chunks took 61704 us (64 MB/s), compress ratio: 3.11 [ 391.891317] lustre_kcompr_13674: compr lz4(1) in 4096kB chunks took 42274 us (94 MB/s), compress ratio: 3.12 [ 391.910710] lustre_kcompr_13674: decompr lz4(1) took 4736 us (270 MB/s) [ 391.916085] lustre_kcompr_13674: test_comp_compress_decompress(lz4,1) ret 0 [ 391.923508] lustre_kcompr_13674: acompr lz4(1) of 128kB chunks took 650 us (192 MB/s), compress ratio: 2.68 [ 391.932365] lustre_kcompr_13674: adecompr lz4(1) took 739 us (63 MB/s) [ 391.938862] lustre_kcompr_13674: test_acomp_compress_decompress(lz4,1) ret 0 [ 392.008545] lustre_kcompr_13674: compr lz4(4) in 64kB chunks took 47211 us (84 MB/s), compress ratio: 2.67 [ 392.068139] lustre_kcompr_13674: compr lz4(4) in 128kB chunks took 45358 us (88 MB/s), compress ratio: 2.63 [ 392.109383] lustre_kcompr_13674: compr lz4(4) in 256kB chunks took 31274 us (127 MB/s), compress ratio: 2.68 [ 392.144086] lustre_kcompr_13674: compr lz4(4) in 512kB chunks took 27893 us (143 MB/s), compress ratio: 2.71 [ 392.187740] lustre_kcompr_13674: compr lz4(4) in 1024kB chunks took 37709 us (106 MB/s), compress ratio: 2.72 [ 392.226961] lustre_kcompr_13674: compr lz4(4) in 2048kB chunks took 31911 us (125 MB/s), compress ratio: 2.72 [ 392.255769] lustre_kcompr_13674: compr lz4(4) in 4096kB chunks took 21816 us (183 MB/s), compress ratio: 2.73 [ 392.275805] lustre_kcompr_13674: decompr lz4(4) took 7079 us (206 MB/s) [ 392.281100] lustre_kcompr_13674: test_comp_compress_decompress(lz4,4) ret 0 [ 392.292520] lustre_kcompr_13674: acompr lz4(4) of 128kB chunks took 3561 us (35 MB/s), compress ratio: 2.30 [ 392.301071] lustre_kcompr_13674: adecompr lz4(4) took 327 us (165 MB/s) [ 392.309983] lustre_kcompr_13674: test_acomp_compress_decompress(lz4,4) ret 0 [ 392.360804] lustre_kcompr_13674: compr lz4(9) in 64kB chunks took 33907 us (117 MB/s), compress ratio: 2.30 [ 392.396960] lustre_kcompr_13674: compr lz4(9) in 128kB chunks took 29501 us (135 MB/s), compress ratio: 2.15 [ 392.430334] lustre_kcompr_13674: compr lz4(9) in 256kB chunks took 25543 us (156 MB/s), compress ratio: 2.20 [ 392.483697] lustre_kcompr_13674: compr lz4(9) in 512kB chunks took 33958 us (117 MB/s), compress ratio: 2.23 [ 392.521979] lustre_kcompr_13674: compr lz4(9) in 1024kB chunks took 27808 us (143 MB/s), compress ratio: 2.25 [ 392.545215] lustre_kcompr_13674: compr lz4(9) in 2048kB chunks took 17246 us (231 MB/s), compress ratio: 2.25 [ 392.564388] lustre_kcompr_13674: compr lz4(9) in 4096kB chunks took 14493 us (275 MB/s), compress ratio: 2.26 [ 392.580183] lustre_kcompr_13674: decompr lz4(9) took 6587 us (268 MB/s) [ 392.584230] lustre_kcompr_13674: test_comp_compress_decompress(lz4,9) ret 0 [ 392.588589] lustre_kcompr_13674: acompr lz4(9) of 128kB chunks took 937 us (133 MB/s), compress ratio: 1.87 [ 392.593368] lustre_kcompr_13674: adecompr lz4(9) took 178 us (374 MB/s) [ 392.598384] lustre_kcompr_13674: test_acomp_compress_decompress(lz4,9) ret 0 [ 392.619635] lustre_kcompr_13674: compr lz4(15) in 64kB chunks took 11885 us (336 MB/s), compress ratio: 1.63 [ 392.641115] lustre_kcompr_13674: compr lz4(15) in 128kB chunks took 14392 us (277 MB/s), compress ratio: 1.52 [ 392.676192] lustre_kcompr_13674: compr lz4(15) in 256kB chunks took 27762 us (144 MB/s), compress ratio: 1.56 [ 392.721355] lustre_kcompr_13674: compr lz4(15) in 512kB chunks took 33512 us (119 MB/s), compress ratio: 1.58 [ 392.751978] lustre_kcompr_13674: compr lz4(15) in 1024kB chunks took 19460 us (205 MB/s), compress ratio: 1.60 [ 392.775565] lustre_kcompr_13674: compr lz4(15) in 2048kB chunks took 17047 us (234 MB/s), compress ratio: 1.60 [ 392.804796] lustre_kcompr_13674: compr lz4(15) in 4096kB chunks took 22256 us (179 MB/s), compress ratio: 1.60 [ 392.841830] lustre_kcompr_13674: decompr lz4(15) took 12834 us (193 MB/s) [ 392.847536] lustre_kcompr_13674: test_comp_compress_decompress(lz4,15) ret 0 [ 392.856678] lustre_kcompr_13674: acompr lz4(15) of 128kB chunks took 433 us (288 MB/s), compress ratio: 1.33 [ 392.867140] lustre_kcompr_13674: adecompr lz4(15) took 259 us (362 MB/s) [ 392.876744] lustre_kcompr_13674: test_acomp_compress_decompress(lz4,15) ret 0 [ 393.108763] lustre_kcompr_13674: compr lz4hc(1) in 64kB chunks took 205439 us (19 MB/s), compress ratio: 3.58 [ 393.343225] lustre_kcompr_13674: compr lz4hc(1) in 128kB chunks took 219261 us (18 MB/s), compress ratio: 3.77 [ 393.500477] lustre_kcompr_13674: compr lz4hc(1) in 256kB chunks took 140543 us (28 MB/s), compress ratio: 3.88 [ 393.723642] lustre_kcompr_13674: compr lz4hc(1) in 512kB chunks took 215697 us (18 MB/s), compress ratio: 3.93 [ 394.032509] lustre_kcompr_13674: compr lz4hc(1) in 1024kB chunks took 289021 us (13 MB/s), compress ratio: 3.96 [ 394.269263] lustre_kcompr_13674: compr lz4hc(1) in 2048kB chunks took 227029 us (17 MB/s), compress ratio: 3.97 [ 394.560141] lustre_kcompr_13674: compr lz4hc(1) in 4096kB chunks took 280613 us (14 MB/s), compress ratio: 3.98 [ 394.592332] lustre_kcompr_13674: decompr lz4hc(1) took 15005 us (66 MB/s) [ 394.599469] lustre_kcompr_13674: test_comp_compress_decompress(lz4hc,1) ret 0 [ 394.619859] lustre_kcompr_13674: acompr lz4hc(1) of 128kB chunks took 10987 us (11 MB/s), compress ratio: 3.27 [ 394.627785] lustre_kcompr_13674: adecompr lz4hc(1) took 249 us (153 MB/s) [ 394.646771] lustre_kcompr_13674: test_acomp_compress_decompress(lz4hc,1) ret 0 [ 395.053866] lustre_kcompr_13674: compr lz4hc(3) in 64kB chunks took 387932 us (10 MB/s), compress ratio: 3.68 [ 395.430922] lustre_kcompr_13674: compr lz4hc(3) in 128kB chunks took 364853 us (10 MB/s), compress ratio: 3.91 [ 395.853462] lustre_kcompr_13674: compr lz4hc(3) in 256kB chunks took 415587 us (9 MB/s), compress ratio: 4.04 [ 396.278356] lustre_kcompr_13674: compr lz4hc(3) in 512kB chunks took 402725 us (9 MB/s), compress ratio: 4.10 [ 396.631844] lustre_kcompr_13674: compr lz4hc(3) in 1024kB chunks took 343120 us (11 MB/s), compress ratio: 4.13 [ 396.875313] lustre_kcompr_13674: compr lz4hc(3) in 2048kB chunks took 234853 us (17 MB/s), compress ratio: 4.15 [ 397.214881] lustre_kcompr_13674: compr lz4hc(3) in 4096kB chunks took 335268 us (11 MB/s), compress ratio: 4.16 [ 397.252764] lustre_kcompr_13674: decompr lz4hc(3) took 12603 us (76 MB/s) [ 397.258149] lustre_kcompr_13674: test_comp_compress_decompress(lz4hc,3) ret 0 [ 397.274275] lustre_kcompr_13674: acompr lz4hc(3) of 128kB chunks took 9133 us (13 MB/s), compress ratio: 3.38 [ 397.281191] lustre_kcompr_13674: adecompr lz4hc(3) took 207 us (178 MB/s) [ 397.287410] lustre_kcompr_13674: test_acomp_compress_decompress(lz4hc,3) ret 0 [ 397.779940] lustre_kcompr_13674: compr lz4hc(8) in 64kB chunks took 462709 us (8 MB/s), compress ratio: 3.72 [ 398.410482] lustre_kcompr_13674: compr lz4hc(8) in 128kB chunks took 618546 us (6 MB/s), compress ratio: 3.96 [ 398.978970] lustre_kcompr_13674: compr lz4hc(8) in 256kB chunks took 550082 us (7 MB/s), compress ratio: 4.10 [ 399.805196] lustre_kcompr_13674: compr lz4hc(8) in 512kB chunks took 819192 us (4 MB/s), compress ratio: 4.17 [ 400.691053] lustre_kcompr_13674: compr lz4hc(8) in 1024kB chunks took 874841 us (4 MB/s), compress ratio: 4.21 [ 401.563438] lustre_kcompr_13674: compr lz4hc(8) in 2048kB chunks took 864153 us (4 MB/s), compress ratio: 4.22 [ 402.094940] lustre_kcompr_13674: compr lz4hc(8) in 4096kB chunks took 518848 us (7 MB/s), compress ratio: 4.23 [ 402.122369] lustre_kcompr_13674: decompr lz4hc(8) took 10380 us (91 MB/s) [ 402.127832] lustre_kcompr_13674: test_comp_compress_decompress(lz4hc,8) ret 0 [ 402.145408] lustre_kcompr_13674: acompr lz4hc(8) of 128kB chunks took 11998 us (10 MB/s), compress ratio: 3.42 [ 402.151279] lustre_kcompr_13674: adecompr lz4hc(8) took 462 us (78 MB/s) [ 402.157515] lustre_kcompr_13674: test_acomp_compress_decompress(lz4hc,8) ret 0 [ 402.649241] lustre_kcompr_13674: compr lz4hc(15) in 64kB chunks took 466449 us (8 MB/s), compress ratio: 3.72 [ 403.203790] lustre_kcompr_13674: compr lz4hc(15) in 128kB chunks took 549497 us (7 MB/s), compress ratio: 3.96 [ 403.864159] lustre_kcompr_13674: compr lz4hc(15) in 256kB chunks took 649957 us (6 MB/s), compress ratio: 4.10 [ 404.617079] lustre_kcompr_13674: compr lz4hc(15) in 512kB chunks took 744693 us (5 MB/s), compress ratio: 4.17 [ 405.499448] lustre_kcompr_13674: compr lz4hc(15) in 1024kB chunks took 865555 us (4 MB/s), compress ratio: 4.21 [ 406.380806] lustre_kcompr_13674: compr lz4hc(15) in 2048kB chunks took 870763 us (4 MB/s), compress ratio: 4.22 [ 407.357238] lustre_kcompr_13674: compr lz4hc(15) in 4096kB chunks took 963072 us (4 MB/s), compress ratio: 4.23 [ 407.383997] lustre_kcompr_13674: decompr lz4hc(15) took 7490 us (126 MB/s) [ 407.389880] lustre_kcompr_13674: test_comp_compress_decompress(lz4hc,15) ret 0 [ 407.419882] lustre_kcompr_13674: acompr lz4hc(15) of 128kB chunks took 20882 us (5 MB/s), compress ratio: 3.42 [ 407.424784] lustre_kcompr_13674: adecompr lz4hc(15) took 256 us (142 MB/s) [ 407.435570] lustre_kcompr_13674: test_acomp_compress_decompress(lz4hc,15) ret 0 [ 407.473231] lustre_kcompr_13674: compr lzo(-1) in 64kB chunks took 33222 us (120 MB/s), compress ratio: 2.90 [ 407.512745] lustre_kcompr_13674: compr lzo(-1) in 128kB chunks took 34039 us (117 MB/s), compress ratio: 2.95 [ 407.563852] lustre_kcompr_13674: compr lzo(-1) in 256kB chunks took 40497 us (98 MB/s), compress ratio: 2.96 [ 407.604429] lustre_kcompr_13674: compr lzo(-1) in 512kB chunks took 31498 us (126 MB/s), compress ratio: 2.98 [ 407.653939] lustre_kcompr_13674: compr lzo(-1) in 1024kB chunks took 36475 us (109 MB/s), compress ratio: 2.97 [ 407.695600] lustre_kcompr_13674: compr lzo(-1) in 2048kB chunks took 33820 us (118 MB/s), compress ratio: 2.97 [ 407.732554] lustre_kcompr_13674: compr lzo(-1) in 4096kB chunks took 29263 us (136 MB/s), compress ratio: 2.96 [ 407.759794] lustre_kcompr_13674: decompr lzo(-1) took 13694 us (98 MB/s) [ 407.764745] lustre_kcompr_13674: test_comp_compress_decompress(lzo,-1) ret 0 [ 407.772905] lustre_kcompr_13674: acompr lzo(-1) of 128kB chunks took 916 us (136 MB/s), compress ratio: 2.62 [ 407.777669] lustre_kcompr_13674: adecompr lzo(-1) took 384 us (124 MB/s) [ 407.788216] lustre_kcompr_13674: test_acomp_compress_decompress(lzo,-1) ret 0 [ 408.800765] lustre_kcompr_13674: compr deflate(1) in 64kB chunks took 997216 us (4 MB/s), compress ratio: 3.51 [ 409.709205] lustre_kcompr_13674: compr deflate(1) in 128kB chunks took 897324 us (4 MB/s), compress ratio: 3.53 [ 410.229189] lustre_kcompr_13674: compr deflate(1) in 256kB chunks took 507715 us (7 MB/s), compress ratio: 3.53 [ 410.928428] lustre_kcompr_13674: compr deflate(1) in 512kB chunks took 693589 us (5 MB/s), compress ratio: 3.54 [ 411.789951] lustre_kcompr_13674: compr deflate(1) in 1024kB chunks took 850035 us (4 MB/s), compress ratio: 3.54 [ 412.585562] lustre_kcompr_13674: compr deflate(1) in 2048kB chunks took 778898 us (5 MB/s), compress ratio: 3.54 [ 413.368025] lustre_kcompr_13674: compr deflate(1) in 4096kB chunks took 768825 us (5 MB/s), compress ratio: 3.54 [ 413.438157] lustre_kcompr_13674: decompr deflate(1) took 51401 us (21 MB/s) [ 413.463916] lustre_kcompr_13674: test_comp_compress_decompress(deflate,1) ret 0 [ 413.507420] lustre_kcompr_13674: acompr deflate(1) of 128kB chunks took 32535 us (3 MB/s), compress ratio: 3.09 [ 413.523782] lustre_kcompr_13674: adecompr deflate(1) took 3533 us (11 MB/s) [ 413.540164] lustre_kcompr_13674: test_acomp_compress_decompress(deflate,1) ret 0 [ 414.343788] lustre_kcompr_13674: compr deflate(6) in 64kB chunks took 775197 us (5 MB/s), compress ratio: 3.51 [ 415.061701] lustre_kcompr_13674: compr deflate(6) in 128kB chunks took 702057 us (5 MB/s), compress ratio: 3.53 [ 415.638650] lustre_kcompr_13674: compr deflate(6) in 256kB chunks took 562897 us (7 MB/s), compress ratio: 3.53 [ 416.405846] lustre_kcompr_13674: compr deflate(6) in 512kB chunks took 761059 us (5 MB/s), compress ratio: 3.54 [ 417.201358] lustre_kcompr_13674: compr deflate(6) in 1024kB chunks took 780898 us (5 MB/s), compress ratio: 3.54 [ 417.956639] lustre_kcompr_13674: compr deflate(6) in 2048kB chunks took 730169 us (5 MB/s), compress ratio: 3.54 [ 418.608499] lustre_kcompr_13674: compr deflate(6) in 4096kB chunks took 645395 us (6 MB/s), compress ratio: 3.54 [ 418.671384] lustre_kcompr_13674: decompr deflate(6) took 47185 us (23 MB/s) [ 418.680213] lustre_kcompr_13674: test_comp_compress_decompress(deflate,6) ret 0 [ 418.713857] lustre_kcompr_13674: acompr deflate(6) of 128kB chunks took 23934 us (5 MB/s), compress ratio: 3.09 [ 418.721696] lustre_kcompr_13674: adecompr deflate(6) took 1868 us (21 MB/s) [ 418.733718] lustre_kcompr_13674: test_acomp_compress_decompress(deflate,6) ret 0 [ 419.513360] lustre_kcompr_13674: compr deflate(9) in 64kB chunks took 763839 us (5 MB/s), compress ratio: 3.51 [ 420.112432] lustre_kcompr_13674: compr deflate(9) in 128kB chunks took 593908 us (6 MB/s), compress ratio: 3.53 [ 420.577942] lustre_kcompr_13674: compr deflate(9) in 256kB chunks took 459060 us (8 MB/s), compress ratio: 3.53 [ 421.185867] lustre_kcompr_13674: compr deflate(9) in 512kB chunks took 602189 us (6 MB/s), compress ratio: 3.54 [ 421.949148] lustre_kcompr_13674: compr deflate(9) in 1024kB chunks took 757528 us (5 MB/s), compress ratio: 3.54 [ 422.733071] lustre_kcompr_13674: compr deflate(9) in 2048kB chunks took 764847 us (5 MB/s), compress ratio: 3.54 [ 423.460601] lustre_kcompr_13674: compr deflate(9) in 4096kB chunks took 718932 us (5 MB/s), compress ratio: 3.54 [ 423.560412] lustre_kcompr_13674: decompr deflate(9) took 76874 us (14 MB/s) [ 423.584717] lustre_kcompr_13674: test_comp_compress_decompress(deflate,9) ret 0 [ 423.634089] lustre_kcompr_13674: acompr deflate(9) of 128kB chunks took 34895 us (3 MB/s), compress ratio: 3.09 [ 423.649756] lustre_kcompr_13674: adecompr deflate(9) took 1235 us (32 MB/s) [ 423.687552] lustre_kcompr_13674: test_acomp_compress_decompress(deflate,9) ret 0 [ 423.823464] lustre_kcompr_13674: compr zstd(0) in 64kB chunks took 113257 us (35 MB/s), compress ratio: 4.28 [ 423.948629] lustre_kcompr_13674: compr zstd(0) in 128kB chunks took 118372 us (33 MB/s), compress ratio: 4.46 [ 424.108587] lustre_kcompr_13674: compr zstd(0) in 256kB chunks took 143061 us (27 MB/s), compress ratio: 4.61 [ 424.320874] lustre_kcompr_13674: compr zstd(0) in 512kB chunks took 204704 us (19 MB/s), compress ratio: 4.72 [ 424.463401] lustre_kcompr_13674: compr zstd(0) in 1024kB chunks took 135802 us (29 MB/s), compress ratio: 4.80 [ 424.747859] lustre_kcompr_13674: compr zstd(0) in 2048kB chunks took 278084 us (14 MB/s), compress ratio: 4.85 [ 424.993518] lustre_kcompr_13674: compr zstd(0) in 4096kB chunks took 238817 us (16 MB/s), compress ratio: 4.88 [ 425.035625] lustre_kcompr_13674: decompr zstd(0) took 27988 us (29 MB/s) [ 425.042820] lustre_kcompr_13674: test_comp_compress_decompress(zstd,0) ret 0 [ 425.058956] lustre_kcompr_13674: acompr zstd(0) of 128kB chunks took 7408 us (16 MB/s), compress ratio: 3.98 [ 425.069325] lustre_kcompr_13674: adecompr zstd(0) took 791 us (39 MB/s) [ 425.080565] lustre_kcompr_13674: test_acomp_compress_decompress(zstd,0) ret 0 [ 425.170477] lustre_kcompr_13674: compr zstd(1) in 64kB chunks took 77911 us (51 MB/s), compress ratio: 4.06 [ 425.229475] lustre_kcompr_13674: compr zstd(1) in 128kB chunks took 50677 us (78 MB/s), compress ratio: 4.20 [ 425.327157] lustre_kcompr_13674: compr zstd(1) in 256kB chunks took 88763 us (45 MB/s), compress ratio: 4.34 [ 425.410804] lustre_kcompr_13674: compr zstd(1) in 512kB chunks took 78075 us (51 MB/s), compress ratio: 4.34 [ 425.518812] lustre_kcompr_13674: compr zstd(1) in 1024kB chunks took 101347 us (39 MB/s), compress ratio: 4.37 [ 425.646850] lustre_kcompr_13674: compr zstd(1) in 2048kB chunks took 122211 us (32 MB/s), compress ratio: 4.39 [ 425.707732] lustre_kcompr_13674: compr zstd(1) in 4096kB chunks took 52778 us (75 MB/s), compress ratio: 4.40 [ 425.770208] lustre_kcompr_13674: decompr zstd(1) took 43084 us (21 MB/s) [ 425.782515] lustre_kcompr_13674: test_comp_compress_decompress(zstd,1) ret 0 [ 425.798355] lustre_kcompr_13674: acompr zstd(1) of 128kB chunks took 2847 us (43 MB/s), compress ratio: 3.73 [ 425.807718] lustre_kcompr_13674: adecompr zstd(1) took 1581 us (21 MB/s) [ 425.815603] lustre_kcompr_13674: test_acomp_compress_decompress(zstd,1) ret 0 [ 427.270921] lustre_kcompr_13674: compr zstd(10) in 64kB chunks took 1434526 us (2 MB/s), compress ratio: 4.80 [ 428.571419] lustre_kcompr_13674: compr zstd(10) in 128kB chunks took 1286225 us (3 MB/s), compress ratio: 5.09 [ 430.989821] lustre_kcompr_13674: compr zstd(10) in 256kB chunks took 2413727 us (1 MB/s), compress ratio: 5.34 [ 432.253685] lustre_kcompr_13674: compr zstd(10) in 512kB chunks took 1256028 us (3 MB/s), compress ratio: 5.45 [ 433.397021] lustre_kcompr_13674: compr zstd(10) in 1024kB chunks took 1134613 us (3 MB/s), compress ratio: 5.62 [ 434.367218] lustre_kcompr_13674: compr zstd(10) in 2048kB chunks took 950943 us (4 MB/s), compress ratio: 5.76 [ 436.852983] lustre_kcompr_13674: compr zstd(10) in 4096kB chunks took 2480086 us (1 MB/s), compress ratio: 5.89 [ 436.954410] lustre_kcompr_13674: decompr zstd(10) took 77407 us (8 MB/s) [ 436.978254] lustre_kcompr_13674: test_comp_compress_decompress(zstd,10) ret 0 [ 437.096457] lustre_kcompr_13674: acompr zstd(10) of 128kB chunks took 107659 us (1 MB/s), compress ratio: 4.46 [ 437.113871] lustre_kcompr_13674: adecompr zstd(10) took 456 us (61 MB/s) [ 437.148887] lustre_kcompr_13674: test_acomp_compress_decompress(zstd,10) ret 0 [ 457.141437] lustre_kcompr_13674: compr zstd(15) in 64kB chunks took 19948884 us (0 MB/s), compress ratio: 4.96 [ 474.193075] lustre_kcompr_13674: compr zstd(15) in 128kB chunks took 17046548 us (0 MB/s), compress ratio: 5.28 [ 488.344129] lustre_kcompr_13674: compr zstd(15) in 256kB chunks took 14144219 us (0 MB/s), compress ratio: 5.57 [ 501.997247] lustre_kcompr_13674: compr zstd(15) in 512kB chunks took 13643549 us (0 MB/s), compress ratio: 5.83 [ 514.436042] lustre_kcompr_13674: compr zstd(15) in 1024kB chunks took 12425413 us (0 MB/s), compress ratio: 6.06 [ 528.447111] lustre_kcompr_13674: compr zstd(15) in 2048kB chunks took 13989165 us (0 MB/s), compress ratio: 6.26