[ 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-8.fc42 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 412616018 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 0x00000000BFFE2421 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE22BD 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 00227D (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2331 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE23C1 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE23F9 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe22bd-0xbffe2330] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22bc] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2331-0xbffe23c0] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe23c1-0xbffe23f8] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe23f9-0xbffe2420] [ 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, 524588K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001010] APIC: Switch to symmetric I/O mode setup [ 0.002080] x2apic enabled [ 0.003006] Switched APIC routing to physical x2apic. [ 0.004010] kvm-guest: setup PV IPIs [ 0.007000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.007000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.007017] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.008008] pid_max: default: 32768 minimum: 301 [ 0.009125] LSM: Security Framework initializing [ 0.010050] Yama: becoming mindful. [ 0.011025] SELinux: Initializing. [ 0.012042] *** VALIDATE selinux *** [ 0.019410] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.023652] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.024119] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.025074] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.026074] *** VALIDATE tmpfs *** [ 0.027395] *** VALIDATE proc *** [ 0.028188] *** VALIDATE cgroup *** [ 0.029007] *** VALIDATE cgroup2 *** [ 0.030288] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.032080] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.033004] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.034017] Spectre V2 : User space: Vulnerable [ 0.035003] Speculative Store Bypass: Vulnerable [ 0.037216] debug: unmapping init [mem 0xffffffff98259000-0xffffffff98260fff] [ 0.039855] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.040444] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.041015] ... version: 2 [ 0.042008] ... bit width: 48 [ 0.043008] ... generic registers: 4 [ 0.044006] ... value mask: 0000ffffffffffff [ 0.045006] ... max period: 00007fffffffffff [ 0.046004] ... fixed-purpose events: 3 [ 0.047006] ... event mask: 000000070000000f [ 0.048255] rcu: Hierarchical SRCU implementation. [ 0.050164] smp: Bringing up secondary CPUs ... [ 0.051400] x86: Booting SMP configuration: [ 0.052015] .... node #0, CPUs: #1 #2 #3 [ 0.055556] smp: Brought up 1 node, 4 CPUs [ 0.056884] smpboot: Max logical packages: 1 [ 0.057007] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.141017] node 0 deferred pages initialised in 81ms [ 0.144315] devtmpfs: initialized [ 0.145198] x86/mm: Memory block size: 128MB [ 0.147343] gcov: version magic: 0x41383552 [ 0.149238] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.150048] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.151179] pinctrl core: initialized pinctrl subsystem [ 0.152101] [ 0.152379] ************************************************************* [ 0.153006] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.154006] ** ** [ 0.155007] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.156005] ** ** [ 0.157005] ** This means that this kernel is built to expose internal ** [ 0.158007] ** IOMMU data structures, which may compromise security on ** [ 0.159006] ** your system. ** [ 0.160008] ** ** [ 0.161007] ** If you see this message and you are not debugging the ** [ 0.162007] ** kernel, report this immediately to your vendor! ** [ 0.163005] ** ** [ 0.164005] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.165005] ************************************************************* [ 0.166692] NET: Registered protocol family 16 [ 0.167315] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.168031] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.169027] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.171013] cpuidle: using governor menu [ 0.172791] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.175332] PCI: Using configuration type 1 for base access [ 0.177093] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.185054] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.186036] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.188054] cryptd: max_cpu_qlen set to 1000 [ 0.190250] ACPI: Added _OSI(Module Device) [ 0.191011] ACPI: Added _OSI(Processor Device) [ 0.192011] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.193011] ACPI: Added _OSI(Processor Aggregator Device) [ 0.197063] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.199697] ACPI: Interpreter enabled [ 0.200045] ACPI: PM: (supports S0 S3 S4 S5) [ 0.201008] ACPI: Using IOAPIC for interrupt routing [ 0.202087] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.203359] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.212996] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.214023] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.215012] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.216062] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.217959] acpiphp: Slot [2] registered [ 0.218090] acpiphp: Slot [5] registered [ 0.219089] acpiphp: Slot [6] registered [ 0.220113] acpiphp: Slot [3] registered [ 0.221049] acpiphp: Slot [4] registered [ 0.222070] acpiphp: Slot [7] registered [ 0.223253] acpiphp: Slot [8] registered [ 0.224047] acpiphp: Slot [9] registered [ 0.225414] acpiphp: Slot [10] registered [ 0.227111] acpiphp: Slot [11] registered [ 0.229058] acpiphp: Slot [12] registered [ 0.230077] acpiphp: Slot [13] registered [ 0.232044] acpiphp: Slot [14] registered [ 0.233046] acpiphp: Slot [15] registered [ 0.234046] acpiphp: Slot [16] registered [ 0.235070] acpiphp: Slot [17] registered [ 0.235892] acpiphp: Slot [18] registered [ 0.237060] acpiphp: Slot [19] registered [ 0.238058] acpiphp: Slot [20] registered [ 0.239084] acpiphp: Slot [21] registered [ 0.241052] acpiphp: Slot [22] registered [ 0.241933] acpiphp: Slot [23] registered [ 0.242041] acpiphp: Slot [24] registered [ 0.242866] acpiphp: Slot [25] registered [ 0.244062] acpiphp: Slot [26] registered [ 0.245071] acpiphp: Slot [27] registered [ 0.246074] acpiphp: Slot [28] registered [ 0.248136] acpiphp: Slot [29] registered [ 0.249074] acpiphp: Slot [30] registered [ 0.251103] acpiphp: Slot [31] registered [ 0.252067] PCI host bridge to bus 0000:00 [ 0.253014] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.255016] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.256014] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.258019] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.260016] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.262018] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.264142] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.266893] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.270079] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.275000] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.280026] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.282008] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.283010] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.285017] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.288586] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.291867] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.295033] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.297739] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.303009] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.313013] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.319011] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.325638] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.337013] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.341010] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.352026] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.361642] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.370010] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.374007] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.381019] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.388448] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.391206] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.392223] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.394317] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.397268] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.402024] iommu: Default domain type: Passthrough [ 0.404431] SCSI subsystem initialized [ 0.405062] ACPI: bus type USB registered [ 0.406063] usbcore: registered new interface driver usbfs [ 0.408050] usbcore: registered new interface driver hub [ 0.409064] usbcore: registered new device driver usb [ 0.411105] pps_core: LinuxPPS API ver. 1 registered [ 0.412011] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.414027] PTP clock support registered [ 0.416059] EDAC MC: Ver: 3.0.0 [ 0.417204] PCI: Using ACPI for IRQ routing [ 0.418445] NetLabel: Initializing [ 0.420010] NetLabel: domain hash size = 128 [ 0.421007] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.423085] NetLabel: unlabeled traffic allowed by default [ 0.426121] vgaarb: loaded [ 0.427199] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.429010] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.436005] clocksource: Switched to clocksource kvm-clock [ 0.533072] VFS: Disk quotas dquot_6.6.0 [ 0.533958] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.535717] *** VALIDATE ramfs *** [ 0.536382] *** VALIDATE hugetlbfs *** [ 0.537819] pnp: PnP ACPI init [ 0.539316] pnp: PnP ACPI: found 6 devices [ 0.554038] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.555687] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.556747] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.557887] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.559413] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.560578] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.562189] NET: Registered protocol family 2 [ 0.563895] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.567744] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.570475] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.575208] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.578944] TCP: Hash tables configured (established 65536 bind 65536) [ 0.581767] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.584826] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.588348] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.591058] NET: Registered protocol family 1 [ 0.593371] RPC: Registered named UNIX socket transport module. [ 0.595399] RPC: Registered udp transport module. [ 0.596611] RPC: Registered tcp transport module. [ 0.597751] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.599208] NET: Registered protocol family 44 [ 0.600632] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.601937] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.603111] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.604258] PCI: CLS 0 bytes, default 64 [ 0.605220] Unpacking initramfs... [ 2.062148] debug: unmapping init [mem 0xffff9cc47cc64000-0xffff9cc47ffcffff] [ 2.064740] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.066405] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.069421] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.551838] Initialise system trusted keyrings [ 2.553122] Key type blacklist registered [ 2.554575] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.561715] zbud: loaded [ 2.563967] *** VALIDATE nfs *** [ 2.564833] *** VALIDATE nfs4 *** [ 2.565941] pstore: using deflate compression [ 2.568726] Platform Keyring initialized [ 2.659030] NET: Registered protocol family 38 [ 2.660632] Key type asymmetric registered [ 2.661744] Asymmetric key parser 'x509' registered [ 2.664388] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.666776] io scheduler mq-deadline registered [ 2.668345] io scheduler kyber registered [ 2.669819] io scheduler bfq registered [ 2.671402] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.673931] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.675890] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.677747] ACPI: Power Button [PWRF] [ 2.682232] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.686426] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.697617] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.725182] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.752996] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.757762] Non-volatile memory driver v1.3 [ 2.759435] Linux agpgart interface v0.103 [ 2.784727] virtio_blk virtio1: [vda] 134584 512-byte logical blocks (68.9 MB/65.7 MiB) [ 2.787503] vda: detected capacity change from 0 to 68907008 [ 2.799167] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.801655] vdb: detected capacity change from 0 to 1073741824 [ 2.813836] libphy: Fixed MDIO Bus: probed [ 2.824764] usbcore: registered new interface driver usbserial_generic [ 2.826920] usbserial: USB Serial support registered for generic [ 2.829148] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.832878] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.834539] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.836911] mousedev: PS/2 mouse device common for all mice [ 2.839934] rtc_cmos 00:05: RTC can wake from S4 [ 2.844422] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.847503] rtc_cmos 00:05: registered as rtc0 [ 2.851952] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.854102] intel_pstate: CPU model not supported [ 2.856400] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.856870] hid: raw HID events driver (C) Jiri Kosina [ 2.861252] usbcore: registered new interface driver usbhid [ 2.862711] usbhid: USB HID core driver [ 2.862835] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.863916] drop_monitor: Initializing network drop monitor service [ 2.868274] Initializing XFRM netlink socket [ 2.869816] NET: Registered protocol family 10 [ 2.872495] Segment Routing with IPv6 [ 2.873783] NET: Registered protocol family 17 [ 2.875802] mpls_gso: MPLS GSO support [ 2.880834] RAS: Correctable Errors collector initialized. [ 2.882254] AVX version of gcm_enc/dec engaged. [ 2.883554] AES CTR mode by8 optimization enabled [ 2.940974] sched_clock: Marking stable (2940948442, 0)->(3750885397, -809936955) [ 2.943895] registered taskstats version 1 [ 2.945735] Loading compiled-in X.509 certificates [ 2.947497] zswap: loaded using pool lzo/zbud [ 2.965320] Key type big_key registered [ 2.973635] Key type encrypted registered [ 2.974667] ima: No TPM chip found, activating TPM-bypass! [ 2.975899] ima: Allocated hash algorithm: sha1 [ 2.977024] ima: No architecture policies found [ 2.978193] evm: Initialising EVM extended attributes: [ 2.979516] evm: security.selinux [ 2.980750] evm: security.ima [ 2.981664] evm: security.capability [ 2.982536] evm: HMAC attrs: 0x1 [ 2.984203] rtc_cmos 00:05: setting system clock to 2026-03-16 13:35:22 UTC (1773668122) [ 2.988874] debug: unmapping init [mem 0xffffffff99203000-0xffffffff993fffff] [ 2.991772] debug: unmapping init [mem 0xffffffff97f82000-0xffffffff98258fff] [ 3.000405] Write protecting the kernel read-only data: 28672k [ 3.003781] debug: unmapping init [mem 0xffffffff96603000-0xffffffff967fffff] [ 3.006513] debug: unmapping init [mem 0xffffffff96f14000-0xffffffff96ffffff] [ 3.039181] 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.047213] systemd[1]: Detected virtualization kvm. [ 3.048884] systemd[1]: Detected architecture x86-64. [ 3.050857] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.077704] systemd[1]: No hostname configured. [ 3.079075] systemd[1]: Set hostname to . [ 3.081023] random: systemd: uninitialized urandom read (16 bytes read) [ 3.082655] systemd[1]: Initializing machine ID from random generator. [ 3.205636] random: systemd: uninitialized urandom read (16 bytes read) [ 3.208066] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 3.210797] random: systemd: uninitialized urandom read (16 bytes read) [ 3.212505] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 3.216245] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Local File Systems. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Control Socket. [ OK ] Listening on Journal Socket. Starting Setup Virtual Console... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Timers. Starting Apply Kernel Variables... [ OK ] Started Memstrack Anylazing Service. Starting Journal Service... [ OK ] Reached target Paths. Starting Create Volatile Files and Directories... [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Sockets. [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 3.725629] device-mapper: uevent: version 1.0.3 [ 3.727055] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 4.415161] virtio_net virtio0 ens2: renamed from eth0 [ 4.514413] scsi host0: ata_piix [ 4.566867] scsi host1: ata_piix [ 4.568626] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.571135] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.237135] dracut-initqueue[580]: RTNETLINK answers: File exists [ 8.547319] random: fast init done [ 9.486550] random: crng init done [ 9.487914] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 9.836913] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Timers. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Swap. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 10.928248] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.131302] SELinux: Disabled at runtime. [ 11.190585] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 11.199463] systemd[1]: Detected virtualization kvm. [ 11.201513] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 11.621150] systemd[1]: initrd-switch-root.service: Succeeded. [ 11.625453] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 11.628826] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 11.632416] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 11.635655] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 11.641814] systemd[1]: Starting Journal Service... Starting Journal Service... [ 11.646467] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Created slice system-getty.slice. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Stopped target Switch Root. [ OK ] Listening on udev Control Socket. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Listening on Process Core Dump Socket. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Reached target rpc_pipefs.target. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Mounting Huge Pages File System... Activating swap /dev/disk/by-label/SWAP... [ OK ] Created slice User and Session Slice. [ OK ] Stopped target Initrd File Systems. Mounting Kernel Debug File System... [ OK ] Listening on RPCbind Server Activation Socket. [ 11.749702] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Starting Apply Kernel Variables... [ OK ] Reached target RPC Port Mapper. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... Starting Remount Root and Kernel File Systems... [ OK ] Stopped target Initrd Root File System. [ OK ] Reached target Slices. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. Mounting POSIX Message Queue File System... [ OK ] Started Journal Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Huge Pages File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Kernel Debug 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 ] Mounted POSIX Message Queue File System. Starting Configure read-only root support... [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started udev Coldplug all Devices. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Mounted /mnt. [ 12.137548] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 12.439017] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 12.446928] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 12.473667] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 12.496750] EDAC sbridge: Ver: 1.1.2 [ 13.638536] Key type dns_resolver registered [ 13.967820] NFS: Registering the id_resolver key type [ 13.969723] Key type id_resolver registered [ 13.971453] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Load/Save Random Seed... Starting Create Volatile Files and Directories... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting 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 Cleanup of Temporary Directories. [ OK ] Started dnf makecache --timer. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Reached target sshd-keygen.target. [ OK ] Started irqbalance daemon. Starting Login Service... Starting Restore /run/initramfs on shutdown... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ 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 Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ 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 Notify NFS peers of a restart... Starting System Logging Service... Starting Crash recovery kernel arming... [ 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 oleg350-client login: [ 43.324897] libcfs: loading out-of-tree module taints kernel. [ 43.344103] Key type ._llcrypt registered [ 43.345474] Key type .llcrypt registered [ 43.607957] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 43.613139] alg: No test for adler32 (adler32-zlib) [ 44.617092] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 44.959321] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 46.567155] Key type lgssc registered [ 47.073612] Lustre: Echo OBD driver; http://www.lustre.org/ [ 104.697466] Lustre: Mounted lustre-client [ 106.957818] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 116.415218] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing check_logdir /tmp/testlogs/ [ 117.753088] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing yml_node [ 119.248403] Lustre: DEBUG MARKER: Client: 2.17.51.1 [ 120.064802] Lustre: DEBUG MARKER: MDS: 2.17.51.1 [ 120.864763] Lustre: DEBUG MARKER: OSS: 2.17.51.1 [ 121.354870] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Mon Mar 16 09:37:20 EDT 2026 [ 126.951921] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 127.408639] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 127.844592] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 138.103960] Lustre: Unmounted lustre-client [ 195.875407] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 197.078387] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 205.089918] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 224.998416] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 09:39:04 (1773668344) [ 225.900395] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 226.511665] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 09:39:05 (1773668345) [ 236.949605] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 238.223614] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 244.916567] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 255.495542] Lustre: Mounted lustre-client [ 257.595110] LustreError: 14380:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c8b35000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 257.644713] Lustre: Unmounted lustre-client [ 275.933753] Key type lgssc unregistered [ 276.053491] LNet: 14787:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 276.056876] LNetError: 14787:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 276.064578] LNet: Removed LNI 192.168.203.50@tcp [ 276.337115] Key type .llcrypt unregistered [ 276.338629] Key type ._llcrypt unregistered [ 281.841152] Key type ._llcrypt registered [ 281.842890] Key type .llcrypt registered [ 282.299686] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 09:40:01 (1773668401) [ 282.814610] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 282.824481] alg: No test for adler32 (adler32-zlib) [ 283.681288] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 283.785333] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 285.367148] Key type lgssc registered [ 285.757519] Lustre: Echo OBD driver; http://www.lustre.org/ [ 319.451046] Lustre: Mounted lustre-client [ 397.977703] LustreError: 18090:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c63d6000: lov tgt 1990 not cleaned! deathrow=0, lovrc=1 [ 398.000344] Lustre: Unmounted lustre-client [ 412.574690] Key type lgssc unregistered [ 412.712711] LNet: 18555:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 412.716727] LNetError: 18555:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 412.727943] LNet: Removed LNI 192.168.203.50@tcp [ 412.999922] Key type .llcrypt unregistered [ 413.000951] Key type ._llcrypt unregistered [ 416.820266] Key type ._llcrypt registered [ 416.824131] Key type .llcrypt registered [ 417.452067] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 09:42:16 (1773668536) [ 418.402731] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 418.425905] alg: No test for adler32 (adler32-zlib) [ 419.314493] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 419.437987] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 421.039829] Key type lgssc registered [ 421.684435] Lustre: Echo OBD driver; http://www.lustre.org/ [ 459.351933] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 462.249578] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 479.286124] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 490.591821] Lustre: Mounted lustre-client [ 506.832983] LustreError: 21666:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c8b50000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 506.967856] Lustre: Unmounted lustre-client [ 539.646357] Key type lgssc unregistered [ 539.842767] LNet: 22070:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 539.852368] LNetError: 22070:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 540.902205] LNet: Removed LNI 192.168.203.50@tcp [ 541.312316] Key type .llcrypt unregistered [ 541.314188] Key type ._llcrypt unregistered [ 547.693220] Key type ._llcrypt registered [ 547.695365] Key type .llcrypt registered [ 548.601660] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 09:44:27 (1773668667) [ 550.437216] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 550.463452] alg: No test for adler32 (adler32-zlib) [ 551.447414] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 551.596900] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 553.216674] Key type lgssc registered [ 554.009297] Lustre: Echo OBD driver; http://www.lustre.org/ [ 590.650979] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 593.443565] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 607.884801] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 619.537241] Lustre: Mounted lustre-client [ 623.701700] LustreError: 24772:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4d0987800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 623.774214] Lustre: Unmounted lustre-client [ 645.710308] Key type lgssc unregistered [ 645.878542] LNet: 25175:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 645.883736] LNetError: 25175:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 645.902267] LNet: Removed LNI 192.168.203.50@tcp [ 646.344783] Key type .llcrypt unregistered [ 646.346253] Key type ._llcrypt unregistered [ 655.285614] Key type ._llcrypt registered [ 655.288820] Key type .llcrypt registered [ 656.456938] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 09:46:14 (1773668774) [ 658.641336] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 658.663981] alg: No test for adler32 (adler32-zlib) [ 659.720853] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 659.883390] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 661.519158] Key type lgssc registered [ 662.501356] Lustre: Echo OBD driver; http://www.lustre.org/ [ 694.109589] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 695.972239] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 705.431339] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 706.777563] Lustre: Mounted lustre-client [ 712.183085] Lustre: lustre-MDT0000-mdc-ffff9cc4c5f32000: Connection to lustre-MDT0000 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 722.421401] Lustre: lustre-MDT0001-mdc-ffff9cc4c5f32000: Connection to lustre-MDT0001 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 725.605744] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 732.639837] Lustre: 26132:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773668836/real 1773668836] req@00000000c9f324b2 x1859826513614208/t0(0) o400->MGC192.168.203.150@tcp@192.168.203.150@tcp:26/25 lens 224/224 e 0 to 1 dl 1773668852 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 732.667471] LustreError: MGC192.168.203.150@tcp: Connection to MGS (at 192.168.203.150@tcp) was lost; in progress operations using this service will fail [ 735.911619] Lustre: Unmounted lustre-client [ 751.637820] Key type lgssc unregistered [ 751.791863] LNet: 28742:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 751.796262] LNetError: 28742:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 751.808199] LNet: Removed LNI 192.168.203.50@tcp [ 752.294355] Key type .llcrypt unregistered [ 752.298308] Key type ._llcrypt unregistered [ 761.181640] Key type ._llcrypt registered [ 761.183250] Key type .llcrypt registered [ 761.998573] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 09:48:00 (1773668880) [ 763.500217] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 763.524277] alg: No test for adler32 (adler32-zlib) [ 764.460301] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 764.593352] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 766.200267] Key type lgssc registered [ 766.977715] Lustre: Echo OBD driver; http://www.lustre.org/ [ 798.304510] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 800.688451] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 810.892263] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 812.247222] Lustre: Mounted lustre-client [ 822.765666] Lustre: lustre-OST0000-osc-ffff9cc4c5447800: Connection to lustre-OST0000 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 826.926512] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 837.265417] Lustre: Unmounted lustre-client [ 849.468020] hrtimer: interrupt took 2585607 ns [ 859.398445] Key type lgssc unregistered [ 859.543121] LNet: 32264:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 859.547523] LNetError: 32264:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 859.563040] LNet: Removed LNI 192.168.203.50@tcp [ 859.937436] Key type .llcrypt unregistered [ 859.939928] Key type ._llcrypt unregistered [ 869.600151] Key type ._llcrypt registered [ 869.604984] Key type .llcrypt registered [ 870.442718] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 09:49:49 (1773668989) [ 871.886696] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 871.909254] alg: No test for adler32 (adler32-zlib) [ 872.788527] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 872.897659] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 874.511160] Key type lgssc registered [ 875.036350] Lustre: Echo OBD driver; http://www.lustre.org/ [ 903.607057] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 905.463762] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 914.453275] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 916.792517] Lustre: Mounted lustre-client [ 928.072641] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 930.265078] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 941.100686] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-03-16 9h50m59s [ 942.164025] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 947.689792] Lustre: lustre-MDT0000-mdc-ffff9cc4c37a6000: Connection to lustre-MDT0000 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 950.410264] LustreError: 35965:0:(lmv_obd.c:1434:lmv_statfs()) lustre-MDT0000-mdc-ffff9cc4c37a6000: can't stat MDS #0: rc = -19 [ 951.242926] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 958.947120] LustreError: MGC192.168.203.150@tcp: Connection to MGS (at 192.168.203.150@tcp) was lost; in progress operations using this service will fail [ 958.961244] Lustre: Evicted from MGS (at 192.168.203.150@tcp) after server handle changed from 0xbf642c744a265196 to 0xbf642c744a265460 [ 958.968045] Lustre: MGC192.168.203.150@tcp: Connection restored to 192.168.203.150@tcp (at 192.168.203.150@tcp) [ 959.103511] Lustre: DEBUG MARKER: Wait for df (35965) ... [ 959.937579] Lustre: DEBUG MARKER: done [ 959.970642] LustreError: lustre-MDT0000-mdc-ffff9cc4c37a6000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 959.980940] Lustre: lustre-MDT0000-mdc-ffff9cc4c37a6000: Connection restored to 192.168.203.150@tcp (at 192.168.203.150@tcp) [ 960.576018] LustreError: 36574:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c37a6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 960.643777] Lustre: Unmounted lustre-client [ 981.145616] Key type lgssc unregistered [ 981.295433] LNet: 36976:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 981.299075] LNetError: 36976:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 981.311739] LNet: Removed LNI 192.168.203.50@tcp [ 981.637809] Key type .llcrypt unregistered [ 981.639476] Key type ._llcrypt unregistered [ 998.998053] Key type ._llcrypt registered [ 999.001259] Key type .llcrypt registered [ 999.872530] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 09:51:58 (1773669118) [ 1001.445731] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1001.508951] alg: No test for adler32 (adler32-zlib) [ 1002.449959] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 1002.603056] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 1004.239148] Key type lgssc registered [ 1004.990642] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1033.790664] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1036.388397] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1047.546659] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1049.973344] Lustre: Mounted lustre-client [ 1053.075502] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-03-16 9h52m51s [ 1054.012518] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1059.071411] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1061.416952] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1082.335747] Lustre: 41035:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773669181/real 1773669181] req@000000007639acf7 x1859826873276544/t0(0) o101->lustre-MDT0000-mdc-ffff9cc4c5287800@192.168.203.150@tcp:12/10 lens 576/1152 e 0 to 1 dl 1773669201 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1082.388576] Lustre: lustre-MDT0000-mdc-ffff9cc4c5287800: Connection to lustre-MDT0000 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1082.445560] Lustre: lustre-MDT0000-mdc-ffff9cc4c5287800: Connection restored to 192.168.203.150@tcp (at 192.168.203.150@tcp) [ 1104.736817] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1108.541463] Lustre: DEBUG MARKER: done [ 1113.917326] LustreError: 41509:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c5287800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1114.068440] Lustre: Unmounted lustre-client [ 1147.776443] Key type lgssc unregistered [ 1147.983684] LNet: 41914:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1147.988978] LNetError: 41914:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1148.000420] LNet: Removed LNI 192.168.203.50@tcp [ 1148.692065] Key type .llcrypt unregistered [ 1148.695023] Key type ._llcrypt unregistered [ 1175.309298] Key type ._llcrypt registered [ 1175.310366] Key type .llcrypt registered [ 1176.296772] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 09:54:54 (1773669294) [ 1177.396034] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1178.612697] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 09:54:57 (1773669297) [ 1182.015749] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1182.050071] alg: No test for adler32 (adler32-zlib) [ 1183.044599] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 1183.214849] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 1184.855283] Key type lgssc registered [ 1185.716518] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1212.084610] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 09:55:30 (1773669330) [ 1236.807764] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1239.484756] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1251.343468] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1252.767448] Lustre: Mounted lustre-client [ 1255.506918] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1258.193475] LustreError: 46252:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c846d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1258.269182] Lustre: Unmounted lustre-client [ 1269.281244] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 1306.196847] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1308.428563] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1308.961844] Lustre: Mounted lustre-client [ 1311.642543] LustreError: 47509:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c5f31000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1311.734538] Lustre: Unmounted lustre-client [ 1361.580901] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1363.956483] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1364.443119] Lustre: Mounted lustre-client [ 1366.855846] LustreError: 48629:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c5f34000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1366.924400] Lustre: Unmounted lustre-client [ 1382.814088] Lustre: DEBUG MARKER: files compared the same [ 1399.927868] Key type lgssc unregistered [ 1400.136387] LNet: 49335:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1400.141410] LNetError: 49335:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1400.159070] LNet: Removed LNI 192.168.203.50@tcp [ 1400.751906] Key type .llcrypt unregistered [ 1400.753764] Key type ._llcrypt unregistered [ 1409.672415] Key type ._llcrypt registered [ 1409.674083] Key type .llcrypt registered [ 1410.510482] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 09:58:49 (1773669529) [ 1411.800815] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1411.843150] alg: No test for adler32 (adler32-zlib) [ 1412.814531] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 1412.984925] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 1414.623647] Key type lgssc registered [ 1415.415135] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1441.413400] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1443.458676] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1453.615987] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1455.951908] Lustre: Mounted lustre-client [ 1467.049320] LustreError: 52425:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c8b56000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1467.081299] Lustre: Unmounted lustre-client [ 1486.959762] Key type lgssc unregistered [ 1487.143736] LNet: 52827:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1487.150900] LNetError: 52827:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1487.162255] LNet: Removed LNI 192.168.203.50@tcp [ 1487.566871] Key type .llcrypt unregistered [ 1487.568086] Key type ._llcrypt unregistered [ 1495.505772] Key type ._llcrypt registered [ 1495.507352] Key type .llcrypt registered [ 1496.256626] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 10:00:14 (1773669614) [ 1497.358351] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1497.404730] alg: No test for adler32 (adler32-zlib) [ 1498.363881] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 1498.510253] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 1500.159152] Key type lgssc registered [ 1500.861985] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1563.917695] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1573.728472] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1574.950931] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1591.875155] Key type lgssc unregistered [ 1591.976400] LNet: 56229:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1591.978389] LNetError: 56229:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1591.987246] LNet: Removed LNI 192.168.203.50@tcp [ 1592.187429] Key type .llcrypt unregistered [ 1592.188895] Key type ._llcrypt unregistered [ 1596.588659] Key type ._llcrypt registered [ 1596.590166] Key type .llcrypt registered [ 1596.994944] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 10:01:56 (1773669716) [ 1597.993869] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1598.024182] alg: No test for adler32 (adler32-zlib) [ 1598.886591] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 1598.972269] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 1600.559153] Key type lgssc registered [ 1600.980441] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1625.137295] Lustre: Mounted lustre-client [ 1630.463702] LustreError: 58708:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c5286800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1630.491069] Lustre: Unmounted lustre-client [ 1659.135244] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 10:02:58 (1773669778) [ 1696.787606] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1707.503241] Lustre: Mounted lustre-client [ 1712.830093] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1712.833080] LustreError: 62653:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff9cc4c4493000: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 1712.842235] Lustre: 57211:0:(llite_lib.c:4151:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.203.150@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 1723.131188] Lustre: Unmounted lustre-client [ 1757.883655] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 10:04:36 (1773669876) [ 1761.455967] Key type lgssc unregistered [ 1761.563517] LNet: 63865:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1761.565783] LNetError: 63865:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1761.574666] LNet: Removed LNI 192.168.203.50@tcp [ 1761.791506] Key type .llcrypt unregistered [ 1761.792823] Key type ._llcrypt unregistered [ 1765.683845] Key type ._llcrypt registered [ 1765.685293] Key type .llcrypt registered [ 1765.847118] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1765.851366] alg: No test for adler32 (adler32-zlib) [ 1766.689749] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 1766.760576] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 1768.335129] Key type lgssc registered [ 1768.667368] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1785.041784] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1786.107975] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1791.608882] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1792.767873] Lustre: Mounted lustre-client [ 1794.347098] LustreError: 66377:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c6f27000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1794.376599] Lustre: Unmounted lustre-client [ 1811.529449] Key type lgssc unregistered [ 1811.639397] LNet: 66780:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1811.641577] LNetError: 66780:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1811.647695] LNet: Removed LNI 192.168.203.50@tcp [ 1811.862393] Key type .llcrypt unregistered [ 1811.863479] Key type ._llcrypt unregistered [ 1815.408670] Key type ._llcrypt registered [ 1815.409884] Key type .llcrypt registered [ 1815.635693] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1815.641191] alg: No test for adler32 (adler32-zlib) [ 1816.493871] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 1816.577523] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 1818.159114] Key type lgssc registered [ 1818.559351] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1867.037165] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1868.186105] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1873.680023] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1889.261736] Lustre: Mounted lustre-client [ 1889.662292] LustreError: 69709:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c9d2c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1889.691611] Lustre: Unmounted lustre-client [ 1907.088325] Key type lgssc unregistered [ 1907.210598] LNet: 70111:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1907.213550] LNetError: 70111:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1907.226396] LNet: Removed LNI 192.168.203.50@tcp [ 1907.501809] Key type .llcrypt unregistered [ 1907.503467] Key type ._llcrypt unregistered [ 1912.700881] Key type ._llcrypt registered [ 1912.702300] Key type .llcrypt registered [ 1913.122804] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 10:07:12 (1773670032) [ 1913.744440] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1913.769036] alg: No test for adler32 (adler32-zlib) [ 1914.608863] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 1914.683445] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 1916.255097] Key type lgssc registered [ 1916.582526] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1932.250576] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1933.359426] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1938.804641] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1939.956288] Lustre: Mounted lustre-client [ 1941.916497] LustreError: 73225:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c6f25800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1941.944594] Lustre: Unmounted lustre-client [ 1942.055805] Lustre: Mounted lustre-client [ 1942.653880] LustreError: 73300:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c6260000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1942.680693] Lustre: Unmounted lustre-client [ 1959.367922] Key type lgssc unregistered [ 1959.470499] LNet: 73703:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1959.472516] LNetError: 73703:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1959.479841] LNet: Removed LNI 192.168.203.50@tcp [ 1959.705680] Key type .llcrypt unregistered [ 1959.706732] Key type ._llcrypt unregistered [ 1963.223203] Key type ._llcrypt registered [ 1963.243636] Key type .llcrypt registered [ 1963.464571] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1963.471908] alg: No test for adler32 (adler32-zlib) [ 1964.323588] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 1964.414583] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 1965.999190] Key type lgssc registered [ 1966.343283] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1982.052136] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1983.229689] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1988.834537] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1990.007909] Lustre: Mounted lustre-client [ 1991.715935] LustreError: 76232:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c9976800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1991.742622] Lustre: Unmounted lustre-client [ 2008.883118] Key type lgssc unregistered [ 2009.009652] LNet: 76635:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2009.012026] LNetError: 76635:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2009.021361] LNet: Removed LNI 192.168.203.50@tcp [ 2009.249702] Key type .llcrypt unregistered [ 2009.250875] Key type ._llcrypt unregistered [ 2014.066398] Key type ._llcrypt registered [ 2014.068363] Key type .llcrypt registered [ 2014.473410] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 10:08:53 (1773670133) [ 2015.263169] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2015.271909] alg: No test for adler32 (adler32-zlib) [ 2016.121404] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 2016.200652] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 2017.783118] Key type lgssc registered [ 2018.140247] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2034.936677] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2036.142019] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2041.618985] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2057.199287] Lustre: Mounted lustre-client [ 2068.976749] Lustre: lustre: nosquash_nids is cleared [ 2068.986140] Lustre: lustre: root_squash is set to 500:500 [ 2074.927276] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.203.50@tcp 192.168.0.[2,10]@tcp [ 2083.310339] Lustre: lustre: nosquash_nids set to 192.168.203.50/24@tcp [ 2084.739298] LustreError: 80134:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4cab92800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2084.768132] Lustre: Unmounted lustre-client [ 2101.985591] Key type lgssc unregistered [ 2102.100436] LNet: 80535:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2102.102991] LNetError: 80535:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2102.110726] LNet: Removed LNI 192.168.203.50@tcp [ 2102.347656] Key type .llcrypt unregistered [ 2102.348879] Key type ._llcrypt unregistered [ 2107.465978] Key type ._llcrypt registered [ 2107.467207] Key type .llcrypt registered [ 2107.934886] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 10:10:26 (1773670226) [ 2108.729813] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 2109.279960] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 10:10:28 (1773670228) [ 2110.180722] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2110.186339] alg: No test for adler32 (adler32-zlib) [ 2111.036611] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 2111.119944] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 2112.703176] Key type lgssc registered [ 2113.049610] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2129.836119] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2130.984183] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2136.783300] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2136.960830] Lustre: lustre: root_squash is set to 500:500 [ 2136.970736] Lustre: lustre: nosquash_nids set to 192.168.203.50/24@tcp [ 2152.430672] Lustre: Mounted lustre-client [ 2154.690965] LustreError: 83996:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c79ec000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2154.717646] Lustre: Unmounted lustre-client [ 2171.765967] Key type lgssc unregistered [ 2171.884504] LNet: 84399:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2171.886895] LNetError: 84399:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2171.895164] LNet: Removed LNI 192.168.203.50@tcp [ 2172.130725] Key type .llcrypt unregistered [ 2172.131958] Key type ._llcrypt unregistered [ 2176.734183] Key type ._llcrypt registered [ 2176.735304] Key type .llcrypt registered [ 2177.174947] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 2177.738750] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 10:11:36 (1773670296) [ 2183.419055] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2183.426265] alg: No test for adler32 (adler32-zlib) [ 2184.292687] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 2184.379930] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 2185.975144] Key type lgssc registered [ 2186.296250] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2214.929273] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2216.102608] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2223.415663] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2260.072496] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2261.258260] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2267.379902] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2281.388818] Lustre: Mounted lustre-client [ 2294.506471] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9cc4c45c1000.ost_server_uuid 50 [ 2295.008142] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9cc4c45c1000.ost_server_uuid in FULL state after 0 sec [ 2295.125317] Lustre: Mounted lustre-client [ 2295.857040] LustreError: 91277:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c458e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2295.899199] Lustre: Unmounted lustre-client [ 2323.602265] Key type lgssc unregistered [ 2323.730588] LNet: 91849:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2323.733267] LNetError: 91849:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2323.739777] LNet: Removed LNI 192.168.203.50@tcp [ 2323.989178] Key type .llcrypt unregistered [ 2323.990312] Key type ._llcrypt unregistered [ 2334.191916] Key type ._llcrypt registered [ 2334.192878] Key type .llcrypt registered [ 2334.632271] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 10:14:13 (1773670453) [ 2340.628955] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2340.655080] alg: No test for adler32 (adler32-zlib) [ 2341.518699] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 2341.608875] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 2343.199201] Key type lgssc registered [ 2343.618521] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2371.903589] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2373.083556] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2380.374725] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2381.548952] Lustre: Mounted lustre-client [ 2397.154332] Lustre: lustre-OST0000-osc-ffff9cc4c8df1800: Connection to lustre-OST0000 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2402.273780] Lustre: lustre-MDT0000-mdc-ffff9cc4c8df1800: Connection to lustre-MDT0000 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2403.295149] Lustre: 93210:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773670506/real 1773670506] req@00000000defd1fcc x1859828277318784/t0(0) o400->lustre-OST0000-osc-ffff9cc4c8df1800@192.168.203.150@tcp:28/4 lens 224/224 e 0 to 1 dl 1773670522 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2408.415153] Lustre: 93210:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773670511/real 1773670511] req@000000004a90ae2d x1859828277319296/t0(0) o400->lustre-OST0000-osc-ffff9cc4c8df1800@192.168.203.150@tcp:28/4 lens 224/224 e 0 to 1 dl 1773670527 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2411.539222] Lustre: lustre-OST0000-osc-ffff9cc4c8df1800: Connection restored to 192.168.203.150@tcp (at 192.168.203.150@tcp) [ 2412.513051] LustreError: MGC192.168.203.150@tcp: Connection to MGS (at 192.168.203.150@tcp) was lost; in progress operations using this service will fail [ 2412.519536] Lustre: 96282:0:(mgc_request.c:1917:mgc_process_log()) MGC192.168.203.150@tcp: IR log lustre-cliir failed, not fatal: rc = -5 [ 2412.520900] Lustre: Evicted from MGS (at 192.168.203.150@tcp) after server handle changed from 0xfdd1ee282584de0d to 0xfdd1ee282584e0c2 [ 2412.527351] Lustre: MGC192.168.203.150@tcp: Connection restored to 192.168.203.150@tcp (at 192.168.203.150@tcp) [ 2416.565710] Lustre: lustre-MDT0000-mdc-ffff9cc4c8df1800: Connection restored to 192.168.203.150@tcp (at 192.168.203.150@tcp) [ 2416.936229] LustreError: 97003:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c8df1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2416.963222] Lustre: Unmounted lustre-client [ 2434.067341] Key type lgssc unregistered [ 2434.184946] LNet: 97405:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2434.188099] LNetError: 97405:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2434.196136] LNet: Removed LNI 192.168.203.50@tcp [ 2434.459657] Key type .llcrypt unregistered [ 2434.461371] Key type ._llcrypt unregistered [ 2439.408963] Key type ._llcrypt registered [ 2439.410562] Key type .llcrypt registered [ 2439.886091] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 10:15:58 (1773670558) [ 2451.498200] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2451.516900] alg: No test for adler32 (adler32-zlib) [ 2452.376941] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 2452.467477] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 2454.055154] Key type lgssc registered [ 2454.456133] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2468.429530] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2499.933994] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2501.105687] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2508.439847] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2514.907799] Lustre: Mounted lustre-client [ 2515.540448] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 2516.109659] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 10:17:15 (1773670635) [ 2563.834836] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2574.134072] Lustre: Unmounted lustre-client [ 2632.351862] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2633.605328] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2642.045600] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2663.605603] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 10:19:42 (1773670782) [ 2693.964517] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2695.165771] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2703.680369] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2703.875962] Lustre: Mounted lustre-client [ 2704.907918] LustreError: 115526:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c3580000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2704.936111] Lustre: Unmounted lustre-client [ 2723.424268] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 10:20:42 (1773670842) [ 2757.565515] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2758.714926] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2766.879888] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2767.054147] Lustre: Mounted lustre-client [ 2768.344093] LustreError: 119561:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c5f33000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2768.386181] Lustre: Unmounted lustre-client [ 2786.547873] Key type lgssc unregistered [ 2786.670418] LNet: 119964:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2786.673552] LNetError: 119964:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2786.681125] LNet: Removed LNI 192.168.203.50@tcp [ 2786.953615] Key type .llcrypt unregistered [ 2786.954614] Key type ._llcrypt unregistered [ 2792.750088] Key type ._llcrypt registered [ 2792.751127] Key type .llcrypt registered [ 2793.256214] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 10:21:52 (1773670912) [ 2793.976266] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2794.068730] alg: No test for adler32 (adler32-zlib) [ 2794.915072] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 2794.997783] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 2796.583088] Key type lgssc registered [ 2796.984450] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2815.361113] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2816.474861] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2823.403295] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2833.908071] Lustre: Mounted lustre-client [ 2844.796882] LustreError: 123059:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c4170000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2844.827641] Lustre: Unmounted lustre-client [ 2862.231437] Key type lgssc unregistered [ 2862.345528] LNet: 123461:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2862.348437] LNetError: 123461:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2862.360181] LNet: Removed LNI 192.168.203.50@tcp [ 2862.602920] Key type .llcrypt unregistered [ 2862.604036] Key type ._llcrypt unregistered [ 2867.536941] Key type ._llcrypt registered [ 2867.537959] Key type .llcrypt registered [ 2867.992706] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 10:23:06 (1773670986) [ 2868.832897] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2868.841142] alg: No test for adler32 (adler32-zlib) [ 2869.693437] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 2869.767639] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 2871.343123] Key type lgssc registered [ 2871.695617] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2889.616139] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2891.083478] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2898.243742] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2908.654863] Lustre: Mounted lustre-client [ 2913.763217] Lustre: lustre-OST0000-osc-ffff9cc4c658e000: Connection to lustre-OST0000 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2918.793623] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff9cc4c658e000.ost_server_uuid 50 [ 2919.313660] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9cc4c658e000.ost_server_uuid in DISCONN state after 0 sec [ 2919.831913] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 2929.153112] LustreError: 127130:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c658e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2929.178132] Lustre: Unmounted lustre-client [ 2940.152379] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 10:24:19 (1773671059) [ 2949.627310] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2950.885268] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2956.626874] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2963.740758] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 2963.903253] Lustre: Mounted lustre-client [ 2969.058571] Lustre: lustre-OST0000-osc-ffff9cc4c79e9000: Connection to lustre-OST0000 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2983.229943] LustreError: 129776:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c79e9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2983.255130] Lustre: Unmounted lustre-client [ 3006.449939] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 10:25:25 (1773671125) [ 3015.711570] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3016.961880] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3023.002287] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3029.462317] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3029.639567] Lustre: Mounted lustre-client [ 3035.110397] Lustre: lustre-OST0000-osc-ffff9cc4c846d800: Connection to lustre-OST0000 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3055.042019] LustreError: 132524:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c846d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3055.046162] LustreError: 132524:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 3055.073536] Lustre: Unmounted lustre-client [ 3079.350339] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 10:26:38 (1773671198) [ 3111.661960] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3113.183211] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3120.803995] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3152.179530] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3153.394293] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3160.590123] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3180.572591] Lustre: Mounted lustre-client [ 3207.688108] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3208.191477] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3208.497518] LustreError: 139099:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4d0982000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3208.500188] LustreError: 139099:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 3208.523549] Lustre: Unmounted lustre-client [ 3226.773814] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 10:29:05 (1773671345) [ 3236.250681] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3237.415645] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3243.358931] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3264.283089] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3280.866601] Lustre: Mounted lustre-client [ 3323.630719] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3325.981226] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3342.123397] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3352.639141] Lustre: Unmounted lustre-client [ 3390.764377] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 10:31:49 (1773671509) [ 3405.222493] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3407.113072] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3416.765470] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3417.221049] Lustre: Mounted lustre-client [ 3429.585663] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3430.474190] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3436.674391] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9cc4c6256800.ost_server_uuid 50 [ 3437.418345] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9cc4c6256800.ost_server_uuid in FULL state after 0 sec [ 3438.477210] LustreError: 146090:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c6256800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3438.532391] Lustre: Unmounted lustre-client [ 3438.724407] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 3438.727128] Lustre: Skipped 3 previous similar messages [ 3438.796025] Lustre: Mounted lustre-client [ 3439.412832] LustreError: 146156:0:(sec.c:413:import_sec_validate_get()) import 00000000673781ff (NEW) with no sec [ 3476.924147] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 10:33:15 (1773671595) [ 3493.737545] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3495.555441] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3505.523834] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3514.853296] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3515.050175] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 3520.538735] Lustre: Mounted lustre-client [ 3526.554943] LustreError: 148978:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c4f95800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3526.565866] LustreError: 148978:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 3526.630369] Lustre: Unmounted lustre-client [ 3526.633285] Lustre: Skipped 1 previous similar message [ 3554.470761] Key type lgssc unregistered [ 3554.634926] LNet: 149425:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3554.638825] LNetError: 149425:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3554.655113] LNet: Removed LNI 192.168.203.50@tcp [ 3554.974849] Key type .llcrypt unregistered [ 3554.976683] Key type ._llcrypt unregistered [ 3560.506897] Key type ._llcrypt registered [ 3560.507995] Key type .llcrypt registered [ 3561.029162] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 10:34:39 (1773671679) [ 3561.468784] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3561.481631] alg: No test for adler32 (adler32-zlib) [ 3562.349407] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 3562.435487] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 3564.023150] Key type lgssc registered [ 3564.380030] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3580.773027] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3581.804297] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3587.599075] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3593.017035] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3593.139815] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 3598.317798] Lustre: Mounted lustre-client [ 3599.081543] LustreError: lustre-MDT0001-mdc-ffff9cc4c9b1b000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 3599.087057] Lustre: lustre-MDT0001-mdc-ffff9cc4c9b1b000: Connection restored to 192.168.203.150@tcp (at 192.168.203.150@tcp) [ 3600.839074] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3601.353926] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 3618.278514] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 3618.996218] LustreError: 153628:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c9b1b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3619.021897] Lustre: Unmounted lustre-client [ 3637.790323] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 10:35:56 (1773671756) [ 3665.683375] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3666.771954] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3673.578701] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3673.726984] Lustre: Mounted lustre-client [ 3682.714185] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3683.175038] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3699.684745] Lustre: lustre-OST0001-osc-ffff9cc4c79e8000: Connection to lustre-OST0001 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3702.103057] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3712.375802] Lustre: Unmounted lustre-client [ 3735.593399] Key type lgssc unregistered [ 3735.703608] LNet: 158752:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3735.707052] LNetError: 158752:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3735.714761] LNet: Removed LNI 192.168.203.50@tcp [ 3735.935301] Key type .llcrypt unregistered [ 3735.936522] Key type ._llcrypt unregistered [ 3745.902426] Key type ._llcrypt registered [ 3745.903348] Key type .llcrypt registered [ 3746.368483] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 10:37:45 (1773671865) [ 3747.166483] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3747.178816] alg: No test for adler32 (adler32-zlib) [ 3748.025767] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 3748.101883] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 3749.679169] Key type lgssc registered [ 3750.038706] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3766.709176] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3767.998857] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3774.209618] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3780.576107] Lustre: Mounted lustre-client [ 3817.839437] LustreError: 162013:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c79e9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3817.869115] Lustre: Unmounted lustre-client [ 3837.135567] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3837.349568] Lustre: Mounted lustre-client [ 3870.138438] LustreError: 162666:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c439a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3870.189964] Lustre: Unmounted lustre-client [ 3896.764137] Key type lgssc unregistered [ 3896.889651] LNet: 163076:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3896.895732] LNetError: 163076:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3896.903819] LNet: Removed LNI 192.168.203.50@tcp [ 3897.156278] Key type .llcrypt unregistered [ 3897.158111] Key type ._llcrypt unregistered [ 3902.975442] Key type ._llcrypt registered [ 3902.976389] Key type .llcrypt registered [ 3903.565918] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 10:40:22 (1773672022) [ 3904.525586] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3904.584076] alg: No test for adler32 (adler32-zlib) [ 3905.464285] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 3905.564568] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 3907.159148] Key type lgssc registered [ 3907.894836] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3948.533821] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3952.765198] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3973.075244] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3975.016192] Lustre: Mounted lustre-client [ 4000.892070] LustreError: 166345:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c846c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4000.994349] Lustre: Unmounted lustre-client [ 4039.762637] Key type lgssc unregistered [ 4040.058341] LNet: 166749:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4040.064743] LNetError: 166749:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4040.084409] LNet: Removed LNI 192.168.203.50@tcp [ 4040.961685] Key type .llcrypt unregistered [ 4040.968810] Key type ._llcrypt unregistered [ 4056.407983] Key type ._llcrypt registered [ 4056.415462] Key type .llcrypt registered [ 4057.218577] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4057.236176] alg: No test for adler32 (adler32-zlib) [ 4058.406879] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 4058.810536] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 4060.535191] Key type lgssc registered [ 4062.426656] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4114.269134] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4118.515847] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4137.943811] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4139.614404] Lustre: Mounted lustre-client [ 4146.694724] LustreError: 169331:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c439a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4146.778323] Lustre: Unmounted lustre-client [ 4170.380498] Key type lgssc unregistered [ 4170.690386] LNet: 169734:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4170.713292] LNetError: 169734:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4170.735180] LNet: Removed LNI 192.168.203.50@tcp [ 4171.376361] Key type .llcrypt unregistered [ 4171.379646] Key type ._llcrypt unregistered [ 4187.441234] Key type ._llcrypt registered [ 4187.445943] Key type .llcrypt registered [ 4188.790048] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 10:45:06 (1773672306) [ 4191.186365] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4191.212783] alg: No test for adler32 (adler32-zlib) [ 4192.303500] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 4192.536384] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 4194.255163] Key type lgssc registered [ 4196.091515] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4246.010381] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4249.809940] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4268.010615] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4270.615236] Lustre: Mounted lustre-client [ 4289.885898] LustreError: 173005:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c6589800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4289.969768] Lustre: Unmounted lustre-client [ 4317.097376] Key type lgssc unregistered [ 4317.440814] LNet: 173417:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4317.455082] LNetError: 173417:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4317.484065] LNet: Removed LNI 192.168.203.50@tcp [ 4318.218166] Key type .llcrypt unregistered [ 4318.220627] Key type ._llcrypt unregistered [ 4331.617834] Key type ._llcrypt registered [ 4331.645400] Key type .llcrypt registered [ 4332.155334] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4332.167497] alg: No test for adler32 (adler32-zlib) [ 4333.303089] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 4333.632712] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 4335.385855] Key type lgssc registered [ 4336.927966] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4390.358121] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4394.718178] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4414.439392] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4416.286191] Lustre: Mounted lustre-client [ 4424.410264] LustreError: 175996:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c72cf000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4424.554186] Lustre: Unmounted lustre-client [ 4462.465872] Key type lgssc unregistered [ 4462.706726] LNet: 176402:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4462.714122] LNetError: 176402:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4462.743876] LNet: Removed LNI 192.168.203.50@tcp [ 4463.590668] Key type .llcrypt unregistered [ 4463.598604] Key type ._llcrypt unregistered [ 4480.144102] Key type ._llcrypt registered [ 4480.147490] Key type .llcrypt registered [ 4481.578400] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 10:49:59 (1773672599) [ 4504.803912] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4504.837825] alg: No test for adler32 (adler32-zlib) [ 4506.022899] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 4506.302722] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 4508.031175] Key type lgssc registered [ 4509.662289] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4596.077370] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4600.556283] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4624.320651] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4665.708858] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 10:53:03 (1773672783) [ 4698.977760] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4703.545691] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4720.588594] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4723.345566] Lustre: Mounted lustre-client [ 4730.022546] LustreError: 182936:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c5f30800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4730.279267] Lustre: Unmounted lustre-client [ 4760.360877] Key type lgssc unregistered [ 4760.661130] LNet: 183340:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4760.665469] LNetError: 183340:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4760.677157] LNet: Removed LNI 192.168.203.50@tcp [ 4761.376457] Key type .llcrypt unregistered [ 4761.381269] Key type ._llcrypt unregistered [ 4778.124777] Key type ._llcrypt registered [ 4778.129991] Key type .llcrypt registered [ 4779.581914] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 4781.405517] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 10:54:59 (1773672899) [ 4799.769354] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4799.793650] alg: No test for adler32 (adler32-zlib) [ 4800.946753] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 4801.281780] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 4802.999140] Key type lgssc registered [ 4804.078601] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4896.199320] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4899.948629] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4925.121981] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid [ 4945.347758] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid [ 4948.266560] Lustre: Mounted lustre-client [ 4972.746335] LustreError: 188897:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c6267800: lov tgt 1000 not cleaned! deathrow=0, lovrc=1 [ 4972.845977] Lustre: Unmounted lustre-client [ 5101.349473] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5104.745924] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5124.294908] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5174.039285] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 11:01:32 (1773673292) [ 5175.467837] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 5177.283917] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 11:01:35 (1773673295) [ 5231.145108] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5235.551443] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5278.870707] Key type lgssc unregistered [ 5279.247319] LNet: 195104:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5279.252496] LNetError: 195104:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5279.276272] LNet: Removed LNI 192.168.203.50@tcp [ 5280.039331] Key type .llcrypt unregistered [ 5280.059183] Key type ._llcrypt unregistered [ 5293.451744] Key type ._llcrypt registered [ 5293.453832] Key type .llcrypt registered [ 5294.731935] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 11:03:32 (1773673412) [ 5347.992344] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5348.027934] alg: No test for adler32 (adler32-zlib) [ 5349.064709] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 5349.283291] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 5350.967153] Key type lgssc registered [ 5352.231215] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5396.311757] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5400.847873] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5433.608607] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5468.059926] Key type lgssc unregistered [ 5468.269756] LNet: 198748:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5468.273631] LNetError: 198748:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5468.296017] LNet: Removed LNI 192.168.203.50@tcp [ 5468.982240] Key type .llcrypt unregistered [ 5468.988317] Key type ._llcrypt unregistered [ 5487.567585] Key type ._llcrypt registered [ 5487.569130] Key type .llcrypt registered [ 5488.938482] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 11:06:46 (1773673606) [ 5491.792216] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5491.902755] alg: No test for adler32 (adler32-zlib) [ 5493.045416] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 5493.323359] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 5495.023248] Key type lgssc registered [ 5496.501172] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5545.283366] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5549.307930] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5570.076750] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5571.643889] Lustre: Mounted lustre-client [ 5581.813150] Lustre: lustre-MDT0000-mdc-ffff9cc4c846f000: Connection to lustre-MDT0000 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5592.042787] Lustre: lustre-MDT0001-mdc-ffff9cc4c846f000: Connection to lustre-MDT0001 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5603.039321] Lustre: 199738:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773673706/real 1773673706] req@000000004ff97690 x1859831581458432/t0(0) o400->MGC192.168.203.150@tcp@192.168.203.150@tcp:26/25 lens 224/224 e 0 to 1 dl 1773673722 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5603.053511] LustreError: MGC192.168.203.150@tcp: Connection to MGS (at 192.168.203.150@tcp) was lost; in progress operations using this service will fail [ 5613.367945] Lustre: Evicted from MGS (at 192.168.203.150@tcp) after server handle changed from 0x82b787d087f4c60e to 0x82b787d087f4e27e [ 5613.382505] Lustre: MGC192.168.203.150@tcp: Connection restored to 192.168.203.150@tcp (at 192.168.203.150@tcp) [ 5631.974712] LustreError: lustre-MDT0000-mdc-ffff9cc4c846f000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 5632.017251] Lustre: lustre-MDT0000-mdc-ffff9cc4c846f000: Connection restored to 192.168.203.150@tcp (at 192.168.203.150@tcp) [ 5635.695762] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5637.659741] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 5641.582824] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5643.329723] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 5646.450576] LustreError: 203168:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c846f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5646.521158] Lustre: Unmounted lustre-client [ 5681.122532] Key type lgssc unregistered [ 5681.447669] LNet: 203573:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5681.454509] LNetError: 203573:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5681.471131] LNet: Removed LNI 192.168.203.50@tcp [ 5682.074967] Key type .llcrypt unregistered [ 5682.076965] Key type ._llcrypt unregistered [ 5697.033209] Key type ._llcrypt registered [ 5697.034839] Key type .llcrypt registered [ 5698.229812] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 11:10:16 (1773673816) [ 5701.076146] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5701.146029] alg: No test for adler32 (adler32-zlib) [ 5702.259516] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 5702.511673] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 5704.199213] Key type lgssc registered [ 5705.700938] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5757.181482] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5761.318704] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5813.478440] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5817.104694] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5850.277930] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5871.060624] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5910.842566] Key type lgssc unregistered [ 5911.178898] LNet: 208688:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5911.196274] LNetError: 208688:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5911.213805] LNet: Removed LNI 192.168.203.50@tcp [ 5911.857325] Key type .llcrypt unregistered [ 5911.860084] Key type ._llcrypt unregistered [ 5952.407751] Key type ._llcrypt registered [ 5952.409911] Key type .llcrypt registered [ 5953.622501] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 11:14:31 (1773674071) [ 5990.369945] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5990.475338] alg: No test for adler32 (adler32-zlib) [ 5991.633906] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 5991.958345] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 5993.631332] Key type lgssc registered [ 5995.194955] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6073.925607] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6077.612052] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6098.402946] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6149.614140] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 11:17:47 (1773674267) [ 6157.602534] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 11:17:55 (1773674275) [ 6189.820703] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6193.860960] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6214.735977] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6217.547169] Lustre: Mounted lustre-client [ 6223.189148] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [ 6224.949372] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 6226.824302] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 6228.630305] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [ 6233.073964] Lustre: lustre-MDT0000-mdc-ffff9cc4c86ba000: Connection to lustre-MDT0000 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6249.439167] Lustre: 210583:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773674352/real 1773674352] req@000000004ba948d5 x1859832104625792/t0(0) o400->MGC192.168.203.150@tcp@192.168.203.150@tcp:26/25 lens 224/224 e 0 to 1 dl 1773674368 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6249.480144] LustreError: MGC192.168.203.150@tcp: Connection to MGS (at 192.168.203.150@tcp) was lost; in progress operations using this service will fail [ 6259.695332] Lustre: Evicted from MGS (at 192.168.203.150@tcp) after server handle changed from 0x71384b856bf9b903 to 0x71384b856bf9ba76 [ 6259.716572] Lustre: MGC192.168.203.150@tcp: Connection restored to 192.168.203.150@tcp (at 192.168.203.150@tcp) [ 6265.854681] Lustre: lustre-MDT0000-mdc-ffff9cc4c86ba000: Connection restored to 192.168.203.150@tcp (at 192.168.203.150@tcp) [ 6271.433859] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6272.972957] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6276.981067] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [ 6280.188520] Lustre: lustre-MDT0000-mdc-ffff9cc4c86ba000: Connection to lustre-MDT0000 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6296.480395] Lustre: 210583:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773674399/real 1773674399] req@00000000fa543b52 x1859832104634624/t0(0) o400->MGC192.168.203.150@tcp@192.168.203.150@tcp:26/25 lens 224/224 e 0 to 1 dl 1773674415 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6296.593194] LustreError: MGC192.168.203.150@tcp: Connection to MGS (at 192.168.203.150@tcp) was lost; in progress operations using this service will fail [ 6306.839552] Lustre: Evicted from MGS (at 192.168.203.150@tcp) after server handle changed from 0x71384b856bf9ba76 to 0x71384b856bf9be82 [ 6306.880263] Lustre: MGC192.168.203.150@tcp: Connection restored to 192.168.203.150@tcp (at 192.168.203.150@tcp) [ 6311.961715] Lustre: lustre-MDT0000-mdc-ffff9cc4c86ba000: Connection restored to 192.168.203.150@tcp (at 192.168.203.150@tcp) [ 6320.046923] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6321.433464] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6324.509200] LustreError: 218866:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c86ba000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6324.561030] Lustre: Unmounted lustre-client [ 6385.679508] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6389.284754] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6406.993107] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6407.496802] Lustre: Mounted lustre-client [ 6409.277175] LustreError: 220482:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c72c9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6409.373093] Lustre: Unmounted lustre-client [ 6431.571455] Key type lgssc unregistered [ 6431.754035] LNet: 220884:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6431.761146] LNetError: 220884:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6431.778190] LNet: Removed LNI 192.168.203.50@tcp [ 6432.623221] Key type .llcrypt unregistered [ 6432.627594] Key type ._llcrypt unregistered [ 6448.084705] Key type ._llcrypt registered [ 6448.087952] Key type .llcrypt registered [ 6449.482081] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 11:22:47 (1773674567) [ 6452.991932] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6453.006211] alg: No test for adler32 (adler32-zlib) [ 6454.112945] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 6454.316811] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 6455.959291] Key type lgssc registered [ 6456.802235] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6499.548755] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6502.641464] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6520.031766] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6532.147404] Lustre: Mounted lustre-client [ 6538.287412] LustreError: 224061:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c36f4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6538.338068] Lustre: Unmounted lustre-client [ 6598.739820] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6602.110072] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6621.879132] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6622.402776] Lustre: Mounted lustre-client [ 6630.916957] LustreError: 225660:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c72cd800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6630.997085] Lustre: Unmounted lustre-client [ 6694.551620] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6697.924790] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6712.982636] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6718.992903] Lustre: Mounted lustre-client [ 6720.596239] LustreError: 227284:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4d0983800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6720.651184] Lustre: Unmounted lustre-client [ 6746.311935] Key type lgssc unregistered [ 6746.508454] LNet: 227686:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6746.516367] LNetError: 227686:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6746.531905] LNet: Removed LNI 192.168.203.50@tcp [ 6747.177845] Key type .llcrypt unregistered [ 6747.182182] Key type ._llcrypt unregistered [ 6760.955328] Key type ._llcrypt registered [ 6760.957610] Key type .llcrypt registered [ 6762.413902] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 11:28:00 (1773674880) [ 6765.575497] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6765.629457] alg: No test for adler32 (adler32-zlib) [ 6766.955466] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 6767.416529] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 6769.183485] Key type lgssc registered [ 6770.366666] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6815.486786] Key type lgssc unregistered [ 6815.724247] LNet: 230022:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6815.735722] LNetError: 230022:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6815.755951] LNet: Removed LNI 192.168.203.50@tcp [ 6816.216329] Key type .llcrypt unregistered [ 6816.222311] Key type ._llcrypt unregistered [ 6843.953300] Key type ._llcrypt registered [ 6843.963860] Key type .llcrypt registered [ 6844.382293] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6844.392713] alg: No test for adler32 (adler32-zlib) [ 6845.589505] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 6845.862356] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 6847.511166] Key type lgssc registered [ 6848.379493] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6918.627809] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6921.977724] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6939.370923] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6976.659647] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [ 6978.093480] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 11:31:36 (1773675096) [ 7008.028214] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7012.237096] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7031.413401] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7053.918172] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 7055.791875] Lustre: Mounted lustre-client [ 7060.985135] Lustre: lustre-OST0001-osc-ffff9cc4c44d6800: Connection to lustre-OST0001 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7067.700574] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7078.088257] Lustre: Unmounted lustre-client [ 7111.944883] Key type lgssc unregistered [ 7112.322258] LNet: 236933:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7112.335705] LNetError: 236933:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7112.357246] LNet: Removed LNI 192.168.203.50@tcp [ 7113.115205] Key type .llcrypt unregistered [ 7113.119177] Key type ._llcrypt unregistered [ 7140.574182] Key type ._llcrypt registered [ 7140.576704] Key type .llcrypt registered [ 7141.803664] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 11:34:19 (1773675259) [ 7154.608557] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7154.633547] alg: No test for adler32 (adler32-zlib) [ 7155.771224] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 7156.039304] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 7157.695137] Key type lgssc registered [ 7158.843956] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7203.795454] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7209.468897] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7235.439352] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 11:35:53 (1773675353) [ 7264.049690] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7267.663047] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7286.435041] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7289.064201] Lustre: Mounted lustre-client [ 7295.177434] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 7307.207834] Lustre: Unmounted lustre-client [ 7411.718932] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7415.739367] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7463.836886] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7464.513304] Lustre: Mounted lustre-client [ 7466.741556] LustreError: 244021:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c846e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7466.838928] Lustre: Unmounted lustre-client [ 7493.881145] Key type lgssc unregistered [ 7494.245095] LNet: 244424:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7494.251051] LNetError: 244424:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7494.279347] LNet: Removed LNI 192.168.203.50@tcp [ 7494.928886] Key type .llcrypt unregistered [ 7494.933663] Key type ._llcrypt unregistered [ 7523.677088] Key type ._llcrypt registered [ 7523.685239] Key type .llcrypt registered [ 7524.124146] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7524.140093] alg: No test for adler32 (adler32-zlib) [ 7525.175672] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 7525.402194] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 7527.073167] Key type lgssc registered [ 7528.218304] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7601.130612] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7604.617305] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7625.341828] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7665.130348] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [ 7667.031296] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 11:43:04 (1773675784) [ 7697.735560] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7700.977041] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7718.965407] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7723.186944] Lustre: Mounted lustre-client [ 7725.401868] LustreError: 250482:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c5446000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7725.544849] Lustre: Unmounted lustre-client [ 7750.693580] Key type lgssc unregistered [ 7750.943589] LNet: 250885:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7750.952564] LNetError: 250885:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7750.963500] LNet: Removed LNI 192.168.203.50@tcp [ 7751.451937] Key type .llcrypt unregistered [ 7751.454830] Key type ._llcrypt unregistered [ 7765.238373] Key type ._llcrypt registered [ 7765.240467] Key type .llcrypt registered [ 7766.467153] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [ 7767.737565] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 11:44:46 (1773675886) [ 7776.119474] Key type .llcrypt unregistered [ 7776.121671] Key type ._llcrypt unregistered [ 7784.383060] Key type ._llcrypt registered [ 7784.386075] Key type .llcrypt registered [ 7784.748518] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7784.759606] alg: No test for adler32 (adler32-zlib) [ 7785.881780] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 7786.076299] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 7787.743153] Key type lgssc registered [ 7788.857788] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7828.962906] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7838.853149] Lustre: Mounted lustre-client [ 7840.646712] LustreError: 253909:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c9915000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7840.717179] Lustre: Unmounted lustre-client [ 7858.991639] Key type lgssc unregistered [ 7859.168863] LNet: 254311:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7859.175897] LNetError: 254311:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7860.200042] LNet: Removed LNI 192.168.203.50@tcp [ 7860.795130] Key type .llcrypt unregistered [ 7860.797231] Key type ._llcrypt unregistered [ 7874.782717] Key type ._llcrypt registered [ 7874.786139] Key type .llcrypt registered [ 7875.815283] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 11:46:34 (1773675994) [ 7877.793300] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7877.972373] alg: No test for adler32 (adler32-zlib) [ 7879.140775] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 7879.329345] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 7881.012482] Key type lgssc registered [ 7881.981943] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7958.282789] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7983.473379] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7986.379712] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7989.056551] Lustre: Mounted lustre-client [ 7990.927619] LustreError: 257364:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c63d4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7990.984167] Lustre: Unmounted lustre-client [ 8010.915982] Key type lgssc unregistered [ 8011.100894] LNet: 257766:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8011.111503] LNetError: 257766:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8011.143113] LNet: Removed LNI 192.168.203.50@tcp [ 8011.766102] Key type .llcrypt unregistered [ 8011.767928] Key type ._llcrypt unregistered [ 8024.799599] Key type ._llcrypt registered [ 8024.802907] Key type .llcrypt registered [ 8027.578385] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 11:49:04 (1773676144) [ 8029.459933] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8029.653308] alg: No test for adler32 (adler32-zlib) [ 8030.691974] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 8030.909600] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 8032.607348] Key type lgssc registered [ 8033.583736] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8072.176799] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8074.928683] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8089.380607] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8100.371822] Lustre: Mounted lustre-client [ 8105.457723] Lustre: lustre-MDT0000-mdc-ffff9cc4c8b33800: Connection to lustre-MDT0000 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8110.545633] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 8110.594218] LustreError: 260838:0:(file.c:6080:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [ 8110.933335] LustreError: 260857:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c8b33800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8110.955859] LustreError: MGC192.168.203.150@tcp: Connection to MGS (at 192.168.203.150@tcp) was lost; in progress operations using this service will fail [ 8110.996229] Lustre: Unmounted lustre-client [ 8127.988755] Key type lgssc unregistered [ 8128.224078] LNet: 261252:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8128.231655] LNetError: 261252:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8128.265252] LNet: Removed LNI 192.168.203.50@tcp [ 8128.909262] Key type .llcrypt unregistered [ 8128.912504] Key type ._llcrypt unregistered [ 8141.277534] Key type ._llcrypt registered [ 8141.283900] Key type .llcrypt registered [ 8142.218157] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 11:51:00 (1773676260) [ 8144.222702] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8144.333272] alg: No test for adler32 (adler32-zlib) [ 8145.415408] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 8145.644613] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 8147.327475] Key type lgssc registered [ 8148.480133] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8190.128882] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8193.037896] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8208.861571] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8215.601119] Lustre: Mounted lustre-client [ 8220.667218] Lustre: lustre-MDT0001-mdc-ffff9cc4d0987000: Connection to lustre-MDT0001 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8233.804594] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 8235.322465] LustreError: 264356:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4d0987000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8235.370378] Lustre: Unmounted lustre-client [ 8252.256421] Key type lgssc unregistered [ 8252.488890] LNet: 264749:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8252.498855] LNetError: 264749:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8252.518504] LNet: Removed LNI 192.168.203.50@tcp [ 8253.060668] Key type .llcrypt unregistered [ 8253.062217] Key type ._llcrypt unregistered [ 8266.400940] Key type ._llcrypt registered [ 8266.404512] Key type .llcrypt registered [ 8267.466672] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 11:53:05 (1773676385) [ 8282.948096] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8283.021525] alg: No test for adler32 (adler32-zlib) [ 8284.073311] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 8284.259429] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 8285.895454] Key type lgssc registered [ 8287.120556] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8371.163858] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8373.773860] Lustre: Mounted lustre-client [ 8403.434493] Lustre: lustre-MDT0001-mdc-ffff9cc4c5f37800: Connection to lustre-MDT0001 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8412.309387] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 8422.612449] Lustre: Unmounted lustre-client [ 8453.987262] Key type lgssc unregistered [ 8454.184955] LNet: 270502:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8454.190470] LNetError: 270502:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8455.211062] LNet: Removed LNI 192.168.203.50@tcp [ 8455.761776] Key type .llcrypt unregistered [ 8455.765807] Key type ._llcrypt unregistered [ 8467.150777] Key type ._llcrypt registered [ 8467.152829] Key type .llcrypt registered [ 8468.248524] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 11:56:26 (1773676586) [ 8469.437291] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [ 8470.704416] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 11:56:29 (1773676589) [ 8471.933778] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [ 8473.212731] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 11:56:31 (1773676591) [ 8474.446932] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [ 8475.602776] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 11:56:34 (1773676594) [ 8476.685101] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [ 8477.784745] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 11:56:36 (1773676596) [ 8478.913667] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [ 8480.330440] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 11:56:38 (1773676598) [ 8487.056932] Key type .llcrypt unregistered [ 8487.060797] Key type ._llcrypt unregistered [ 8493.030182] Key type ._llcrypt registered [ 8493.032931] Key type .llcrypt registered [ 8493.579841] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8493.594543] alg: No test for adler32 (adler32-zlib) [ 8494.573848] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 8494.722921] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 8496.375162] Key type lgssc registered [ 8497.305521] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8551.845534] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8554.868929] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8570.471317] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8573.152250] Lustre: Mounted lustre-client [ 8574.806728] LustreError: 276095:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c3587000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8574.858706] Lustre: Unmounted lustre-client [ 8604.345251] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 11:58:43 (1773676723) [ 8622.427264] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8624.734893] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8649.064536] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8654.846842] Lustre: Mounted lustre-client [ 8655.432629] LustreError: 278207:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c9911800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8655.489246] Lustre: Unmounted lustre-client [ 8690.431685] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 12:00:08 (1773676808) [ 8706.720330] LNet: 1 local NIs in recovery (showing 1): 192.168.203.50@tcp [ 9283.155516] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9284.351600] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9303.555968] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9351.071366] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 12:11:10 (1773677470) [ 9354.889883] Key type lgssc unregistered [ 9355.009498] LNet: 283747:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9355.013034] LNetError: 283747:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9355.023154] LNet: Removed LNI 192.168.203.50@tcp [ 9355.274230] Key type .llcrypt unregistered [ 9355.275503] Key type ._llcrypt unregistered [ 9429.535337] Key type ._llcrypt registered [ 9429.536481] Key type .llcrypt registered [ 9429.698446] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9429.704196] alg: No test for adler32 (adler32-zlib) [ 9430.561776] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 9430.637452] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 9432.223136] Key type lgssc registered [ 9432.605625] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9464.185923] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9465.517973] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9473.970249] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9487.178685] Key type lgssc unregistered [ 9487.304462] LNet: 287332:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9487.307341] LNetError: 287332:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9487.315709] LNet: Removed LNI 192.168.203.50@tcp [ 9487.555365] Key type .llcrypt unregistered [ 9487.556916] Key type ._llcrypt unregistered [ 9502.561683] Key type ._llcrypt registered [ 9502.562648] Key type .llcrypt registered [ 9503.040271] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 12:13:42 (1773677622) [ 9509.683581] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9509.714263] alg: No test for adler32 (adler32-zlib) [ 9510.577127] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 9510.660805] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 9512.239136] Key type lgssc registered [ 9512.617114] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9529.621446] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 12:14:08 (1773677648) [ 9533.633630] Key type lgssc unregistered [ 9533.742497] LNet: 290875:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9533.745828] LNetError: 290875:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9533.753436] LNet: Removed LNI 192.168.203.50@tcp [ 9533.983447] Key type .llcrypt unregistered [ 9533.984495] Key type ._llcrypt unregistered [ 9537.625890] Key type ._llcrypt registered [ 9537.627310] Key type .llcrypt registered [ 9537.770248] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9537.775719] alg: No test for adler32 (adler32-zlib) [ 9538.637951] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 9538.724778] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 9540.321229] Key type lgssc registered [ 9540.770634] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9562.142858] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9563.363075] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9571.073769] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9659.200396] LNet: Added LNI 192.168.203.50@tcp50 [8/256/0/180] [ 9659.962196] LNet: Removed LNI 192.168.203.50@tcp [ 9661.358246] Lustre: 295140:0:(ldlm_lib.c:562:client_obd_setup()) lustre-MDT0000-mdc-ffff9cc4c4697000: config has no valid NIDs, force dynamic NIDs [ 9662.403310] Lustre: Mounted lustre-client [ 9662.424028] LustreError: 295156:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c4697000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9662.453130] Lustre: Unmounted lustre-client [ 9663.399024] Key type lgssc unregistered [ 9663.555074] LNet: 295406:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9663.557583] LNetError: 295406:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9663.570394] LNet: Removed LNI 192.168.203.50@tcp50 [ 9663.767895] Key type .llcrypt unregistered [ 9663.769042] Key type ._llcrypt unregistered [ 9673.786815] Key type ._llcrypt registered [ 9673.789727] Key type .llcrypt registered [ 9674.017545] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9674.023428] alg: No test for adler32 (adler32-zlib) [ 9674.894425] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 9674.980603] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 9676.567147] Key type lgssc registered [ 9676.984079] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9705.047790] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9706.382377] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9708.929733] LNet: Added LNI 192.168.203.50@tcp50 [8/256/0/180] [ 9709.195619] LNet: Removed LNI 192.168.203.50@tcp [ 9710.251796] Lustre: 298029:0:(ldlm_lib.c:562:client_obd_setup()) lustre-MDT0000-mdc-ffff9cc4c5443000: config has no valid NIDs, force dynamic NIDs [ 9711.299748] Lustre: Mounted lustre-client [ 9711.447243] LustreError: 298064:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c5443000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9711.479680] Lustre: Unmounted lustre-client [ 9738.727706] Key type lgssc unregistered [ 9738.873980] LNet: 298905:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9738.876496] LNetError: 298905:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9738.885518] LNet: Removed LNI 192.168.203.50@tcp50 [ 9739.091409] Key type .llcrypt unregistered [ 9739.092930] Key type ._llcrypt unregistered [ 9742.779542] Key type ._llcrypt registered [ 9742.781219] Key type .llcrypt registered [ 9743.265464] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 12:17:42 (1773677862) [ 9744.081577] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9744.089882] alg: No test for adler32 (adler32-zlib) [ 9744.964189] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 9745.057426] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 9746.647207] Key type lgssc registered [ 9747.069083] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9764.560114] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9765.832768] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9772.339127] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9782.773732] Lustre: Mounted lustre-client [ 9803.444062] Lustre: Mounted fs15246-client [ 9808.422659] LustreError: 303037:0:(lov_obd.c:783:lov_cleanup()) fs15246-clilov-ffff9cc4c9914000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9808.444143] Lustre: Unmounted fs15246-client [ 9821.298437] LustreError: 303256:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cc4c6254000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9821.324706] Lustre: Unmounted lustre-client [ 9846.484760] Key type lgssc unregistered [ 9846.601476] LNet: 303659:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9846.603647] LNetError: 303659:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9846.612603] LNet: Removed LNI 192.168.203.50@tcp [ 9846.832282] Key type .llcrypt unregistered [ 9846.833920] Key type ._llcrypt unregistered [ 9850.414103] Key type ._llcrypt registered [ 9850.415756] Key type .llcrypt registered [ 9850.879470] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 12:19:29 (1773677969) [ 9851.369904] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9851.417714] alg: No test for adler32 (adler32-zlib) [ 9852.278533] Lustre: Lustre: Build Version: 2.17.51_1_gb548ff5 [ 9852.359727] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 9853.943121] Key type lgssc registered [ 9854.272408] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9896.527335] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9897.599796] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9904.383208] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9942.331640] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 9821 sec ========== 12:21:01 (1773678061) [ 9942.848180] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 12:21:01 (1773678061) === [ 9943.859066] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 12:21:02 (1773678062) === [ 9954.477054] Key type lgssc unregistered [ 9954.584861] LNet: 312801:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9954.586954] LNetError: 312801:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9954.592571] LNet: Removed LNI 192.168.203.50@tcp [ 9954.793318] Key type .llcrypt unregistered [ 9954.794163] Key type ._llcrypt unregistered