[ 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 468452651 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2400.000 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: 2895288K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001011] APIC: Switch to symmetric I/O mode setup [ 0.002128] x2apic enabled [ 0.003005] Switched APIC routing to physical x2apic. [ 0.004015] kvm-guest: setup PV IPIs [ 0.007000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.007000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x22983777dd9, max_idle_ns: 440795300422 ns [ 0.007016] Calibrating delay loop (skipped) preset value.. 4800.00 BogoMIPS (lpj=2400000) [ 0.008007] pid_max: default: 32768 minimum: 301 [ 0.009125] LSM: Security Framework initializing [ 0.010048] Yama: becoming mindful. [ 0.011029] SELinux: Initializing. [ 0.012057] *** VALIDATE selinux *** [ 0.019584] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.023261] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.024141] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.025089] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.026088] *** VALIDATE tmpfs *** [ 0.028136] *** VALIDATE proc *** [ 0.029204] *** VALIDATE cgroup *** [ 0.030006] *** VALIDATE cgroup2 *** [ 0.031263] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.032149] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.033008] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.034024] Spectre V2 : User space: Vulnerable [ 0.035006] Speculative Store Bypass: Vulnerable [ 0.038553] debug: unmapping init [mem 0xffffffffb1c59000-0xffffffffb1c60fff] [ 0.040228] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.041721] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.042018] ... version: 2 [ 0.043009] ... bit width: 48 [ 0.044016] ... generic registers: 4 [ 0.045014] ... value mask: 0000ffffffffffff [ 0.046008] ... max period: 00007fffffffffff [ 0.047009] ... fixed-purpose events: 3 [ 0.048010] ... event mask: 000000070000000f [ 0.049368] rcu: Hierarchical SRCU implementation. [ 0.051378] smp: Bringing up secondary CPUs ... [ 0.052590] x86: Booting SMP configuration: [ 0.053018] .... node #0, CPUs: #1 #2 #3 [ 0.056255] smp: Brought up 1 node, 4 CPUs [ 0.058010] smpboot: Max logical packages: 1 [ 0.059015] smpboot: Total of 4 processors activated (19200.00 BogoMIPS) [ 0.122704] node 0 deferred pages initialised in 61ms [ 0.125592] devtmpfs: initialized [ 0.127311] x86/mm: Memory block size: 128MB [ 0.130934] gcov: version magic: 0x41383552 [ 0.132194] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.137081] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.141493] pinctrl core: initialized pinctrl subsystem [ 0.145171] [ 0.145929] ************************************************************* [ 0.151013] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.154098] ** ** [ 0.157008] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.160009] ** ** [ 0.164010] ** This means that this kernel is built to expose internal ** [ 0.167010] ** IOMMU data structures, which may compromise security on ** [ 0.170015] ** your system. ** [ 0.174011] ** ** [ 0.178009] ** If you see this message and you are not debugging the ** [ 0.181010] ** kernel, report this immediately to your vendor! ** [ 0.183008] ** ** [ 0.186008] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.191011] ************************************************************* [ 0.194399] NET: Registered protocol family 16 [ 0.196377] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.198036] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.200035] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.203046] cpuidle: using governor menu [ 0.204699] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.205492] PCI: Using configuration type 1 for base access [ 0.207125] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.218092] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.220009] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.222322] cryptd: max_cpu_qlen set to 1000 [ 0.224208] ACPI: Added _OSI(Module Device) [ 0.225009] ACPI: Added _OSI(Processor Device) [ 0.226007] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.228007] ACPI: Added _OSI(Processor Aggregator Device) [ 0.232401] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.237333] ACPI: Interpreter enabled [ 0.238041] ACPI: PM: (supports S0 S3 S4 S5) [ 0.240010] ACPI: Using IOAPIC for interrupt routing [ 0.241071] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.244349] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.253472] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.255022] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.257012] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.260063] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.264208] acpiphp: Slot [2] registered [ 0.265104] acpiphp: Slot [5] registered [ 0.266123] acpiphp: Slot [6] registered [ 0.267111] acpiphp: Slot [3] registered [ 0.269060] acpiphp: Slot [4] registered [ 0.270054] acpiphp: Slot [7] registered [ 0.271057] acpiphp: Slot [8] registered [ 0.272059] acpiphp: Slot [9] registered [ 0.273057] acpiphp: Slot [10] registered [ 0.274056] acpiphp: Slot [11] registered [ 0.275073] acpiphp: Slot [12] registered [ 0.276067] acpiphp: Slot [13] registered [ 0.278062] acpiphp: Slot [14] registered [ 0.279065] acpiphp: Slot [15] registered [ 0.280052] acpiphp: Slot [16] registered [ 0.281060] acpiphp: Slot [17] registered [ 0.282000] acpiphp: Slot [18] registered [ 0.282000] acpiphp: Slot [19] registered [ 0.282070] acpiphp: Slot [20] registered [ 0.283000] acpiphp: Slot [21] registered [ 0.284061] acpiphp: Slot [22] registered [ 0.286061] acpiphp: Slot [23] registered [ 0.287063] acpiphp: Slot [24] registered [ 0.288090] acpiphp: Slot [25] registered [ 0.289062] acpiphp: Slot [26] registered [ 0.290144] acpiphp: Slot [27] registered [ 0.292078] acpiphp: Slot [28] registered [ 0.293057] acpiphp: Slot [29] registered [ 0.294055] acpiphp: Slot [30] registered [ 0.295053] acpiphp: Slot [31] registered [ 0.296040] PCI host bridge to bus 0000:00 [ 0.297010] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.299010] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.300008] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.302041] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.304011] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.306012] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.307148] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.309943] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.312081] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.318009] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.322053] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.324011] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.327015] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.330014] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.332466] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.333000] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.334029] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.336628] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.339961] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.348013] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.351010] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.355717] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.367011] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.378799] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.415011] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.434265] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.459016] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.480012] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.509012] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.529472] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.531361] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.534270] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.536302] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.538149] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.542161] iommu: Default domain type: Passthrough [ 0.543339] SCSI subsystem initialized [ 0.545102] ACPI: bus type USB registered [ 0.546077] usbcore: registered new interface driver usbfs [ 0.547049] usbcore: registered new interface driver hub [ 0.549053] usbcore: registered new device driver usb [ 0.550149] pps_core: LinuxPPS API ver. 1 registered [ 0.552008] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.554040] PTP clock support registered [ 0.557064] EDAC MC: Ver: 3.0.0 [ 0.559099] PCI: Using ACPI for IRQ routing [ 0.560426] NetLabel: Initializing [ 0.561007] NetLabel: domain hash size = 128 [ 0.563010] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.564055] NetLabel: unlabeled traffic allowed by default [ 0.566078] vgaarb: loaded [ 0.568282] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.569000] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.576305] clocksource: Switched to clocksource kvm-clock [ 0.673454] VFS: Disk quotas dquot_6.6.0 [ 0.674610] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.676955] *** VALIDATE ramfs *** [ 0.677949] *** VALIDATE hugetlbfs *** [ 0.679627] pnp: PnP ACPI init [ 0.681933] pnp: PnP ACPI: found 6 devices [ 0.753827] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.757694] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.760696] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.763266] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.766227] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.769261] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.773230] NET: Registered protocol family 2 [ 0.776370] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.782327] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.787320] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.793598] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.796413] TCP: Hash tables configured (established 65536 bind 65536) [ 0.798744] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.801730] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.804305] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.807270] NET: Registered protocol family 1 [ 0.811377] RPC: Registered named UNIX socket transport module. [ 0.814428] RPC: Registered udp transport module. [ 0.816815] RPC: Registered tcp transport module. [ 0.818618] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.821355] NET: Registered protocol family 44 [ 0.823470] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.826261] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.829864] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.832794] PCI: CLS 0 bytes, default 64 [ 0.834964] Unpacking initramfs... [ 2.139841] debug: unmapping init [mem 0xffff9f727cc64000-0xffff9f727ffcffff] [ 2.146775] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.149564] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.152737] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x22983777dd9, max_idle_ns: 440795300422 ns [ 2.652490] Initialise system trusted keyrings [ 2.654962] Key type blacklist registered [ 2.657117] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.666846] zbud: loaded [ 2.670242] *** VALIDATE nfs *** [ 2.671664] *** VALIDATE nfs4 *** [ 2.673180] pstore: using deflate compression [ 2.677888] Platform Keyring initialized [ 2.782353] NET: Registered protocol family 38 [ 2.784281] Key type asymmetric registered [ 2.785961] Asymmetric key parser 'x509' registered [ 2.787963] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.791624] io scheduler mq-deadline registered [ 2.793695] io scheduler kyber registered [ 2.795590] io scheduler bfq registered [ 2.797765] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.801158] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.804573] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.807875] ACPI: Power Button [PWRF] [ 2.813400] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.819919] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.837404] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.868168] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.897879] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.902935] Non-volatile memory driver v1.3 [ 2.904528] Linux agpgart interface v0.103 [ 2.937961] virtio_blk virtio1: [vda] 145880 512-byte logical blocks (74.7 MB/71.2 MiB) [ 2.940590] vda: detected capacity change from 0 to 74690560 [ 2.968969] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.972390] vdb: detected capacity change from 0 to 1073741824 [ 2.986877] libphy: Fixed MDIO Bus: probed [ 2.991787] usbcore: registered new interface driver usbserial_generic [ 2.993928] usbserial: USB Serial support registered for generic [ 2.996362] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.999755] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.001157] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.002997] mousedev: PS/2 mouse device common for all mice [ 3.005592] rtc_cmos 00:05: RTC can wake from S4 [ 3.007881] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.011109] rtc_cmos 00:05: registered as rtc0 [ 3.012165] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.014618] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.019128] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.020384] intel_pstate: CPU model not supported [ 3.026752] hid: raw HID events driver (C) Jiri Kosina [ 3.029164] usbcore: registered new interface driver usbhid [ 3.031669] usbhid: USB HID core driver [ 3.033456] drop_monitor: Initializing network drop monitor service [ 3.036266] Initializing XFRM netlink socket [ 3.038241] NET: Registered protocol family 10 [ 3.042056] Segment Routing with IPv6 [ 3.043487] NET: Registered protocol family 17 [ 3.045649] mpls_gso: MPLS GSO support [ 3.052357] RAS: Correctable Errors collector initialized. [ 3.054567] AVX version of gcm_enc/dec engaged. [ 3.056284] AES CTR mode by8 optimization enabled [ 3.124673] sched_clock: Marking stable (3124641056, 0)->(3941650640, -817009584) [ 3.127977] registered taskstats version 1 [ 3.130660] Loading compiled-in X.509 certificates [ 3.133149] zswap: loaded using pool lzo/zbud [ 3.156108] Key type big_key registered [ 3.168124] Key type encrypted registered [ 3.170224] ima: No TPM chip found, activating TPM-bypass! [ 3.173234] ima: Allocated hash algorithm: sha1 [ 3.175530] ima: No architecture policies found [ 3.177629] evm: Initialising EVM extended attributes: [ 3.179928] evm: security.selinux [ 3.181355] evm: security.ima [ 3.182802] evm: security.capability [ 3.184449] evm: HMAC attrs: 0x1 [ 3.186985] rtc_cmos 00:05: setting system clock to 2026-08-03 17:08:50 UTC (1785776930) [ 3.194393] debug: unmapping init [mem 0xffffffffb2c03000-0xffffffffb2dfffff] [ 3.198106] debug: unmapping init [mem 0xffffffffb1982000-0xffffffffb1c58fff] [ 3.207053] Write protecting the kernel read-only data: 28672k [ 3.212162] debug: unmapping init [mem 0xffffffffb0003000-0xffffffffb01fffff] [ 3.216442] debug: unmapping init [mem 0xffffffffb0914000-0xffffffffb09fffff] [ 3.243424] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 3.256423] systemd[1]: Detected virtualization kvm. [ 3.258337] systemd[1]: Detected architecture x86-64. [ 3.260117] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.285094] systemd[1]: No hostname configured. [ 3.286681] systemd[1]: Set hostname to . [ 3.288719] random: systemd: uninitialized urandom read (16 bytes read) [ 3.291217] systemd[1]: Initializing machine ID from random generator. [ 3.334708] random: ln: uninitialized urandom read (6 bytes read) [ 3.425955] random: systemd: uninitialized urandom read (16 bytes read) [ 3.428041] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ 3.431477] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 3.434804] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ OK ] Listening on Journal Socket. Starting Apply Kernel Variables... [ OK ] Started Memstrack Anylazing Service. Starting Setup Virtual Console... [ OK ] Listening on udev Control Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Swap. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.014692] device-mapper: uevent: version 1.0.3 [ 4.016718] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ 4.733695] virtio_net virtio0 ens2: renamed from eth0 [ 4.739479] random: fast init done [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 4.790155] scsi host0: ata_piix [ 4.793703] scsi host1: ata_piix [ 4.798207] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.800740] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.511554] dracut-initqueue[581]: RTNETLINK answers: File exists [ 9.689687] random: crng init done [ 9.691315] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 10.159680] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Timers. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped target Paths. [ 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 Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.281241] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.501407] SELinux: Disabled at runtime. [ 11.556192] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 11.564061] systemd[1]: Detected virtualization kvm. [ 11.565639] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.014521] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.018304] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.023913] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.028418] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.031857] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.038886] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.043535] systemd[1]: Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Listening on Process Core Dump Socket. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Listening on udev Kernel Socket. Activating swap /dev/disk/by-label/SWAP... [ OK ] Created slice system-getty.slice. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target RPC Port Mapper. [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 udev Control Socket. Mounting Huge Pages File System... [ OK ] Reached target rpc_pipefs.target. [ 12.135904] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Reached target Paths. Mounting Kernel Debug File System... Mounting POSIX Message Queue File System... Starting Remount Root and Kernel File Systems... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. Starting Apply Kernel Variables... Starting udev Coldplug all Devices... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Stopped target Initrd Root File System. [ OK ] Started Journal Service. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted POSIX Message Queue File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... Starting udev Kernel Device Manager... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 12.465392] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 12.771168] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 12.818707] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 12.878795] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 12.895597] EDAC sbridge: Ver: 1.1.2 [ 15.488111] Key type dns_resolver registered [ 16.761636] NFS: Registering the id_resolver key type [ 16.770776] Key type id_resolver registered [ 16.776568] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... 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 dnf makecache --timer. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Started irqbalance daemon. [ OK ] Reached target sshd-keygen.target. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... Starting Login Service... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started Login Service. [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg407-client login: [ 34.946406] hrtimer: interrupt took 6397190 ns [ 113.768448] libcfs: loading out-of-tree module taints kernel. [ 113.928544] Key type ._llcrypt registered [ 113.939275] Key type .llcrypt registered [ 115.230185] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 115.273544] alg: No test for adler32 (adler32-zlib) [ 117.170143] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [ 118.901406] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [ 120.847166] Key type lgssc registered [ 123.552457] Lustre: Echo OBD driver; http://www.lustre.org/ [ 346.051553] Lustre: Mounted lustre-client [ 354.319635] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 371.683985] Lustre: lustre-OST0000-osc-ffff9f72c373d800: disconnect after 21s idle [ 372.724368] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing check_logdir /tmp/testlogs/ [ 379.346742] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing yml_node [ 384.605627] Lustre: DEBUG MARKER: Client: 2.17.56.2 [ 388.974297] Lustre: DEBUG MARKER: MDS: 2.17.56.2 [ 393.229587] Lustre: DEBUG MARKER: OSS: 2.17.56.2 [ 395.524364] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Mon Aug 3 13:15:20 EDT 2026 [ 422.763362] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 426.090910] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 433.147176] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 443.516805] Lustre: Unmounted lustre-client [ 600.129794] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 610.686135] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 641.943668] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 691.150479] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 13:20:16 (1785777616) [ 694.432010] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 696.766824] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 13:20:22 (1785777622) [ 747.259665] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 754.966381] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 784.454818] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 785.360639] Lustre: Mounted lustre-client [ 791.924743] Lustre: Unmounted lustre-client [ 825.310938] Key type lgssc unregistered [ 825.866820] LNet: 15050:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 825.885558] LNetError: 15050:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 825.911779] LNet: Removed LNI 192.168.204.7@tcp [ 827.474953] Key type .llcrypt unregistered [ 827.485194] Key type ._llcrypt unregistered [ 853.451048] Key type ._llcrypt registered [ 853.461913] Key type .llcrypt registered [ 855.717987] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 13:23:00 (1785777780) [ 858.157554] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 858.197172] alg: No test for adler32 (adler32-zlib) [ 859.443979] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [ 859.834930] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [ 861.567269] Key type lgssc registered [ 862.839346] Lustre: Echo OBD driver; http://www.lustre.org/ [ 977.313956] Lustre: Mounted lustre-client [ 1080.445102] Lustre: Unmounted lustre-client [ 1115.632506] Key type lgssc unregistered [ 1116.043476] LNet: 18853:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1116.055523] LNetError: 18853:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1116.081548] LNet: Removed LNI 192.168.204.7@tcp [ 1117.454830] Key type .llcrypt unregistered [ 1117.460807] Key type ._llcrypt unregistered [ 1133.908449] Key type ._llcrypt registered [ 1133.911532] Key type .llcrypt registered [ 1136.353983] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 13:27:40 (1785778060) [ 1139.862358] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1139.896548] alg: No test for adler32 (adler32-zlib) [ 1141.258486] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [ 1141.813334] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [ 1143.600648] Key type lgssc registered [ 1145.599671] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1206.943813] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1216.658817] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1250.481813] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1252.232963] Lustre: Mounted lustre-client [ 1270.324811] Lustre: Unmounted lustre-client [ 1307.128434] Key type lgssc unregistered [ 1307.412792] LNet: 22458:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1307.420508] LNetError: 22458:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1307.436859] LNet: Removed LNI 192.168.204.7@tcp [ 1308.288294] Key type .llcrypt unregistered [ 1308.294236] Key type ._llcrypt unregistered [ 1327.576452] Key type ._llcrypt registered [ 1327.582847] Key type .llcrypt registered [ 1330.439294] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 13:30:54 (1785778254) [ 1334.114141] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1334.198738] alg: No test for adler32 (adler32-zlib) [ 1335.590463] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [ 1336.124221] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [ 1338.031400] Key type lgssc registered [ 1341.121853] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1422.915879] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1432.026287] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1463.729448] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1466.787620] Lustre: Mounted lustre-client [ 1474.797353] Lustre: Unmounted lustre-client [ 1509.446333] Key type lgssc unregistered [ 1509.922924] LNet: 25657:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1509.935651] LNetError: 25657:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1509.966422] LNet: Removed LNI 192.168.204.7@tcp [ 1510.987288] Key type .llcrypt unregistered [ 1510.992117] Key type ._llcrypt unregistered [ 1534.461794] Key type ._llcrypt registered [ 1534.465609] Key type .llcrypt registered [ 1536.017988] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 13:34:21 (1785778461) [ 1539.448414] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1539.504412] alg: No test for adler32 (adler32-zlib) [ 1540.940728] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [ 1541.650377] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [ 1543.599304] Key type lgssc registered [ 1546.252457] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1618.330183] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1625.262889] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1657.813714] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1660.851748] Lustre: Mounted lustre-client [ 1671.155698] Lustre: lustre-MDT0000-mdc-ffff9f72c95e0800: Connection to lustre-MDT0000 (at 192.168.204.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1681.406910] Lustre: lustre-MDT0001-mdc-ffff9f72c95e0800: Connection to lustre-MDT0001 (at 192.168.204.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1690.892830] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1692.639188] Lustre: 26616:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1785778603/real 1785778603] req@00000000feb83285 x1872524442867712/t0(0) o400->MGC192.168.204.107@tcp@192.168.204.107@tcp:26/25 lens 224/224 e 0 to 1 dl 1785778619 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1692.687189] LustreError: MGC192.168.204.107@tcp: Connection to MGS (at 192.168.204.107@tcp) was lost; in progress operations using this service will fail [ 1701.343290] Lustre: Unmounted lustre-client [ 1721.312840] Key type lgssc unregistered [ 1721.642666] LNet: 29317:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1721.656420] LNetError: 29317:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1721.682858] LNet: Removed LNI 192.168.204.7@tcp [ 1722.659305] Key type .llcrypt unregistered [ 1722.665193] Key type ._llcrypt unregistered [ 1744.642705] Key type ._llcrypt registered [ 1744.652206] Key type .llcrypt registered [ 1746.685375] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 13:37:51 (1785778671) [ 1750.869026] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1750.950554] alg: No test for adler32 (adler32-zlib) [ 1752.290944] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [ 1752.767550] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [ 1754.511265] Key type lgssc registered [ 1756.514514] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1830.002074] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1838.261820] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1870.332298] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1872.303480] Lustre: Mounted lustre-client [ 1882.607182] Lustre: lustre-OST0000-osc-ffff9f72c95e3000: Connection to lustre-OST0000 (at 192.168.204.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1887.542220] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1897.887599] Lustre: Unmounted lustre-client [ 1938.990935] Key type lgssc unregistered [ 1939.288416] LNet: 32935:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1939.294732] LNetError: 32935:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1939.308670] LNet: Removed LNI 192.168.204.7@tcp [ 1940.530130] Key type .llcrypt unregistered [ 1940.535860] Key type ._llcrypt unregistered [ 1963.737562] Key type ._llcrypt registered [ 1963.738977] Key type .llcrypt registered [ 1965.322760] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 13:41:30 (1785778890) [ 1968.889920] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1968.942912] alg: No test for adler32 (adler32-zlib) [ 1970.253629] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [ 1970.556636] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [ 1972.289688] Key type lgssc registered [ 1974.446735] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2049.961076] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2056.962652] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2088.775376] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2090.859110] Lustre: Mounted lustre-client [ 2095.640524] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 2100.301847] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 2113.130767] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-08-0313h43m57s [ 2116.956086] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 2121.707116] Lustre: lustre-MDT0000-mdc-ffff9f72c7957800: Connection to lustre-MDT0000 (at 192.168.204.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2129.065387] LustreError: 36731:0:(lmv_obd.c:1468:lmv_statfs()) lustre-MDT0000-mdc-ffff9f72c7957800: can't stat MDS #0: rc = -19 [ 2130.950257] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 2140.140903] LustreError: MGC192.168.204.107@tcp: Connection to MGS (at 192.168.204.107@tcp) was lost; in progress operations using this service will fail [ 2140.182575] Lustre: Evicted from MGS (at 192.168.204.107@tcp) after server handle changed from 0x5016f987dab24b0f to 0x5016f987dab24ccf [ 2140.202635] Lustre: MGC192.168.204.107@tcp: Connection restored to 192.168.204.107@tcp (at 192.168.204.107@tcp) [ 2141.260548] LustreError: lustre-MDT0000-mdc-ffff9f72c7957800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 2141.308342] Lustre: lustre-MDT0000-mdc-ffff9f72c7957800: Connection restored to 192.168.204.107@tcp (at 192.168.204.107@tcp) [ 2151.930328] Lustre: DEBUG MARKER: Wait for df (36731) ... [ 2155.302313] Lustre: DEBUG MARKER: done [ 2160.688926] Lustre: Unmounted lustre-client [ 2196.559748] Key type lgssc unregistered [ 2197.061907] LNet: 37749:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2197.072400] LNetError: 37749:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2197.102951] LNet: Removed LNI 192.168.204.7@tcp [ 2198.158782] Key type .llcrypt unregistered [ 2198.164114] Key type ._llcrypt unregistered [ 2255.287708] Key type ._llcrypt registered [ 2255.289372] Key type .llcrypt registered [ 2257.446860] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 13:46:22 (1785779182) [ 2261.173215] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2261.315497] alg: No test for adler32 (adler32-zlib) [ 2262.790925] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [ 2263.340214] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [ 2265.223211] Key type lgssc registered [ 2267.566072] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2350.387262] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2361.313397] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2393.807384] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2395.782248] Lustre: Mounted lustre-client [ 2401.804396] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-08-0313h48m46s [ 2404.583690] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 2414.323685] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 2417.470437] Lustre: DEBUG MARKER: Creating a test file and stat it [ 2438.623384] Lustre: 41912:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1785779345/real 1785779345] req@000000006cd9a51f x1872525199940736/t0(0) o101->lustre-MDT0000-mdc-ffff9f72c95e1800@192.168.204.107@tcp:12/10 lens 576/1152 e 0 to 1 dl 1785779365 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 2438.673714] Lustre: lustre-MDT0000-mdc-ffff9f72c95e1800: Connection to lustre-MDT0000 (at 192.168.204.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2438.747930] Lustre: lustre-MDT0000-mdc-ffff9f72c95e1800: Connection restored to 192.168.204.107@tcp (at 192.168.204.107@tcp) [ 2460.910558] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 2465.302269] Lustre: DEBUG MARKER: done [ 2473.201438] Lustre: Unmounted lustre-client [ 2508.732949] Key type lgssc unregistered [ 2508.960725] LNet: 42790:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2508.965620] LNetError: 42790:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2508.991586] LNet: Removed LNI 192.168.204.7@tcp [ 2510.060647] Key type .llcrypt unregistered [ 2510.064354] Key type ._llcrypt unregistered [ 2556.313289] Key type ._llcrypt registered [ 2556.317428] Key type .llcrypt registered [ 2558.641813] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 13:51:23 (1785779483) [ 2561.035782] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 2564.189207] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 13:51:28 (1785779488) [ 2570.026809] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2570.072780] alg: No test for adler32 (adler32-zlib) [ 2571.455305] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [ 2571.996180] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [ 2573.919387] Key type lgssc registered [ 2575.520790] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2614.445152] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 13:52:20 (1785779540) [ 2658.956847] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2667.738959] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2693.903213] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2695.906193] Lustre: Mounted lustre-client [ 2700.729781] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 2706.782830] Lustre: Unmounted lustre-client [ 2726.794290] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 2781.670654] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2789.755831] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2790.464063] Lustre: Mounted lustre-client [ 2797.008925] Lustre: Unmounted lustre-client [ 2872.497579] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2879.385616] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2880.580539] Lustre: Mounted lustre-client [ 2886.645875] Lustre: Unmounted lustre-client [ 2914.353527] Lustre: DEBUG MARKER: files compared the same [ 2942.105881] Key type lgssc unregistered [ 2942.445776] LNet: 50428:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2942.476181] LNetError: 50428:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2942.524498] LNet: Removed LNI 192.168.204.7@tcp [ 2943.845340] Key type .llcrypt unregistered [ 2943.850529] Key type ._llcrypt unregistered [ 2970.905235] Key type ._llcrypt registered [ 2970.909509] Key type .llcrypt registered [ 2973.527325] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 13:58:18 (1785779898) [ 2977.160773] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2977.235565] alg: No test for adler32 (adler32-zlib) [ 2978.519332] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [ 2978.933761] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [ 2980.671193] Key type lgssc registered [ 2982.086510] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3052.151070] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3060.644378] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3101.625266] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3104.441540] Lustre: Mounted lustre-client [ 3109.840127] Lustre: Unmounted lustre-client [ 3149.226559] Key type lgssc unregistered [ 3149.748867] LNet: 54038:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3149.766549] LNetError: 54038:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3149.798477] LNet: Removed LNI 192.168.204.7@tcp [ 3150.917087] Key type .llcrypt unregistered [ 3150.921379] Key type ._llcrypt unregistered [ 3172.559309] Key type ._llcrypt registered [ 3172.561152] Key type .llcrypt registered [ 3174.091584] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 14:01:39 (1785780099) [ 3177.275378] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3177.331853] alg: No test for adler32 (adler32-zlib) [ 3178.729118] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [ 3179.215489] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [ 3180.871899] Key type lgssc registered [ 3184.214553] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3295.409194] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3342.782457] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3351.004442] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3383.893808] Key type lgssc unregistered [ 3384.383060] LNet: 57535:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3384.397863] LNetError: 57535:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3384.429982] LNet: Removed LNI 192.168.204.7@tcp [ 3385.496688] Key type .llcrypt unregistered [ 3385.498605] Key type ._llcrypt unregistered [ 3409.415298] Key type ._llcrypt registered [ 3409.424580] Key type .llcrypt registered [ 3411.510094] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 14:05:36 (1785780336) [ 3418.432355] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3418.476842] alg: No test for adler32 (adler32-zlib) [ 3419.901775] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [ 3420.485366] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [ 3422.567226] Key type lgssc registered [ 3424.068463] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3539.270428] Lustre: Mounted lustre-client [ 3547.601028] Lustre: Unmounted lustre-client [ 3592.253330] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 14:08:37 (1785780517) [ 3777.426370] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3801.163332] Lustre: Mounted lustre-client [ 3810.585366] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3810.593912] LustreError: 64033:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff9f72c442e800: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 3810.638537] Lustre: 58522:0:(llite_lib.c:4339:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.204.107@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 3820.957237] Lustre: Unmounted lustre-client [ 3886.176430] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 14:13:31 (1785780811) [ 3902.676784] Key type lgssc unregistered [ 3903.003947] LNet: 65249:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3903.018990] LNetError: 65249:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3903.057425] LNet: Removed LNI 192.168.204.7@tcp [ 3904.157787] Key type .llcrypt unregistered [ 3904.160568] Key type ._llcrypt unregistered [ 3925.901060] Key type ._llcrypt registered [ 3925.903069] Key type .llcrypt registered [ 3926.682709] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3926.719188] alg: No test for adler32 (adler32-zlib) [ 3927.775918] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [ 3927.967418] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [ 3929.647856] Key type lgssc registered [ 3931.044772] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4008.973570] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4018.128698] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4054.688830] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4057.226240] Lustre: Mounted lustre-client [ 4065.230696] Lustre: Unmounted lustre-client [ 4111.688993] Key type lgssc unregistered [ 4112.654422] LNet: 68273:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4112.675662] LNetError: 68273:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4112.819061] LNet: Removed LNI 192.168.204.7@tcp [ 4114.026233] Key type .llcrypt unregistered [ 4114.031726] Key type ._llcrypt unregistered [ 4133.817828] Key type ._llcrypt registered [ 4133.824052] Key type .llcrypt registered [ 4134.548386] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4134.570178] alg: No test for adler32 (adler32-zlib) [ 4135.738379] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [ 4136.077253] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [ 4137.823414] Key type lgssc registered [ 4139.723901] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4298.623871] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4308.323532] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4340.117750] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4342.398268] Lustre: Mounted lustre-client [ 4347.802133] Lustre: Unmounted lustre-client [ 4386.285810] Key type lgssc unregistered [ 4386.717780] LNet: 71717:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4386.743162] LNetError: 71717:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4386.776270] LNet: Removed LNI 192.168.204.7@tcp [ 4388.928324] Key type .llcrypt unregistered [ 4388.930168] Key type ._llcrypt unregistered [ 4415.877513] Key type ._llcrypt registered [ 4415.887410] Key type .llcrypt registered [ 4418.246652] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 14:22:22 (1785781342) [ 4422.003831] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4422.215765] alg: No test for adler32 (adler32-zlib) [ 4423.590748] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [ 4424.373837] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [ 4426.383168] Key type lgssc registered [ 4429.129516] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4501.552508] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4511.883021] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4546.347818] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4549.061965] Lustre: Mounted lustre-client [ 4555.720290] Lustre: Unmounted lustre-client [ 4556.171945] Lustre: Mounted lustre-client [ 4560.488590] Lustre: Unmounted lustre-client [ 4605.819177] Key type lgssc unregistered [ 4606.590371] LNet: 75411:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4606.599611] LNetError: 75411:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4606.619668] LNet: Removed LNI 192.168.204.7@tcp [ 4608.389476] Key type .llcrypt unregistered [ 4608.403072] Key type ._llcrypt unregistered [ 4630.068527] Key type ._llcrypt registered [ 4630.071860] Key type .llcrypt registered [ 4630.441436] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4630.473831] alg: No test for adler32 (adler32-zlib) [ 4631.964724] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [ 4632.366960] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [ 4634.151293] Key type lgssc registered [ 4636.481378] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4707.333992] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4717.616540] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4749.608440] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4751.545989] Lustre: Mounted lustre-client [ 4761.446685] Lustre: Unmounted lustre-client [ 4795.972658] Key type lgssc unregistered [ 4796.393405] LNet: 78452:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4796.400673] LNetError: 78452:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4797.430415] LNet: Removed LNI 192.168.204.7@tcp [ 4798.697092] Key type .llcrypt unregistered [ 4798.700611] Key type ._llcrypt unregistered [ 4820.599861] Key type ._llcrypt registered [ 4820.604589] Key type .llcrypt registered [ 4822.577841] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 14:29:07 (1785781747) [ 4826.231578] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4826.410380] alg: No test for adler32 (adler32-zlib) [ 4827.731300] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [ 4828.152757] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [ 4830.194047] Key type lgssc registered [ 4833.406057] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4912.851276] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4921.895758] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4951.594197] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4953.930441] Lustre: Mounted lustre-client [ 4965.140845] Lustre: lustre: root_squash is set to 0:0 [ 4972.107390] Lustre: lustre: nosquash_nids is cleared [ 4980.844903] Lustre: lustre: root_squash is set to 500:500 [ 4989.229481] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.204.7@tcp 192.168.0.[2,10]@tcp [ 5010.053240] Lustre: lustre: nosquash_nids set to 192.168.204.7/24@tcp [ 5019.389369] Lustre: Unmounted lustre-client [ 5069.567556] Key type lgssc unregistered [ 5069.850072] LNet: 82427:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5069.855959] LNetError: 82427:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5069.874575] LNet: Removed LNI 192.168.204.7@tcp [ 5071.183860] Key type .llcrypt unregistered [ 5071.188253] Key type ._llcrypt unregistered [ 5100.982610] Key type ._llcrypt registered [ 5100.995933] Key type .llcrypt registered [ 5102.845257] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 14:33:48 (1785782028) [ 5106.139703] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 5108.032995] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 14:33:53 (1785782033) [ 5110.904035] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5111.093667] alg: No test for adler32 (adler32-zlib) [ 5112.272714] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [ 5112.672284] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [ 5114.495545] Key type lgssc registered [ 5116.650785] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5189.719263] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5204.256434] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5237.817303] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5238.482917] Lustre: lustre: root_squash is set to 500:500 [ 5238.530532] Lustre: lustre: nosquash_nids set to 192.168.204.7/24@tcp [ 5239.633389] Lustre: Mounted lustre-client [ 5248.138829] Lustre: Unmounted lustre-client [ 5296.141122] Key type lgssc unregistered [ 5296.602925] LNet: 86397:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5296.608954] LNetError: 86397:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5297.640951] LNet: Removed LNI 192.168.204.7@tcp [ 5298.714216] Key type .llcrypt unregistered [ 5298.717431] Key type ._llcrypt unregistered [ 5327.878602] Key type ._llcrypt registered [ 5327.880323] Key type .llcrypt registered [ 5329.328894] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 5331.987168] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 14:37:36 (1785782256) [ 5355.920633] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5356.156339] alg: No test for adler32 (adler32-zlib) [ 5357.329329] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [ 5357.665391] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [ 5359.435055] Key type lgssc registered [ 5360.824329] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5480.399817] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5489.963191] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5527.520794] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5623.115708] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5631.346504] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5664.261271] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5682.720266] Lustre: Mounted lustre-client [ 5728.326729] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9f72c892b800.ost_server_uuid 50 [ 5730.518264] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9f72c892b800.ost_server_uuid in IDLE state after 0 sec [ 5731.052118] Lustre: Mounted lustre-client [ 5738.114558] Lustre: Unmounted lustre-client [ 5741.949326] Lustre: Unmounted lustre-client [ 5806.535505] Key type lgssc unregistered [ 5807.192489] LNet: 94088:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5807.205862] LNetError: 94088:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5807.242991] LNet: Removed LNI 192.168.204.7@tcp [ 5808.171338] Key type .llcrypt unregistered [ 5808.179431] Key type ._llcrypt unregistered [ 5856.364544] Key type ._llcrypt registered [ 5856.372337] Key type .llcrypt registered [ 5858.831328] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 14:46:23 (1785782783) [ 5890.351728] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5890.398425] alg: No test for adler32 (adler32-zlib) [ 5891.707742] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [ 5892.159918] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [ 5893.967519] Key type lgssc registered [ 5896.068087] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6011.171441] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6017.936483] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6049.933626] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6052.741224] Lustre: Mounted lustre-client [ 6074.335182] Lustre: 95456:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1785782985/real 1785782985] req@000000001ebb1581 x1872529005221120/t0(0) o400->lustre-OST0000-osc-ffff9f72c7856800@192.168.204.107@tcp:28/4 lens 224/224 e 0 to 1 dl 1785783001 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6074.382642] Lustre: lustre-OST0000-osc-ffff9f72c7856800: Connection to lustre-OST0000 (at 192.168.204.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6079.502710] Lustre: 95456:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1785782990/real 1785782990] req@00000000e6a8394f x1872529005221632/t0(0) o400->lustre-OST0000-osc-ffff9f72c7856800@192.168.204.107@tcp:28/4 lens 224/224 e 0 to 1 dl 1785783006 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6084.579291] Lustre: 95457:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1785782995/real 1785782995] req@00000000f830d2a0 x1872529005222144/t0(0) o400->lustre-OST0000-osc-ffff9f72c7856800@192.168.204.107@tcp:28/4 lens 224/224 e 0 to 1 dl 1785783011 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6088.735189] Lustre: 95457:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1785783000/real 1785783000] req@00000000fceecf9f x1872529005222656/t0(0) o400->lustre-OST0000-osc-ffff9f72c7856800@192.168.204.107@tcp:28/4 lens 224/224 e 0 to 1 dl 1785783016 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6099.959483] Lustre: lustre-MDT0000-mdc-ffff9f72c7856800: Connection to lustre-MDT0000 (at 192.168.204.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6116.320395] Lustre: 95456:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1785783027/real 1785783027] req@0000000070746857 x1872529005225344/t0(0) o400->MGC192.168.204.107@tcp@192.168.204.107@tcp:26/25 lens 224/224 e 0 to 1 dl 1785783043 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6116.365514] LustreError: MGC192.168.204.107@tcp: Connection to MGS (at 192.168.204.107@tcp) was lost; in progress operations using this service will fail [ 6126.566543] Lustre: Evicted from MGS (at 192.168.204.107@tcp) after server handle changed from 0x2308bd85acceb0f1 to 0x2308bd85acceb36e [ 6126.589342] Lustre: MGC192.168.204.107@tcp: Connection restored to 192.168.204.107@tcp (at 192.168.204.107@tcp) [ 6128.931631] Lustre: lustre-OST0000-osc-ffff9f72c7856800: Connection restored to 192.168.204.107@tcp (at 192.168.204.107@tcp) [ 6138.831692] Lustre: Unmounted lustre-client [ 6174.725393] Key type lgssc unregistered [ 6175.006908] LNet: 99776:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6175.030734] LNetError: 99776:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6175.062035] LNet: Removed LNI 192.168.204.7@tcp [ 6175.917584] Key type .llcrypt unregistered [ 6175.921132] Key type ._llcrypt unregistered [ 6199.321738] Key type ._llcrypt registered [ 6199.324948] Key type .llcrypt registered [ 6201.638377] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 14:52:06 (1785783126) [ 6219.616703] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6219.640758] alg: No test for adler32 (adler32-zlib) [ 6221.233903] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [ 6221.860940] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [ 6223.736868] Key type lgssc registered [ 6226.844648] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6290.680853] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6440.914214] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6448.653402] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6486.037995] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6489.207498] Lustre: Mounted lustre-client [ 6491.949044] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 6494.542439] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 14:56:59 (1785783419) [ 6859.959646] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6870.379577] Lustre: Unmounted lustre-client [ 7034.958110] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7043.623943] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7076.633911] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7133.366128] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 15:07:38 (1785784058) [ 7255.697026] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7264.600264] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7298.395383] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7299.389357] Lustre: Mounted lustre-client [ 7309.049696] Lustre: Unmounted lustre-client [ 7346.134789] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 15:11:11 (1785784271) [ 7471.004510] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7479.917185] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7519.632974] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7520.726690] Lustre: Mounted lustre-client [ 7527.339542] Lustre: Unmounted lustre-client [ 7565.707661] Key type lgssc unregistered [ 7566.238833] LNet: 122885:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7566.269221] LNetError: 122885:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7566.292692] LNet: Removed LNI 192.168.204.7@tcp [ 7568.219871] Key type .llcrypt unregistered [ 7568.221048] Key type ._llcrypt unregistered [ 7591.634390] Key type ._llcrypt registered [ 7591.638379] Key type .llcrypt registered [ 7593.347366] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 15:15:18 (1785784518) [ 7596.693230] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7596.733157] alg: No test for adler32 (adler32-zlib) [ 7597.934069] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [ 7598.298505] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [ 7600.087164] Key type lgssc registered [ 7601.410194] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7666.841919] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7674.761272] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7701.663730] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7708.770584] Lustre: Mounted lustre-client [ 7724.840047] Lustre: Unmounted lustre-client [ 7760.375123] Key type lgssc unregistered [ 7760.762206] LNet: 126490:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7760.766208] LNetError: 126490:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7760.799372] LNet: Removed LNI 192.168.204.7@tcp [ 7761.997819] Key type .llcrypt unregistered [ 7762.004478] Key type ._llcrypt unregistered [ 7784.368660] Key type ._llcrypt registered [ 7784.370287] Key type .llcrypt registered [ 7786.081964] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 15:18:31 (1785784711) [ 7789.834154] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7789.940462] alg: No test for adler32 (adler32-zlib) [ 7791.070472] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [ 7791.259647] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [ 7792.914531] Key type lgssc registered [ 7795.248975] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7855.903838] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7863.992610] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7889.205893] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7895.132771] Lustre: Mounted lustre-client [ 7905.269136] Lustre: lustre-OST0000-osc-ffff9f72c373c800: Connection to lustre-OST0000 (at 192.168.204.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7915.697458] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff9f72c373c800.ost_server_uuid 50 [ 7917.709678] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 7930.897197] Lustre: Unmounted lustre-client [ 7956.248838] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 15:21:21 (1785784881) [ 7992.726785] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8000.094298] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8024.135519] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8050.890599] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8051.621708] Lustre: Mounted lustre-client [ 8056.805583] Lustre: lustre-OST0000-osc-ffff9f72c74fd800: Connection to lustre-OST0000 (at 192.168.204.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8086.278835] Lustre: Unmounted lustre-client [ 8138.535596] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 15:24:24 (1785785064) [ 8175.134619] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8181.709649] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8205.165216] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8230.854512] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8231.752082] Lustre: Mounted lustre-client [ 8237.062873] Lustre: lustre-OST0000-osc-ffff9f72c373e000: Connection to lustre-OST0000 (at 192.168.204.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8257.011754] Lustre: Unmounted lustre-client [ 8313.111601] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 15:27:18 (1785785238) [ 8473.241321] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8482.142725] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8514.024535] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8602.004189] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8611.692298] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8641.318199] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8680.743083] Lustre: Mounted lustre-client [ 8730.151216] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8731.944168] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 8734.674942] Lustre: Unmounted lustre-client [ 8775.970258] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 15:35:01 (1785785701) [ 8819.425176] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8827.268750] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8854.075928] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8894.778389] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8950.597861] Lustre: Mounted lustre-client [ 9002.357763] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9004.575119] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 9019.766835] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 9030.214688] Lustre: Unmounted lustre-client [ 9096.798461] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 15:40:21 (1785786021) [ 9148.515195] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9156.304413] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9184.481847] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9185.428208] Lustre: Mounted lustre-client [ 9216.174563] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9218.250462] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 9260.486721] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9f72c70fe000.ost_server_uuid 50 [ 9265.429848] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9f72c70fe000.ost_server_uuid in IDLE state after 0 sec [ 9270.655137] Lustre: Unmounted lustre-client [ 9271.385849] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 9271.406568] Lustre: Skipped 3 previous similar messages [ 9271.618169] Lustre: Mounted lustre-client [ 9273.232134] LustreError: 149981:0:(sec.c:413:import_sec_validate_get()) import 00000000427e599b (NEW) with no sec [ 9376.878688] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 15:45:01 (1785786301) [ 9426.027873] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9434.989327] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9461.772406] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9491.003365] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9491.713663] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 9491.950031] Lustre: Mounted lustre-client [ 9508.423621] Lustre: Unmounted lustre-client [ 9508.424887] Lustre: Skipped 1 previous similar message [ 9564.678953] Key type lgssc unregistered [ 9565.157120] LNet: 153407:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9565.178806] LNetError: 153407:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9565.207371] LNet: Removed LNI 192.168.204.7@tcp [ 9566.369375] Key type .llcrypt unregistered [ 9566.372970] Key type ._llcrypt unregistered [ 9588.272638] Key type ._llcrypt registered [ 9588.275721] Key type .llcrypt registered [ 9589.623183] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 15:48:35 (1785786515) [ 9591.922533] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9591.942559] alg: No test for adler32 (adler32-zlib) [ 9593.365777] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [ 9593.828977] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [ 9595.735856] Key type lgssc registered [ 9598.470718] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9677.339612] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9686.164439] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9715.843899] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9743.315414] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9744.096330] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 9745.284405] Lustre: Mounted lustre-client [ 9749.644940] LustreError: lustre-MDT0001-mdc-ffff9f72c7931000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 9749.686212] Lustre: lustre-MDT0001-mdc-ffff9f72c7931000: Connection restored to 192.168.204.107@tcp (at 192.168.204.107@tcp) [ 9757.159887] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9759.412212] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9784.399156] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 9791.746963] Lustre: Unmounted lustre-client [ 9829.756565] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 15:52:35 (1785786755) [ 9973.670469] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9982.787835] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10019.141332] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10020.233477] Lustre: Mounted lustre-client [10066.060411] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10068.658524] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [10086.090771] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [10096.479539] Lustre: Unmounted lustre-client [10145.912316] Key type lgssc unregistered [10146.888069] LNet: 163019:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10146.954527] LNetError: 163019:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10147.081055] LNet: Removed LNI 192.168.204.7@tcp [10149.277858] Key type .llcrypt unregistered [10149.289129] Key type ._llcrypt unregistered [10200.884745] Key type ._llcrypt registered [10200.888347] Key type .llcrypt registered [10202.732929] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 15:58:47 (1785787127) [10205.704866] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10205.752093] alg: No test for adler32 (adler32-zlib) [10207.012371] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [10207.242524] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [10208.887261] Key type lgssc registered [10211.243437] Lustre: Echo OBD driver; http://www.lustre.org/ [10280.818078] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10289.507405] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10320.344297] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10322.192642] Lustre: Mounted lustre-client [10380.326886] Lustre: Unmounted lustre-client [10428.564875] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10429.089029] Lustre: Mounted lustre-client [10467.394577] Lustre: Unmounted lustre-client [10499.811963] Key type lgssc unregistered [10500.510721] LNet: 167490:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10500.526954] LNetError: 167490:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10500.572350] LNet: Removed LNI 192.168.204.7@tcp [10501.842853] Key type .llcrypt unregistered [10501.844712] Key type ._llcrypt unregistered [10523.279650] Key type ._llcrypt registered [10523.281905] Key type .llcrypt registered [10524.872664] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 16:04:10 (1785787450) [10528.740631] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10528.834087] alg: No test for adler32 (adler32-zlib) [10530.269861] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [10530.904260] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [10532.755329] Key type lgssc registered [10534.732988] Lustre: Echo OBD driver; http://www.lustre.org/ [10607.996132] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10616.896774] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10647.923594] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10651.069459] Lustre: Mounted lustre-client [10677.344361] Lustre: Unmounted lustre-client [10712.416299] Key type lgssc unregistered [10712.869600] LNet: 171255:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10712.879988] LNetError: 171255:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10712.906835] LNet: Removed LNI 192.168.204.7@tcp [10713.803693] Key type .llcrypt unregistered [10713.806872] Key type ._llcrypt unregistered [10731.915606] Key type ._llcrypt registered [10731.922834] Key type .llcrypt registered [10732.301539] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10732.328673] alg: No test for adler32 (adler32-zlib) [10733.579462] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [10733.950558] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [10735.703376] Key type lgssc registered [10738.003369] Lustre: Echo OBD driver; http://www.lustre.org/ [10826.103826] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10839.690930] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10871.995342] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10873.975496] Lustre: Mounted lustre-client [10885.810165] Lustre: Unmounted lustre-client [10920.913603] Key type lgssc unregistered [10921.115400] LNet: 174331:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10921.133303] LNetError: 174331:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10921.157288] LNet: Removed LNI 192.168.204.7@tcp [10921.778904] Key type .llcrypt unregistered [10921.782116] Key type ._llcrypt unregistered [10944.541377] Key type ._llcrypt registered [10944.542924] Key type .llcrypt registered [10947.776203] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 16:11:11 (1785787871) [10952.611454] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10952.656899] alg: No test for adler32 (adler32-zlib) [10954.771727] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [10955.413625] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [10957.399269] Key type lgssc registered [10959.380202] Lustre: Echo OBD driver; http://www.lustre.org/ [11034.412091] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11046.471723] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11079.774311] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11083.206707] Lustre: Mounted lustre-client [11109.544018] Lustre: Unmounted lustre-client [11143.271978] Key type lgssc unregistered [11143.746071] LNet: 178101:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11143.756889] LNetError: 178101:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11144.807832] LNet: Removed LNI 192.168.204.7@tcp [11146.127742] Key type .llcrypt unregistered [11146.132373] Key type ._llcrypt unregistered [11165.074873] Key type ._llcrypt registered [11165.077882] Key type .llcrypt registered [11165.863908] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11165.895901] alg: No test for adler32 (adler32-zlib) [11167.379901] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [11167.895348] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [11169.767696] Key type lgssc registered [11171.239464] Lustre: Echo OBD driver; http://www.lustre.org/ [11248.102777] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11259.206328] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11286.704903] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11288.636790] Lustre: Mounted lustre-client [11298.225583] Lustre: Unmounted lustre-client [11330.395580] Key type lgssc unregistered [11330.715289] LNet: 181172:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11330.726634] LNetError: 181172:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11330.752289] LNet: Removed LNI 192.168.204.7@tcp [11331.584611] Key type .llcrypt unregistered [11331.588567] Key type ._llcrypt unregistered [11352.887341] Key type ._llcrypt registered [11352.888911] Key type .llcrypt registered [11354.450967] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 16:18:00 (1785788280) [11384.125286] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11384.275029] alg: No test for adler32 (adler32-zlib) [11385.743910] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [11386.201986] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [11387.927599] Key type lgssc registered [11389.356836] Lustre: Echo OBD driver; http://www.lustre.org/ [11507.581497] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11514.826864] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11542.778432] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11603.407597] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 16:22:08 (1785788528) [11652.802602] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11660.059342] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11688.686712] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11690.705254] Lustre: Mounted lustre-client [11699.437039] Lustre: Unmounted lustre-client [11735.600327] Key type lgssc unregistered [11735.931058] LNet: 188285:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11735.964121] LNetError: 188285:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11737.000390] LNet: Removed LNI 192.168.204.7@tcp [11738.235300] Key type .llcrypt unregistered [11738.239578] Key type ._llcrypt unregistered [11760.253521] Key type ._llcrypt registered [11760.254773] Key type .llcrypt registered [11761.888207] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [11764.068665] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 16:24:49 (1785788689) [11789.165558] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11789.577671] alg: No test for adler32 (adler32-zlib) [11791.321410] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [11792.158749] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [11794.223409] Key type lgssc registered [11796.849822] Lustre: Echo OBD driver; http://www.lustre.org/ [11923.963252] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11932.053612] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11964.128382] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11992.467497] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11995.581180] Lustre: Mounted lustre-client [12023.970233] Lustre: Unmounted lustre-client [12188.384709] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12195.824559] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12225.460745] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12273.795807] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 16:33:19 (1785789199) [12275.874236] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [12278.104412] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 16:33:23 (1785789203) [12399.362740] Key type lgssc unregistered [12399.845874] LNet: 199755:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12399.865147] LNetError: 199755:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12399.887451] LNet: Removed LNI 192.168.204.7@tcp [12400.999318] Key type .llcrypt unregistered [12401.002965] Key type ._llcrypt unregistered [12424.745310] Key type ._llcrypt registered [12424.747310] Key type .llcrypt registered [12426.473604] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 16:35:51 (1785789351) [12474.184718] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12474.229504] alg: No test for adler32 (adler32-zlib) [12475.766577] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [12476.238718] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [12478.199309] Key type lgssc registered [12480.097588] Lustre: Echo OBD driver; http://www.lustre.org/ [12542.617864] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12550.361435] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12595.236732] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12626.959223] Key type lgssc unregistered [12627.209620] LNet: 203498:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12627.215342] LNetError: 203498:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12627.250770] LNet: Removed LNI 192.168.204.7@tcp [12628.165542] Key type .llcrypt unregistered [12628.169182] Key type ._llcrypt unregistered [12647.800226] Key type ._llcrypt registered [12647.801805] Key type .llcrypt registered [12649.433425] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 16:39:34 (1785789574) [12652.868186] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12652.947539] alg: No test for adler32 (adler32-zlib) [12654.191478] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [12654.535881] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [12656.263168] Key type lgssc registered [12657.653095] Lustre: Echo OBD driver; http://www.lustre.org/ [12726.585332] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12736.440830] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12767.242873] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12769.189825] Lustre: Mounted lustre-client [12779.504037] Lustre: lustre-MDT0000-mdc-ffff9f72c9610800: Connection to lustre-MDT0000 (at 192.168.204.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [12789.738769] Lustre: lustre-MDT0001-mdc-ffff9f72c9610800: Connection to lustre-MDT0001 (at 192.168.204.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [12799.972398] Lustre: 204488:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1785789711/real 1785789711] req@000000005ea6668e x1872536095768448/t0(0) o400->MGC192.168.204.107@tcp@192.168.204.107@tcp:26/25 lens 224/224 e 0 to 1 dl 1785789727 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [12799.997174] LustreError: MGC192.168.204.107@tcp: Connection to MGS (at 192.168.204.107@tcp) was lost; in progress operations using this service will fail [12825.708264] Lustre: Evicted from MGS (at 192.168.204.107@tcp) after server handle changed from 0xe1f5ec69afac621 to 0xe1f5ec69afadf65 [12825.728744] Lustre: MGC192.168.204.107@tcp: Connection restored to 192.168.204.107@tcp (at 192.168.204.107@tcp) [12859.902463] LustreError: lustre-MDT0000-mdc-ffff9f72c9610800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [12859.938359] Lustre: lustre-MDT0000-mdc-ffff9f72c9610800: Connection restored to 192.168.204.107@tcp (at 192.168.204.107@tcp) [12866.244401] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12868.613049] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [12875.532787] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12877.481173] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [12883.287475] Lustre: Unmounted lustre-client [12932.408285] Key type lgssc unregistered [12932.785597] LNet: 208457:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12932.791357] LNetError: 208457:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12932.825945] LNet: Removed LNI 192.168.204.7@tcp [12933.672170] Key type .llcrypt unregistered [12933.677165] Key type ._llcrypt unregistered [12957.456274] Key type ._llcrypt registered [12957.460506] Key type .llcrypt registered [12959.264787] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 16:44:44 (1785789884) [12962.206425] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12962.258989] alg: No test for adler32 (adler32-zlib) [12963.411336] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [12963.774371] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [12965.447854] Key type lgssc registered [12966.630955] Lustre: Echo OBD driver; http://www.lustre.org/ [13021.979595] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13027.762899] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13104.120681] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13110.460579] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13152.329902] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13177.141459] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13236.338557] Key type lgssc unregistered [13236.669703] LNet: 213762:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13236.675953] LNetError: 213762:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13236.692848] LNet: Removed LNI 192.168.204.7@tcp [13237.517668] Key type .llcrypt unregistered [13237.520974] Key type ._llcrypt unregistered [13284.122543] Key type ._llcrypt registered [13284.130773] Key type .llcrypt registered [13285.547867] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 16:50:11 (1785790211) [13329.434589] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13329.468500] alg: No test for adler32 (adler32-zlib) [13330.569348] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [13330.955191] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [13332.711644] Key type lgssc registered [13334.396875] Lustre: Echo OBD driver; http://www.lustre.org/ [13475.025771] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13486.454659] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13523.096265] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13575.616283] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 16:55:01 (1785790501) [13585.948328] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 16:55:11 (1785790511) [13627.245762] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13634.599875] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13678.277194] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13681.365119] Lustre: Mounted lustre-client [13688.820177] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [13691.524724] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [13693.297823] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [13696.122608] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [13702.127158] Lustre: lustre-MDT0000-mdc-ffff9f72c5150800: Connection to lustre-MDT0000 (at 192.168.204.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [13718.495469] Lustre: 215658:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1785790629/real 1785790629] req@00000000cfd97588 x1872536805582592/t0(0) o400->MGC192.168.204.107@tcp@192.168.204.107@tcp:26/25 lens 224/224 e 0 to 1 dl 1785790645 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [13718.524684] LustreError: MGC192.168.204.107@tcp: Connection to MGS (at 192.168.204.107@tcp) was lost; in progress operations using this service will fail [13727.752168] Lustre: Evicted from MGS (at 192.168.204.107@tcp) after server handle changed from 0x7e241ced13a78e4f to 0x7e241ced13a790be [13727.793926] Lustre: MGC192.168.204.107@tcp: Connection restored to 192.168.204.107@tcp (at 192.168.204.107@tcp) [13731.638130] Lustre: lustre-MDT0000-mdc-ffff9f72c5150800: Connection restored to 192.168.204.107@tcp (at 192.168.204.107@tcp) [13742.222461] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13744.698656] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [13749.295502] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [13753.335083] Lustre: lustre-MDT0000-mdc-ffff9f72c5150800: Connection to lustre-MDT0000 (at 192.168.204.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [13769.696967] Lustre: 215660:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1785790680/real 1785790680] req@000000009abb90eb x1872536805591936/t0(0) o400->MGC192.168.204.107@tcp@192.168.204.107@tcp:26/25 lens 224/224 e 0 to 1 dl 1785790696 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [13769.731941] LustreError: MGC192.168.204.107@tcp: Connection to MGS (at 192.168.204.107@tcp) was lost; in progress operations using this service will fail [13779.954674] Lustre: Evicted from MGS (at 192.168.204.107@tcp) after server handle changed from 0x7e241ced13a790be to 0x7e241ced13a793ce [13779.998397] Lustre: MGC192.168.204.107@tcp: Connection restored to 192.168.204.107@tcp (at 192.168.204.107@tcp) [13783.427706] Lustre: lustre-MDT0000-mdc-ffff9f72c5150800: Connection restored to 192.168.204.107@tcp (at 192.168.204.107@tcp) [13796.743225] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13798.862294] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [13803.727882] Lustre: Unmounted lustre-client [13891.879607] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13899.468385] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13927.429471] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13928.513790] Lustre: Mounted lustre-client [13931.507240] Lustre: Unmounted lustre-client [13971.413781] Key type lgssc unregistered [13971.914659] LNet: 226279:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13971.920163] LNetError: 226279:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13971.950802] LNet: Removed LNI 192.168.204.7@tcp [13973.433989] Key type .llcrypt unregistered [13973.437242] Key type ._llcrypt unregistered [13995.655573] Key type ._llcrypt registered [13995.659908] Key type .llcrypt registered [13997.702886] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 17:02:02 (1785790922) [14002.732973] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14002.767867] alg: No test for adler32 (adler32-zlib) [14003.863137] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [14004.097400] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [14005.807219] Key type lgssc registered [14007.123276] Lustre: Echo OBD driver; http://www.lustre.org/ [14085.892341] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14094.993555] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14120.883853] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14123.683367] Lustre: Mounted lustre-client [14132.124500] Lustre: Unmounted lustre-client [14212.930988] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14220.781493] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14254.592992] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14257.138438] Lustre: Mounted lustre-client [14271.763320] Lustre: Unmounted lustre-client [14360.530427] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14368.238748] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14395.836423] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14401.654394] Lustre: Mounted lustre-client [14404.538379] Lustre: Unmounted lustre-client [14448.769672] Key type lgssc unregistered [14449.223809] LNet: 233351:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14449.245660] LNetError: 233351:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14449.274590] LNet: Removed LNI 192.168.204.7@tcp [14450.737277] Key type .llcrypt unregistered [14450.750198] Key type ._llcrypt unregistered [14473.537291] Key type ._llcrypt registered [14473.541995] Key type .llcrypt registered [14474.954357] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 17:10:00 (1785791400) [14478.691463] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14478.799066] alg: No test for adler32 (adler32-zlib) [14480.054463] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [14480.525676] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [14482.384173] Key type lgssc registered [14484.952411] Lustre: Echo OBD driver; http://www.lustre.org/ [14546.573878] Key type lgssc unregistered [14547.136978] LNet: 235679:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14547.155982] LNetError: 235679:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14547.195396] LNet: Removed LNI 192.168.204.7@tcp [14548.519759] Key type .llcrypt unregistered [14548.524092] Key type ._llcrypt unregistered [14589.815478] Key type ._llcrypt registered [14589.821846] Key type .llcrypt registered [14590.588296] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14590.603487] alg: No test for adler32 (adler32-zlib) [14591.549178] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [14592.031482] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [14593.743187] Key type lgssc registered [14596.955496] Lustre: Echo OBD driver; http://www.lustre.org/ [14715.446856] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14723.886700] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14754.269609] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14805.791375] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [14807.732480] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 17:15:33 (1785791733) [14850.969032] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14859.179133] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14896.778410] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14940.676293] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14942.702267] Lustre: Mounted lustre-client [14947.822270] Lustre: lustre-OST0001-osc-ffff9f72c9611000: Connection to lustre-OST0001 (at 192.168.204.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [14960.796177] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [14971.287454] Lustre: Unmounted lustre-client [15021.701195] Key type lgssc unregistered [15022.082267] LNet: 242818:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15022.105612] LNetError: 242818:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15023.143083] LNet: Removed LNI 192.168.204.7@tcp [15024.309806] Key type .llcrypt unregistered [15024.311282] Key type ._llcrypt unregistered [15069.979190] Key type ._llcrypt registered [15069.988930] Key type .llcrypt registered [15071.928970] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 17:19:57 (1785791997) [15090.469956] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15090.617600] alg: No test for adler32 (adler32-zlib) [15092.061975] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [15092.457058] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [15094.263306] Key type lgssc registered [15095.352081] Lustre: Echo OBD driver; http://www.lustre.org/ [15165.918546] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15173.796168] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15208.149991] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 17:22:11 (1785792131) [15260.149635] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15267.170606] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15297.627988] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15299.564358] Lustre: Mounted lustre-client [15310.280341] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [15321.573538] Lustre: Unmounted lustre-client [15449.743840] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15460.047366] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15521.805093] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15522.653083] Lustre: Mounted lustre-client [15527.731296] Lustre: Unmounted lustre-client [15559.096067] Key type lgssc unregistered [15559.511892] LNet: 250516:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15559.525355] LNetError: 250516:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15559.544302] LNet: Removed LNI 192.168.204.7@tcp [15560.731382] Key type .llcrypt unregistered [15560.739463] Key type ._llcrypt unregistered [15598.175630] Key type ._llcrypt registered [15598.195051] Key type .llcrypt registered [15599.048973] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15599.077951] alg: No test for adler32 (adler32-zlib) [15600.694990] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [15601.360021] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [15603.135768] Key type lgssc registered [15606.675673] Lustre: Echo OBD driver; http://www.lustre.org/ [15724.895785] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15736.011668] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15768.481910] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15826.163601] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [15831.105119] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 17:32:34 (1785792754) [15888.165155] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15897.177800] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15933.844828] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15941.288725] Lustre: Mounted lustre-client [15948.480557] Lustre: Unmounted lustre-client [15988.238259] Key type lgssc unregistered [15988.607279] LNet: 257168:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15988.621833] LNetError: 257168:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15988.645732] LNet: Removed LNI 192.168.204.7@tcp [15989.884768] Key type .llcrypt unregistered [15989.891578] Key type ._llcrypt unregistered [16015.257968] Key type ._llcrypt registered [16015.259454] Key type .llcrypt registered [16017.430331] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [16019.667098] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 17:35:45 (1785792945) [16032.693345] Key type .llcrypt unregistered [16032.694858] Key type ._llcrypt unregistered [16044.685398] Key type ._llcrypt registered [16044.692647] Key type .llcrypt registered [16045.186314] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [16045.197905] alg: No test for adler32 (adler32-zlib) [16046.342929] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [16046.678170] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [16048.391343] Key type lgssc registered [16050.355760] Lustre: Echo OBD driver; http://www.lustre.org/ [16129.497136] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16160.934498] Lustre: Mounted lustre-client [16166.506295] Lustre: Unmounted lustre-client [16200.595127] Key type lgssc unregistered [16200.999307] LNet: 260632:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16201.024811] LNetError: 260632:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16201.052571] LNet: Removed LNI 192.168.204.7@tcp [16202.255490] Key type .llcrypt unregistered [16202.261735] Key type ._llcrypt unregistered [16226.566327] Key type ._llcrypt registered [16226.570423] Key type .llcrypt registered [16228.823938] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 17:39:13 (1785793153) [16233.261502] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16233.285665] alg: No test for adler32 (adler32-zlib) [16234.827107] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [16235.440450] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [16237.407206] Key type lgssc registered [16239.891877] Lustre: Echo OBD driver; http://www.lustre.org/ [16341.213373] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16390.518791] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16398.450795] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16400.277788] Lustre: Mounted lustre-client [16404.974177] Lustre: Unmounted lustre-client [16445.329287] Key type lgssc unregistered [16445.956924] LNet: 264182:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16445.970514] LNetError: 264182:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16447.019307] LNet: Removed LNI 192.168.204.7@tcp [16448.778235] Key type .llcrypt unregistered [16448.779965] Key type ._llcrypt unregistered [16476.809100] Key type ._llcrypt registered [16476.816799] Key type .llcrypt registered [16478.795469] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 17:43:23 (1785793403) [16485.410807] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16485.484894] alg: No test for adler32 (adler32-zlib) [16487.602458] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [16488.670893] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [16490.984272] Key type lgssc registered [16496.055201] Lustre: Echo OBD driver; http://www.lustre.org/ [16579.422100] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16587.851880] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16621.648982] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16624.068042] Lustre: Mounted lustre-client [16629.254240] Lustre: lustre-MDT0000-mdc-ffff9f72c73da800: Connection to lustre-MDT0000 (at 192.168.204.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [16631.643490] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [16631.725265] LustreError: 267350:0:(file.c:6154:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [16632.331791] LustreError: 267369:0:(file.c:6154:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [16632.352342] LustreError: 267369:0:(file.c:6154:ll_inode_revalidate_fini()) Skipped 5 previous similar messages [16632.424356] LustreError: MGC192.168.204.107@tcp: Connection to MGS (at 192.168.204.107@tcp) was lost; in progress operations using this service will fail [16632.529313] Lustre: Unmounted lustre-client [16660.085271] Key type lgssc unregistered [16660.469193] LNet: 267765:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16660.476476] LNetError: 267765:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16660.499340] LNet: Removed LNI 192.168.204.7@tcp [16661.578514] Key type .llcrypt unregistered [16661.587669] Key type ._llcrypt unregistered [16689.573170] Key type ._llcrypt registered [16689.584089] Key type .llcrypt registered [16691.848647] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 17:46:56 (1785793616) [16695.803256] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16695.857064] alg: No test for adler32 (adler32-zlib) [16697.364202] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [16697.642670] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [16699.480541] Key type lgssc registered [16700.985616] Lustre: Echo OBD driver; http://www.lustre.org/ [16771.959152] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16782.303524] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16813.550453] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16815.673669] Lustre: Mounted lustre-client [16820.721868] Lustre: lustre-MDT0001-mdc-ffff9f72c70fd800: Connection to lustre-MDT0001 (at 192.168.204.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [16824.538617] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [16829.600439] Lustre: Unmounted lustre-client [16859.507849] Key type lgssc unregistered [16859.958500] LNet: 271353:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16859.973959] LNetError: 271353:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16860.032948] LNet: Removed LNI 192.168.204.7@tcp [16861.127874] Key type .llcrypt unregistered [16861.134940] Key type ._llcrypt unregistered [16885.456528] Key type ._llcrypt registered [16885.461892] Key type .llcrypt registered [16888.404079] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 17:50:12 (1785793812) [16916.703691] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16916.731951] alg: No test for adler32 (adler32-zlib) [16918.250857] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [16918.548605] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [16920.463232] Key type lgssc registered [16922.847415] Lustre: Echo OBD driver; http://www.lustre.org/ [17077.387915] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [17079.298948] Lustre: Mounted lustre-client [17134.056614] Lustre: lustre-MDT0001-mdc-ffff9f72c9e74000: Connection to lustre-MDT0001 (at 192.168.204.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [17147.919760] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [17158.350063] Lustre: Unmounted lustre-client [17191.751362] Key type lgssc unregistered [17192.166793] LNet: 277151:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [17192.171545] LNetError: 277151:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [17192.196797] LNet: Removed LNI 192.168.204.7@tcp [17193.205765] Key type .llcrypt unregistered [17193.210188] Key type ._llcrypt unregistered [17216.729906] Key type ._llcrypt registered [17216.737719] Key type .llcrypt registered [17219.010801] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 17:55:43 (1785794143) [17223.138799] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [17226.656909] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 17:55:50 (1785794150) [17228.564598] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [17231.707522] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 17:55:56 (1785794156) [17234.423329] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [17238.391071] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 17:56:02 (1785794162) [17241.329889] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [17243.051665] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 17:56:09 (1785794169) [17245.067838] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [17246.694410] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 17:56:12 (1785794172) [17257.229404] Key type .llcrypt unregistered [17257.237568] Key type ._llcrypt unregistered [17267.925500] Key type ._llcrypt registered [17267.933545] Key type .llcrypt registered [17268.754478] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [17268.784825] alg: No test for adler32 (adler32-zlib) [17269.875583] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [17270.252833] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [17271.967245] Key type lgssc registered [17274.019324] Lustre: Echo OBD driver; http://www.lustre.org/ [17379.692179] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [17389.302842] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [17428.979403] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [17431.130255] Lustre: Mounted lustre-client [17436.613160] Lustre: Unmounted lustre-client [17486.520905] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 18:00:11 (1785794411) [17536.347699] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [17543.955614] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [17586.305798] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [17587.037698] Lustre: Mounted lustre-client [17588.918167] Lustre: Unmounted lustre-client [17644.004965] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 18:02:48 (1785794568) [17648.160119] LNet: 1 local NIs in recovery (showing 1): 192.168.204.7@tcp [18349.038503] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [18357.429197] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [18402.902067] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [18527.352127] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 18:17:32 (1785795452) [18541.994982] Key type lgssc unregistered [18542.357079] LNet: 290724:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [18542.369317] LNetError: 290724:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [18542.400337] LNet: Removed LNI 192.168.204.7@tcp [18543.477163] Key type .llcrypt unregistered [18543.485199] Key type ._llcrypt unregistered [18646.624632] Key type ._llcrypt registered [18646.625803] Key type .llcrypt registered [18647.563776] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [18647.608543] alg: No test for adler32 (adler32-zlib) [18648.909767] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [18649.445165] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [18651.327200] Key type lgssc registered [18653.326224] Lustre: Echo OBD driver; http://www.lustre.org/ [18776.141860] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [18783.973232] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [18815.773305] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [18853.197713] Key type lgssc unregistered [18853.608791] LNet: 294429:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [18853.620107] LNetError: 294429:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [18853.641537] LNet: Removed LNI 192.168.204.7@tcp [18854.524551] Key type .llcrypt unregistered [18854.529383] Key type ._llcrypt unregistered [18870.748924] Key type ._llcrypt registered [18870.754383] Key type .llcrypt registered [18872.677595] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 18:23:17 (1785795797) [18900.257184] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [18900.292690] alg: No test for adler32 (adler32-zlib) [18901.558757] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [18901.886248] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [18903.671226] Key type lgssc registered [18905.558333] Lustre: Echo OBD driver; http://www.lustre.org/ [18978.090653] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 18:25:03 (1785795903) [19000.659327] Key type lgssc unregistered [19001.123267] LNet: 297990:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [19001.129549] LNetError: 297990:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [19001.150563] LNet: Removed LNI 192.168.204.7@tcp [19002.163148] Key type .llcrypt unregistered [19002.167767] Key type ._llcrypt unregistered [19022.382434] Key type ._llcrypt registered [19022.395971] Key type .llcrypt registered [19023.050748] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [19023.099898] alg: No test for adler32 (adler32-zlib) [19024.574779] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [19025.035261] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [19026.823194] Key type lgssc registered [19029.551460] Lustre: Echo OBD driver; http://www.lustre.org/ [19119.621641] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [19127.118462] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [19157.600568] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [19544.905495] LNet: Added LNI 192.168.204.7@tcp50 [8/256/0/180] [19549.025970] LNet: Removed LNI 192.168.204.7@tcp [19551.745150] Lustre: 302433:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff9f72c73d8000: config has no valid NIDs, force dynamic NIDs [19553.024059] Lustre: Mounted lustre-client [19553.284213] Lustre: Unmounted lustre-client [19557.543139] Key type lgssc unregistered [19557.897265] LNet: 302692:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [19557.904532] LNetError: 302692:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [19557.940117] LNet: Removed LNI 192.168.204.7@tcp50 [19558.943817] Key type .llcrypt unregistered [19558.956218] Key type ._llcrypt unregistered [19582.178790] Key type ._llcrypt registered [19582.185192] Key type .llcrypt registered [19583.131164] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [19583.188704] alg: No test for adler32 (adler32-zlib) [19584.404226] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [19584.810448] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [19586.623645] Key type lgssc registered [19589.372483] Lustre: Echo OBD driver; http://www.lustre.org/ [19657.256763] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [19663.480948] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [19673.446112] LNet: Added LNI 192.168.204.7@tcp50 [8/256/0/180] [19674.838637] LNet: Removed LNI 192.168.204.7@tcp [19676.102444] Lustre: 305391:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff9f72c70fd000: config has no valid NIDs, force dynamic NIDs [19677.439134] Lustre: Mounted lustre-client [19678.385684] Lustre: Unmounted lustre-client [19730.612341] Key type lgssc unregistered [19730.984027] LNet: 306265:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [19730.991366] LNetError: 306265:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [19731.015935] LNet: Removed LNI 192.168.204.7@tcp50 [19731.800915] Key type .llcrypt unregistered [19731.803889] Key type ._llcrypt unregistered [19748.957951] Key type ._llcrypt registered [19748.967569] Key type .llcrypt registered [19751.723154] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 18:37:55 (1785796675) [19784.619943] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [19784.759102] alg: No test for adler32 (adler32-zlib) [19786.229756] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [19786.559597] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [19788.271207] Key type lgssc registered [19789.617918] Lustre: Echo OBD driver; http://www.lustre.org/ [19914.816787] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [19926.196266] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [19967.180441] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [20016.943224] Key type lgssc unregistered [20017.374370] LNet: 311028:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [20017.383513] LNetError: 311028:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [20017.420388] LNet: Removed LNI 192.168.204.7@tcp [20018.653829] Key type .llcrypt unregistered [20018.656390] Key type ._llcrypt unregistered [20038.534351] Key type ._llcrypt registered [20038.537559] Key type .llcrypt registered [20040.793960] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 18:42:45 (1785796965) [20043.962993] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [20043.978602] alg: No test for adler32 (adler32-zlib) [20045.240951] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [20045.474078] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [20047.168661] Key type lgssc registered [20049.505879] Lustre: Echo OBD driver; http://www.lustre.org/ [20117.275167] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [20124.215572] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [20152.887577] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [20154.662701] Lustre: Mounted lustre-client [20224.653535] Lustre: Mounted fs15246-client [20245.974083] Lustre: Unmounted fs15246-client [20282.674880] Lustre: Unmounted lustre-client [20315.841785] Key type lgssc unregistered [20316.211083] LNet: 315894:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [20316.219444] LNetError: 315894:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [20317.290481] LNet: Removed LNI 192.168.204.7@tcp [20318.410822] Key type .llcrypt unregistered [20318.412455] Key type ._llcrypt unregistered [20337.479809] Key type ._llcrypt registered [20337.481478] Key type .llcrypt registered [20339.705824] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 18:47:44 (1785797264) [20342.797545] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [20342.879597] alg: No test for adler32 (adler32-zlib) [20344.288500] Lustre: Lustre: Build Version: 2.17.56_2_g1ec524e [20344.681608] LNet: Added LNI 192.168.204.7@tcp [8/256/0/180] [20346.359194] Key type lgssc registered [20348.328810] Lustre: Echo OBD driver; http://www.lustre.org/ [20552.210319] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [20559.919728] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [20590.948883] Lustre: DEBUG MARKER: oleg407-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [20738.136797] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 20340 sec ========= 18:54:23 (1785797663) [20740.253495] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 18:54:25 (1785797665) === [20745.074368] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 18:54:30 (1785797670) === [20796.317976] Key type lgssc unregistered [20796.983532] LNet: 325179:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [20797.000072] LNetError: 325179:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [20797.048680] LNet: Removed LNI 192.168.204.7@tcp [20799.073640] Key type .llcrypt unregistered [20799.075231] Key type ._llcrypt unregistered