[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-10.fc44 06/10/2025 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 368068965 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524584K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001008] APIC: Switch to symmetric I/O mode setup [ 0.002291] x2apic enabled [ 0.003005] Switched APIC routing to physical x2apic. [ 0.004007] kvm-guest: setup PV IPIs [ 0.006620] ..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.007016] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.008006] pid_max: default: 32768 minimum: 301 [ 0.009116] LSM: Security Framework initializing [ 0.010034] Yama: becoming mindful. [ 0.011022] SELinux: Initializing. [ 0.012047] *** VALIDATE selinux *** [ 0.020153] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.024491] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.025148] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027089] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028153] *** VALIDATE tmpfs *** [ 0.030021] *** VALIDATE proc *** [ 0.031268] *** VALIDATE cgroup *** [ 0.032005] *** VALIDATE cgroup2 *** [ 0.033215] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.034116] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.035003] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.036020] Spectre V2 : User space: Vulnerable [ 0.037004] Speculative Store Bypass: Vulnerable [ 0.040336] debug: unmapping init [mem 0xffffffff84859000-0xffffffff84860fff] [ 0.042248] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.043752] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.044027] ... version: 2 [ 0.045011] ... bit width: 48 [ 0.046011] ... generic registers: 4 [ 0.047009] ... value mask: 0000ffffffffffff [ 0.048010] ... max period: 00007fffffffffff [ 0.049009] ... fixed-purpose events: 3 [ 0.050007] ... event mask: 000000070000000f [ 0.052174] rcu: Hierarchical SRCU implementation. [ 0.054329] smp: Bringing up secondary CPUs ... [ 0.055541] x86: Booting SMP configuration: [ 0.056013] .... node #0, CPUs: #1 #2 #3 [ 0.065069] smp: Brought up 1 node, 4 CPUs [ 0.067007] smpboot: Max logical packages: 1 [ 0.068014] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.156014] node 0 deferred pages initialised in 86ms [ 0.159204] devtmpfs: initialized [ 0.160199] x86/mm: Memory block size: 128MB [ 0.162568] gcov: version magic: 0x41383552 [ 0.164284] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.165062] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.166228] pinctrl core: initialized pinctrl subsystem [ 0.167162] [ 0.167690] ************************************************************* [ 0.168012] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.169006] ** ** [ 0.170006] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.171007] ** ** [ 0.172008] ** This means that this kernel is built to expose internal ** [ 0.173012] ** IOMMU data structures, which may compromise security on ** [ 0.174013] ** your system. ** [ 0.175011] ** ** [ 0.176012] ** If you see this message and you are not debugging the ** [ 0.177011] ** kernel, report this immediately to your vendor! ** [ 0.178009] ** ** [ 0.179008] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.180009] ************************************************************* [ 0.181650] NET: Registered protocol family 16 [ 0.182415] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.183039] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.184042] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.185617] cpuidle: using governor menu [ 0.188369] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.191603] PCI: Using configuration type 1 for base access [ 0.193144] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.204129] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.205010] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.206250] cryptd: max_cpu_qlen set to 1000 [ 0.207249] ACPI: Added _OSI(Module Device) [ 0.208010] ACPI: Added _OSI(Processor Device) [ 0.209007] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.210007] ACPI: Added _OSI(Processor Aggregator Device) [ 0.214498] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.217109] ACPI: Interpreter enabled [ 0.218045] ACPI: PM: (supports S0 S3 S4 S5) [ 0.219007] ACPI: Using IOAPIC for interrupt routing [ 0.220177] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.221373] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.233054] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.235024] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.237011] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.240073] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.243260] acpiphp: Slot [2] registered [ 0.245121] acpiphp: Slot [5] registered [ 0.246078] acpiphp: Slot [6] registered [ 0.247073] acpiphp: Slot [3] registered [ 0.248070] acpiphp: Slot [4] registered [ 0.249058] acpiphp: Slot [7] registered [ 0.250000] acpiphp: Slot [8] registered [ 0.250000] acpiphp: Slot [9] registered [ 0.252062] acpiphp: Slot [10] registered [ 0.253058] acpiphp: Slot [11] registered [ 0.254080] acpiphp: Slot [12] registered [ 0.255064] acpiphp: Slot [13] registered [ 0.257068] acpiphp: Slot [14] registered [ 0.258063] acpiphp: Slot [15] registered [ 0.259058] acpiphp: Slot [16] registered [ 0.260064] acpiphp: Slot [17] registered [ 0.261070] acpiphp: Slot [18] registered [ 0.263086] acpiphp: Slot [19] registered [ 0.264062] acpiphp: Slot [20] registered [ 0.265062] acpiphp: Slot [21] registered [ 0.266061] acpiphp: Slot [22] registered [ 0.267061] acpiphp: Slot [23] registered [ 0.268067] acpiphp: Slot [24] registered [ 0.270093] acpiphp: Slot [25] registered [ 0.271104] acpiphp: Slot [26] registered [ 0.272152] acpiphp: Slot [27] registered [ 0.273000] acpiphp: Slot [28] registered [ 0.273000] acpiphp: Slot [29] registered [ 0.274081] acpiphp: Slot [30] registered [ 0.276073] acpiphp: Slot [31] registered [ 0.277083] PCI host bridge to bus 0000:00 [ 0.279060] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.281012] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.283015] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.285015] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.287013] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.290015] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.291145] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.293924] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.297184] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.303008] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.306565] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.308014] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.311016] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.313012] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.315531] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.317712] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.320035] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.322784] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.325687] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.330568] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.336012] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.340000] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.349014] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.356013] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.376014] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.386000] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.392012] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.397014] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.409014] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.418548] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.420353] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.422296] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.424289] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.426196] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.429029] iommu: Default domain type: Passthrough [ 0.430358] SCSI subsystem initialized [ 0.431125] ACPI: bus type USB registered [ 0.432043] usbcore: registered new interface driver usbfs [ 0.434057] usbcore: registered new interface driver hub [ 0.436090] usbcore: registered new device driver usb [ 0.437155] pps_core: LinuxPPS API ver. 1 registered [ 0.439050] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.442043] PTP clock support registered [ 0.444212] EDAC MC: Ver: 3.0.0 [ 0.446013] PCI: Using ACPI for IRQ routing [ 0.446889] NetLabel: Initializing [ 0.448008] NetLabel: domain hash size = 128 [ 0.449007] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.451075] NetLabel: unlabeled traffic allowed by default [ 0.453373] vgaarb: loaded [ 0.455035] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.457009] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.463461] clocksource: Switched to clocksource kvm-clock [ 0.576365] VFS: Disk quotas dquot_6.6.0 [ 0.577458] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.579502] *** VALIDATE ramfs *** [ 0.580512] *** VALIDATE hugetlbfs *** [ 0.581729] pnp: PnP ACPI init [ 0.583868] pnp: PnP ACPI: found 6 devices [ 0.599498] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.607863] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.611411] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.615599] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.620772] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.624512] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.628211] NET: Registered protocol family 2 [ 0.629865] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.632962] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.635940] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.642912] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.649092] TCP: Hash tables configured (established 65536 bind 65536) [ 0.654364] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.661337] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.664735] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.667512] NET: Registered protocol family 1 [ 0.675495] RPC: Registered named UNIX socket transport module. [ 0.677924] RPC: Registered udp transport module. [ 0.679976] RPC: Registered tcp transport module. [ 0.681451] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.683839] NET: Registered protocol family 44 [ 0.685706] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.688122] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.692320] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.695898] PCI: CLS 0 bytes, default 64 [ 0.699569] Unpacking initramfs... [ 2.331812] debug: unmapping init [mem 0xffff91bf7cc64000-0xffff91bf7ffcffff] [ 2.336154] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.338454] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.341469] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 3.064789] Initialise system trusted keyrings [ 3.066567] Key type blacklist registered [ 3.068653] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 3.083447] zbud: loaded [ 3.086548] *** VALIDATE nfs *** [ 3.087914] *** VALIDATE nfs4 *** [ 3.089653] pstore: using deflate compression [ 3.094018] Platform Keyring initialized [ 3.287474] NET: Registered protocol family 38 [ 3.289185] Key type asymmetric registered [ 3.290588] Asymmetric key parser 'x509' registered [ 3.292508] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.295469] io scheduler mq-deadline registered [ 3.297405] io scheduler kyber registered [ 3.299322] io scheduler bfq registered [ 3.320173] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.322685] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.325380] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.328834] ACPI: Power Button [PWRF] [ 3.335209] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.343868] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.355643] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.395222] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.426706] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.434095] Non-volatile memory driver v1.3 [ 3.436218] Linux agpgart interface v0.103 [ 3.478447] virtio_blk virtio1: [vda] 146640 512-byte logical blocks (75.1 MB/71.6 MiB) [ 3.481704] vda: detected capacity change from 0 to 75079680 [ 3.699538] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.704290] vdb: detected capacity change from 0 to 1073741824 [ 3.712827] libphy: Fixed MDIO Bus: probed [ 3.717670] usbcore: registered new interface driver usbserial_generic [ 3.720054] usbserial: USB Serial support registered for generic [ 3.722318] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.726936] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.728577] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.730731] mousedev: PS/2 mouse device common for all mice [ 3.733207] rtc_cmos 00:05: RTC can wake from S4 [ 3.736563] rtc_cmos 00:05: registered as rtc0 [ 3.738489] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.741412] intel_pstate: CPU model not supported [ 3.746105] hid: raw HID events driver (C) Jiri Kosina [ 3.746897] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.748885] usbcore: registered new interface driver usbhid [ 3.756102] usbhid: USB HID core driver [ 3.757241] drop_monitor: Initializing network drop monitor service [ 3.762410] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.764123] Initializing XFRM netlink socket [ 3.764513] NET: Registered protocol family 10 [ 3.777715] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.782624] Segment Routing with IPv6 [ 3.784232] NET: Registered protocol family 17 [ 3.786564] mpls_gso: MPLS GSO support [ 3.792268] RAS: Correctable Errors collector initialized. [ 3.795097] AVX version of gcm_enc/dec engaged. [ 3.797125] AES CTR mode by8 optimization enabled [ 3.932312] sched_clock: Marking stable (3932291070, 0)->(4742849390, -810558320) [ 3.941461] registered taskstats version 1 [ 3.947443] Loading compiled-in X.509 certificates [ 3.953574] zswap: loaded using pool lzo/zbud [ 4.057966] Key type big_key registered [ 4.621874] Key type encrypted registered [ 4.624109] ima: No TPM chip found, activating TPM-bypass! [ 4.628291] ima: Allocated hash algorithm: sha1 [ 4.632901] ima: No architecture policies found [ 4.634941] evm: Initialising EVM extended attributes: [ 4.637081] evm: security.selinux [ 4.638420] evm: security.ima [ 4.639375] evm: security.capability [ 4.640715] evm: HMAC attrs: 0x1 [ 4.643201] rtc_cmos 00:05: setting system clock to 2026-08-27 14:21:08 UTC (1787840468) [ 4.650553] debug: unmapping init [mem 0xffffffff85803000-0xffffffff859fffff] [ 4.653900] debug: unmapping init [mem 0xffffffff84582000-0xffffffff84858fff] [ 4.661159] Write protecting the kernel read-only data: 28672k [ 4.669056] debug: unmapping init [mem 0xffffffff82c03000-0xffffffff82dfffff] [ 4.674411] debug: unmapping init [mem 0xffffffff83514000-0xffffffff835fffff] [ 4.815211] 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) [ 4.841188] systemd[1]: Detected virtualization kvm. [ 4.842826] systemd[1]: Detected architecture x86-64. [ 4.844278] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 4.869335] systemd[1]: No hostname configured. [ 4.870950] systemd[1]: Set hostname to . [ 4.873086] random: systemd: uninitialized urandom read (16 bytes read) [ 4.875329] systemd[1]: Initializing machine ID from random generator. [ 5.034253] random: ln: uninitialized urandom read (6 bytes read) [ 5.483480] random: systemd: uninitialized urandom read (16 bytes read) [ 5.486996] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 5.496319] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 5.506852] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket. Starting Journal Service... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Timers. Starting Setup Virtual Console... [ OK ] Reached target Swap. Starting Apply Kernel Variables... [ OK ] Started Memstrack Anylazing Service. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. Starting Create Volatile Files and Directories... [ OK ] Reached target Slices. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Journal Service. [ OK ] Started Create Volatile Files and Directories. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 6.798664] device-mapper: uevent: version 1.0.3 [ 6.800586] 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 ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Mounted Kernel Configuration File System. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 8.045263] virtio_net virtio0 ens2: renamed from eth0 [ 8.520986] scsi host0: ata_piix [ 8.671879] scsi host1: ata_piix [ 8.675307] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 8.681199] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 14.154409] random: crng init done [ 14.160791] random: 7 urandom warning(s) missed due to ratelimiting [ 16.817875] dracut-initqueue[584]: RTNETLINK answers: File exists 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... [ 18.687575] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Swap. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ 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... [ 22.027477] printk: systemd: 22 output lines suppressed due to ratelimiting [ 23.243742] SELinux: Disabled at runtime. [ 23.645093] 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) [ 23.671151] systemd[1]: Detected virtualization kvm. [ 23.677938] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 25.818932] systemd[1]: initrd-switch-root.service: Succeeded. [ 25.836439] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 25.905848] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 25.942665] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 25.969026] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 26.007613] systemd[1]: Starting Journal Service... Starting Journal Service... [ 26.074587] systemd[1]: Mounting Kernel Debug File System... Mounting Kernel Debug File System... [ OK ] Created slice system-serial\x2dgetty.slice. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Created slice system-getty.slice. [ OK ] Listening on Process Core Dump Socket. Mounting Huge Pages File System... Starting Remount Root and Kernel File Systems... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target rpc_pipefs.target. [ OK ] Created slice system-sshd\x2dkeygen.slice. Activating swap /dev/disk/by-label/SWAP... [ OK ] Reached target Paths. Starting Apply Kernel Variables... [ OK ] Stopped target Switch Root. [ 26.822423] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Stopped target Initrd Root File System. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on udev Control Socket. Mounting POSIX Message Queue File System... [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Stopped target Initrd File Systems. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Started Journal Service. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted Huge Pages File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Configure read-only root support... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 28.895558] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 32.137545] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 32.329712] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 33.417546] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 33.552660] EDAC sbridge: Ver: 1.1.2 [* ] A start job is running for Configur…only root support (11s / no limit) [** ] A start job is running for Configur…only root support (12s / no limit) [*** ] A start job is running for Configur…only root support (13s / no limit) [ *** ] A start job is running for Configur…only root support (13s / no limit) [ *** ] A start job is running for Configur…only root support (14s / no limit) [ ***] A start job is running for Configur…only root support (15s / no limit) [ **] A start job is running for Configur…only root support (16s / no limit)[ 41.913637] Key type dns_resolver registered [ *] A start job is running for Configur…only root support (16s / no limit) [ **] A start job is running for Configur…only root support (17s / no limit) [ ***] A start job is running for Configur…only root support (19s / no limit)[ 44.747736] NFS: Registering the id_resolver key type [ 44.749906] Key type id_resolver registered [ 44.752279] Key type id_legacy registered [ *** ] A start job is running for Configur…only root support (19s / no limit) [ *** ] A start job is running for Configur…only root support (20s / no limit) [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started D-Bus System Message Bus. [ OK ] Started dnf makecache --timer. [ OK ] Started irqbalance daemon. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Reached target sshd-keygen.target. Starting Login Service... Starting Network Manager... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. [ OK ] Started Login Service. [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Network Manager Wait Online... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. Starting Hostname Service... [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ 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 Crash recovery kernel arming... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. [ 63.156804] hrtimer: interrupt took 6125051 ns Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg116-client login: [ 148.608835] libcfs: loading out-of-tree module taints kernel. [ 148.768307] Key type ._llcrypt registered [ 148.770221] Key type .llcrypt registered [ 149.032201] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 149.057265] alg: No test for adler32 (adler32-zlib) [ 150.559444] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [ 151.483468] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 153.167172] Key type lgssc registered [ 154.692443] Lustre: Echo OBD driver; http://www.lustre.org/ [ 309.048628] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 313.691351] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 324.397481] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing check_logdir /tmp/testlogs/ [ 330.039820] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing yml_node [ 334.815369] Lustre: lustre-OST0000-osc-ffff91bfc3385800: disconnect after 23s idle [ 335.679610] Lustre: DEBUG MARKER: Client: 2.17.57.85 [ 339.115113] Lustre: DEBUG MARKER: MDS: 2.17.57.85 [ 342.657181] Lustre: DEBUG MARKER: OSS: 2.17.57.85 [ 345.225617] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Thu Aug 27 10:26:47 EDT 2026 [ 365.691537] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 367.041495] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 371.941881] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 382.288533] Lustre: Unmounted lustre-client [ 510.152184] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 516.801893] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 544.039588] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 598.131719] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 10:30:59 (1787841059) [ 600.956847] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 605.861321] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 10:31:06 (1787841066) [ 660.358824] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 668.427142] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 699.810465] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 700.601470] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 708.530385] Lustre: Unmounted lustre-client [ 739.658602] Key type lgssc unregistered [ 740.020768] LNet: 15046:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 740.034984] LNetError: 15046:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 740.053269] LNet: Removed LNI 192.168.201.16@tcp [ 740.926196] Key type .llcrypt unregistered [ 740.931922] Key type ._llcrypt unregistered [ 756.181647] Key type ._llcrypt registered [ 756.184563] Key type .llcrypt registered [ 757.580708] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 10:33:39 (1787841219) [ 758.852267] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 758.861747] alg: No test for adler32 (adler32-zlib) [ 760.150578] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [ 760.412612] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 762.096367] Key type lgssc registered [ 763.344279] Lustre: Echo OBD driver; http://www.lustre.org/ [ 847.027188] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 935.149948] Lustre: Unmounted lustre-client [ 962.311955] Key type lgssc unregistered [ 962.760918] LNet: 18830:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 962.776401] LNetError: 18830:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 962.834323] LNet: Removed LNI 192.168.201.16@tcp [ 964.220229] Key type .llcrypt unregistered [ 964.222720] Key type ._llcrypt unregistered [ 980.575719] Key type ._llcrypt registered [ 980.595582] Key type .llcrypt registered [ 982.464871] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 10:37:24 (1787841444) [ 985.331997] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 985.517508] alg: No test for adler32 (adler32-zlib) [ 986.655700] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [ 986.893912] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 988.567243] Key type lgssc registered [ 990.987581] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1054.053464] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1060.299778] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1091.813888] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1093.929994] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 1105.903683] Lustre: Unmounted lustre-client [ 1140.940806] Key type lgssc unregistered [ 1141.179181] LNet: 22436:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1141.189933] LNetError: 22436:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1141.229248] LNet: Removed LNI 192.168.201.16@tcp [ 1142.120434] Key type .llcrypt unregistered [ 1142.125776] Key type ._llcrypt unregistered [ 1151.976205] Key type ._llcrypt registered [ 1151.979983] Key type .llcrypt registered [ 1153.212598] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 10:40:15 (1787841615) [ 1155.633365] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1155.666848] alg: No test for adler32 (adler32-zlib) [ 1156.730232] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [ 1156.998710] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 1158.727397] Key type lgssc registered [ 1159.573458] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1217.641964] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1226.401425] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1250.628623] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1258.150227] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 1265.881087] Lustre: Unmounted lustre-client [ 1298.070185] Key type lgssc unregistered [ 1298.321165] LNet: 25625:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1298.326793] LNetError: 25625:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1298.342570] LNet: Removed LNI 192.168.201.16@tcp [ 1299.002614] Key type .llcrypt unregistered [ 1299.008809] Key type ._llcrypt unregistered [ 1317.242612] Key type ._llcrypt registered [ 1317.246804] Key type .llcrypt registered [ 1319.090958] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 10:43:00 (1787841780) [ 1322.083964] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1322.167439] alg: No test for adler32 (adler32-zlib) [ 1323.500345] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [ 1323.864639] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 1325.655329] Key type lgssc registered [ 1326.837900] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1390.028824] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1397.321806] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1422.408896] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1425.130797] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 1430.535050] Lustre: lustre-MDT0000-mdc-ffff91bfca59f000: Connection to lustre-MDT0000 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1445.871054] Lustre: lustre-MDT0001-mdc-ffff91bfca59f000: Connection to lustre-MDT0001 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1450.317555] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1457.119325] Lustre: 26579:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787841904/real 1787841904] req@00000000d500d56a x1874687989651456/t0(0) o400->MGC192.168.201.116@tcp@192.168.201.116@tcp:26/25 lens 224/224 e 0 to 1 dl 1787841920 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1457.159058] LustreError: MGC192.168.201.116@tcp: Connection to MGS (at 192.168.201.116@tcp) was lost; in progress operations using this service will fail [ 1460.782647] Lustre: Unmounted lustre-client [ 1492.268541] Key type lgssc unregistered [ 1492.515939] LNet: 29275:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1492.525264] LNetError: 29275:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1492.553256] LNet: Removed LNI 192.168.201.16@tcp [ 1493.520640] Key type .llcrypt unregistered [ 1493.526380] Key type ._llcrypt unregistered [ 1509.890138] Key type ._llcrypt registered [ 1509.891395] Key type .llcrypt registered [ 1511.110166] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 10:46:13 (1787841973) [ 1513.444742] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1513.457732] alg: No test for adler32 (adler32-zlib) [ 1514.606791] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [ 1514.874597] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 1516.615310] Key type lgssc registered [ 1518.015962] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1572.159436] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1578.004869] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1600.761683] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1603.546208] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 1613.820984] Lustre: lustre-OST0000-osc-ffff91bfc734e800: Connection to lustre-OST0000 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1619.624732] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1630.065084] Lustre: Unmounted lustre-client [ 1675.928417] Key type lgssc unregistered [ 1676.340816] LNet: 32886:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1676.354211] LNetError: 32886:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1676.375104] LNet: Removed LNI 192.168.201.16@tcp [ 1677.421958] Key type .llcrypt unregistered [ 1677.426982] Key type ._llcrypt unregistered [ 1695.245042] Key type ._llcrypt registered [ 1695.248250] Key type .llcrypt registered [ 1697.017491] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 10:49:18 (1787842158) [ 1699.973971] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1700.063492] alg: No test for adler32 (adler32-zlib) [ 1701.259395] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [ 1701.595095] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 1703.296521] Key type lgssc registered [ 1705.400319] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1760.054434] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1766.927978] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1788.993832] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1790.788577] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 1797.926459] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1801.548187] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1812.956580] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-08-2710h51m15s [ 1815.120890] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1821.668982] Lustre: lustre-MDT0000-mdc-ffff91bfca4f0000: Connection to lustre-MDT0000 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1825.949584] LustreError: 36674:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff91bfca4f0000: can't stat MDS #0: rc = -19 [ 1827.832559] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1840.106101] LustreError: MGC192.168.201.116@tcp: Connection to MGS (at 192.168.201.116@tcp) was lost; in progress operations using this service will fail [ 1840.117620] Lustre: Evicted from MGS (at 192.168.201.116@tcp) after server handle changed from 0x727506aeb263a40 to 0x727506aeb263d0a [ 1840.134092] Lustre: MGC192.168.201.116@tcp: Connection restored to 192.168.201.116@tcp (at 192.168.201.116@tcp) [ 1841.194331] LustreError: lustre-MDT0000-mdc-ffff91bfca4f0000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1841.210314] Lustre: lustre-MDT0000-mdc-ffff91bfca4f0000: Connection restored to 192.168.201.116@tcp (at 192.168.201.116@tcp) [ 1843.818580] Lustre: DEBUG MARKER: Wait for df (36674) ... [ 1845.085171] Lustre: DEBUG MARKER: done [ 1846.487067] Lustre: Unmounted lustre-client [ 1883.099206] Key type lgssc unregistered [ 1883.423218] LNet: 37689:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1883.430738] LNetError: 37689:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1884.454257] LNet: Removed LNI 192.168.201.16@tcp [ 1884.962884] Key type .llcrypt unregistered [ 1884.964912] Key type ._llcrypt unregistered [ 1920.757814] Key type ._llcrypt registered [ 1920.759381] Key type .llcrypt registered [ 1922.287319] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 10:53:04 (1787842384) [ 1925.355788] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1925.391525] alg: No test for adler32 (adler32-zlib) [ 1926.563391] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [ 1926.851281] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 1928.657616] Key type lgssc registered [ 1930.262408] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1989.613115] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1995.910797] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2019.144396] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2021.222124] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 2026.088366] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-08-2710h54m48s [ 2027.821089] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 2034.869434] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 2037.391769] Lustre: DEBUG MARKER: Creating a test file and stat it [ 2057.695496] Lustre: 41824:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787842501/real 1787842501] req@000000001c662e7a x1874688621942528/t0(0) o101->lustre-MDT0000-mdc-ffff91bfc79a4000@192.168.201.116@tcp:12/10 lens 576/1152 e 0 to 1 dl 1787842521 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 2057.766866] Lustre: lustre-MDT0000-mdc-ffff91bfc79a4000: Connection to lustre-MDT0000 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2057.848053] Lustre: lustre-MDT0000-mdc-ffff91bfc79a4000: Connection restored to 192.168.201.116@tcp (at 192.168.201.116@tcp) [ 2079.419841] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 2082.662381] Lustre: DEBUG MARKER: done [ 2087.313223] Lustre: Unmounted lustre-client [ 2116.075760] Key type lgssc unregistered [ 2116.386285] LNet: 42711:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2116.394840] LNetError: 42711:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2116.426464] LNet: Removed LNI 192.168.201.16@tcp [ 2117.326069] Key type .llcrypt unregistered [ 2117.329166] Key type ._llcrypt unregistered [ 2148.979548] Key type ._llcrypt registered [ 2148.989479] Key type .llcrypt registered [ 2150.172943] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 10:56:52 (1787842612) [ 2151.767552] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 2153.353793] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 10:56:55 (1787842615) [ 2156.681040] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2156.782746] alg: No test for adler32 (adler32-zlib) [ 2157.860762] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [ 2158.083384] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 2159.783222] Key type lgssc registered [ 2160.811407] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2188.078967] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 10:57:30 (1787842650) [ 2221.004518] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2226.446330] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2247.715839] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2250.379711] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 2253.711760] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 2256.430614] Lustre: Unmounted lustre-client [ 2273.385641] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 2320.762828] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2325.685592] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2326.148898] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 2329.708159] Lustre: Unmounted lustre-client [ 2391.665533] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2396.667908] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2397.638851] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 2401.552099] Lustre: Unmounted lustre-client [ 2422.247628] Lustre: DEBUG MARKER: files compared the same [ 2445.571469] Key type lgssc unregistered [ 2445.852835] LNet: 50334:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2445.858836] LNetError: 50334:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2445.887769] LNet: Removed LNI 192.168.201.16@tcp [ 2446.576500] Key type .llcrypt unregistered [ 2446.579719] Key type ._llcrypt unregistered [ 2461.388296] Key type ._llcrypt registered [ 2461.391254] Key type .llcrypt registered [ 2462.598528] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 11:02:05 (1787842925) [ 2464.938158] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2465.051154] alg: No test for adler32 (adler32-zlib) [ 2466.182257] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [ 2466.414278] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 2468.079523] Key type lgssc registered [ 2469.129319] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2523.304387] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2530.453950] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2555.431440] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2558.173309] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 2563.001574] Lustre: Unmounted lustre-client [ 2591.800970] Key type lgssc unregistered [ 2592.116874] LNet: 53911:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2592.128278] LNetError: 53911:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2592.150674] LNet: Removed LNI 192.168.201.16@tcp [ 2592.862319] Key type .llcrypt unregistered [ 2592.864891] Key type ._llcrypt unregistered [ 2609.874879] Key type ._llcrypt registered [ 2609.880096] Key type .llcrypt registered [ 2611.593532] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 11:04:33 (1787843073) [ 2613.794427] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2613.968366] alg: No test for adler32 (adler32-zlib) [ 2614.989741] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [ 2615.194676] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 2616.863532] Key type lgssc registered [ 2618.760477] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2706.245406] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2739.397535] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2745.128627] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2783.523959] Key type lgssc unregistered [ 2783.785837] LNet: 57393:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2783.792072] LNetError: 57393:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2783.807989] LNet: Removed LNI 192.168.201.16@tcp [ 2784.626774] Key type .llcrypt unregistered [ 2784.631797] Key type ._llcrypt unregistered [ 2801.247737] Key type ._llcrypt registered [ 2801.251438] Key type .llcrypt registered [ 2802.954486] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 11:07:44 (1787843264) [ 2806.821681] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2806.959359] alg: No test for adler32 (adler32-zlib) [ 2808.063315] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [ 2808.277408] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 2809.943194] Key type lgssc registered [ 2811.209397] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2885.969902] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 2892.841262] Lustre: Unmounted lustre-client [ 2929.897346] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 11:09:52 (1787843392) [ 3058.093799] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3075.821738] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 3083.455548] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3083.470866] LustreError: 63873:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff91bfca464800: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 3083.498554] Lustre: 58375:0:(llite_lib.c:4342:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.201.116@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 3093.798287] Lustre: Unmounted lustre-client [ 3136.622115] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 11:13:19 (1787843599) [ 3149.224130] Key type lgssc unregistered [ 3149.545780] LNet: 65086:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3149.569180] LNetError: 65086:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3149.595181] LNet: Removed LNI 192.168.201.16@tcp [ 3150.655671] Key type .llcrypt unregistered [ 3150.658360] Key type ._llcrypt unregistered [ 3163.420742] Key type ._llcrypt registered [ 3163.478762] Key type .llcrypt registered [ 3163.812866] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3163.830629] alg: No test for adler32 (adler32-zlib) [ 3165.007966] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [ 3165.252531] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 3167.015187] Key type lgssc registered [ 3168.539855] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3222.523595] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3228.908655] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3253.110404] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3255.912813] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 3260.039608] Lustre: Unmounted lustre-client [ 3286.392915] Key type lgssc unregistered [ 3286.804096] LNet: 68099:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3286.810394] LNetError: 68099:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3286.835956] LNet: Removed LNI 192.168.201.16@tcp [ 3287.933781] Key type .llcrypt unregistered [ 3287.942253] Key type ._llcrypt unregistered [ 3298.540791] Key type ._llcrypt registered [ 3298.544397] Key type .llcrypt registered [ 3299.137586] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3299.150201] alg: No test for adler32 (adler32-zlib) [ 3300.197469] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [ 3300.452832] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 3302.135253] Key type lgssc registered [ 3303.468922] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3408.894228] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3415.079759] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3435.583832] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3437.346221] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 3440.769413] Lustre: Unmounted lustre-client [ 3469.658413] Key type lgssc unregistered [ 3469.954817] LNet: 71523:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3469.961358] LNetError: 71523:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3469.978899] LNet: Removed LNI 192.168.201.16@tcp [ 3470.667658] Key type .llcrypt unregistered [ 3470.670747] Key type ._llcrypt unregistered [ 3491.745213] Key type ._llcrypt registered [ 3491.748164] Key type .llcrypt registered [ 3493.966889] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 11:19:15 (1787843955) [ 3496.752967] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3496.777149] alg: No test for adler32 (adler32-zlib) [ 3497.840597] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [ 3498.072329] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 3499.690817] Key type lgssc registered [ 3501.028110] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3556.361814] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3563.183945] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3590.286790] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3592.217097] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 3598.595156] Lustre: Unmounted lustre-client [ 3599.074253] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 3602.806575] Lustre: Unmounted lustre-client [ 3630.421277] Key type lgssc unregistered [ 3630.732806] LNet: 75211:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3630.740916] LNetError: 75211:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3630.768096] LNet: Removed LNI 192.168.201.16@tcp [ 3631.729218] Key type .llcrypt unregistered [ 3631.730976] Key type ._llcrypt unregistered [ 3646.486352] Key type ._llcrypt registered [ 3646.490510] Key type .llcrypt registered [ 3646.863901] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3646.884759] alg: No test for adler32 (adler32-zlib) [ 3648.012914] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [ 3648.276868] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 3649.943194] Key type lgssc registered [ 3650.981550] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3699.909725] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3705.480519] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3727.876779] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3729.666373] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 3734.275181] Lustre: Unmounted lustre-client [ 3778.663822] Key type lgssc unregistered [ 3778.888329] LNet: 78241:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3778.893504] LNetError: 78241:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3779.943606] LNet: Removed LNI 192.168.201.16@tcp [ 3780.966926] Key type .llcrypt unregistered [ 3780.974278] Key type ._llcrypt unregistered [ 3796.757709] Key type ._llcrypt registered [ 3796.759639] Key type .llcrypt registered [ 3798.303240] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 11:24:20 (1787844260) [ 3800.700839] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3800.725085] alg: No test for adler32 (adler32-zlib) [ 3801.874076] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [ 3802.215511] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 3803.944852] Key type lgssc registered [ 3805.208136] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3860.142422] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3865.315840] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3886.284756] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3889.148531] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 3902.522481] Lustre: lustre: root_squash is set to 0:0 [ 3902.569536] Lustre: lustre: nosquash_nids is cleared [ 3911.767631] Lustre: lustre: root_squash is set to 500:500 [ 3922.010036] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.201.16@tcp 192.168.0.[2,10]@tcp [ 3927.476495] Lustre: lustre: nosquash_nids set to 192.168.201.16/24@tcp [ 3931.866644] Lustre: Unmounted lustre-client [ 3959.968943] Key type lgssc unregistered [ 3960.354674] LNet: 82164:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3960.366811] LNetError: 82164:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3960.395271] LNet: Removed LNI 192.168.201.16@tcp [ 3961.346142] Key type .llcrypt unregistered [ 3961.349960] Key type ._llcrypt unregistered [ 3977.689505] Key type ._llcrypt registered [ 3977.691467] Key type .llcrypt registered [ 3978.907101] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 11:27:21 (1787844441) [ 3981.180985] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3982.813445] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 11:27:25 (1787844445) [ 3985.129824] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3985.159040] alg: No test for adler32 (adler32-zlib) [ 3986.270793] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [ 3986.529581] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 3988.255210] Key type lgssc registered [ 3989.444529] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4040.819619] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4047.983768] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4073.348324] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4075.031746] Lustre: lustre: root_squash is set to 500:500 [ 4075.079925] Lustre: lustre: nosquash_nids set to 192.168.201.16/24@tcp [ 4076.108393] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 4082.136808] Lustre: Unmounted lustre-client [ 4110.454272] Key type lgssc unregistered [ 4110.670515] LNet: 86115:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4110.674828] LNetError: 86115:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4110.703379] LNet: Removed LNI 192.168.201.16@tcp [ 4111.353524] Key type .llcrypt unregistered [ 4111.355222] Key type ._llcrypt unregistered [ 4128.387331] Key type ._llcrypt registered [ 4128.390951] Key type .llcrypt registered [ 4129.862917] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 4131.653327] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 11:29:54 (1787844594) [ 4154.934973] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4154.965452] alg: No test for adler32 (adler32-zlib) [ 4156.261138] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [ 4156.638451] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 4158.351291] Key type lgssc registered [ 4160.226050] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4251.070974] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4257.452213] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4280.280524] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4368.842826] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4375.302476] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4401.118313] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4417.496318] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 4455.515925] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff91bfc8d36800.ost_server_uuid 50 [ 4457.592232] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff91bfc8d36800.ost_server_uuid in IDLE state after 0 sec [ 4458.352964] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 4463.346310] Lustre: Unmounted lustre-client [ 4466.152986] Lustre: Unmounted lustre-client [ 4517.582471] Key type lgssc unregistered [ 4517.885367] LNet: 93779:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4517.891986] LNetError: 93779:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4517.913872] LNet: Removed LNI 192.168.201.16@tcp [ 4518.829820] Key type .llcrypt unregistered [ 4518.831414] Key type ._llcrypt unregistered [ 4556.180347] Key type ._llcrypt registered [ 4556.182680] Key type .llcrypt registered [ 4557.724733] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 11:36:59 (1787845019) [ 4580.021131] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4580.046912] alg: No test for adler32 (adler32-zlib) [ 4581.568347] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [ 4582.078836] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 4583.859244] Key type lgssc registered [ 4585.935414] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4686.230322] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4692.628979] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4717.066452] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4718.675265] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 4740.063240] Lustre: 95142:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787845187/real 1787845187] req@000000001753832a x1874691405911168/t0(0) o400->lustre-OST0000-osc-ffff91bfc80a2000@192.168.201.116@tcp:28/4 lens 224/224 e 0 to 1 dl 1787845203 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4740.112023] Lustre: lustre-OST0000-osc-ffff91bfc80a2000: Connection to lustre-OST0000 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4744.351241] Lustre: 95141:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787845192/real 1787845192] req@0000000008f42852 x1874691405911680/t0(0) o400->lustre-OST0000-osc-ffff91bfc80a2000@192.168.201.116@tcp:28/4 lens 224/224 e 0 to 1 dl 1787845208 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4749.535162] Lustre: 95141:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787845197/real 1787845197] req@000000005686a3bc x1874691405912192/t0(0) o400->lustre-OST0000-osc-ffff91bfc80a2000@192.168.201.116@tcp:28/4 lens 224/224 e 0 to 1 dl 1787845213 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4750.395334] Lustre: lustre-OST0000-osc-ffff91bfc80a2000: Connection restored to 192.168.201.116@tcp (at 192.168.201.116@tcp) [ 4754.335309] Lustre: 95142:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787845202/real 1787845202] req@000000004937d390 x1874691405912704/t0(0) o400->lustre-OST0000-osc-ffff91bfc80a2000@192.168.201.116@tcp:28/4 lens 224/224 e 0 to 1 dl 1787845218 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4755.449847] Lustre: lustre-MDT0000-mdc-ffff91bfc80a2000: Connection to lustre-MDT0000 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4771.615184] Lustre: 95141:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787845219/real 1787845219] req@00000000c0eca89f x1874691405915008/t0(0) o400->MGC192.168.201.116@tcp@192.168.201.116@tcp:26/25 lens 224/224 e 0 to 1 dl 1787845235 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4771.664064] LustreError: MGC192.168.201.116@tcp: Connection to MGS (at 192.168.201.116@tcp) was lost; in progress operations using this service will fail [ 4781.886042] Lustre: Evicted from MGS (at 192.168.201.116@tcp) after server handle changed from 0xce02d988d109fe86 to 0xce02d988d10a0118 [ 4781.920944] Lustre: MGC192.168.201.116@tcp: Connection restored to 192.168.201.116@tcp (at 192.168.201.116@tcp) [ 4785.836362] Lustre: lustre-MDT0000-mdc-ffff91bfc80a2000: Connection restored to 192.168.201.116@tcp (at 192.168.201.116@tcp) [ 4792.504983] Lustre: Unmounted lustre-client [ 4824.440667] Key type lgssc unregistered [ 4824.960914] LNet: 99447:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4824.984333] LNetError: 99447:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4825.039374] LNet: Removed LNI 192.168.201.16@tcp [ 4826.213587] Key type .llcrypt unregistered [ 4826.219527] Key type ._llcrypt unregistered [ 4847.243843] Key type ._llcrypt registered [ 4847.255567] Key type .llcrypt registered [ 4849.006755] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 11:41:50 (1787845310) [ 4864.425231] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4864.440519] alg: No test for adler32 (adler32-zlib) [ 4865.736958] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [ 4866.073940] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 4867.812393] Key type lgssc registered [ 4869.275289] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4909.927919] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5022.437861] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5029.624947] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5054.621865] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5057.717121] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 5059.772711] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 5061.717883] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 11:45:24 (1787845524) [ 5256.000952] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5266.361344] Lustre: Unmounted lustre-client [ 5401.269888] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5407.672475] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5432.990171] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5477.143627] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 11:52:19 (1787845939) [ 5580.943402] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5587.711554] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5611.154286] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5611.852956] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 5616.386887] Lustre: Unmounted lustre-client [ 5648.502030] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 11:55:11 (1787846111) [ 5748.833830] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5755.552103] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5781.362473] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5782.072259] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 5786.368530] Lustre: Unmounted lustre-client [ 5815.074552] Key type lgssc unregistered [ 5815.352264] LNet: 122476:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5815.374342] LNetError: 122476:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5815.394826] LNet: Removed LNI 192.168.201.16@tcp [ 5816.471324] Key type .llcrypt unregistered [ 5816.478278] Key type ._llcrypt unregistered [ 5832.985078] Key type ._llcrypt registered [ 5832.991911] Key type .llcrypt registered [ 5834.452852] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 11:58:16 (1787846296) [ 5837.068720] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5837.109739] alg: No test for adler32 (adler32-zlib) [ 5838.305149] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [ 5838.508723] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 5840.223241] Key type lgssc registered [ 5841.430704] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5901.105893] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5908.795232] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5935.675574] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5942.844729] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 5957.761540] Lustre: Unmounted lustre-client [ 5989.501883] Key type lgssc unregistered [ 5989.749734] LNet: 126066:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5989.764971] LNetError: 126066:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5989.780818] LNet: Removed LNI 192.168.201.16@tcp [ 5990.362206] Key type .llcrypt unregistered [ 5990.365288] Key type ._llcrypt unregistered [ 6008.171242] Key type ._llcrypt registered [ 6008.173453] Key type .llcrypt registered [ 6009.755793] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 12:01:11 (1787846471) [ 6012.036638] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6012.131128] alg: No test for adler32 (adler32-zlib) [ 6013.346332] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [ 6013.589326] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 6015.255194] Key type lgssc registered [ 6016.564874] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6072.050931] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6078.065943] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6102.982696] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6106.126182] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 6111.225431] Lustre: lustre-OST0000-osc-ffff91bfc32db800: Connection to lustre-OST0000 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6124.613514] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff91bfc32db800.ost_server_uuid 50 [ 6126.775481] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 6138.574212] Lustre: Unmounted lustre-client [ 6161.920110] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 12:03:43 (1787846623) [ 6197.547871] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6204.252142] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6230.029411] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6261.102753] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6261.878288] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 6267.394139] Lustre: lustre-OST0000-osc-ffff91bfd0318800: Connection to lustre-OST0000 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6295.578034] Lustre: Unmounted lustre-client [ 6346.156419] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 12:06:48 (1787846808) [ 6381.584442] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6387.783889] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6413.401779] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6436.079544] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6436.721221] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 6441.967817] Lustre: lustre-OST0000-osc-ffff91bfc3386800: Connection to lustre-OST0000 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6459.901471] Lustre: Unmounted lustre-client [ 6511.996190] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 12:09:34 (1787846974) [ 6615.229171] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6622.231969] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6651.812109] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6722.511930] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6728.629327] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6749.431856] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6776.076386] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 6822.405082] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6824.769994] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6827.557595] Lustre: Unmounted lustre-client [ 6858.575299] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 12:15:21 (1787847321) [ 6891.925289] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6897.056855] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6917.296869] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6948.342223] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6985.094565] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 7029.871491] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7032.310368] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7046.303963] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7056.624622] Lustre: Unmounted lustre-client [ 7114.592500] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 12:19:37 (1787847577) [ 7145.286700] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7150.873522] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7171.299683] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7171.804987] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 7192.875668] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7194.431427] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7207.810540] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff91bfc9389800.ost_server_uuid 50 [ 7209.116520] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff91bfc9389800.ost_server_uuid in IDLE state after 0 sec [ 7211.600745] Lustre: Unmounted lustre-client [ 7211.848699] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 7211.851253] Lustre: Skipped 3 previous similar messages [ 7211.952133] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 7212.796835] LustreError: 149479:0:(sec.c:403:import_sec_validate_get()) import 00000000a731d2f6 (NEW) with no sec [ 7275.315659] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 12:22:17 (1787847737) [ 7310.033537] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7316.034443] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7341.331793] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7364.063576] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7364.651230] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 7364.843731] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 7380.752157] Lustre: Unmounted lustre-client [ 7380.756234] Lustre: Skipped 1 previous similar message [ 7428.879870] Key type lgssc unregistered [ 7429.193377] LNet: 152906:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7429.210984] LNetError: 152906:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7429.239473] LNet: Removed LNI 192.168.201.16@tcp [ 7430.117319] Key type .llcrypt unregistered [ 7430.119327] Key type ._llcrypt unregistered [ 7450.546787] Key type ._llcrypt registered [ 7450.549673] Key type .llcrypt registered [ 7451.821647] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 12:25:14 (1787847914) [ 7453.323351] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7453.531032] alg: No test for adler32 (adler32-zlib) [ 7454.576741] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [ 7454.894639] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 7456.647498] Key type lgssc registered [ 7457.898307] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7522.821632] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7528.711374] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7555.741871] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7579.409244] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7580.010899] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7581.194148] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 7587.849225] LustreError: lustre-MDT0001-mdc-ffff91bfc2ebf800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 7587.881139] Lustre: lustre-MDT0001-mdc-ffff91bfc2ebf800: Connection restored to 192.168.201.116@tcp (at 192.168.201.116@tcp) [ 7594.735446] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7596.824344] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7618.573913] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7630.825665] Lustre: Unmounted lustre-client [ 7665.516684] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 12:28:47 (1787848127) [ 7775.020968] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7783.427838] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7808.578385] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7809.397638] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 7843.218979] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7845.902438] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7859.160332] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7869.514763] Lustre: Unmounted lustre-client [ 7913.828420] Key type lgssc unregistered [ 7914.076824] LNet: 162520:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7914.082815] LNetError: 162520:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7914.106386] LNet: Removed LNI 192.168.201.16@tcp [ 7915.169941] Key type .llcrypt unregistered [ 7915.174623] Key type ._llcrypt unregistered [ 7954.203555] Key type ._llcrypt registered [ 7954.208403] Key type .llcrypt registered [ 7955.703812] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 12:33:37 (1787848417) [ 7958.258191] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7958.445937] alg: No test for adler32 (adler32-zlib) [ 7959.610261] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [ 7959.852272] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 7961.503431] Key type lgssc registered [ 7963.263639] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8019.641172] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8026.518826] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8050.241699] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8053.126528] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 8104.187375] Lustre: Unmounted lustre-client [ 8149.459380] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8150.102694] LustreError: 163659:0:(lmv_obd.c:211:lmv_notify()) activation of lustre-MDT0001_UUID failed: -22 [ 8150.142560] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 8186.186240] Lustre: Unmounted lustre-client [ 8215.779733] Key type lgssc unregistered [ 8216.091129] LNet: 166965:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8216.104929] LNetError: 166965:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8216.145560] LNet: Removed LNI 192.168.201.16@tcp [ 8216.977246] Key type .llcrypt unregistered [ 8216.978640] Key type ._llcrypt unregistered [ 8233.179627] Key type ._llcrypt registered [ 8233.181334] Key type .llcrypt registered [ 8234.391544] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 12:38:16 (1787848696) [ 8237.243280] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8237.539381] alg: No test for adler32 (adler32-zlib) [ 8238.796705] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [ 8239.215168] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 8240.975195] Key type lgssc registered [ 8242.347581] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8295.685156] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8301.014108] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8319.880589] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8322.771138] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 8342.340210] Lustre: Unmounted lustre-client [ 8375.484819] Key type lgssc unregistered [ 8375.795880] LNet: 170712:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8375.799794] LNetError: 170712:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8375.818392] LNet: Removed LNI 192.168.201.16@tcp [ 8377.215269] Key type .llcrypt unregistered [ 8377.217717] Key type ._llcrypt unregistered [ 8394.262049] Key type ._llcrypt registered [ 8394.273233] Key type .llcrypt registered [ 8394.826868] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8394.846355] alg: No test for adler32 (adler32-zlib) [ 8396.104885] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [ 8396.436862] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 8398.167309] Key type lgssc registered [ 8400.067773] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8459.238218] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8465.432391] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8488.583231] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8490.203803] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 8498.124533] Lustre: Unmounted lustre-client [ 8525.810662] Key type lgssc unregistered [ 8526.098753] LNet: 173773:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8526.108965] LNetError: 173773:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8526.129937] LNet: Removed LNI 192.168.201.16@tcp [ 8527.045154] Key type .llcrypt unregistered [ 8527.054211] Key type ._llcrypt unregistered [ 8545.086688] Key type ._llcrypt registered [ 8545.091758] Key type .llcrypt registered [ 8546.504176] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 12:43:28 (1787849008) [ 8548.982712] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8549.011142] alg: No test for adler32 (adler32-zlib) [ 8550.135355] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [ 8550.345455] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 8551.983290] Key type lgssc registered [ 8553.257613] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8610.814373] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8618.109205] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8641.485929] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8643.404540] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 8661.233083] Lustre: Unmounted lustre-client [ 8687.430155] Key type lgssc unregistered [ 8687.662915] LNet: 177520:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8687.670146] LNetError: 177520:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8688.678139] LNet: Removed LNI 192.168.201.16@tcp [ 8689.401142] Key type .llcrypt unregistered [ 8689.405512] Key type ._llcrypt unregistered [ 8702.230369] Key type ._llcrypt registered [ 8702.235133] Key type .llcrypt registered [ 8702.745927] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8702.761400] alg: No test for adler32 (adler32-zlib) [ 8703.864557] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [ 8704.119590] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 8705.776199] Key type lgssc registered [ 8706.985563] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8756.157306] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8763.406377] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8786.997218] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8789.747626] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 8798.140296] Lustre: Unmounted lustre-client [ 8825.146604] Key type lgssc unregistered [ 8825.335821] LNet: 180579:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8825.339782] LNetError: 180579:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8826.425174] LNet: Removed LNI 192.168.201.16@tcp [ 8827.399961] Key type .llcrypt unregistered [ 8827.403823] Key type ._llcrypt unregistered [ 8845.788452] Key type ._llcrypt registered [ 8845.790863] Key type .llcrypt registered [ 8846.985684] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 12:48:29 (1787849309) [ 8873.719492] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8873.732884] alg: No test for adler32 (adler32-zlib) [ 8874.981932] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [ 8875.585779] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 8877.432402] Key type lgssc registered [ 8879.025069] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8969.175582] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8975.807923] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9000.349591] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9064.595955] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 12:52:06 (1787849526) [ 9107.952387] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9113.712299] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9136.541339] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9137.903122] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 9146.399281] Lustre: Unmounted lustre-client [ 9173.642908] Key type lgssc unregistered [ 9173.868235] LNet: 187677:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9173.878127] LNetError: 187677:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9173.907105] LNet: Removed LNI 192.168.201.16@tcp [ 9174.892495] Key type .llcrypt unregistered [ 9174.897430] Key type ._llcrypt unregistered [ 9191.473329] Key type ._llcrypt registered [ 9191.475603] Key type .llcrypt registered [ 9192.858424] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 9194.891767] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 12:54:17 (1787849657) [ 9213.813510] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9213.995196] alg: No test for adler32 (adler32-zlib) [ 9215.188315] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [ 9215.472531] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 9217.152482] Key type lgssc registered [ 9218.867421] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9312.863193] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9318.788393] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9342.301276] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9362.222455] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9363.965166] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [ 9387.047437] Lustre: Unmounted lustre-client [ 9520.793048] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9526.435989] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9548.968231] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9588.849651] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 13:00:51 (1787850051) [ 9590.492986] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 9592.272454] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 13:00:54 (1787850054) [ 9685.487153] Key type lgssc unregistered [ 9685.856976] LNet: 199123:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9685.868780] LNetError: 199123:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9685.895713] LNet: Removed LNI 192.168.201.16@tcp [ 9686.565506] Key type .llcrypt unregistered [ 9686.567701] Key type ._llcrypt unregistered [ 9701.719938] Key type ._llcrypt registered [ 9701.722185] Key type .llcrypt registered [ 9703.097673] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 13:02:45 (1787850165) [ 9748.720239] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9748.850448] alg: No test for adler32 (adler32-zlib) [ 9750.060550] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [ 9750.371953] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 9752.143190] Key type lgssc registered [ 9753.715945] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9802.478564] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9809.599154] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9846.470146] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9875.029486] Key type lgssc unregistered [ 9875.259195] LNet: 202852:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9875.269794] LNetError: 202852:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9875.285487] LNet: Removed LNI 192.168.201.16@tcp [ 9876.095308] Key type .llcrypt unregistered [ 9876.099861] Key type ._llcrypt unregistered [ 9895.986982] Key type ._llcrypt registered [ 9895.989170] Key type .llcrypt registered [ 9897.629317] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 13:05:59 (1787850359) [ 9900.859378] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9900.878920] alg: No test for adler32 (adler32-zlib) [ 9901.931493] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [ 9902.226183] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 9903.951182] Key type lgssc registered [ 9905.435217] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9958.853926] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9964.287636] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9987.201284] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9989.962927] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [10000.387824] Lustre: lustre-MDT0000-mdc-ffff91bfc6b81800: Connection to lustre-MDT0000 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [10005.489116] Lustre: lustre-MDT0001-mdc-ffff91bfc6b81800: Connection to lustre-MDT0001 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [10021.856342] Lustre: 203842:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787850469/real 1787850469] req@0000000001eeb65e x1874696985460992/t0(0) o400->MGC192.168.201.116@tcp@192.168.201.116@tcp:26/25 lens 224/224 e 0 to 1 dl 1787850485 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10021.894837] LustreError: MGC192.168.201.116@tcp: Connection to MGS (at 192.168.201.116@tcp) was lost; in progress operations using this service will fail [10046.435549] Lustre: Evicted from MGS (at 192.168.201.116@tcp) after server handle changed from 0x828514d3ccaf7fdf to 0x828514d3ccaf9923 [10046.446564] Lustre: MGC192.168.201.116@tcp: Connection restored to 192.168.201.116@tcp (at 192.168.201.116@tcp) [10070.959203] LustreError: lustre-MDT0000-mdc-ffff91bfc6b81800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [10071.042412] Lustre: lustre-MDT0000-mdc-ffff91bfc6b81800: Connection restored to 192.168.201.116@tcp (at 192.168.201.116@tcp) [10076.892407] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10079.160129] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10086.121896] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10088.012332] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [10092.291512] Lustre: Unmounted lustre-client [10131.251712] Key type lgssc unregistered [10131.483215] LNet: 207799:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10131.492489] LNetError: 207799:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10131.524303] LNet: Removed LNI 192.168.201.16@tcp [10132.324415] Key type .llcrypt unregistered [10132.330684] Key type ._llcrypt unregistered [10148.786908] Key type ._llcrypt registered [10148.789626] Key type .llcrypt registered [10150.527644] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 13:10:12 (1787850612) [10153.187483] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10153.219444] alg: No test for adler32 (adler32-zlib) [10154.310523] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [10154.677209] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [10156.335628] Key type lgssc registered [10157.697664] Lustre: Echo OBD driver; http://www.lustre.org/ [10210.862514] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10216.468761] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10282.684924] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10288.532561] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10329.878442] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10353.559953] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10401.190870] Key type lgssc unregistered [10401.547397] LNet: 213091:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10401.559363] LNetError: 213091:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10401.568539] LNet: Removed LNI 192.168.201.16@tcp [10402.351872] Key type .llcrypt unregistered [10402.368260] Key type ._llcrypt unregistered [10451.924445] Key type ._llcrypt registered [10451.926174] Key type .llcrypt registered [10453.584449] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 13:15:15 (1787850915) [10489.761337] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10489.949456] alg: No test for adler32 (adler32-zlib) [10491.110967] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [10491.321181] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [10492.991365] Key type lgssc registered [10494.887938] Lustre: Echo OBD driver; http://www.lustre.org/ [10584.563507] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10591.670955] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10617.205993] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10673.034756] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 13:18:54 (1787851134) [10682.335739] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 13:19:04 (1787851144) [10722.717093] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10729.041615] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10750.666507] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10752.427483] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [10763.403489] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [10766.642264] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [10768.977243] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [10770.588612] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [10772.978790] Lustre: lustre-MDT0000-mdc-ffff91bfc3381000: Connection to lustre-MDT0000 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [10789.345048] Lustre: 214987:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787851236/real 1787851236] req@000000003214d6ec x1874697603000448/t0(0) o400->MGC192.168.201.116@tcp@192.168.201.116@tcp:26/25 lens 224/224 e 0 to 1 dl 1787851252 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10789.370311] LustreError: MGC192.168.201.116@tcp: Connection to MGS (at 192.168.201.116@tcp) was lost; in progress operations using this service will fail [10799.604939] Lustre: Evicted from MGS (at 192.168.201.116@tcp) after server handle changed from 0xc8bf03805b7cb885 to 0xc8bf03805b7cb9ff [10799.612680] Lustre: MGC192.168.201.116@tcp: Connection restored to 192.168.201.116@tcp (at 192.168.201.116@tcp) [10806.460769] Lustre: lustre-MDT0000-mdc-ffff91bfc3381000: Connection restored to 192.168.201.116@tcp (at 192.168.201.116@tcp) [10817.241868] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10819.827978] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10824.188461] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [10830.311307] Lustre: lustre-MDT0000-mdc-ffff91bfc3381000: Connection to lustre-MDT0000 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [10846.687194] Lustre: 214984:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787851294/real 1787851294] req@00000000cb23bd9d x1874697603010304/t0(0) o400->MGC192.168.201.116@tcp@192.168.201.116@tcp:26/25 lens 224/224 e 0 to 1 dl 1787851310 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10846.748086] LustreError: MGC192.168.201.116@tcp: Connection to MGS (at 192.168.201.116@tcp) was lost; in progress operations using this service will fail [10856.956626] Lustre: Evicted from MGS (at 192.168.201.116@tcp) after server handle changed from 0xc8bf03805b7cb9ff to 0xc8bf03805b7cbe0b [10856.978800] Lustre: MGC192.168.201.116@tcp: Connection restored to 192.168.201.116@tcp (at 192.168.201.116@tcp) [10859.194311] Lustre: lustre-MDT0000-mdc-ffff91bfc3381000: Connection restored to 192.168.201.116@tcp (at 192.168.201.116@tcp) [10873.914107] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10875.827708] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10879.259464] Lustre: Unmounted lustre-client [10951.303434] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10957.288456] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10980.179922] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10981.569826] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [10990.120463] Lustre: Unmounted lustre-client [11017.845145] Key type lgssc unregistered [11018.227776] LNet: 225567:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11018.234895] LNetError: 225567:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11018.264530] LNet: Removed LNI 192.168.201.16@tcp [11019.318502] Key type .llcrypt unregistered [11019.320428] Key type ._llcrypt unregistered [11038.533417] Key type ._llcrypt registered [11038.535573] Key type .llcrypt registered [11040.002894] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 13:25:02 (1787851502) [11045.478859] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11045.542447] alg: No test for adler32 (adler32-zlib) [11046.692817] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [11047.134307] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [11049.185248] Key type lgssc registered [11051.535937] Lustre: Echo OBD driver; http://www.lustre.org/ [11114.075557] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11120.816654] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11145.618863] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11147.349976] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [11155.613246] Lustre: Unmounted lustre-client [11218.226030] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11224.671752] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11253.470419] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11254.373415] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [11263.477165] Lustre: Unmounted lustre-client [11347.877239] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11356.854130] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11385.439832] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11391.553405] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [11394.999875] Lustre: Unmounted lustre-client [11423.308476] Key type lgssc unregistered [11423.608233] LNet: 232613:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11423.625270] LNetError: 232613:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11423.649793] LNet: Removed LNI 192.168.201.16@tcp [11424.340160] Key type .llcrypt unregistered [11424.344440] Key type ._llcrypt unregistered [11441.247246] Key type ._llcrypt registered [11441.249433] Key type .llcrypt registered [11442.718140] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 13:31:44 (1787851904) [11445.855700] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11445.940350] alg: No test for adler32 (adler32-zlib) [11447.071519] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [11447.502554] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [11449.327472] Key type lgssc registered [11450.972471] Lustre: Echo OBD driver; http://www.lustre.org/ [11492.686478] Key type lgssc unregistered [11493.050766] LNet: 234930:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11493.057520] LNetError: 234930:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11493.077254] LNet: Removed LNI 192.168.201.16@tcp [11493.647628] Key type .llcrypt unregistered [11493.650168] Key type ._llcrypt unregistered [11525.938653] Key type ._llcrypt registered [11525.942904] Key type .llcrypt registered [11526.420180] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11526.428770] alg: No test for adler32 (adler32-zlib) [11527.700642] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [11528.109364] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [11530.007181] Key type lgssc registered [11532.925606] Lustre: Echo OBD driver; http://www.lustre.org/ [11624.382401] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11630.698761] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11657.062035] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11703.400067] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [11705.260712] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 13:36:07 (1787852167) [11745.033279] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11751.950847] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11781.618770] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11810.514720] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11812.158437] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [11817.458608] Lustre: lustre-OST0001-osc-ffff91bfc6a9f800: Connection to lustre-OST0001 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [11825.584547] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11835.965166] Lustre: Unmounted lustre-client [11882.595890] Key type lgssc unregistered [11882.873805] LNet: 242047:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11882.879994] LNetError: 242047:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11882.898861] LNet: Removed LNI 192.168.201.16@tcp [11883.727277] Key type .llcrypt unregistered [11883.733564] Key type ._llcrypt unregistered [11925.438223] Key type ._llcrypt registered [11925.441288] Key type .llcrypt registered [11926.816541] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 13:39:49 (1787852389) [11943.198588] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11943.327120] alg: No test for adler32 (adler32-zlib) [11944.535711] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [11944.752095] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [11946.479253] Key type lgssc registered [11948.083467] Lustre: Echo OBD driver; http://www.lustre.org/ [11998.494555] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12005.086578] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12029.192596] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 13:41:31 (1787852491) [12062.028916] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12067.816564] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12095.340103] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12099.094639] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [12109.117261] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [12119.996149] Lustre: Unmounted lustre-client [12234.237465] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12241.501533] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12292.797297] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12293.196250] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [12295.375948] Lustre: Unmounted lustre-client [12324.458243] Key type lgssc unregistered [12324.771659] LNet: 249732:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12324.775277] LNetError: 249732:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12324.801062] LNet: Removed LNI 192.168.201.16@tcp [12326.008474] Key type .llcrypt unregistered [12326.011066] Key type ._llcrypt unregistered [12357.402248] Key type ._llcrypt registered [12357.406386] Key type .llcrypt registered [12357.906856] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12357.939187] alg: No test for adler32 (adler32-zlib) [12359.021962] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [12359.171923] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [12360.831924] Key type lgssc registered [12362.060926] Lustre: Echo OBD driver; http://www.lustre.org/ [12453.122897] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12460.790116] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12486.462681] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12527.057361] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [12529.079342] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 13:49:51 (1787852991) [12562.230418] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12567.712980] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12589.864864] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12594.464890] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [12597.291377] Lustre: Unmounted lustre-client [12626.986584] Key type lgssc unregistered [12627.202600] LNet: 256367:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12627.221519] LNetError: 256367:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12628.294351] LNet: Removed LNI 192.168.201.16@tcp [12629.243503] Key type .llcrypt unregistered [12629.246336] Key type ._llcrypt unregistered [12648.352655] Key type ._llcrypt registered [12648.354023] Key type .llcrypt registered [12649.747310] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [12651.375937] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 13:51:53 (1787853113) [12660.367365] Key type .llcrypt unregistered [12660.369600] Key type ._llcrypt unregistered [12669.662542] Key type ._llcrypt registered [12669.670409] Key type .llcrypt registered [12670.410694] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12670.437240] alg: No test for adler32 (adler32-zlib) [12671.578957] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [12671.884417] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [12673.615288] Key type lgssc registered [12675.301469] Lustre: Echo OBD driver; http://www.lustre.org/ [12739.060030] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12755.282249] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [12757.942655] Lustre: Unmounted lustre-client [12789.824717] Key type lgssc unregistered [12790.339125] LNet: 259816:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12790.352565] LNetError: 259816:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12790.381697] LNet: Removed LNI 192.168.201.16@tcp [12791.493193] Key type .llcrypt unregistered [12791.502095] Key type ._llcrypt unregistered [12809.575473] Key type ._llcrypt registered [12809.580536] Key type .llcrypt registered [12810.701936] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 13:54:33 (1787853273) [12812.649357] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12812.673474] alg: No test for adler32 (adler32-zlib) [12813.814995] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [12814.108621] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [12815.775162] Key type lgssc registered [12817.505730] Lustre: Echo OBD driver; http://www.lustre.org/ [12906.492700] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12942.670486] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12948.702116] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12951.375468] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [12954.488689] Lustre: Unmounted lustre-client [12974.072278] Key type lgssc unregistered [12974.384636] LNet: 263352:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12974.394978] LNetError: 263352:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12974.410724] LNet: Removed LNI 192.168.201.16@tcp [12975.198635] Key type .llcrypt unregistered [12975.200057] Key type ._llcrypt unregistered [12993.099814] Key type ._llcrypt registered [12993.108344] Key type .llcrypt registered [12994.360673] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 13:57:36 (1787853456) [12996.947649] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12996.967088] alg: No test for adler32 (adler32-zlib) [12998.144349] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [12998.394805] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [13000.136412] Key type lgssc registered [13001.243580] Lustre: Echo OBD driver; http://www.lustre.org/ [13054.668639] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13059.726258] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13082.828437] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13085.581427] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [13090.798120] Lustre: lustre-MDT0000-mdc-ffff91bfc9ef9800: Connection to lustre-MDT0000 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [13096.432439] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [13096.492867] LustreError: 266507:0:(file.c:6167:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [13096.979305] LustreError: MGC192.168.201.116@tcp: Connection to MGS (at 192.168.201.116@tcp) was lost; in progress operations using this service will fail [13097.076365] Lustre: Unmounted lustre-client [13118.679590] Key type lgssc unregistered [13119.032554] LNet: 266920:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13119.037809] LNetError: 266920:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13119.055802] LNet: Removed LNI 192.168.201.16@tcp [13119.905417] Key type .llcrypt unregistered [13119.911923] Key type ._llcrypt unregistered [13137.478885] Key type ._llcrypt registered [13137.486536] Key type .llcrypt registered [13139.217751] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 14:00:01 (1787853601) [13141.975736] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13142.009257] alg: No test for adler32 (adler32-zlib) [13143.209447] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [13143.425657] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [13145.079193] Key type lgssc registered [13146.271380] Lustre: Echo OBD driver; http://www.lustre.org/ [13196.991406] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13203.587232] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13226.425654] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13229.417342] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [13234.678737] Lustre: lustre-MDT0001-mdc-ffff91bfc6b86000: Connection to lustre-MDT0001 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [13240.087635] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [13242.258628] Lustre: Unmounted lustre-client [13263.475433] Key type lgssc unregistered [13263.761971] LNet: 270510:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13263.771410] LNetError: 270510:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13264.806468] LNet: Removed LNI 192.168.201.16@tcp [13265.605738] Key type .llcrypt unregistered [13265.607814] Key type ._llcrypt unregistered [13281.940366] Key type ._llcrypt registered [13281.945397] Key type .llcrypt registered [13283.722658] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 14:02:25 (1787853745) [13304.876688] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13304.903195] alg: No test for adler32 (adler32-zlib) [13306.080249] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [13306.422986] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [13308.103713] Key type lgssc registered [13309.281127] Lustre: Echo OBD driver; http://www.lustre.org/ [13433.513446] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13435.132431] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [13473.269056] Lustre: lustre-MDT0001-mdc-ffff91bfd03c4000: Connection to lustre-MDT0001 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [13484.928308] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [13495.289904] Lustre: Unmounted lustre-client [13523.216242] Key type lgssc unregistered [13523.615205] LNet: 276289:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13523.626515] LNetError: 276289:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13523.649784] LNet: Removed LNI 192.168.201.16@tcp [13524.486960] Key type .llcrypt unregistered [13524.490287] Key type ._llcrypt unregistered [13543.167512] Key type ._llcrypt registered [13543.170445] Key type .llcrypt registered [13544.716390] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 14:06:46 (1787854006) [13546.382276] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [13548.802327] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 14:06:50 (1787854010) [13550.442080] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [13552.555406] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 14:06:54 (1787854014) [13554.286675] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [13556.466533] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 14:06:58 (1787854018) [13558.071272] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [13560.317522] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 14:07:02 (1787854022) [13562.192142] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [13563.855680] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 14:07:06 (1787854026) [13572.948371] Key type .llcrypt unregistered [13572.950785] Key type ._llcrypt unregistered [13580.065144] Key type ._llcrypt registered [13580.066948] Key type .llcrypt registered [13580.710540] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13580.747181] alg: No test for adler32 (adler32-zlib) [13581.924552] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [13582.121744] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [13583.759220] Key type lgssc registered [13585.067185] Lustre: Echo OBD driver; http://www.lustre.org/ [13656.078454] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13662.851338] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13686.938147] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13689.486918] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [13692.192898] Lustre: Unmounted lustre-client [13734.123216] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 14:09:55 (1787854195) [13768.250766] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13773.840484] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13809.879522] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13815.961336] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [13819.594158] Lustre: Unmounted lustre-client [13867.367443] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 14:12:09 (1787854329) [13871.521596] LNet: 1 local NIs in recovery (showing 1): 192.168.201.16@tcp [14513.093640] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14518.825946] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14552.330343] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14639.577884] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 14:25:02 (1787855102) [14652.032590] Key type lgssc unregistered [14652.329812] LNet: 289810:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14652.347457] LNetError: 289810:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14652.374342] LNet: Removed LNI 192.168.201.16@tcp [14653.265703] Key type .llcrypt unregistered [14653.268143] Key type ._llcrypt unregistered [14753.227079] Key type ._llcrypt registered [14753.229234] Key type .llcrypt registered [14753.586331] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14753.600454] alg: No test for adler32 (adler32-zlib) [14754.860101] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [14755.133139] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [14756.808208] Key type lgssc registered [14758.247300] Lustre: Echo OBD driver; http://www.lustre.org/ [14857.602507] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14863.562452] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14888.178718] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14922.904725] Key type lgssc unregistered [14923.181693] LNet: 293500:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14923.199223] LNetError: 293500:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14923.222140] LNet: Removed LNI 192.168.201.16@tcp [14923.917689] Key type .llcrypt unregistered [14923.921916] Key type ._llcrypt unregistered [14965.930266] Key type ._llcrypt registered [14965.935596] Key type .llcrypt registered [14967.595677] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 14:30:29 (1787855429) [14994.834992] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14994.846189] alg: No test for adler32 (adler32-zlib) [14995.945036] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [14996.372717] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [14998.183204] Key type lgssc registered [14999.846610] Lustre: Echo OBD driver; http://www.lustre.org/ [15056.533563] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 14:31:58 (1787855518) [15070.523537] Key type lgssc unregistered [15070.845861] LNet: 297059:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15070.855827] LNetError: 297059:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15070.870823] LNet: Removed LNI 192.168.201.16@tcp [15071.609865] Key type .llcrypt unregistered [15071.612532] Key type ._llcrypt unregistered [15082.781551] Key type ._llcrypt registered [15082.783139] Key type .llcrypt registered [15083.289700] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15083.303265] alg: No test for adler32 (adler32-zlib) [15084.386843] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [15084.654428] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [15086.335218] Key type lgssc registered [15087.522277] Lustre: Echo OBD driver; http://www.lustre.org/ [15148.204611] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15154.435282] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15181.030658] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15475.530321] LNet: Added LNI 192.168.201.16@tcp50 [8/256/0/180] [15477.707810] LNet: Removed LNI 192.168.201.16@tcp [15479.838321] Lustre: 301466:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff91bfc34e4000: config has no valid NIDs, force dynamic NIDs [15481.025386] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [15481.210052] Lustre: Unmounted lustre-client [15483.936657] Key type lgssc unregistered [15484.319600] LNet: 301726:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15484.329768] LNetError: 301726:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15484.363236] LNet: Removed LNI 192.168.201.16@tcp50 [15485.081713] Key type .llcrypt unregistered [15485.084282] Key type ._llcrypt unregistered [15502.491653] Key type ._llcrypt registered [15502.499949] Key type .llcrypt registered [15503.108555] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15503.129473] alg: No test for adler32 (adler32-zlib) [15504.330296] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [15504.614568] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [15506.295210] Key type lgssc registered [15507.710860] Lustre: Echo OBD driver; http://www.lustre.org/ [15570.420359] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15575.882673] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15583.211041] LNet: Added LNI 192.168.201.16@tcp50 [8/256/0/180] [15583.929837] LNet: Removed LNI 192.168.201.16@tcp [15585.146331] Lustre: 304416:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff91bfca465000: config has no valid NIDs, force dynamic NIDs [15586.451828] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [15586.957901] Lustre: Unmounted lustre-client [15619.483315] Key type lgssc unregistered [15619.847617] LNet: 305287:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15619.854262] LNetError: 305287:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15619.875103] LNet: Removed LNI 192.168.201.16@tcp50 [15620.521141] Key type .llcrypt unregistered [15620.526498] Key type ._llcrypt unregistered [15632.510994] Key type ._llcrypt registered [15632.513690] Key type .llcrypt registered [15634.019516] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 14:41:36 (1787856096) [15652.146111] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15652.288324] alg: No test for adler32 (adler32-zlib) [15653.421925] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [15653.610970] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [15655.263221] Key type lgssc registered [15656.661441] Lustre: Echo OBD driver; http://www.lustre.org/ [15744.025689] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15750.467552] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15776.931932] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15819.737802] Key type lgssc unregistered [15819.996940] LNet: 310044:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15820.005769] LNetError: 310044:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15820.021496] LNet: Removed LNI 192.168.201.16@tcp [15820.684789] Key type .llcrypt unregistered [15820.688616] Key type ._llcrypt unregistered [15832.580191] Key type ._llcrypt registered [15832.581893] Key type .llcrypt registered [15834.239291] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 14:44:56 (1787856296) [15836.579187] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15836.643390] alg: No test for adler32 (adler32-zlib) [15837.700899] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [15837.896363] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [15839.535968] Key type lgssc registered [15840.735968] Lustre: Echo OBD driver; http://www.lustre.org/ [15890.737890] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15896.396719] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15918.904705] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15921.807697] Lustre: Mounted lustre-client - version 2.17.57_85_g0128fd2 [15979.326218] Lustre: Mounted fs15246-client - version 2.17.57_85_g0128fd2 [15996.565569] Lustre: Unmounted fs15246-client [16028.245947] Lustre: Unmounted lustre-client [16060.219342] Key type lgssc unregistered [16060.542297] LNet: 314910:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16060.554990] LNetError: 314910:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16060.600066] LNet: Removed LNI 192.168.201.16@tcp [16061.312764] Key type .llcrypt unregistered [16061.318070] Key type ._llcrypt unregistered [16072.851822] Key type ._llcrypt registered [16072.859919] Key type .llcrypt registered [16073.762373] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 14:48:56 (1787856536) [16075.691130] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16075.800243] alg: No test for adler32 (adler32-zlib) [16076.885451] Lustre: Lustre: Build Version: 2.17.57_85_g0128fd2 [16077.128824] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [16078.783194] Key type lgssc registered [16079.971175] Lustre: Echo OBD driver; http://www.lustre.org/ [16217.031882] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16223.489838] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16250.164912] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16361.178830] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 16014 sec ========= 14:53:43 (1787856823) [16362.755427] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 14:53:45 (1787856825) === [16366.449659] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 14:53:48 (1787856828) === [16400.354919] Key type lgssc unregistered [16400.694480] LNet: 324176:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16400.703517] LNetError: 324176:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16400.716507] LNet: Removed LNI 192.168.201.16@tcp [16401.335669] Key type .llcrypt unregistered [16401.340171] Key type ._llcrypt unregistered