[ 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 489558695 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001011] APIC: Switch to symmetric I/O mode setup [ 0.003119] x2apic enabled [ 0.004008] Switched APIC routing to physical x2apic. [ 0.005015] kvm-guest: setup PV IPIs [ 0.008621] ..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.009019] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010011] pid_max: default: 32768 minimum: 301 [ 0.011123] LSM: Security Framework initializing [ 0.012049] Yama: becoming mindful. [ 0.014003] SELinux: Initializing. [ 0.014964] *** VALIDATE selinux *** [ 0.021740] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026024] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027142] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028101] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029123] *** VALIDATE tmpfs *** [ 0.030406] *** VALIDATE proc *** [ 0.031237] *** VALIDATE cgroup *** [ 0.033009] *** VALIDATE cgroup2 *** [ 0.035062] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.037021] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.038013] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.039028] Spectre V2 : User space: Vulnerable [ 0.040008] Speculative Store Bypass: Vulnerable [ 0.043453] debug: unmapping init [mem 0xffffffffb4259000-0xffffffffb4260fff] [ 0.045161] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.046624] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.047020] ... version: 2 [ 0.048011] ... bit width: 48 [ 0.049009] ... generic registers: 4 [ 0.050010] ... value mask: 0000ffffffffffff [ 0.051011] ... max period: 00007fffffffffff [ 0.052010] ... fixed-purpose events: 3 [ 0.053010] ... event mask: 000000070000000f [ 0.055214] rcu: Hierarchical SRCU implementation. [ 0.057483] smp: Bringing up secondary CPUs ... [ 0.058530] x86: Booting SMP configuration: [ 0.059024] .... node #0, CPUs: #1 #2 #3 [ 0.062481] smp: Brought up 1 node, 4 CPUs [ 0.064013] smpboot: Max logical packages: 1 [ 0.065017] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.242202] node 0 deferred pages initialised in 174ms [ 0.246058] devtmpfs: initialized [ 0.248387] x86/mm: Memory block size: 128MB [ 0.253041] gcov: version magic: 0x41383552 [ 0.256392] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.260111] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.263447] pinctrl core: initialized pinctrl subsystem [ 0.265363] [ 0.265977] ************************************************************* [ 0.269019] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.271018] ** ** [ 0.274022] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.276017] ** ** [ 0.279021] ** This means that this kernel is built to expose internal ** [ 0.282020] ** IOMMU data structures, which may compromise security on ** [ 0.284017] ** your system. ** [ 0.287020] ** ** [ 0.289016] ** If you see this message and you are not debugging the ** [ 0.291019] ** kernel, report this immediately to your vendor! ** [ 0.293016] ** ** [ 0.296021] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.299023] ************************************************************* [ 0.301970] NET: Registered protocol family 16 [ 0.304624] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.307094] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.310084] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.316157] cpuidle: using governor menu [ 0.318000] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.320879] PCI: Using configuration type 1 for base access [ 0.324410] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.336207] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.340033] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.343183] cryptd: max_cpu_qlen set to 1000 [ 0.347372] ACPI: Added _OSI(Module Device) [ 0.349018] ACPI: Added _OSI(Processor Device) [ 0.351022] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.352018] ACPI: Added _OSI(Processor Aggregator Device) [ 0.357522] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.363394] ACPI: Interpreter enabled [ 0.365078] ACPI: PM: (supports S0 S3 S4 S5) [ 0.367019] ACPI: Using IOAPIC for interrupt routing [ 0.369158] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.372449] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.384955] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.388066] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.390028] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.394108] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.400623] acpiphp: Slot [2] registered [ 0.402157] acpiphp: Slot [5] registered [ 0.403114] acpiphp: Slot [6] registered [ 0.404195] acpiphp: Slot [3] registered [ 0.406139] acpiphp: Slot [4] registered [ 0.408126] acpiphp: Slot [7] registered [ 0.409242] acpiphp: Slot [8] registered [ 0.411162] acpiphp: Slot [9] registered [ 0.413156] acpiphp: Slot [10] registered [ 0.415132] acpiphp: Slot [11] registered [ 0.416138] acpiphp: Slot [12] registered [ 0.418140] acpiphp: Slot [13] registered [ 0.420223] acpiphp: Slot [14] registered [ 0.421131] acpiphp: Slot [15] registered [ 0.423162] acpiphp: Slot [16] registered [ 0.425122] acpiphp: Slot [17] registered [ 0.427196] acpiphp: Slot [18] registered [ 0.428134] acpiphp: Slot [19] registered [ 0.430138] acpiphp: Slot [20] registered [ 0.432166] acpiphp: Slot [21] registered [ 0.434133] acpiphp: Slot [22] registered [ 0.435141] acpiphp: Slot [23] registered [ 0.437100] acpiphp: Slot [24] registered [ 0.439208] acpiphp: Slot [25] registered [ 0.442198] acpiphp: Slot [26] registered [ 0.445259] acpiphp: Slot [27] registered [ 0.448250] acpiphp: Slot [28] registered [ 0.451168] acpiphp: Slot [29] registered [ 0.455178] acpiphp: Slot [30] registered [ 0.457177] acpiphp: Slot [31] registered [ 0.459115] PCI host bridge to bus 0000:00 [ 0.462032] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.465036] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.468033] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.472038] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.476049] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.480055] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.483260] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.487263] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.490705] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.499033] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.504066] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.507036] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.510028] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.512029] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.516242] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.518784] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.521065] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.524941] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.528933] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.539022] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.544019] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.550202] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.559016] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.571022] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.590022] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.604294] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.614020] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.625033] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.651031] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.663991] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.668496] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.672548] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.676532] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.680305] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.688088] iommu: Default domain type: Passthrough [ 0.689794] SCSI subsystem initialized [ 0.690441] ACPI: bus type USB registered [ 0.692055] usbcore: registered new interface driver usbfs [ 0.695086] usbcore: registered new interface driver hub [ 0.697271] usbcore: registered new device driver usb [ 0.699184] pps_core: LinuxPPS API ver. 1 registered [ 0.701012] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.705074] PTP clock support registered [ 0.707118] EDAC MC: Ver: 3.0.0 [ 0.709135] PCI: Using ACPI for IRQ routing [ 0.710772] NetLabel: Initializing [ 0.712010] NetLabel: domain hash size = 128 [ 0.714010] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.716079] NetLabel: unlabeled traffic allowed by default [ 0.718130] vgaarb: loaded [ 0.719438] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.721020] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.729586] clocksource: Switched to clocksource kvm-clock [ 0.845263] VFS: Disk quotas dquot_6.6.0 [ 0.847256] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.850254] *** VALIDATE ramfs *** [ 0.851682] *** VALIDATE hugetlbfs *** [ 0.853487] pnp: PnP ACPI init [ 0.856570] pnp: PnP ACPI: found 6 devices [ 0.873209] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.876048] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.877950] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.879717] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.881717] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.883717] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.886144] NET: Registered protocol family 2 [ 0.888275] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.892593] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.895623] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.901429] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.906044] TCP: Hash tables configured (established 65536 bind 65536) [ 0.909824] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.914163] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.917974] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.920611] NET: Registered protocol family 1 [ 0.923207] RPC: Registered named UNIX socket transport module. [ 0.925836] RPC: Registered udp transport module. [ 0.928186] RPC: Registered tcp transport module. [ 0.930393] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.933515] NET: Registered protocol family 44 [ 0.935633] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.937857] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.940557] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.943040] PCI: CLS 0 bytes, default 64 [ 0.944675] Unpacking initramfs... [ 2.384992] debug: unmapping init [mem 0xffff99a83cc64000-0xffff99a83ffcffff] [ 2.390683] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.394164] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.397531] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.912652] Initialise system trusted keyrings [ 2.914691] Key type blacklist registered [ 2.917094] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.926925] zbud: loaded [ 2.930951] *** VALIDATE nfs *** [ 2.932312] *** VALIDATE nfs4 *** [ 2.934299] pstore: using deflate compression [ 2.939613] Platform Keyring initialized [ 3.048400] NET: Registered protocol family 38 [ 3.050403] Key type asymmetric registered [ 3.052286] Asymmetric key parser 'x509' registered [ 3.054736] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.058775] io scheduler mq-deadline registered [ 3.060742] io scheduler kyber registered [ 3.062677] io scheduler bfq registered [ 3.065200] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.069118] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.072449] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.075817] ACPI: Power Button [PWRF] [ 3.081403] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.089693] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.103569] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.132732] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.163885] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.169604] Non-volatile memory driver v1.3 [ 3.171771] Linux agpgart interface v0.103 [ 3.208717] virtio_blk virtio1: [vda] 142768 512-byte logical blocks (73.1 MB/69.7 MiB) [ 3.212078] vda: detected capacity change from 0 to 73097216 [ 3.232651] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.235770] vdb: detected capacity change from 0 to 1073741824 [ 3.247868] libphy: Fixed MDIO Bus: probed [ 3.257114] usbcore: registered new interface driver usbserial_generic [ 3.260080] usbserial: USB Serial support registered for generic [ 3.262851] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.267414] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.269623] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.272676] mousedev: PS/2 mouse device common for all mice [ 3.276531] rtc_cmos 00:05: RTC can wake from S4 [ 3.277195] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.283679] rtc_cmos 00:05: registered as rtc0 [ 3.287763] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.289575] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.294721] intel_pstate: CPU model not supported [ 3.301318] hid: raw HID events driver (C) Jiri Kosina [ 3.303016] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.303888] usbcore: registered new interface driver usbhid [ 3.308539] usbhid: USB HID core driver [ 3.310672] drop_monitor: Initializing network drop monitor service [ 3.312947] Initializing XFRM netlink socket [ 3.315422] NET: Registered protocol family 10 [ 3.318595] Segment Routing with IPv6 [ 3.319899] NET: Registered protocol family 17 [ 3.321675] mpls_gso: MPLS GSO support [ 3.327309] RAS: Correctable Errors collector initialized. [ 3.330090] AVX version of gcm_enc/dec engaged. [ 3.332025] AES CTR mode by8 optimization enabled [ 3.423062] sched_clock: Marking stable (3423032992, 0)->(4291057403, -868024411) [ 3.427179] registered taskstats version 1 [ 3.429362] Loading compiled-in X.509 certificates [ 3.431987] zswap: loaded using pool lzo/zbud [ 3.459080] Key type big_key registered [ 3.471470] Key type encrypted registered [ 3.473938] ima: No TPM chip found, activating TPM-bypass! [ 3.476308] ima: Allocated hash algorithm: sha1 [ 3.478294] ima: No architecture policies found [ 3.480263] evm: Initialising EVM extended attributes: [ 3.482430] evm: security.selinux [ 3.483930] evm: security.ima [ 3.485220] evm: security.capability [ 3.486886] evm: HMAC attrs: 0x1 [ 3.489276] rtc_cmos 00:05: setting system clock to 2026-09-11 07:03:51 UTC (1789110231) [ 3.496078] debug: unmapping init [mem 0xffffffffb5203000-0xffffffffb53fffff] [ 3.499540] debug: unmapping init [mem 0xffffffffb3f82000-0xffffffffb4258fff] [ 3.508091] Write protecting the kernel read-only data: 28672k [ 3.512732] debug: unmapping init [mem 0xffffffffb2603000-0xffffffffb27fffff] [ 3.515547] debug: unmapping init [mem 0xffffffffb2f14000-0xffffffffb2ffffff] [ 3.546344] 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.555082] systemd[1]: Detected virtualization kvm. [ 3.556869] systemd[1]: Detected architecture x86-64. [ 3.558805] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.582429] systemd[1]: No hostname configured. [ 3.584360] systemd[1]: Set hostname to . [ 3.586912] random: systemd: uninitialized urandom read (16 bytes read) [ 3.589484] systemd[1]: Initializing machine ID from random generator. [ 3.616996] random: ln: uninitialized urandom read (6 bytes read) [ 3.721339] random: systemd: uninitialized urandom read (16 bytes read) [ 3.724689] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 3.729786] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 3.735647] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ OK ] Listening on Journal Socket. [ OK ] Started Memstrack Anylazing Service. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Swap. [ OK ] Reached target Timers. [ OK ] Started Dispatch Password Requests to Console Directory Watch. Starting Apply Kernel Variables... [ OK ] Reached target Paths. [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Sockets. Starting Create Volatile Files and Directories... [ OK ] Reached target Slices. [ OK ] Reached target Local Encrypted Volumes. Starting 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 Setup Virtual Console. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.395061] device-mapper: uevent: version 1.0.3 [ 4.397305] 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. [ 5.182506] virtio_net virtio0 ens2: renamed from eth0 [ 5.334634] scsi host0: ata_piix [ 5.381638] scsi host1: ata_piix [ 5.383475] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.386630] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.963596] dracut-initqueue[582]: RTNETLINK answers: File exists [ 9.991411] random: crng init done [ 9.993457] 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. [ 10.443969] 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 Timers. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped dracut initqueue hook. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ 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. Stopping udev Kernel Device Manager... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev 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.667533] printk: systemd: 25 output lines suppressed due to ratelimiting [ 11.933321] SELinux: Disabled at runtime. [ 11.992774] 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) [ 12.003307] systemd[1]: Detected virtualization kvm. [ 12.005546] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.522494] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.527416] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.532055] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.536479] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.539821] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.549708] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.558458] systemd[1]: Mounting POSIX Message Queue File System... Mounting POSIX Message Queue File System... [ OK ] Created slice User and Session Slice. [ OK ] Reached target rpc_pipefs.target. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Listening on udev Kernel Socket. Mounting Kernel Debug File System... [ OK ] Created slice system-serial\x2dgetty.slice. Mounting Huge Pages File System... Starting Apply Kernel Variables... [ OK ] Listening on udev Control Socket. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Starting Remount Root and Kernel File Systems... [ OK ] Listening on Process Core Dump Socket. [ OK ] Created slice system-sshd\x2dkeygen.slice. Activating swap /dev/disk/by-label/SWAP... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Stopped target Initrd File Systems. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Listening on RPCbind Server Activation Socket. Starting Create list of required st…ce nodes for the current kernel... [ 12.715034] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Starting udev Coldplug all Devices... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Reached target RPC Port Mapper. [ OK ] Created slice system-getty.slice. [ OK ] Reached target Slices. [ OK ] Started Journal Service. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted Huge Pages File System. [ 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 ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ 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 ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt.[ 13.070214] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 13.361431] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.486154] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.611285] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.625655] EDAC sbridge: Ver: 1.1.2 [ 14.999811] Key type dns_resolver registered [ 15.316745] NFS: Registering the id_resolver key type [ 15.320396] Key type id_resolver registered [ 15.322073] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... Starting Mark the need to relabel after reboot... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting 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 ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started dnf makecache --timer. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Login Service... [ OK ] Started irqbalance daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting Hostname Service... [ 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 OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg444-client login: [ 46.743027] hrtimer: interrupt took 2080752 ns [ 76.889482] libcfs: loading out-of-tree module taints kernel. [ 77.163491] Key type ._llcrypt registered [ 77.166667] Key type .llcrypt registered [ 78.157580] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 78.172705] alg: No test for adler32 (adler32-zlib) [ 80.316519] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 81.391645] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 83.532765] Key type lgssc registered [ 85.940747] Lustre: Echo OBD driver; http://www.lustre.org/ [ 92.834432] alg: No test for zstdfast (zstdfast-lustre-generic) [ 92.855822] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 272.501610] Lustre: Mounted lustre-client [ 279.271279] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 296.438542] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing check_logdir /tmp/testlogs/ [ 297.963143] Lustre: lustre-OST0000-osc-ffff99a887099000: disconnect after 22s idle [ 301.870404] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing yml_node [ 307.304871] Lustre: DEBUG MARKER: Client: 2.17.53.26 [ 310.970728] Lustre: DEBUG MARKER: MDS: 2.17.53.26 [ 314.872413] Lustre: DEBUG MARKER: OSS: 2.17.53.26 [ 317.004282] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Fri Sep 11 03:09:03 EDT 2026 [ 339.614577] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 341.570883] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 344.459747] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 354.799947] Lustre: Unmounted lustre-client [ 475.677918] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 482.008827] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 508.573925] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 553.772345] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 03:13:00 (1789110780) [ 556.383174] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 558.051896] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 03:13:04 (1789110784) [ 591.570629] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 595.198486] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 617.296570] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 617.813898] Lustre: Mounted lustre-client [ 623.717258] LustreError: 14513:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a88725b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 623.797347] Lustre: Unmounted lustre-client [ 651.237862] Key type lgssc unregistered [ 651.537263] LNet: 14922:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 651.550983] LNetError: 14922:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 651.572896] LNet: Removed LNI 192.168.204.44@tcp [ 652.276607] Key type .llcrypt unregistered [ 652.280171] Key type ._llcrypt unregistered [ 667.369581] Key type ._llcrypt registered [ 667.374187] Key type .llcrypt registered [ 668.862849] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 03:14:55 (1789110895) [ 670.619307] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 670.635987] alg: No test for adler32 (adler32-zlib) [ 671.784275] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 672.243533] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 673.903221] Key type lgssc registered [ 675.293848] Lustre: Echo OBD driver; http://www.lustre.org/ [ 679.539567] alg: No test for zstdfast (zstdfast-lustre-generic) [ 679.553501] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 752.422463] Lustre: Mounted lustre-client [ 839.833124] LustreError: 18341:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a888a19000: lov tgt 1990 not cleaned! deathrow=0, lovrc=1 [ 839.975786] Lustre: Unmounted lustre-client [ 879.906336] Key type lgssc unregistered [ 880.302180] LNet: 18807:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 880.310864] LNetError: 18807:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 880.336727] LNet: Removed LNI 192.168.204.44@tcp [ 881.227895] Key type .llcrypt unregistered [ 881.234217] Key type ._llcrypt unregistered [ 893.692219] Key type ._llcrypt registered [ 893.695343] Key type .llcrypt registered [ 895.298783] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 03:18:41 (1789111121) [ 897.751665] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 897.815041] alg: No test for adler32 (adler32-zlib) [ 898.789882] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 898.963419] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 900.657876] Key type lgssc registered [ 901.814266] Lustre: Echo OBD driver; http://www.lustre.org/ [ 906.560988] alg: No test for zstdfast (zstdfast-lustre-generic) [ 906.569584] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 947.402262] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 951.781680] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 973.244659] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 976.117850] Lustre: Mounted lustre-client [ 987.051047] LustreError: 22018:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a888a18000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 987.129542] Lustre: Unmounted lustre-client [ 1016.005519] Key type lgssc unregistered [ 1016.407438] LNet: 22421:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1016.420710] LNetError: 22421:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1016.447416] LNet: Removed LNI 192.168.204.44@tcp [ 1017.198455] Key type .llcrypt unregistered [ 1017.201424] Key type ._llcrypt unregistered [ 1029.630986] Key type ._llcrypt registered [ 1029.640030] Key type .llcrypt registered [ 1031.255437] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 03:20:57 (1789111257) [ 1033.711416] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1033.730400] alg: No test for adler32 (adler32-zlib) [ 1034.950481] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 1035.242384] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 1036.951291] Key type lgssc registered [ 1038.218436] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1042.228532] alg: No test for zstdfast (zstdfast-lustre-generic) [ 1042.258144] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 1085.237368] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1089.059550] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1106.815444] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1108.659492] Lustre: Mounted lustre-client [ 1114.449047] LustreError: 25212:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a89170b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1114.551786] Lustre: Unmounted lustre-client [ 1143.627375] Key type lgssc unregistered [ 1144.029239] LNet: 25616:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1144.035538] LNetError: 25616:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1144.062700] LNet: Removed LNI 192.168.204.44@tcp [ 1144.715220] Key type .llcrypt unregistered [ 1144.717933] Key type ._llcrypt unregistered [ 1161.046263] Key type ._llcrypt registered [ 1161.047974] Key type .llcrypt registered [ 1162.442775] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 03:23:08 (1789111388) [ 1165.140435] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1165.152091] alg: No test for adler32 (adler32-zlib) [ 1166.307675] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 1166.606679] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 1168.287199] Key type lgssc registered [ 1169.447550] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1174.511365] alg: No test for zstdfast (zstdfast-lustre-generic) [ 1174.578201] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 1217.016387] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1220.341655] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1241.151865] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1242.752995] Lustre: Mounted lustre-client [ 1247.738201] Lustre: lustre-MDT0000-mdc-ffff99a883629000: Connection to lustre-MDT0000 (at 192.168.204.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1257.969306] Lustre: lustre-MDT0001-mdc-ffff99a883629000: Connection to lustre-MDT0001 (at 192.168.204.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1265.959888] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1273.247200] Lustre: 26608:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789111485/real 1789111485] req@000000003c1ed0bb x1876019269080960/t0(0) o400->MGC192.168.204.144@tcp@192.168.204.144@tcp:26/25 lens 224/224 e 0 to 1 dl 1789111501 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1273.289329] LustreError: MGC192.168.204.144@tcp: Connection to MGS (at 192.168.204.144@tcp) was lost; in progress operations using this service will fail [ 1276.350631] Lustre: Unmounted lustre-client [ 1295.434751] Key type lgssc unregistered [ 1295.662289] LNet: 29270:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1295.675596] LNetError: 29270:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1295.710844] LNet: Removed LNI 192.168.204.44@tcp [ 1296.175663] Key type .llcrypt unregistered [ 1296.179695] Key type ._llcrypt unregistered [ 1312.532032] Key type ._llcrypt registered [ 1312.535299] Key type .llcrypt registered [ 1314.021679] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 03:25:40 (1789111540) [ 1316.535792] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1316.626371] alg: No test for adler32 (adler32-zlib) [ 1317.768659] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 1317.980868] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 1319.647320] Key type lgssc registered [ 1320.847555] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1324.602742] alg: No test for zstdfast (zstdfast-lustre-generic) [ 1324.618591] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 1364.637192] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1368.329128] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1386.928316] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1392.710212] Lustre: Mounted lustre-client [ 1397.738415] Lustre: lustre-OST0000-osc-ffff99a88725d000: Connection to lustre-OST0000 (at 192.168.204.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1405.028480] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1415.399980] Lustre: Unmounted lustre-client [ 1445.050427] Key type lgssc unregistered [ 1445.325661] LNet: 32874:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1445.347368] LNetError: 32874:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1445.366237] LNet: Removed LNI 192.168.204.44@tcp [ 1446.127357] Key type .llcrypt unregistered [ 1446.132173] Key type ._llcrypt unregistered [ 1462.910098] Key type ._llcrypt registered [ 1462.919528] Key type .llcrypt registered [ 1464.225418] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 03:28:10 (1789111690) [ 1466.812360] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1466.907656] alg: No test for adler32 (adler32-zlib) [ 1468.057857] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 1468.325045] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 1470.047295] Key type lgssc registered [ 1471.281377] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1475.454653] alg: No test for zstdfast (zstdfast-lustre-generic) [ 1475.476766] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 1517.592590] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1521.239341] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1539.061784] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1541.808770] Lustre: Mounted lustre-client [ 1545.187696] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1548.513624] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1560.297661] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-09-11 3h29m46s [ 1563.269852] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1567.718126] Lustre: lustre-MDT0000-mdc-ffff99a8a0675800: Connection to lustre-MDT0000 (at 192.168.204.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1573.902689] LustreError: 36654:0:(lmv_obd.c:1450:lmv_statfs()) lustre-MDT0000-mdc-ffff99a8a0675800: can't stat MDS #0: rc = -19 [ 1575.334500] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1585.134256] LustreError: MGC192.168.204.144@tcp: Connection to MGS (at 192.168.204.144@tcp) was lost; in progress operations using this service will fail [ 1585.155387] Lustre: Evicted from MGS (at 192.168.204.144@tcp) after server handle changed from 0x1beb31aeea2ec1d4 to 0x1beb31aeea2ec497 [ 1585.169410] Lustre: MGC192.168.204.144@tcp: Connection restored to 192.168.204.144@tcp (at 192.168.204.144@tcp) [ 1586.218181] LustreError: lustre-MDT0000-mdc-ffff99a8a0675800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1586.248224] Lustre: lustre-MDT0000-mdc-ffff99a8a0675800: Connection restored to 192.168.204.144@tcp (at 192.168.204.144@tcp) [ 1589.814229] Lustre: DEBUG MARKER: Wait for df (36654) ... [ 1591.271493] Lustre: DEBUG MARKER: done [ 1593.607333] LustreError: 37264:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a8a0675800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1593.662793] Lustre: Unmounted lustre-client [ 1621.070486] Key type lgssc unregistered [ 1621.402809] LNet: 37666:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1621.412461] LNetError: 37666:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1621.433978] LNet: Removed LNI 192.168.204.44@tcp [ 1622.037555] Key type .llcrypt unregistered [ 1622.039431] Key type ._llcrypt unregistered [ 1650.388628] Key type ._llcrypt registered [ 1650.391267] Key type .llcrypt registered [ 1651.896158] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 03:31:18 (1789111878) [ 1654.084190] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1654.099240] alg: No test for adler32 (adler32-zlib) [ 1655.189081] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 1655.403603] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 1657.079681] Key type lgssc registered [ 1658.287828] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1663.942700] alg: No test for zstdfast (zstdfast-lustre-generic) [ 1663.952788] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 1703.850661] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1707.486571] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1725.296725] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1728.033901] Lustre: Mounted lustre-client [ 1732.329951] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-09-11 3h32m39s [ 1734.099156] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1741.618513] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1743.997543] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1764.319198] Lustre: 41800:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789111972/real 1789111972] req@000000004d70f8ed x1876019781835520/t0(0) o101->lustre-MDT0000-mdc-ffff99a890026000@192.168.204.144@tcp:12/10 lens 576/1152 e 0 to 1 dl 1789111992 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1764.353087] Lustre: lustre-MDT0000-mdc-ffff99a890026000: Connection to lustre-MDT0000 (at 192.168.204.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1764.389368] Lustre: lustre-MDT0000-mdc-ffff99a890026000: Connection restored to 192.168.204.144@tcp (at 192.168.204.144@tcp) [ 1785.721639] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1788.434288] Lustre: DEBUG MARKER: done [ 1792.099443] LustreError: 42286:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a890026000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1792.168775] Lustre: Unmounted lustre-client [ 1821.886393] Key type lgssc unregistered [ 1822.225571] LNet: 42689:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1822.236836] LNetError: 42689:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1822.260656] LNet: Removed LNI 192.168.204.44@tcp [ 1822.780687] Key type .llcrypt unregistered [ 1822.782834] Key type ._llcrypt unregistered [ 1851.426617] Key type ._llcrypt registered [ 1851.430753] Key type .llcrypt registered [ 1852.645935] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 03:34:39 (1789112079) [ 1854.156151] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1856.030471] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 03:34:42 (1789112082) [ 1859.715156] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1859.745045] alg: No test for adler32 (adler32-zlib) [ 1861.030506] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 1861.433520] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 1863.256387] Key type lgssc registered [ 1864.799864] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1871.543514] alg: No test for zstdfast (zstdfast-lustre-generic) [ 1871.567614] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 1896.201879] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 03:35:22 (1789112122) [ 1928.069163] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1932.024706] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1951.841182] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1953.522739] Lustre: Mounted lustre-client [ 1956.727584] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1959.915905] LustreError: 47118:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a8a0a58000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1960.001092] Lustre: Unmounted lustre-client [ 1976.756516] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 2020.618549] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2024.926911] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2025.475271] Lustre: Mounted lustre-client [ 2028.960890] LustreError: 48370:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a8841da000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2029.077136] Lustre: Unmounted lustre-client [ 2089.048196] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2093.720971] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2094.394874] Lustre: Mounted lustre-client [ 2098.502382] LustreError: 49484:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a890539000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2098.605283] Lustre: Unmounted lustre-client [ 2121.178938] Lustre: DEBUG MARKER: files compared the same [ 2145.223901] Key type lgssc unregistered [ 2145.695592] LNet: 50190:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2145.706849] LNetError: 50190:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2145.722823] LNet: Removed LNI 192.168.204.44@tcp [ 2146.865126] Key type .llcrypt unregistered [ 2146.870257] Key type ._llcrypt unregistered [ 2166.023415] Key type ._llcrypt registered [ 2166.026801] Key type .llcrypt registered [ 2167.405535] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 03:39:53 (1789112393) [ 2169.964725] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2170.032819] alg: No test for adler32 (adler32-zlib) [ 2171.044630] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 2171.204289] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 2172.879474] Key type lgssc registered [ 2173.901257] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2178.319667] alg: No test for zstdfast (zstdfast-lustre-generic) [ 2178.338427] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 2218.902834] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2223.561733] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2241.964361] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2243.681738] Lustre: Mounted lustre-client [ 2248.199913] LustreError: 53358:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a886a56800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2248.345845] Lustre: Unmounted lustre-client [ 2277.679767] Key type lgssc unregistered [ 2277.968475] LNet: 53763:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2277.987704] LNetError: 53763:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2278.011538] LNet: Removed LNI 192.168.204.44@tcp [ 2278.806948] Key type .llcrypt unregistered [ 2278.812068] Key type ._llcrypt unregistered [ 2295.976829] Key type ._llcrypt registered [ 2295.979873] Key type .llcrypt registered [ 2297.746904] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 03:42:03 (1789112523) [ 2300.473490] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2300.543121] alg: No test for adler32 (adler32-zlib) [ 2301.599546] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 2301.813625] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 2303.552918] Key type lgssc registered [ 2304.771350] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2309.782760] alg: No test for zstdfast (zstdfast-lustre-generic) [ 2309.797687] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 2390.621819] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2426.517078] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2431.685948] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2468.292469] Key type lgssc unregistered [ 2468.784715] LNet: 57249:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2468.798764] LNetError: 57249:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2468.843476] LNet: Removed LNI 192.168.204.44@tcp [ 2469.502594] Key type .llcrypt unregistered [ 2469.510329] Key type ._llcrypt unregistered [ 2486.669289] Key type ._llcrypt registered [ 2486.671770] Key type .llcrypt registered [ 2488.102171] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 03:45:14 (1789112714) [ 2491.057458] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2491.167973] alg: No test for adler32 (adler32-zlib) [ 2492.265531] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 2492.571298] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 2494.335861] Key type lgssc registered [ 2495.684832] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2500.216476] alg: No test for zstdfast (zstdfast-lustre-generic) [ 2500.230658] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 2572.407959] Lustre: Mounted lustre-client [ 2579.862849] LustreError: 59820:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a8841d8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2579.993683] Lustre: Unmounted lustre-client [ 2617.424950] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 03:47:24 (1789112844) [ 2745.925626] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2769.474198] Lustre: Mounted lustre-client [ 2776.394953] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2776.403044] LustreError: 63787:0:(file.c:252:ll_close_inode_openhandle()) lustre-clilmv-ffff99a890ef9800: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2776.441735] Lustre: 58265:0:(llite_lib.c:4187:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.204.144@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2786.793394] Lustre: Unmounted lustre-client [ 2836.550482] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 03:51:02 (1789113062) [ 2850.966541] Key type lgssc unregistered [ 2851.207728] LNet: 65000:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2851.219582] LNetError: 65000:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2851.256447] LNet: Removed LNI 192.168.204.44@tcp [ 2852.021239] Key type .llcrypt unregistered [ 2852.025113] Key type ._llcrypt unregistered [ 2865.725292] Key type ._llcrypt registered [ 2865.729266] Key type .llcrypt registered [ 2866.189437] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2866.217965] alg: No test for adler32 (adler32-zlib) [ 2867.402134] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 2867.711592] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 2869.456993] Key type lgssc registered [ 2870.585950] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2874.738531] alg: No test for zstdfast (zstdfast-lustre-generic) [ 2874.751739] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 2921.776912] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2926.117571] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2945.988517] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2947.623294] Lustre: Mounted lustre-client [ 2952.234977] LustreError: 67607:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a8918b3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2952.325153] Lustre: Unmounted lustre-client [ 2988.261136] Key type lgssc unregistered [ 2988.577806] LNet: 68010:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2988.590560] LNetError: 68010:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2988.610650] LNet: Removed LNI 192.168.204.44@tcp [ 2989.284124] Key type .llcrypt unregistered [ 2989.286403] Key type ._llcrypt unregistered [ 3002.398606] Key type ._llcrypt registered [ 3002.403431] Key type .llcrypt registered [ 3003.015073] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3003.033249] alg: No test for adler32 (adler32-zlib) [ 3004.083753] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 3004.404419] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 3006.111223] Key type lgssc registered [ 3007.709773] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3013.515354] alg: No test for zstdfast (zstdfast-lustre-generic) [ 3013.543890] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 3111.984674] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3116.217169] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3136.235806] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3137.922910] Lustre: Mounted lustre-client [ 3140.888737] LustreError: 71027:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a88901d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3141.047111] Lustre: Unmounted lustre-client [ 3167.897552] Key type lgssc unregistered [ 3168.363958] LNet: 71430:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3168.377731] LNetError: 71430:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3168.397952] LNet: Removed LNI 192.168.204.44@tcp [ 3169.183926] Key type .llcrypt unregistered [ 3169.186733] Key type ._llcrypt unregistered [ 3187.925403] Key type ._llcrypt registered [ 3187.933817] Key type .llcrypt registered [ 3189.495958] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 03:56:55 (1789113415) [ 3191.838662] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3191.860900] alg: No test for adler32 (adler32-zlib) [ 3193.158808] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 3193.426121] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 3195.215224] Key type lgssc registered [ 3196.657201] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3203.341602] alg: No test for zstdfast (zstdfast-lustre-generic) [ 3203.353835] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 3250.602964] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3256.031924] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3279.750977] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3281.279989] Lustre: Mounted lustre-client [ 3290.239675] LustreError: 74631:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a8a0a40000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3290.352045] Lustre: Unmounted lustre-client [ 3290.911778] Lustre: Mounted lustre-client [ 3294.871183] LustreError: 74705:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a8a0a5c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3294.985634] Lustre: Unmounted lustre-client [ 3323.267931] Key type lgssc unregistered [ 3323.527870] LNet: 75108:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3323.548559] LNetError: 75108:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3323.561248] LNet: Removed LNI 192.168.204.44@tcp [ 3324.272644] Key type .llcrypt unregistered [ 3324.275916] Key type ._llcrypt unregistered [ 3336.642272] Key type ._llcrypt registered [ 3336.660274] Key type .llcrypt registered [ 3337.142251] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3337.161569] alg: No test for adler32 (adler32-zlib) [ 3338.295738] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 3338.628113] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 3340.407276] Key type lgssc registered [ 3341.851229] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3347.872205] alg: No test for zstdfast (zstdfast-lustre-generic) [ 3347.902303] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 3397.424680] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3401.411950] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3421.123172] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3423.772678] Lustre: Mounted lustre-client [ 3427.999536] LustreError: 77729:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a886484800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3428.096810] Lustre: Unmounted lustre-client [ 3455.050536] Key type lgssc unregistered [ 3455.432939] LNet: 78146:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3455.442942] LNetError: 78146:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3456.491884] LNet: Removed LNI 192.168.204.44@tcp [ 3457.244307] Key type .llcrypt unregistered [ 3457.251168] Key type ._llcrypt unregistered [ 3475.736649] Key type ._llcrypt registered [ 3475.744528] Key type .llcrypt registered [ 3476.973676] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 04:01:43 (1789113703) [ 3479.727886] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3479.802126] alg: No test for adler32 (adler32-zlib) [ 3481.011419] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 3481.180944] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 3482.847223] Key type lgssc registered [ 3484.171662] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3488.460357] alg: No test for zstdfast (zstdfast-lustre-generic) [ 3488.495944] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 3533.591517] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3537.856177] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3559.537317] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3562.251562] Lustre: Mounted lustre-client [ 3568.360453] Lustre: lustre: root_squash is set to 0:0 [ 3575.865968] Lustre: lustre: nosquash_nids is cleared [ 3575.894258] Lustre: lustre: root_squash is set to 500:500 [ 3592.762711] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.204.44@tcp 192.168.0.[2,10]@tcp [ 3607.100532] Lustre: lustre: nosquash_nids set to 192.168.204.44/24@tcp [ 3610.490368] LustreError: 81720:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a8862e1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3610.599847] Lustre: Unmounted lustre-client [ 3640.686725] Key type lgssc unregistered [ 3640.997475] LNet: 82123:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3641.020273] LNetError: 82123:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3641.071207] LNet: Removed LNI 192.168.204.44@tcp [ 3641.926733] Key type .llcrypt unregistered [ 3641.929566] Key type ._llcrypt unregistered [ 3659.640540] Key type ._llcrypt registered [ 3659.643120] Key type .llcrypt registered [ 3661.114871] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 04:04:47 (1789113887) [ 3664.047574] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3666.206672] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 04:04:52 (1789113892) [ 3668.991420] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3669.028186] alg: No test for adler32 (adler32-zlib) [ 3670.084534] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 3670.325637] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 3672.113053] Key type lgssc registered [ 3673.751424] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3678.965319] alg: No test for zstdfast (zstdfast-lustre-generic) [ 3678.982513] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 3728.130398] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3731.861848] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3751.553718] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3753.374876] Lustre: lustre: root_squash is set to 500:500 [ 3753.410227] Lustre: lustre: nosquash_nids set to 192.168.204.44/24@tcp [ 3754.445715] Lustre: Mounted lustre-client [ 3760.694921] LustreError: 85672:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a8a0a43000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3760.807040] Lustre: Unmounted lustre-client [ 3789.445950] Key type lgssc unregistered [ 3789.852265] LNet: 86075:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3789.878338] LNetError: 86075:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3789.897500] LNet: Removed LNI 192.168.204.44@tcp [ 3790.939925] Key type .llcrypt unregistered [ 3790.949682] Key type ._llcrypt unregistered [ 3811.301970] Key type ._llcrypt registered [ 3811.310668] Key type .llcrypt registered [ 3813.295317] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 3815.873442] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 04:07:21 (1789114041) [ 3835.918746] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3835.948123] alg: No test for adler32 (adler32-zlib) [ 3837.027767] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 3837.242715] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 3838.951177] Key type lgssc registered [ 3840.096472] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3844.759270] alg: No test for zstdfast (zstdfast-lustre-generic) [ 3844.784944] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 3928.655649] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3933.516775] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3957.587975] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4029.665766] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4033.594649] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4053.076403] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4066.763414] Lustre: Mounted lustre-client [ 4100.717847] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff99a8841d9000.ost_server_uuid 50 [ 4102.375392] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff99a8841d9000.ost_server_uuid in IDLE state after 0 sec [ 4103.000815] Lustre: Mounted lustre-client [ 4107.265413] LustreError: 93051:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a89019d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4107.430216] Lustre: Unmounted lustre-client [ 4109.886256] LustreError: 93070:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a8841d9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4109.894826] LustreError: 93070:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 4110.049420] Lustre: Unmounted lustre-client [ 4154.715395] Key type lgssc unregistered [ 4154.938179] LNet: 93624:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4154.941710] LNetError: 93624:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4154.953360] LNet: Removed LNI 192.168.204.44@tcp [ 4155.774369] Key type .llcrypt unregistered [ 4155.777152] Key type ._llcrypt unregistered [ 4190.525160] Key type ._llcrypt registered [ 4190.527700] Key type .llcrypt registered [ 4191.824962] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 04:13:38 (1789114418) [ 4212.083260] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4212.125851] alg: No test for adler32 (adler32-zlib) [ 4213.282529] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 4213.674386] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 4215.376099] Key type lgssc registered [ 4216.873539] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4221.391619] alg: No test for zstdfast (zstdfast-lustre-generic) [ 4221.402653] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 4303.156839] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4307.462519] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4329.525662] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4332.239709] Lustre: Mounted lustre-client [ 4353.951989] Lustre: 95018:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789114565/real 1789114565] req@00000000fb934479 x1876022464091392/t0(0) o400->lustre-OST0000-osc-ffff99a886106000@192.168.204.144@tcp:28/4 lens 224/224 e 0 to 1 dl 1789114581 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4354.001860] Lustre: lustre-OST0000-osc-ffff99a886106000: Connection to lustre-OST0000 (at 192.168.204.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4358.111536] Lustre: 95018:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789114570/real 1789114570] req@000000004923f509 x1876022464091904/t0(0) o400->lustre-OST0000-osc-ffff99a886106000@192.168.204.144@tcp:28/4 lens 224/224 e 0 to 1 dl 1789114586 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4362.011701] Lustre: lustre-OST0000-osc-ffff99a886106000: Connection restored to 192.168.204.144@tcp (at 192.168.204.144@tcp) [ 4363.167465] Lustre: 95017:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789114575/real 1789114575] req@00000000d2fa252a x1876022464092416/t0(0) o400->lustre-OST0000-osc-ffff99a886106000@192.168.204.144@tcp:28/4 lens 224/224 e 0 to 1 dl 1789114591 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4364.266861] Lustre: lustre-MDT0000-mdc-ffff99a886106000: Connection to lustre-MDT0000 (at 192.168.204.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4369.375192] Lustre: 95017:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789114581/real 1789114581] req@000000008083d331 x1876022464092928/t0(0) o400->lustre-OST0000-osc-ffff99a886106000@192.168.204.144@tcp:28/4 lens 224/224 e 0 to 1 dl 1789114597 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4380.448852] Lustre: 95020:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789114592/real 1789114592] req@00000000219a33dd x1876022464094720/t0(0) o400->MGC192.168.204.144@tcp@192.168.204.144@tcp:26/25 lens 224/224 e 0 to 1 dl 1789114608 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4380.479081] LustreError: MGC192.168.204.144@tcp: Connection to MGS (at 192.168.204.144@tcp) was lost; in progress operations using this service will fail [ 4388.397094] Lustre: lustre-MDT0000-mdc-ffff99a886106000: Connection restored to 192.168.204.144@tcp (at 192.168.204.144@tcp) [ 4390.951143] Lustre: Evicted from MGS (at 192.168.204.144@tcp) after server handle changed from 0x6069f6097c4010d0 to 0x6069f6097c401450 [ 4390.967113] Lustre: MGC192.168.204.144@tcp: Connection restored to 192.168.204.144@tcp (at 192.168.204.144@tcp) [ 4395.185708] LustreError: 98875:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a886106000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4395.289647] Lustre: Unmounted lustre-client [ 4424.230272] Key type lgssc unregistered [ 4424.591370] LNet: 99280:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4424.604535] LNetError: 99280:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4424.631472] LNet: Removed LNI 192.168.204.44@tcp [ 4425.388706] Key type .llcrypt unregistered [ 4425.393441] Key type ._llcrypt unregistered [ 4442.736434] Key type ._llcrypt registered [ 4442.738053] Key type .llcrypt registered [ 4444.309172] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 04:17:50 (1789114670) [ 4460.227866] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4460.259386] alg: No test for adler32 (adler32-zlib) [ 4461.356956] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 4461.679856] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 4463.391168] Key type lgssc registered [ 4464.734171] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4469.774557] alg: No test for zstdfast (zstdfast-lustre-generic) [ 4469.791583] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 4506.339924] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4610.059264] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4614.148189] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4641.538698] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4644.468356] Lustre: Mounted lustre-client [ 4647.244794] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 4650.073274] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 04:21:15 (1789114875) [ 4919.018535] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4929.357622] Lustre: Unmounted lustre-client [ 5066.691325] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5070.883680] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5096.427470] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5149.455088] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 04:29:35 (1789115375) [ 5251.781517] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5256.670780] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5280.038142] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5280.664262] Lustre: Mounted lustre-client [ 5285.844344] LustreError: 117601:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a8862d9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5285.974899] Lustre: Unmounted lustre-client [ 5319.693618] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 04:32:26 (1789115546) [ 5415.186811] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5419.989048] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5440.889615] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5441.510582] Lustre: Mounted lustre-client [ 5446.437290] LustreError: 121667:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a885875800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5446.503399] Lustre: Unmounted lustre-client [ 5476.344518] Key type lgssc unregistered [ 5476.631628] LNet: 122072:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5476.643693] LNetError: 122072:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5476.664507] LNet: Removed LNI 192.168.204.44@tcp [ 5477.246104] Key type .llcrypt unregistered [ 5477.248114] Key type ._llcrypt unregistered [ 5492.985686] Key type ._llcrypt registered [ 5492.988274] Key type .llcrypt registered [ 5494.367679] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 04:35:20 (1789115720) [ 5496.945620] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5497.128240] alg: No test for adler32 (adler32-zlib) [ 5498.223458] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 5498.448340] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 5500.143572] Key type lgssc registered [ 5501.507897] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5505.487807] alg: No test for zstdfast (zstdfast-lustre-generic) [ 5505.505397] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 5552.462300] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5557.174841] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5578.654132] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5581.468524] Lustre: Mounted lustre-client [ 5594.889684] LustreError: 125263:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a885877000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5594.993453] Lustre: Unmounted lustre-client [ 5623.810900] Key type lgssc unregistered [ 5624.089156] LNet: 125667:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5624.098062] LNetError: 125667:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5624.115935] LNet: Removed LNI 192.168.204.44@tcp [ 5624.805768] Key type .llcrypt unregistered [ 5624.819195] Key type ._llcrypt unregistered [ 5642.167835] Key type ._llcrypt registered [ 5642.177674] Key type .llcrypt registered [ 5644.133663] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 04:37:49 (1789115869) [ 5647.079117] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5647.097468] alg: No test for adler32 (adler32-zlib) [ 5648.157052] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 5648.460262] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 5650.183192] Key type lgssc registered [ 5651.582690] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5655.845341] alg: No test for zstdfast (zstdfast-lustre-generic) [ 5655.868487] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 5702.391350] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5706.342361] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5727.365970] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5729.204605] Lustre: Mounted lustre-client [ 5734.398368] Lustre: lustre-OST0000-osc-ffff99a884b30800: Connection to lustre-OST0000 (at 192.168.204.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5746.000232] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff99a884b30800.ost_server_uuid 50 [ 5748.166692] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 5760.635140] LustreError: 129289:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a884b30800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5760.752794] Lustre: Unmounted lustre-client [ 5783.575928] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 04:40:10 (1789116010) [ 5815.000184] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5818.706468] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5837.483725] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5859.966760] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5860.867318] Lustre: Mounted lustre-client [ 5865.977476] Lustre: lustre-OST0000-osc-ffff99a8918a1800: Connection to lustre-OST0000 (at 192.168.204.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5891.478066] LustreError: 131946:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a8918a1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5891.561840] Lustre: Unmounted lustre-client [ 5946.329411] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 04:42:52 (1789116172) [ 5982.901322] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5987.219164] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6006.623385] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6025.519265] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6026.156802] Lustre: Mounted lustre-client [ 6031.337960] Lustre: lustre-OST0000-osc-ffff99a8a0a5b000: Connection to lustre-OST0000 (at 192.168.204.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6056.117386] LustreError: 134698:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a8a0a5b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6056.141444] LustreError: 134698:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 6056.257157] Lustre: Unmounted lustre-client [ 6105.693649] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 04:45:31 (1789116331) [ 6205.983488] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6210.106092] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6232.445643] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6313.034805] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6317.158348] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6338.540283] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6368.694740] Lustre: Mounted lustre-client [ 6409.939558] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6417.035307] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 4 sec [ 6420.023090] LustreError: 141337:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a89019a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6420.030545] LustreError: 141337:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 6420.141064] Lustre: Unmounted lustre-client [ 6451.245886] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 04:51:17 (1789116677) [ 6484.174512] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6487.914373] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6508.043221] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6541.158498] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6575.436413] Lustre: Mounted lustre-client [ 6615.254334] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6617.539955] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6621.679205] Lustre: lustre-OST0001-osc-ffff99a8918a1000: Connection to lustre-OST0001 (at 192.168.204.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6630.394551] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6640.780766] Lustre: Unmounted lustre-client [ 6709.505718] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 04:55:36 (1789116936) [ 6743.637255] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6747.703567] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6766.800265] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6767.357809] Lustre: Mounted lustre-client [ 6787.362634] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6789.790563] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6805.417577] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff99a88666c800.ost_server_uuid 50 [ 6807.203622] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff99a88666c800.ost_server_uuid in IDLE state after 0 sec [ 6810.939882] LustreError: 148341:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a88666c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6810.998245] Lustre: Unmounted lustre-client [ 6811.293460] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6811.300084] Lustre: Skipped 3 previous similar messages [ 6811.378548] Lustre: Mounted lustre-client [ 6812.643327] LustreError: 148398:0:(sec.c:413:import_sec_validate_get()) import 00000000b9a5dd2e (NEW) with no sec [ 6872.453231] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 04:58:18 (1789117098) [ 6905.115477] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6909.465795] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6931.539137] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6955.721978] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6956.174758] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 6956.339421] Lustre: Mounted lustre-client [ 6969.333872] LustreError: 151233:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a8a0a5a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6969.355340] LustreError: 151233:0:(lov_obd.c:792:lov_cleanup()) Skipped 2 previous similar messages [ 6969.550161] Lustre: Unmounted lustre-client [ 6969.555077] Lustre: Skipped 1 previous similar message [ 7017.411460] Key type lgssc unregistered [ 7017.800114] LNet: 151683:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7017.846691] LNetError: 151683:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7017.921795] LNet: Removed LNI 192.168.204.44@tcp [ 7019.408907] Key type .llcrypt unregistered [ 7019.411862] Key type ._llcrypt unregistered [ 7042.794179] Key type ._llcrypt registered [ 7042.799971] Key type .llcrypt registered [ 7044.285907] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 05:01:10 (1789117270) [ 7045.404504] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7045.490041] alg: No test for adler32 (adler32-zlib) [ 7046.947270] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 7047.324014] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 7049.079204] Key type lgssc registered [ 7050.680528] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7055.561618] alg: No test for zstdfast (zstdfast-lustre-generic) [ 7055.583928] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 7109.410628] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7113.100923] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7137.564563] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7158.081976] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 7158.716778] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7159.875692] Lustre: Mounted lustre-client [ 7168.528311] LustreError: lustre-MDT0001-mdc-ffff99a8918a0800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 7168.563427] Lustre: lustre-MDT0001-mdc-ffff99a8918a0800: Connection restored to 192.168.204.144@tcp (at 192.168.204.144@tcp) [ 7174.640349] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7176.723527] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7202.834076] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7208.595271] LustreError: 156029:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a8918a0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7208.694785] Lustre: Unmounted lustre-client [ 7242.711220] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 05:04:28 (1789117468) [ 7352.088570] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7357.543885] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7381.510519] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7382.020928] Lustre: Mounted lustre-client [ 7411.162716] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 7413.298877] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7426.898330] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7437.254757] Lustre: Unmounted lustre-client [ 7483.107442] Key type lgssc unregistered [ 7483.427877] LNet: 161182:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7483.439803] LNetError: 161182:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7483.471174] LNet: Removed LNI 192.168.204.44@tcp [ 7484.354594] Key type .llcrypt unregistered [ 7484.360264] Key type ._llcrypt unregistered [ 7519.637518] Key type ._llcrypt registered [ 7519.640060] Key type .llcrypt registered [ 7521.073878] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 05:09:07 (1789117747) [ 7523.129925] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7523.153081] alg: No test for adler32 (adler32-zlib) [ 7524.271062] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 7524.546607] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 7526.263421] Key type lgssc registered [ 7527.527511] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7533.404698] alg: No test for zstdfast (zstdfast-lustre-generic) [ 7533.465173] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 7580.805199] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7585.421681] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7604.418838] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7607.233054] Lustre: Mounted lustre-client [ 7653.070646] LustreError: 164537:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a88791e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7653.208543] Lustre: Unmounted lustre-client [ 7693.346782] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7693.855301] Lustre: Mounted lustre-client [ 7728.838957] LustreError: 165187:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a89019e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7728.948462] Lustre: Unmounted lustre-client [ 7766.635872] Key type lgssc unregistered [ 7766.955922] LNet: 165598:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7766.972086] LNetError: 165598:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7767.004379] LNet: Removed LNI 192.168.204.44@tcp [ 7768.011277] Key type .llcrypt unregistered [ 7768.014068] Key type ._llcrypt unregistered [ 7785.808538] Key type ._llcrypt registered [ 7785.810933] Key type .llcrypt registered [ 7787.322625] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 05:13:33 (1789118013) [ 7789.728915] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7789.872992] alg: No test for adler32 (adler32-zlib) [ 7790.996740] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 7791.361424] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 7793.135775] Key type lgssc registered [ 7794.606379] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7799.505523] alg: No test for zstdfast (zstdfast-lustre-generic) [ 7799.552560] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 7848.309300] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7853.167929] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7875.904710] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7878.654614] Lustre: Mounted lustre-client [ 7901.761383] LustreError: 168942:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a884b36000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7901.861512] Lustre: Unmounted lustre-client [ 7929.898899] Key type lgssc unregistered [ 7930.120228] LNet: 169347:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7930.125506] LNetError: 169347:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7930.147648] LNet: Removed LNI 192.168.204.44@tcp [ 7930.864459] Key type .llcrypt unregistered [ 7930.867598] Key type ._llcrypt unregistered [ 7945.655622] Key type ._llcrypt registered [ 7945.661948] Key type .llcrypt registered [ 7946.221947] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7946.242501] alg: No test for adler32 (adler32-zlib) [ 7947.541833] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 7947.780063] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 7949.511175] Key type lgssc registered [ 7950.955831] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7956.230786] alg: No test for zstdfast (zstdfast-lustre-generic) [ 7956.241978] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 8003.093572] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8006.991985] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8026.488953] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8029.143259] Lustre: Mounted lustre-client [ 8036.969391] LustreError: 172004:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a890d5c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8037.101141] Lustre: Unmounted lustre-client [ 8063.402708] Key type lgssc unregistered [ 8063.803120] LNet: 172409:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8063.809595] LNetError: 172409:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8063.828335] LNet: Removed LNI 192.168.204.44@tcp [ 8064.683282] Key type .llcrypt unregistered [ 8064.687851] Key type ._llcrypt unregistered [ 8083.980080] Key type ._llcrypt registered [ 8083.982068] Key type .llcrypt registered [ 8085.679262] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 05:18:31 (1789118311) [ 8087.963965] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8088.017633] alg: No test for adler32 (adler32-zlib) [ 8089.136736] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 8089.385327] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 8091.103243] Key type lgssc registered [ 8092.513341] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8097.878894] alg: No test for zstdfast (zstdfast-lustre-generic) [ 8097.928475] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 8148.083565] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8152.149845] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8173.106584] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8175.660539] Lustre: Mounted lustre-client [ 8195.214316] LustreError: 175749:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a887918000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8195.304840] Lustre: Unmounted lustre-client [ 8222.931509] Key type lgssc unregistered [ 8223.209256] LNet: 176152:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8223.222864] LNetError: 176152:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8223.240595] LNet: Removed LNI 192.168.204.44@tcp [ 8223.910346] Key type .llcrypt unregistered [ 8223.912870] Key type ._llcrypt unregistered [ 8238.361264] Key type ._llcrypt registered [ 8238.363038] Key type .llcrypt registered [ 8238.522486] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8238.538879] alg: No test for adler32 (adler32-zlib) [ 8239.570918] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 8239.727268] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 8241.400203] Key type lgssc registered [ 8242.506915] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8247.851359] alg: No test for zstdfast (zstdfast-lustre-generic) [ 8247.860410] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 8298.841915] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8302.947476] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8322.456364] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8324.084782] Lustre: Mounted lustre-client [ 8332.089179] LustreError: 178807:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a8918a2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8332.196344] Lustre: Unmounted lustre-client [ 8368.089892] Key type lgssc unregistered [ 8368.392812] LNet: 179211:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8368.402049] LNetError: 179211:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8368.423817] LNet: Removed LNI 192.168.204.44@tcp [ 8369.087604] Key type .llcrypt unregistered [ 8369.090454] Key type ._llcrypt unregistered [ 8388.218374] Key type ._llcrypt registered [ 8388.223507] Key type .llcrypt registered [ 8390.143661] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 05:23:36 (1789118616) [ 8411.923562] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8412.022396] alg: No test for adler32 (adler32-zlib) [ 8413.226073] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 8413.585823] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 8415.359209] Key type lgssc registered [ 8416.700555] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8421.610381] alg: No test for zstdfast (zstdfast-lustre-generic) [ 8421.631979] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 8490.652848] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8494.073695] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8513.431235] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8561.629890] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 05:26:28 (1789118788) [ 8590.014523] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8593.335588] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8608.809542] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8614.432362] Lustre: Mounted lustre-client [ 8619.270915] LustreError: 185809:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a8841d9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8619.389333] Lustre: Unmounted lustre-client [ 8645.133326] Key type lgssc unregistered [ 8645.407044] LNet: 186212:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8645.414155] LNetError: 186212:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8645.432376] LNet: Removed LNI 192.168.204.44@tcp [ 8646.189361] Key type .llcrypt unregistered [ 8646.196101] Key type ._llcrypt unregistered [ 8658.577846] Key type ._llcrypt registered [ 8658.579773] Key type .llcrypt registered [ 8659.644644] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 8661.013871] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 05:28:07 (1789118887) [ 8676.244558] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8676.261965] alg: No test for adler32 (adler32-zlib) [ 8677.325549] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 8677.547060] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 8679.215171] Key type lgssc registered [ 8680.636727] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8684.767873] alg: No test for zstdfast (zstdfast-lustre-generic) [ 8684.776375] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 8760.701638] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8764.691839] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8785.808114] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid [ 8804.361943] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid [ 8806.974474] Lustre: Mounted lustre-client [ 8826.822129] LustreError: 191826:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a884b33000: lov tgt 1000 not cleaned! deathrow=0, lovrc=1 [ 8826.886948] Lustre: Unmounted lustre-client [ 8956.150476] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8960.449633] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8982.037913] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9033.480510] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 05:34:20 (1789119260) [ 9035.267407] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 9036.987560] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 05:34:23 (1789119263) [ 9136.742660] Key type lgssc unregistered [ 9136.948391] LNet: 197534:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9136.953903] LNetError: 197534:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9136.964377] LNet: Removed LNI 192.168.204.44@tcp [ 9137.456661] Key type .llcrypt unregistered [ 9137.459311] Key type ._llcrypt unregistered [ 9152.234550] Key type ._llcrypt registered [ 9152.238810] Key type .llcrypt registered [ 9153.297483] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 05:36:20 (1789119380) [ 9195.852718] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9195.912946] alg: No test for adler32 (adler32-zlib) [ 9197.063662] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 9197.389794] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 9199.119183] Key type lgssc registered [ 9200.562254] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9205.633453] alg: No test for zstdfast (zstdfast-lustre-generic) [ 9205.707996] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 9241.524633] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9244.981674] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9276.827354] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9309.717363] Key type lgssc unregistered [ 9309.967777] LNet: 201261:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9309.971845] LNetError: 201261:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9309.984830] LNet: Removed LNI 192.168.204.44@tcp [ 9310.791564] Key type .llcrypt unregistered [ 9310.794154] Key type ._llcrypt unregistered [ 9325.866350] Key type ._llcrypt registered [ 9325.875630] Key type .llcrypt registered [ 9327.468900] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 05:39:13 (1789119553) [ 9329.853909] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9329.872583] alg: No test for adler32 (adler32-zlib) [ 9330.909914] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 9331.127837] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 9332.799213] Key type lgssc registered [ 9333.889166] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9338.751702] alg: No test for zstdfast (zstdfast-lustre-generic) [ 9338.776489] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 9384.461702] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9388.130724] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9411.212722] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9414.010836] Lustre: Mounted lustre-client [ 9424.369667] Lustre: lustre-MDT0000-mdc-ffff99a8918a1800: Connection to lustre-MDT0000 (at 192.168.204.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9434.605751] Lustre: lustre-MDT0001-mdc-ffff99a8918a1800: Connection to lustre-MDT0001 (at 192.168.204.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9445.855121] Lustre: 202281:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789119657/real 1789119657] req@00000000882f1480 x1876027830780032/t0(0) o400->MGC192.168.204.144@tcp@192.168.204.144@tcp:26/25 lens 224/224 e 0 to 1 dl 1789119673 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9445.906413] LustreError: MGC192.168.204.144@tcp: Connection to MGS (at 192.168.204.144@tcp) was lost; in progress operations using this service will fail [ 9455.145175] Lustre: Evicted from MGS (at 192.168.204.144@tcp) after server handle changed from 0x6da2cb146a0ccc85 to 0x6da2cb146a0ce79e [ 9455.154191] Lustre: MGC192.168.204.144@tcp: Connection restored to 192.168.204.144@tcp (at 192.168.204.144@tcp) [ 9479.692350] LustreError: lustre-MDT0000-mdc-ffff99a8918a1800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 9479.733024] Lustre: lustre-MDT0000-mdc-ffff99a8918a1800: Connection restored to 192.168.204.144@tcp (at 192.168.204.144@tcp) [ 9480.744205] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9482.320461] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9486.429650] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9488.045648] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9490.475177] LustreError: 205755:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a8918a1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9490.557988] Lustre: Unmounted lustre-client [ 9526.352913] Key type lgssc unregistered [ 9526.655763] LNet: 206159:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9526.667973] LNetError: 206159:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9526.688655] LNet: Removed LNI 192.168.204.44@tcp [ 9527.377688] Key type .llcrypt unregistered [ 9527.384812] Key type ._llcrypt unregistered [ 9544.049086] Key type ._llcrypt registered [ 9544.052857] Key type .llcrypt registered [ 9545.318674] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 05:42:51 (1789119771) [ 9547.719297] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9547.945110] alg: No test for adler32 (adler32-zlib) [ 9548.943957] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 9549.146630] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 9550.783583] Key type lgssc registered [ 9552.087064] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9556.308708] alg: No test for zstdfast (zstdfast-lustre-generic) [ 9556.319865] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 9602.236635] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9606.931778] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9656.724701] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9660.777811] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9692.455901] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9709.708842] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 9747.924107] Key type lgssc unregistered [ 9748.136368] LNet: 211356:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9748.146228] LNetError: 211356:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9748.158720] LNet: Removed LNI 192.168.204.44@tcp [ 9748.705978] Key type .llcrypt unregistered [ 9748.707914] Key type ._llcrypt unregistered [ 9794.205912] Key type ._llcrypt registered [ 9794.208687] Key type .llcrypt registered [ 9795.540385] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 05:47:02 (1789120022) [ 9831.596304] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9831.618183] alg: No test for adler32 (adler32-zlib) [ 9832.731824] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [ 9832.922368] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 9834.575177] Key type lgssc registered [ 9836.370867] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9840.664690] alg: No test for zstdfast (zstdfast-lustre-generic) [ 9840.680710] alg: No test for zstdfast (zstdfast-lustre-scomp) [ 9917.832441] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9921.500452] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9942.273731] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9983.244388] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 05:50:09 (1789120209) [ 9990.897990] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 05:50:17 (1789120217) [10027.507400] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10031.483808] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10050.828581] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10052.933111] Lustre: Mounted lustre-client [10059.703882] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [10061.858178] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [10063.604383] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [10065.363865] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [10068.455935] Lustre: lustre-MDT0000-mdc-ffff99a884b37000: Connection to lustre-MDT0000 (at 192.168.204.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [10084.639448] Lustre: 213284:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789120296/real 1789120296] req@0000000072a49495 x1876028356044416/t0(0) o400->MGC192.168.204.144@tcp@192.168.204.144@tcp:26/25 lens 224/224 e 0 to 1 dl 1789120312 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10084.672695] LustreError: MGC192.168.204.144@tcp: Connection to MGS (at 192.168.204.144@tcp) was lost; in progress operations using this service will fail [10093.096374] Lustre: lustre-MDT0000-mdc-ffff99a884b37000: Connection restored to 192.168.204.144@tcp (at 192.168.204.144@tcp) [10094.131209] Lustre: Evicted from MGS (at 192.168.204.144@tcp) after server handle changed from 0x1cb7a216af92d063 to 0x1cb7a216af92d3ce [10094.145891] Lustre: MGC192.168.204.144@tcp: Connection restored to 192.168.204.144@tcp (at 192.168.204.144@tcp) [10099.329881] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10100.925109] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10104.385285] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [10109.414028] Lustre: lustre-MDT0000-mdc-ffff99a884b37000: Connection to lustre-MDT0000 (at 192.168.204.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [10125.791124] Lustre: 213283:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789120337/real 1789120337] req@00000000a914348e x1876028356052736/t0(0) o400->MGC192.168.204.144@tcp@192.168.204.144@tcp:26/25 lens 224/224 e 0 to 1 dl 1789120353 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10125.812849] LustreError: MGC192.168.204.144@tcp: Connection to MGS (at 192.168.204.144@tcp) was lost; in progress operations using this service will fail [10135.014452] Lustre: Evicted from MGS (at 192.168.204.144@tcp) after server handle changed from 0x1cb7a216af92d3ce to 0x1cb7a216af92d5e2 [10135.034933] Lustre: MGC192.168.204.144@tcp: Connection restored to 192.168.204.144@tcp (at 192.168.204.144@tcp) [10140.200890] Lustre: lustre-MDT0000-mdc-ffff99a884b37000: Connection restored to 192.168.204.144@tcp (at 192.168.204.144@tcp) [10147.277629] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10149.295375] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10153.471153] LustreError: 221595:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a884b37000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10153.557722] Lustre: Unmounted lustre-client [10223.664111] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10227.543666] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10248.189389] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10248.900706] Lustre: Mounted lustre-client [10252.087209] LustreError: 223205:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a887bfe000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10252.185594] Lustre: Unmounted lustre-client [10289.982640] Key type lgssc unregistered [10290.227841] LNet: 223610:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10290.238410] LNetError: 223610:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [10290.258369] LNet: Removed LNI 192.168.204.44@tcp [10290.983394] Key type .llcrypt unregistered [10290.991217] Key type ._llcrypt unregistered [10310.297549] Key type ._llcrypt registered [10310.299162] Key type .llcrypt registered [10311.721571] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 05:55:38 (1789120538) [10316.207607] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10316.229806] alg: No test for adler32 (adler32-zlib) [10317.253979] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [10317.477657] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [10319.159223] Key type lgssc registered [10320.695624] Lustre: Echo OBD driver; http://www.lustre.org/ [10324.889797] alg: No test for zstdfast (zstdfast-lustre-generic) [10324.899699] alg: No test for zstdfast (zstdfast-lustre-scomp) [10378.108611] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10383.090832] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10406.245102] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10408.081928] Lustre: Mounted lustre-client [10415.623535] LustreError: 226867:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a8841d8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10415.750834] Lustre: Unmounted lustre-client [10482.333179] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10487.237908] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10509.462128] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10510.233943] Lustre: Mounted lustre-client [10519.581481] LustreError: 228463:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a884b30000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10519.668410] Lustre: Unmounted lustre-client [10592.345150] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10597.097035] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10618.122248] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10618.883350] Lustre: Mounted lustre-client [10622.340305] LustreError: 230075:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a888fbf000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10622.460638] Lustre: Unmounted lustre-client [10661.765081] Key type lgssc unregistered [10662.082323] LNet: 230491:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10662.096541] LNetError: 230491:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [10662.137383] LNet: Removed LNI 192.168.204.44@tcp [10663.166459] Key type .llcrypt unregistered [10663.167691] Key type ._llcrypt unregistered [10683.869893] Key type ._llcrypt registered [10683.873550] Key type .llcrypt registered [10685.293707] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 06:01:51 (1789120911) [10690.361156] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10690.376922] alg: No test for adler32 (adler32-zlib) [10691.786711] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [10692.187669] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [10694.007149] Key type lgssc registered [10695.441935] Lustre: Echo OBD driver; http://www.lustre.org/ [10700.498511] alg: No test for zstdfast (zstdfast-lustre-generic) [10700.508888] alg: No test for zstdfast (zstdfast-lustre-scomp) [10745.697545] Key type lgssc unregistered [10746.063380] LNet: 232899:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10746.080526] LNetError: 232899:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [10746.101032] LNet: Removed LNI 192.168.204.44@tcp [10747.008508] Key type .llcrypt unregistered [10747.010371] Key type ._llcrypt unregistered [10777.607316] Key type ._llcrypt registered [10777.609692] Key type .llcrypt registered [10778.002217] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10778.039573] alg: No test for adler32 (adler32-zlib) [10779.065984] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [10779.292948] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [10780.943468] Key type lgssc registered [10782.450082] Lustre: Echo OBD driver; http://www.lustre.org/ [10787.804548] alg: No test for zstdfast (zstdfast-lustre-generic) [10787.829498] alg: No test for zstdfast (zstdfast-lustre-scomp) [10873.397529] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10876.997668] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10902.541645] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10946.485548] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [10948.467927] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 06:06:14 (1789121174) [10982.198249] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10986.022863] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11006.465821] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11027.856891] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [11030.531359] Lustre: Mounted lustre-client [11035.628565] Lustre: lustre-OST0001-osc-ffff99a884b32800: Connection to lustre-OST0001 (at 192.168.204.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [11042.937907] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11053.316115] Lustre: Unmounted lustre-client [11096.579489] Key type lgssc unregistered [11096.865216] LNet: 239891:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11096.877513] LNetError: 239891:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [11096.907708] LNet: Removed LNI 192.168.204.44@tcp [11097.601943] Key type .llcrypt unregistered [11097.604259] Key type ._llcrypt unregistered [11132.173298] Key type ._llcrypt registered [11132.176436] Key type .llcrypt registered [11133.406652] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 06:09:20 (1789121360) [11147.720643] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11147.804350] alg: No test for adler32 (adler32-zlib) [11148.875412] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [11149.126250] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [11150.799169] Key type lgssc registered [11152.098387] Lustre: Echo OBD driver; http://www.lustre.org/ [11157.582935] alg: No test for zstdfast (zstdfast-lustre-generic) [11157.604612] alg: No test for zstdfast (zstdfast-lustre-scomp) [11207.189151] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11211.665940] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11243.243721] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 06:11:09 (1789121469) [11275.846133] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11278.944545] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11298.997470] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11301.704475] Lustre: Mounted lustre-client [11309.390235] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [11315.969345] Lustre: Unmounted lustre-client [11425.938882] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11430.242871] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11483.937550] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11484.679045] Lustre: Mounted lustre-client [11486.342265] LustreError: 247032:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a887a86800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11486.441876] Lustre: Unmounted lustre-client [11515.039360] Key type lgssc unregistered [11515.328830] LNet: 247437:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11515.340068] LNetError: 247437:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [11515.352653] LNet: Removed LNI 192.168.204.44@tcp [11516.116491] Key type .llcrypt unregistered [11516.119240] Key type ._llcrypt unregistered [11543.837762] Key type ._llcrypt registered [11543.847310] Key type .llcrypt registered [11544.067740] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11544.085687] alg: No test for adler32 (adler32-zlib) [11545.107932] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [11545.265240] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [11546.943344] Key type lgssc registered [11548.406389] Lustre: Echo OBD driver; http://www.lustre.org/ [11553.607892] alg: No test for zstdfast (zstdfast-lustre-generic) [11553.656909] alg: No test for zstdfast (zstdfast-lustre-scomp) [11640.059175] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11643.846164] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11666.749388] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11706.545419] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [11708.560681] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 06:18:54 (1789121934) [11741.257519] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11745.144491] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11765.037615] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11769.487276] Lustre: Mounted lustre-client [11773.164919] LustreError: 253577:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a888fbd000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11773.266926] Lustre: Unmounted lustre-client [11801.665887] Key type lgssc unregistered [11801.957520] LNet: 253981:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11801.965551] LNetError: 253981:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [11801.991652] LNet: Removed LNI 192.168.204.44@tcp [11802.943650] Key type .llcrypt unregistered [11802.951733] Key type ._llcrypt unregistered [11820.609294] Key type ._llcrypt registered [11820.611599] Key type .llcrypt registered [11822.260977] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [11824.204788] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 06:20:50 (1789122050) [11833.852377] Key type .llcrypt unregistered [11833.855169] Key type ._llcrypt unregistered [11844.639214] Key type ._llcrypt registered [11844.641295] Key type .llcrypt registered [11844.955218] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11844.973344] alg: No test for adler32 (adler32-zlib) [11846.075985] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [11846.326336] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [11848.015435] Key type lgssc registered [11849.562643] Lustre: Echo OBD driver; http://www.lustre.org/ [11855.157747] alg: No test for zstdfast (zstdfast-lustre-generic) [11855.168296] alg: No test for zstdfast (zstdfast-lustre-scomp) [11904.134869] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11921.941318] Lustre: Mounted lustre-client [11925.370705] LustreError: 257092:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a8862b2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11925.465149] Lustre: Unmounted lustre-client [11943.817247] Key type lgssc unregistered [11944.173285] LNet: 257493:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11944.192712] LNetError: 257493:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [11944.213816] LNet: Removed LNI 192.168.204.44@tcp [11944.979918] Key type .llcrypt unregistered [11944.984645] Key type ._llcrypt unregistered [11960.676606] Key type ._llcrypt registered [11960.683617] Key type .llcrypt registered [11962.240879] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 06:23:08 (1789122188) [11964.199273] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11964.314890] alg: No test for adler32 (adler32-zlib) [11965.519970] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [11965.795048] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [11967.479166] Key type lgssc registered [11969.223294] Lustre: Echo OBD driver; http://www.lustre.org/ [11975.583222] alg: No test for zstdfast (zstdfast-lustre-generic) [11975.637354] alg: No test for zstdfast (zstdfast-lustre-scomp) [12059.344916] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12096.075628] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12100.569947] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12101.837904] Lustre: Mounted lustre-client [12104.717598] LustreError: 260628:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a8a0a40000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12104.825059] Lustre: Unmounted lustre-client [12133.549844] Key type lgssc unregistered [12134.007280] LNet: 261033:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12134.022025] LNetError: 261033:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [12134.050497] LNet: Removed LNI 192.168.204.44@tcp [12134.940824] Key type .llcrypt unregistered [12134.943076] Key type ._llcrypt unregistered [12154.468880] Key type ._llcrypt registered [12154.472127] Key type .llcrypt registered [12155.795618] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 06:26:22 (1789122382) [12158.016833] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12158.040672] alg: No test for adler32 (adler32-zlib) [12159.042517] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [12159.254428] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [12160.887295] Key type lgssc registered [12162.216921] Lustre: Echo OBD driver; http://www.lustre.org/ [12167.735248] alg: No test for zstdfast (zstdfast-lustre-generic) [12167.758562] alg: No test for zstdfast (zstdfast-lustre-scomp) [12224.086488] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12229.159247] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12250.859812] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12263.005891] Lustre: Mounted lustre-client [12268.021742] Lustre: lustre-MDT0000-mdc-ffff99a8903ac000: Connection to lustre-MDT0000 (at 192.168.204.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [12274.600558] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12274.625607] LustreError: 264187:0:(file.c:6098:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [12275.061649] LustreError: 264206:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a8903ac000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12275.085913] LustreError: MGC192.168.204.144@tcp: Connection to MGS (at 192.168.204.144@tcp) was lost; in progress operations using this service will fail [12275.145357] Lustre: Unmounted lustre-client [12293.454910] Key type lgssc unregistered [12293.753858] LNet: 264600:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12293.771040] LNetError: 264600:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [12293.828289] LNet: Removed LNI 192.168.204.44@tcp [12294.724442] Key type .llcrypt unregistered [12294.727548] Key type ._llcrypt unregistered [12315.167555] Key type ._llcrypt registered [12315.176164] Key type .llcrypt registered [12317.068783] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 06:29:02 (1789122542) [12321.652546] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12321.704595] alg: No test for adler32 (adler32-zlib) [12322.746355] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [12322.988871] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [12324.831431] Key type lgssc registered [12326.129289] Lustre: Echo OBD driver; http://www.lustre.org/ [12331.555233] alg: No test for zstdfast (zstdfast-lustre-generic) [12331.578611] alg: No test for zstdfast (zstdfast-lustre-scomp) [12378.004112] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12382.108900] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12403.902947] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12405.280356] Lustre: Mounted lustre-client [12410.341531] Lustre: lustre-MDT0001-mdc-ffff99a883628000: Connection to lustre-MDT0001 (at 192.168.204.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [12416.486904] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [12418.873603] LustreError: 267785:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a883628000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12418.982228] Lustre: Unmounted lustre-client [12439.170819] Key type lgssc unregistered [12439.380789] LNet: 268178:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12439.398525] LNetError: 268178:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [12439.440721] LNet: Removed LNI 192.168.204.44@tcp [12439.911966] Key type .llcrypt unregistered [12439.915700] Key type ._llcrypt unregistered [12455.406472] Key type ._llcrypt registered [12455.409153] Key type .llcrypt registered [12457.468054] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 06:31:23 (1789122683) [12479.619951] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12479.641752] alg: No test for adler32 (adler32-zlib) [12480.680187] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [12480.909572] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [12482.575167] Key type lgssc registered [12483.917625] Lustre: Echo OBD driver; http://www.lustre.org/ [12488.297421] alg: No test for zstdfast (zstdfast-lustre-generic) [12488.331513] alg: No test for zstdfast (zstdfast-lustre-scomp) [12593.984155] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12595.844563] Lustre: Mounted lustre-client [12641.786493] Lustre: lustre-MDT0001-mdc-ffff99a888f06800: Connection to lustre-MDT0001 (at 192.168.204.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [12650.531931] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12660.877848] Lustre: Unmounted lustre-client [12688.774938] Key type lgssc unregistered [12689.201638] LNet: 274034:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12689.212583] LNetError: 274034:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [12689.237970] LNet: Removed LNI 192.168.204.44@tcp [12690.027424] Key type .llcrypt unregistered [12690.030836] Key type ._llcrypt unregistered [12709.794620] Key type ._llcrypt registered [12709.799989] Key type .llcrypt registered [12711.648513] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 06:35:37 (1789122937) [12713.665633] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [12715.497735] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 06:35:42 (1789122942) [12717.335426] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [12719.597243] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 06:35:45 (1789122945) [12721.500677] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [12723.385575] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 06:35:49 (1789122949) [12724.914940] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [12726.930384] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 06:35:53 (1789122953) [12728.612921] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [12730.798923] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 06:35:56 (1789122956) [12739.530850] Key type .llcrypt unregistered [12739.536048] Key type ._llcrypt unregistered [12746.308415] Key type ._llcrypt registered [12746.319956] Key type .llcrypt registered [12746.847954] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12746.882960] alg: No test for adler32 (adler32-zlib) [12748.163620] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [12748.597991] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [12750.448110] Key type lgssc registered [12752.593642] Lustre: Echo OBD driver; http://www.lustre.org/ [12757.500480] alg: No test for zstdfast (zstdfast-lustre-generic) [12757.515067] alg: No test for zstdfast (zstdfast-lustre-scomp) [12841.814877] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12846.115865] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12870.089610] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12871.711254] Lustre: Mounted lustre-client [12874.628107] LustreError: 279730:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a883629800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12874.744902] Lustre: Unmounted lustre-client [12913.460633] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 06:39:00 (1789123140) [12950.251195] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12953.682180] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12989.256546] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12995.125446] Lustre: Mounted lustre-client [12997.422215] LustreError: 281848:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a882ef5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12997.494508] Lustre: Unmounted lustre-client [13040.746863] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 06:41:06 (1789123266) [13049.759342] LNet: 1 local NIs in recovery (showing 1): 192.168.204.44@tcp [13693.498198] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [13697.526336] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [13731.321612] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [13832.641196] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 06:54:18 (1789124058) [13846.495937] Key type lgssc unregistered [13846.759350] LNet: 287414:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13846.775994] LNetError: 287414:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [13846.801360] LNet: Removed LNI 192.168.204.44@tcp [13847.545530] Key type .llcrypt unregistered [13847.547526] Key type ._llcrypt unregistered [13985.842968] Key type ._llcrypt registered [13985.844065] Key type .llcrypt registered [13986.409394] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13986.426852] alg: No test for adler32 (adler32-zlib) [13987.551281] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [13987.889787] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [13989.583719] Key type lgssc registered [13991.033851] Lustre: Echo OBD driver; http://www.lustre.org/ [13995.356975] alg: No test for zstdfast (zstdfast-lustre-generic) [13995.372214] alg: No test for zstdfast (zstdfast-lustre-scomp) [14080.459926] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [14084.336956] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [14104.551570] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [14137.663697] Key type lgssc unregistered [14137.969209] LNet: 291110:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14137.986282] LNetError: 291110:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [14137.999098] LNet: Removed LNI 192.168.204.44@tcp [14138.694482] Key type .llcrypt unregistered [14138.698365] Key type ._llcrypt unregistered [14175.848328] Key type ._llcrypt registered [14175.857776] Key type .llcrypt registered [14177.252463] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 07:00:03 (1789124403) [14200.797217] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14200.832922] alg: No test for adler32 (adler32-zlib) [14202.056318] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [14202.480977] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [14204.263845] Key type lgssc registered [14205.688767] Lustre: Echo OBD driver; http://www.lustre.org/ [14212.227477] alg: No test for zstdfast (zstdfast-lustre-generic) [14212.263204] alg: No test for zstdfast (zstdfast-lustre-scomp) [14264.887183] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 07:01:31 (1789124491) [14277.905782] Key type lgssc unregistered [14278.273825] LNet: 294764:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14278.290091] LNetError: 294764:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [14278.312884] LNet: Removed LNI 192.168.204.44@tcp [14278.913611] Key type .llcrypt unregistered [14278.917559] Key type ._llcrypt unregistered [14291.022197] Key type ._llcrypt registered [14291.024939] Key type .llcrypt registered [14291.537795] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14291.563301] alg: No test for adler32 (adler32-zlib) [14292.613973] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [14292.836209] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [14294.495225] Key type lgssc registered [14295.741989] Lustre: Echo OBD driver; http://www.lustre.org/ [14300.636790] alg: No test for zstdfast (zstdfast-lustre-generic) [14300.650934] alg: No test for zstdfast (zstdfast-lustre-scomp) [14351.893312] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [14355.762626] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [14377.942863] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [14671.111892] LNet: Added LNI 192.168.204.44@tcp50 [8/256/0/180] [14673.814169] LNet: Removed LNI 192.168.204.44@tcp [14676.225315] Lustre: 299156:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff99a886dc0800: config has no valid NIDs, force dynamic NIDs [14677.406698] Lustre: Mounted lustre-client [14677.486934] LustreError: 299164:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a886dc0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [14677.558955] Lustre: Unmounted lustre-client [14680.627461] Key type lgssc unregistered [14681.142718] LNet: 299416:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14681.151719] LNetError: 299416:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [14681.181723] LNet: Removed LNI 192.168.204.44@tcp50 [14681.896848] Key type .llcrypt unregistered [14681.900496] Key type ._llcrypt unregistered [14701.073672] Key type ._llcrypt registered [14701.075784] Key type .llcrypt registered [14701.642372] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14701.661932] alg: No test for adler32 (adler32-zlib) [14702.782199] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [14703.017728] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [14704.679186] Key type lgssc registered [14706.092361] Lustre: Echo OBD driver; http://www.lustre.org/ [14710.926178] alg: No test for zstdfast (zstdfast-lustre-generic) [14710.940656] alg: No test for zstdfast (zstdfast-lustre-scomp) [14767.171503] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [14771.287499] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [14779.746642] LNet: Added LNI 192.168.204.44@tcp50 [8/256/0/180] [14780.666680] LNet: Removed LNI 192.168.204.44@tcp [14781.854461] Lustre: 302146:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff99a8841d8800: config has no valid NIDs, force dynamic NIDs [14783.059047] Lustre: Mounted lustre-client [14783.562491] LustreError: 302176:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a8841d8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [14783.642387] Lustre: Unmounted lustre-client [14818.563543] Key type lgssc unregistered [14818.947649] LNet: 303019:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14818.965770] LNetError: 303019:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [14818.993132] LNet: Removed LNI 192.168.204.44@tcp50 [14819.823573] Key type .llcrypt unregistered [14819.839636] Key type ._llcrypt unregistered [14834.435387] Key type ._llcrypt registered [14834.437654] Key type .llcrypt registered [14835.996456] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 07:11:02 (1789125062) [14857.939304] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14857.958507] alg: No test for adler32 (adler32-zlib) [14859.098820] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [14859.370890] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [14861.103187] Key type lgssc registered [14862.456529] Lustre: Echo OBD driver; http://www.lustre.org/ [14867.629423] alg: No test for zstdfast (zstdfast-lustre-generic) [14867.645750] alg: No test for zstdfast (zstdfast-lustre-scomp) [14947.938755] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [14951.848219] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [14975.490285] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [15016.427865] Key type lgssc unregistered [15016.811954] LNet: 307764:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15016.820276] LNetError: 307764:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [15016.834194] LNet: Removed LNI 192.168.204.44@tcp [15017.480921] Key type .llcrypt unregistered [15017.483343] Key type ._llcrypt unregistered [15029.264863] Key type ._llcrypt registered [15029.266386] Key type .llcrypt registered [15030.794499] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 07:14:17 (1789125257) [15033.942459] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15033.965568] alg: No test for adler32 (adler32-zlib) [15035.088302] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [15035.316888] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [15037.066840] Key type lgssc registered [15038.644968] Lustre: Echo OBD driver; http://www.lustre.org/ [15043.756893] alg: No test for zstdfast (zstdfast-lustre-generic) [15043.784410] alg: No test for zstdfast (zstdfast-lustre-scomp) [15092.431493] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [15096.761521] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [15118.177158] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [15120.906717] Lustre: Mounted lustre-client [15182.714610] Lustre: Mounted fs15246-client [15196.845772] LustreError: 312002:0:(lov_obd.c:792:lov_cleanup()) fs15246-clilov-ffff99a884b34000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [15196.890252] Lustre: Unmounted fs15246-client [15221.090269] LustreError: 312221:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff99a8901e4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [15221.149141] Lustre: Unmounted lustre-client [15249.450031] Key type lgssc unregistered [15249.689949] LNet: 312624:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15249.696557] LNetError: 312624:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [15249.713314] LNet: Removed LNI 192.168.204.44@tcp [15250.371251] Key type .llcrypt unregistered [15250.372765] Key type ._llcrypt unregistered [15261.726780] Key type ._llcrypt registered [15261.729692] Key type .llcrypt registered [15263.236450] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 07:18:09 (1789125489) [15264.601942] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15264.714147] alg: No test for adler32 (adler32-zlib) [15265.829873] Lustre: Lustre: Build Version: 2.17.53_26_gdb05832 [15266.080906] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [15267.767939] Key type lgssc registered [15269.098623] Lustre: Echo OBD driver; http://www.lustre.org/ [15274.125388] alg: No test for zstdfast (zstdfast-lustre-generic) [15274.138130] alg: No test for zstdfast (zstdfast-lustre-scomp) [15401.568431] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [15405.746332] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [15425.521625] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [15541.616311] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 15222 sec ========= 07:22:48 (1789125768) [15543.085571] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 07:22:49 (1789125769) === [15546.638196] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 07:22:53 (1789125773) === [15577.924255] Key type lgssc unregistered [15578.184887] LNet: 321911:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15578.192068] LNetError: 321911:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [15578.207697] LNet: Removed LNI 192.168.204.44@tcp [15578.830646] Key type .llcrypt unregistered [15578.832967] Key type ._llcrypt unregistered