[ 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 457568393 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001011] APIC: Switch to symmetric I/O mode setup [ 0.003172] x2apic enabled [ 0.004009] Switched APIC routing to physical x2apic. [ 0.005015] kvm-guest: setup PV IPIs [ 0.007756] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008020] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009012] pid_max: default: 32768 minimum: 301 [ 0.010132] LSM: Security Framework initializing [ 0.011055] Yama: becoming mindful. [ 0.012041] SELinux: Initializing. [ 0.013070] *** VALIDATE selinux *** [ 0.022177] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026806] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.029115] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030106] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031131] *** VALIDATE tmpfs *** [ 0.032423] *** VALIDATE proc *** [ 0.033250] *** VALIDATE cgroup *** [ 0.034000] *** VALIDATE cgroup2 *** [ 0.034240] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035142] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036009] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037027] Spectre V2 : User space: Vulnerable [ 0.038009] Speculative Store Bypass: Vulnerable [ 0.041150] debug: unmapping init [mem 0xffffffff8c059000-0xffffffff8c060fff] [ 0.044123] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.045695] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.046024] ... version: 2 [ 0.047014] ... bit width: 48 [ 0.048014] ... generic registers: 4 [ 0.049013] ... value mask: 0000ffffffffffff [ 0.050013] ... max period: 00007fffffffffff [ 0.051013] ... fixed-purpose events: 3 [ 0.052012] ... event mask: 000000070000000f [ 0.054325] rcu: Hierarchical SRCU implementation. [ 0.056521] smp: Bringing up secondary CPUs ... [ 0.057838] x86: Booting SMP configuration: [ 0.058023] .... node #0, CPUs: #1 #2 #3 [ 0.063243] smp: Brought up 1 node, 4 CPUs [ 0.065031] smpboot: Max logical packages: 1 [ 0.066013] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.257670] node 0 deferred pages initialised in 189ms [ 0.261187] devtmpfs: initialized [ 0.262307] x86/mm: Memory block size: 128MB [ 0.265094] gcov: version magic: 0x41383552 [ 0.267322] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.268078] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.269325] pinctrl core: initialized pinctrl subsystem [ 0.270202] [ 0.270862] ************************************************************* [ 0.271015] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.272016] ** ** [ 0.273013] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.274014] ** ** [ 0.275019] ** This means that this kernel is built to expose internal ** [ 0.276017] ** IOMMU data structures, which may compromise security on ** [ 0.277015] ** your system. ** [ 0.278014] ** ** [ 0.279016] ** If you see this message and you are not debugging the ** [ 0.280015] ** kernel, report this immediately to your vendor! ** [ 0.281014] ** ** [ 0.282014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.283018] ************************************************************* [ 0.284570] NET: Registered protocol family 16 [ 0.285527] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.286057] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.287145] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.290201] cpuidle: using governor menu [ 0.292861] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.296616] PCI: Using configuration type 1 for base access [ 0.298160] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.308099] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.310132] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.314055] cryptd: max_cpu_qlen set to 1000 [ 0.317266] ACPI: Added _OSI(Module Device) [ 0.319020] ACPI: Added _OSI(Processor Device) [ 0.320000] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.321022] ACPI: Added _OSI(Processor Aggregator Device) [ 0.327163] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.334489] ACPI: Interpreter enabled [ 0.336080] ACPI: PM: (supports S0 S3 S4 S5) [ 0.338020] ACPI: Using IOAPIC for interrupt routing [ 0.340156] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.343441] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.354176] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.357057] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.361027] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.366136] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.370958] acpiphp: Slot [2] registered [ 0.373125] acpiphp: Slot [5] registered [ 0.374093] acpiphp: Slot [6] registered [ 0.375139] acpiphp: Slot [3] registered [ 0.376128] acpiphp: Slot [4] registered [ 0.378130] acpiphp: Slot [7] registered [ 0.380146] acpiphp: Slot [8] registered [ 0.381174] acpiphp: Slot [9] registered [ 0.383120] acpiphp: Slot [10] registered [ 0.384110] acpiphp: Slot [11] registered [ 0.386111] acpiphp: Slot [12] registered [ 0.388135] acpiphp: Slot [13] registered [ 0.389219] acpiphp: Slot [14] registered [ 0.391146] acpiphp: Slot [15] registered [ 0.392136] acpiphp: Slot [16] registered [ 0.394152] acpiphp: Slot [17] registered [ 0.395107] acpiphp: Slot [18] registered [ 0.397122] acpiphp: Slot [19] registered [ 0.398128] acpiphp: Slot [20] registered [ 0.400127] acpiphp: Slot [21] registered [ 0.402160] acpiphp: Slot [22] registered [ 0.403116] acpiphp: Slot [23] registered [ 0.405142] acpiphp: Slot [24] registered [ 0.407140] acpiphp: Slot [25] registered [ 0.408095] acpiphp: Slot [26] registered [ 0.410118] acpiphp: Slot [27] registered [ 0.412117] acpiphp: Slot [28] registered [ 0.413121] acpiphp: Slot [29] registered [ 0.415134] acpiphp: Slot [30] registered [ 0.416143] acpiphp: Slot [31] registered [ 0.418075] PCI host bridge to bus 0000:00 [ 0.420026] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.422022] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.423019] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.425025] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.427036] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.430037] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.432204] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.435175] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.438365] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.445021] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.449055] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.452022] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.454022] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.455019] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.458505] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.462023] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.465040] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.467780] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.471021] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.480667] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.485017] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.492337] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.504023] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.512020] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.536018] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.548849] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.557864] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.567022] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.596021] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.615240] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.617422] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.620605] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.623244] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.625271] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.630156] iommu: Default domain type: Passthrough [ 0.632704] SCSI subsystem initialized [ 0.634103] ACPI: bus type USB registered [ 0.635181] usbcore: registered new interface driver usbfs [ 0.638124] usbcore: registered new interface driver hub [ 0.640095] usbcore: registered new device driver usb [ 0.642218] pps_core: LinuxPPS API ver. 1 registered [ 0.643008] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.646077] PTP clock support registered [ 0.649138] EDAC MC: Ver: 3.0.0 [ 0.651097] PCI: Using ACPI for IRQ routing [ 0.652799] NetLabel: Initializing [ 0.655030] NetLabel: domain hash size = 128 [ 0.658018] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.659099] NetLabel: unlabeled traffic allowed by default [ 0.661162] vgaarb: loaded [ 0.662298] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.665017] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.674000] clocksource: Switched to clocksource kvm-clock [ 0.796771] VFS: Disk quotas dquot_6.6.0 [ 0.799040] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.803268] *** VALIDATE ramfs *** [ 0.804657] *** VALIDATE hugetlbfs *** [ 0.806360] pnp: PnP ACPI init [ 0.809698] pnp: PnP ACPI: found 6 devices [ 0.828227] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.831904] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.834537] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.836861] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.839933] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.842640] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.845718] NET: Registered protocol family 2 [ 0.849798] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.855358] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.859038] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.864215] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.868597] TCP: Hash tables configured (established 65536 bind 65536) [ 0.871984] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.876140] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.879573] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.882746] NET: Registered protocol family 1 [ 0.886152] RPC: Registered named UNIX socket transport module. [ 0.888041] RPC: Registered udp transport module. [ 0.889971] RPC: Registered tcp transport module. [ 0.891879] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.894515] NET: Registered protocol family 44 [ 0.896317] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.897956] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.900528] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.904393] PCI: CLS 0 bytes, default 64 [ 0.906433] Unpacking initramfs... [ 2.363209] debug: unmapping init [mem 0xffff9e4d3cc64000-0xffff9e4d3ffcffff] [ 2.367425] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.369643] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.372607] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.881760] Initialise system trusted keyrings [ 2.883386] Key type blacklist registered [ 2.884882] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.892624] zbud: loaded [ 2.895824] *** VALIDATE nfs *** [ 2.897310] *** VALIDATE nfs4 *** [ 2.899339] pstore: using deflate compression [ 2.902550] Platform Keyring initialized [ 3.037349] NET: Registered protocol family 38 [ 3.038907] Key type asymmetric registered [ 3.040567] Asymmetric key parser 'x509' registered [ 3.042366] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.045888] io scheduler mq-deadline registered [ 3.047517] io scheduler kyber registered [ 3.050287] io scheduler bfq registered [ 3.052422] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.055739] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.058970] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.062144] ACPI: Power Button [PWRF] [ 3.066895] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.073899] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.095697] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.123677] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.153483] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.158886] Non-volatile memory driver v1.3 [ 3.160894] Linux agpgart interface v0.103 [ 3.200887] virtio_blk virtio1: [vda] 146960 512-byte logical blocks (75.2 MB/71.8 MiB) [ 3.204169] vda: detected capacity change from 0 to 75243520 [ 3.226542] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.231398] vdb: detected capacity change from 0 to 1073741824 [ 3.246067] libphy: Fixed MDIO Bus: probed [ 3.251528] usbcore: registered new interface driver usbserial_generic [ 3.254129] usbserial: USB Serial support registered for generic [ 3.256817] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.261715] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.263727] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.266336] mousedev: PS/2 mouse device common for all mice [ 3.269746] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.271586] rtc_cmos 00:05: RTC can wake from S4 [ 3.277335] rtc_cmos 00:05: registered as rtc0 [ 3.280716] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.280755] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.283757] intel_pstate: CPU model not supported [ 3.289580] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.295905] hid: raw HID events driver (C) Jiri Kosina [ 3.298524] usbcore: registered new interface driver usbhid [ 3.301187] usbhid: USB HID core driver [ 3.303288] drop_monitor: Initializing network drop monitor service [ 3.306258] Initializing XFRM netlink socket [ 3.308561] NET: Registered protocol family 10 [ 3.312385] Segment Routing with IPv6 [ 3.313931] NET: Registered protocol family 17 [ 3.316313] mpls_gso: MPLS GSO support [ 3.323207] RAS: Correctable Errors collector initialized. [ 3.326346] AVX version of gcm_enc/dec engaged. [ 3.328473] AES CTR mode by8 optimization enabled [ 3.404316] sched_clock: Marking stable (3404245163, 0)->(4366788392, -962543229) [ 3.407591] registered taskstats version 1 [ 3.409798] Loading compiled-in X.509 certificates [ 3.412683] zswap: loaded using pool lzo/zbud [ 3.438072] Key type big_key registered [ 3.450670] Key type encrypted registered [ 3.452609] ima: No TPM chip found, activating TPM-bypass! [ 3.455188] ima: Allocated hash algorithm: sha1 [ 3.457056] ima: No architecture policies found [ 3.460089] evm: Initialising EVM extended attributes: [ 3.462614] evm: security.selinux [ 3.464535] evm: security.ima [ 3.465816] evm: security.capability [ 3.467540] evm: HMAC attrs: 0x1 [ 3.470638] rtc_cmos 00:05: setting system clock to 2026-08-27 22:05:08 UTC (1787868308) [ 3.478202] debug: unmapping init [mem 0xffffffff8d003000-0xffffffff8d1fffff] [ 3.481953] debug: unmapping init [mem 0xffffffff8bd82000-0xffffffff8c058fff] [ 3.491085] Write protecting the kernel read-only data: 28672k [ 3.496581] debug: unmapping init [mem 0xffffffff8a403000-0xffffffff8a5fffff] [ 3.499648] debug: unmapping init [mem 0xffffffff8ad14000-0xffffffff8adfffff] [ 3.536811] 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.547995] systemd[1]: Detected virtualization kvm. [ 3.550461] systemd[1]: Detected architecture x86-64. [ 3.552897] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.578881] systemd[1]: No hostname configured. [ 3.581057] systemd[1]: Set hostname to . [ 3.583433] random: systemd: uninitialized urandom read (16 bytes read) [ 3.586222] systemd[1]: Initializing machine ID from random generator. [ 3.636902] random: ln: uninitialized urandom read (6 bytes read) [ 3.740250] random: systemd: uninitialized urandom read (16 bytes read) [ 3.742929] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 3.747689] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 3.755976] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Local File Systems. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Started Memstrack Anylazing Service. Starting Create Volatile Files and Directories... [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Control Socket. [ OK ] Listening on udev Kernel Socket. Starting Apply Kernel Variables... [ OK ] Reached target Swap. Starting Create list of required st…ce nodes for the current kernel... Starting Setup Virtual Console... Starting Journal Service... [ OK ] Reached target Paths. [ OK ] Reached target Timers. [ OK ] Reached target Sockets. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.367355] device-mapper: uevent: version 1.0.3 [ 4.369881] 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. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 5.134813] virtio_net virtio0 ens2: renamed from eth0 [ 5.177962] scsi host0: ata_piix [ 5.187656] scsi host1: ata_piix [ 5.189496] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.192161] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.439744] dracut-initqueue[589]: RTNETLINK answers: File exists [ 9.994599] random: crng init done [ 9.996230] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 10.371854] 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 target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ 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 Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.600495] printk: systemd: 25 output lines suppressed due to ratelimiting [ 11.900636] SELinux: Disabled at runtime. [ 11.967770] 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.975925] systemd[1]: Detected virtualization kvm. [ 11.978827] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.583400] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.587107] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.593848] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.597936] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.601971] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.611837] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.619947] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. Mounting Huge Pages File System... [ OK ] Reached target rpc_pipefs.target. Starting Create list of required st…ce nodes for the current kernel... Starting Remount Root and Kernel File Systems... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. Starting Apply Kernel Variables... [ OK ] Stopped target Initrd Root File System. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on udev Kernel Socket. [ OK ] Created slice system-getty.slice. [ OK ] Listening on RPCbind Server Activation Socket. [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 Process Core Dump Socket. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... Activating swap /dev/disk/by-label/SWAP... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. Mounting Kernel Debug File System... [ OK ] Reached target RPC Port Mapper. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ 12.824058] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Reached target Paths. [ OK ] Created slice system-sshd\x2dkeygen.slice. Mounting POSIX Message Queue File System... [ OK ] Started Journal Service. [ OK ] Mounted Huge Pages File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [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 ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ OK ] Started udev Coldplug all Devices. [ 13.169376] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 13.580715] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.589217] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.690264] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.723086] EDAC sbridge: Ver: 1.1.2 [ 15.003610] Key type dns_resolver registered [ 15.341277] NFS: Registering the id_resolver key type [ 15.343976] Key type id_resolver registered [ 15.345913] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started 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... Starting Login Service... [ OK ] Reached target sshd-keygen.target. [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg636-client login: [ 48.098816] hrtimer: interrupt took 9576383 ns [ 70.858960] libcfs: loading out-of-tree module taints kernel. [ 71.024770] Key type ._llcrypt registered [ 71.043377] Key type .llcrypt registered [ 71.983628] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 71.999666] alg: No test for adler32 (adler32-zlib) [ 73.618578] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [ 74.357286] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [ 76.191178] Key type lgssc registered [ 78.865662] Lustre: Echo OBD driver; http://www.lustre.org/ [ 250.189389] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 254.965669] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 274.267914] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing check_logdir /tmp/testlogs/ [ 275.936120] Lustre: lustre-OST0000-osc-ffff9e4d988b2800: disconnect after 23s idle [ 279.190760] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing yml_node [ 284.293712] Lustre: DEBUG MARKER: Client: 2.17.57.99 [ 287.263195] Lustre: DEBUG MARKER: MDS: 2.17.57.99 [ 291.153886] Lustre: DEBUG MARKER: OSS: 2.17.57.99 [ 293.823257] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Thu Aug 27 18:09:56 EDT 2026 [ 314.343935] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 316.046730] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 318.128535] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 328.527967] Lustre: Unmounted lustre-client [ 446.620895] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 452.879877] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 479.762118] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 516.890320] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 18:13:40 (1787868820) [ 519.548330] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 521.405906] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 18:13:44 (1787868824) [ 554.069407] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 559.746434] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 581.340904] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 582.083851] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 590.348391] Lustre: Unmounted lustre-client [ 620.684314] Key type lgssc unregistered [ 620.938644] LNet: 15071:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 620.956191] LNetError: 15071:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 620.976203] LNet: Removed LNI 192.168.206.36@tcp [ 621.499187] Key type .llcrypt unregistered [ 621.501234] Key type ._llcrypt unregistered [ 636.994771] Key type ._llcrypt registered [ 636.998992] Key type .llcrypt registered [ 638.249848] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 18:15:41 (1787868941) [ 639.816090] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 639.886074] alg: No test for adler32 (adler32-zlib) [ 641.120392] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [ 641.564374] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [ 643.303253] Key type lgssc registered [ 644.586791] Lustre: Echo OBD driver; http://www.lustre.org/ [ 722.520632] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 808.635824] Lustre: Unmounted lustre-client [ 836.155102] Key type lgssc unregistered [ 836.445636] LNet: 18909:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 836.467963] LNetError: 18909:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 836.483365] LNet: Removed LNI 192.168.206.36@tcp [ 837.298333] Key type .llcrypt unregistered [ 837.302109] Key type ._llcrypt unregistered [ 847.706313] Key type ._llcrypt registered [ 847.708032] Key type .llcrypt registered [ 849.103142] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 18:19:12 (1787869152) [ 851.579911] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 851.606733] alg: No test for adler32 (adler32-zlib) [ 852.612514] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [ 852.906221] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [ 854.631168] Key type lgssc registered [ 855.827193] Lustre: Echo OBD driver; http://www.lustre.org/ [ 901.916613] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 907.208095] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 925.723218] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 928.347655] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 937.845449] Lustre: Unmounted lustre-client [ 965.819517] Key type lgssc unregistered [ 966.123816] LNet: 22514:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 966.137476] LNetError: 22514:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 966.159252] LNet: Removed LNI 192.168.206.36@tcp [ 966.776972] Key type .llcrypt unregistered [ 966.779943] Key type ._llcrypt unregistered [ 977.364541] Key type ._llcrypt registered [ 977.368749] Key type .llcrypt registered [ 979.080863] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 18:21:22 (1787869282) [ 981.376686] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 981.456250] alg: No test for adler32 (adler32-zlib) [ 982.542129] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [ 982.777840] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [ 984.488257] Key type lgssc registered [ 985.777641] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1033.088723] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1038.273881] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1059.477862] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1062.224435] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 1068.377054] Lustre: Unmounted lustre-client [ 1096.134691] Key type lgssc unregistered [ 1096.411029] LNet: 25701:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1096.419160] LNetError: 25701:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1096.442088] LNet: Removed LNI 192.168.206.36@tcp [ 1097.132101] Key type .llcrypt unregistered [ 1097.135960] Key type ._llcrypt unregistered [ 1111.710828] Key type ._llcrypt registered [ 1111.713727] Key type .llcrypt registered [ 1112.738574] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 18:23:36 (1787869416) [ 1115.052141] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1115.080492] alg: No test for adler32 (adler32-zlib) [ 1116.109135] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [ 1116.425610] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [ 1118.103218] Key type lgssc registered [ 1119.620894] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1171.741509] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1177.450430] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1197.278685] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1199.902589] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 1210.344486] Lustre: lustre-MDT0000-mdc-ffff9e4d87efb800: Connection to lustre-MDT0000 (at 192.168.206.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1220.586576] Lustre: lustre-MDT0001-mdc-ffff9e4d87efb800: Connection to lustre-MDT0001 (at 192.168.206.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1224.762121] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1231.839504] Lustre: 26658:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787869520/real 1787869520] req@00000000051ad683 x1874716966000768/t0(0) o400->MGC192.168.206.136@tcp@192.168.206.136@tcp:26/25 lens 224/224 e 0 to 1 dl 1787869536 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1231.862504] LustreError: MGC192.168.206.136@tcp: Connection to MGS (at 192.168.206.136@tcp) was lost; in progress operations using this service will fail [ 1235.101471] Lustre: Unmounted lustre-client [ 1254.382838] Key type lgssc unregistered [ 1254.626156] LNet: 29357:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1254.632835] LNetError: 29357:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1254.653048] LNet: Removed LNI 192.168.206.36@tcp [ 1255.347223] Key type .llcrypt unregistered [ 1255.352731] Key type ._llcrypt unregistered [ 1270.793893] Key type ._llcrypt registered [ 1270.795571] Key type .llcrypt registered [ 1271.875179] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 18:26:15 (1787869575) [ 1274.183935] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1274.261061] alg: No test for adler32 (adler32-zlib) [ 1275.334919] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [ 1275.538966] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [ 1277.193432] Key type lgssc registered [ 1278.260197] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1324.744742] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1330.763643] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1351.865912] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1354.952644] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 1360.370083] Lustre: lustre-OST0000-osc-ffff9e4d83e6b000: Connection to lustre-OST0000 (at 192.168.206.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1367.162170] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1377.530855] Lustre: Unmounted lustre-client [ 1404.158556] Key type lgssc unregistered [ 1404.427867] LNet: 32971:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1404.431960] LNetError: 32971:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1404.461892] LNet: Removed LNI 192.168.206.36@tcp [ 1405.058771] Key type .llcrypt unregistered [ 1405.061904] Key type ._llcrypt unregistered [ 1418.372199] Key type ._llcrypt registered [ 1418.374260] Key type .llcrypt registered [ 1419.539429] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 18:28:43 (1787869723) [ 1421.592894] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1421.601351] alg: No test for adler32 (adler32-zlib) [ 1422.625474] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [ 1422.837363] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [ 1424.504085] Key type lgssc registered [ 1425.334905] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1465.671985] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1471.243574] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1491.773710] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1493.299512] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 1496.629072] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1500.005172] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1511.429369] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-08-2718h30m15s [ 1513.476695] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1519.086120] Lustre: lustre-MDT0000-mdc-ffff9e4da0aba000: Connection to lustre-MDT0000 (at 192.168.206.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1524.087918] LustreError: 36756:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff9e4da0aba000: can't stat MDS #0: rc = -19 [ 1525.651711] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1536.495970] LustreError: MGC192.168.206.136@tcp: Connection to MGS (at 192.168.206.136@tcp) was lost; in progress operations using this service will fail [ 1536.525873] Lustre: Evicted from MGS (at 192.168.206.136@tcp) after server handle changed from 0xd6a35ba748dc4311 to 0xd6a35ba748dc45d4 [ 1536.547437] Lustre: MGC192.168.206.136@tcp: Connection restored to 192.168.206.136@tcp (at 192.168.206.136@tcp) [ 1537.598715] LustreError: lustre-MDT0000-mdc-ffff9e4da0aba000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1537.632839] Lustre: lustre-MDT0000-mdc-ffff9e4da0aba000: Connection restored to 192.168.206.136@tcp (at 192.168.206.136@tcp) [ 1540.041366] Lustre: DEBUG MARKER: Wait for df (36756) ... [ 1541.426831] Lustre: DEBUG MARKER: done [ 1543.347097] Lustre: Unmounted lustre-client [ 1569.741171] Key type lgssc unregistered [ 1569.991504] LNet: 37773:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1569.999654] LNetError: 37773:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1570.015491] LNet: Removed LNI 192.168.206.36@tcp [ 1570.574500] Key type .llcrypt unregistered [ 1570.577208] Key type ._llcrypt unregistered [ 1599.465863] Key type ._llcrypt registered [ 1599.468666] Key type .llcrypt registered [ 1600.616759] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 18:31:44 (1787869904) [ 1602.901898] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1603.117967] alg: No test for adler32 (adler32-zlib) [ 1604.140816] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [ 1604.414074] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [ 1606.143154] Key type lgssc registered [ 1607.327746] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1652.225803] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1657.820267] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1677.992871] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1680.901293] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 1685.245883] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-08-2718h33m09s [ 1687.006857] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1693.626786] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1695.808891] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1716.191394] Lustre: 41917:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787870000/real 1787870000] req@0000000050c80284 x1874717477705984/t0(0) o101->lustre-MDT0000-mdc-ffff9e4d916bf000@192.168.206.136@tcp:12/10 lens 576/1152 e 0 to 1 dl 1787870020 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1716.223425] Lustre: lustre-MDT0000-mdc-ffff9e4d916bf000: Connection to lustre-MDT0000 (at 192.168.206.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1716.256486] Lustre: lustre-MDT0000-mdc-ffff9e4d916bf000: Connection restored to 192.168.206.136@tcp (at 192.168.206.136@tcp) [ 1737.513503] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1740.237537] Lustre: DEBUG MARKER: done [ 1743.447576] Lustre: Unmounted lustre-client [ 1769.786404] Key type lgssc unregistered [ 1770.014897] LNet: 42805:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1770.020364] LNetError: 42805:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1770.032588] LNet: Removed LNI 192.168.206.36@tcp [ 1770.517417] Key type .llcrypt unregistered [ 1770.519622] Key type ._llcrypt unregistered [ 1796.215107] Key type ._llcrypt registered [ 1796.217774] Key type .llcrypt registered [ 1797.645022] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 18:35:01 (1787870101) [ 1799.712386] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1801.640877] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 18:35:05 (1787870105) [ 1805.252936] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1805.266764] alg: No test for adler32 (adler32-zlib) [ 1806.550234] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [ 1806.838879] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [ 1808.551868] Key type lgssc registered [ 1810.140145] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1835.859036] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 18:35:39 (1787870139) [ 1864.780634] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1871.201917] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1889.127543] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1890.761177] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 1893.920648] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1897.349625] Lustre: Unmounted lustre-client [ 1913.499496] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 1958.535808] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1963.821539] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1964.455837] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 1968.966505] Lustre: Unmounted lustre-client [ 2032.381492] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2038.428448] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2039.114386] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 2043.578230] Lustre: Unmounted lustre-client [ 2064.589231] Lustre: DEBUG MARKER: files compared the same [ 2086.915765] Key type lgssc unregistered [ 2087.220152] LNet: 50435:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2087.234960] LNetError: 50435:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2087.253950] LNet: Removed LNI 192.168.206.36@tcp [ 2088.004586] Key type .llcrypt unregistered [ 2088.006779] Key type ._llcrypt unregistered [ 2104.194430] Key type ._llcrypt registered [ 2104.198252] Key type .llcrypt registered [ 2105.656637] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 18:40:09 (1787870409) [ 2108.016627] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2108.086669] alg: No test for adler32 (adler32-zlib) [ 2109.214242] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [ 2109.466458] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [ 2111.184451] Key type lgssc registered [ 2112.364583] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2156.625502] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2160.954929] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2178.950285] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2184.727249] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 2188.287283] Lustre: Unmounted lustre-client [ 2212.401525] Key type lgssc unregistered [ 2212.611519] LNet: 54021:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2212.630872] LNetError: 54021:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2212.660295] LNet: Removed LNI 192.168.206.36@tcp [ 2213.319190] Key type .llcrypt unregistered [ 2213.323247] Key type ._llcrypt unregistered [ 2228.311417] Key type ._llcrypt registered [ 2228.315649] Key type .llcrypt registered [ 2229.936426] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 18:42:13 (1787870533) [ 2232.698963] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2232.712945] alg: No test for adler32 (adler32-zlib) [ 2233.787845] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [ 2234.014689] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [ 2235.695221] Key type lgssc registered [ 2237.053098] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2317.873505] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2349.372632] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2354.782116] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2380.140937] Key type lgssc unregistered [ 2380.376270] LNet: 57511:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2380.385407] LNetError: 57511:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2380.398237] LNet: Removed LNI 192.168.206.36@tcp [ 2380.972798] Key type .llcrypt unregistered [ 2380.975831] Key type ._llcrypt unregistered [ 2395.499300] Key type ._llcrypt registered [ 2395.507282] Key type .llcrypt registered [ 2396.830507] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 18:45:00 (1787870700) [ 2400.038213] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2400.070438] alg: No test for adler32 (adler32-zlib) [ 2401.225499] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [ 2401.535871] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [ 2403.295239] Key type lgssc registered [ 2404.609676] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2472.237230] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 2479.393845] Lustre: Unmounted lustre-client [ 2514.781958] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 18:46:58 (1787870818) [ 2629.714330] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2650.150479] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 2656.663790] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2656.674210] LustreError: 63977:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff9e4d85f6c000: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2656.695361] Lustre: 58493:0:(llite_lib.c:4352:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.206.136@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2667.076393] Lustre: Unmounted lustre-client [ 2707.911077] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 18:50:11 (1787871011) [ 2716.982919] Key type lgssc unregistered [ 2717.168731] LNet: 65190:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2717.179418] LNetError: 65190:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2717.200336] LNet: Removed LNI 192.168.206.36@tcp [ 2717.691457] Key type .llcrypt unregistered [ 2717.694347] Key type ._llcrypt unregistered [ 2727.359157] Key type ._llcrypt registered [ 2727.370266] Key type .llcrypt registered [ 2727.585725] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2727.595665] alg: No test for adler32 (adler32-zlib) [ 2728.625474] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [ 2728.796078] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [ 2730.431237] Key type lgssc registered [ 2731.450564] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2775.299443] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2781.681356] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2802.916300] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2804.599840] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 2811.470607] Lustre: Unmounted lustre-client [ 2833.214638] Key type lgssc unregistered [ 2833.443052] LNet: 68206:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2833.450263] LNetError: 68206:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2833.468540] LNet: Removed LNI 192.168.206.36@tcp [ 2833.985291] Key type .llcrypt unregistered [ 2833.989750] Key type ._llcrypt unregistered [ 2845.483382] Key type ._llcrypt registered [ 2845.508290] Key type .llcrypt registered [ 2845.871091] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2845.882784] alg: No test for adler32 (adler32-zlib) [ 2847.039091] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [ 2847.233206] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [ 2848.895389] Key type lgssc registered [ 2850.188379] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2959.276846] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2964.351161] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2984.737649] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2986.424515] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 2989.163831] Lustre: Unmounted lustre-client [ 3015.833570] Key type lgssc unregistered [ 3016.098865] LNet: 71633:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3016.119300] LNetError: 71633:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3016.137485] LNet: Removed LNI 192.168.206.36@tcp [ 3016.704133] Key type .llcrypt unregistered [ 3016.707357] Key type ._llcrypt unregistered [ 3034.705298] Key type ._llcrypt registered [ 3034.707587] Key type .llcrypt registered [ 3036.186961] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 18:55:39 (1787871339) [ 3038.695802] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3038.728952] alg: No test for adler32 (adler32-zlib) [ 3039.811959] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [ 3040.090665] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [ 3041.751214] Key type lgssc registered [ 3042.920609] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3089.970704] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3094.840301] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3111.220630] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3112.682563] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 3117.113632] Lustre: Unmounted lustre-client [ 3117.396654] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 3120.027878] Lustre: Unmounted lustre-client [ 3145.874452] Key type lgssc unregistered [ 3146.111490] LNet: 75321:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3146.119735] LNetError: 75321:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3146.144443] LNet: Removed LNI 192.168.206.36@tcp [ 3146.785873] Key type .llcrypt unregistered [ 3146.790997] Key type ._llcrypt unregistered [ 3158.493689] Key type ._llcrypt registered [ 3158.497122] Key type .llcrypt registered [ 3158.938955] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3158.967742] alg: No test for adler32 (adler32-zlib) [ 3160.112981] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [ 3160.344541] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [ 3162.017925] Key type lgssc registered [ 3163.222250] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3211.332797] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3218.193792] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3238.521912] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3240.472948] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 3248.367476] Lustre: Unmounted lustre-client [ 3271.246550] Key type lgssc unregistered [ 3271.488706] LNet: 78355:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3271.496325] LNetError: 78355:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3271.512472] LNet: Removed LNI 192.168.206.36@tcp [ 3272.151900] Key type .llcrypt unregistered [ 3272.154725] Key type ._llcrypt unregistered [ 3288.185738] Key type ._llcrypt registered [ 3288.188892] Key type .llcrypt registered [ 3289.534296] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 18:59:52 (1787871592) [ 3291.651136] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3291.702084] alg: No test for adler32 (adler32-zlib) [ 3292.825697] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [ 3293.152588] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [ 3294.913188] Key type lgssc registered [ 3296.152527] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3350.116631] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3355.955278] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3376.521891] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3379.283973] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 3396.180668] Lustre: lustre: nosquash_nids is cleared [ 3396.217856] Lustre: lustre: root_squash is set to 500:500 [ 3404.185740] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.206.36@tcp 192.168.0.[2,10]@tcp [ 3413.084929] Lustre: lustre: nosquash_nids set to 192.168.206.36/24@tcp [ 3420.442667] Lustre: Unmounted lustre-client [ 3444.003259] Key type lgssc unregistered [ 3444.343254] LNet: 82305:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3444.356400] LNetError: 82305:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3444.390305] LNet: Removed LNI 192.168.206.36@tcp [ 3445.681319] Key type .llcrypt unregistered [ 3445.685504] Key type ._llcrypt unregistered [ 3466.053222] Key type ._llcrypt registered [ 3466.056264] Key type .llcrypt registered [ 3467.530957] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 19:02:50 (1787871770) [ 3470.353984] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3471.829852] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 19:02:55 (1787871775) [ 3474.193702] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3474.271774] alg: No test for adler32 (adler32-zlib) [ 3475.305675] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [ 3475.613191] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [ 3477.287170] Key type lgssc registered [ 3478.320336] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3524.114331] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3530.634958] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3551.638453] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3553.314904] Lustre: lustre: root_squash is set to 500:500 [ 3553.392937] Lustre: lustre: nosquash_nids set to 192.168.206.36/24@tcp [ 3554.497458] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 3564.391939] Lustre: Unmounted lustre-client [ 3594.524878] Key type lgssc unregistered [ 3594.793180] LNet: 86270:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3594.808539] LNetError: 86270:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3594.823330] LNet: Removed LNI 192.168.206.36@tcp [ 3595.960166] Key type .llcrypt unregistered [ 3595.962638] Key type ._llcrypt unregistered [ 3614.564730] Key type ._llcrypt registered [ 3614.567755] Key type .llcrypt registered [ 3616.109762] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 3617.852882] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 19:05:21 (1787871921) [ 3635.802966] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3635.831105] alg: No test for adler32 (adler32-zlib) [ 3636.867139] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [ 3637.086654] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [ 3638.736534] Key type lgssc registered [ 3639.900415] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3723.760784] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3729.731245] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3753.035550] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3819.478570] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3825.373687] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3846.810255] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3859.781982] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 3893.886198] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9e4d8470d000.ost_server_uuid 50 [ 3895.761788] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9e4d8470d000.ost_server_uuid in IDLE state after 0 sec [ 3896.185950] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 3900.174601] Lustre: Unmounted lustre-client [ 3902.799419] Lustre: Unmounted lustre-client [ 3946.377725] Key type lgssc unregistered [ 3946.705386] LNet: 93940:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3946.716440] LNetError: 93940:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3947.753158] LNet: Removed LNI 192.168.206.36@tcp [ 3948.672742] Key type .llcrypt unregistered [ 3948.676649] Key type ._llcrypt unregistered [ 3980.359244] Key type ._llcrypt registered [ 3980.368845] Key type .llcrypt registered [ 3981.855564] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 19:11:25 (1787872285) [ 4000.752226] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4000.929955] alg: No test for adler32 (adler32-zlib) [ 4002.026413] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [ 4002.294590] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [ 4003.943232] Key type lgssc registered [ 4005.049922] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4085.717072] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4092.373481] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4119.570280] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4121.276060] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 4143.071966] Lustre: 95318:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787872431/real 1787872431] req@000000000848a0ec x1874719992190080/t0(0) o400->lustre-OST0000-osc-ffff9e4d87efd000@192.168.206.136@tcp:28/4 lens 224/224 e 0 to 1 dl 1787872447 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4143.115206] Lustre: lustre-OST0000-osc-ffff9e4d87efd000: Connection to lustre-OST0000 (at 192.168.206.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4147.231178] Lustre: 95317:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787872436/real 1787872436] req@00000000db1c0fbd x1874719992190592/t0(0) o400->lustre-OST0000-osc-ffff9e4d87efd000@192.168.206.136@tcp:28/4 lens 224/224 e 0 to 1 dl 1787872452 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4152.351411] Lustre: 95317:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787872441/real 1787872441] req@000000003227bdcc x1874719992191104/t0(0) o400->lustre-OST0000-osc-ffff9e4d87efd000@192.168.206.136@tcp:28/4 lens 224/224 e 0 to 1 dl 1787872457 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4153.354808] Lustre: lustre-OST0000-osc-ffff9e4d87efd000: Connection restored to 192.168.206.136@tcp (at 192.168.206.136@tcp) [ 4158.449596] Lustre: lustre-MDT0000-mdc-ffff9e4d87efd000: Connection to lustre-MDT0000 (at 192.168.206.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4158.472788] Lustre: 95318:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787872447/real 1787872447] req@00000000c5ca168d x1874719992191616/t0(0) o400->lustre-OST0000-osc-ffff9e4d87efd000@192.168.206.136@tcp:28/4 lens 224/224 e 0 to 1 dl 1787872463 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4174.825479] Lustre: 95317:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787872463/real 1787872463] req@00000000307b66aa x1874719992193920/t0(0) o400->MGC192.168.206.136@tcp@192.168.206.136@tcp:26/25 lens 224/224 e 0 to 1 dl 1787872479 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4174.877413] LustreError: MGC192.168.206.136@tcp: Connection to MGS (at 192.168.206.136@tcp) was lost; in progress operations using this service will fail [ 4174.906092] Lustre: Evicted from MGS (at 192.168.206.136@tcp) after server handle changed from 0xc27aad950caf39e0 to 0xc27aad950caf3b76 [ 4174.926208] Lustre: MGC192.168.206.136@tcp: Connection restored to 192.168.206.136@tcp (at 192.168.206.136@tcp) [ 4180.983177] Lustre: 98503:0:(mgc_request.c:1900:mgc_process_log()) MGC192.168.206.136@tcp: IR log lustre-cliir failed, not fatal: rc = -2 [ 4188.353751] Lustre: lustre-MDT0000-mdc-ffff9e4d87efd000: Connection restored to 192.168.206.136@tcp (at 192.168.206.136@tcp) [ 4194.070414] Lustre: Unmounted lustre-client [ 4232.405832] Key type lgssc unregistered [ 4232.731920] LNet: 99634:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4232.737471] LNetError: 99634:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4232.756962] LNet: Removed LNI 192.168.206.36@tcp [ 4233.897351] Key type .llcrypt unregistered [ 4233.900065] Key type ._llcrypt unregistered [ 4252.770648] Key type ._llcrypt registered [ 4252.773393] Key type .llcrypt registered [ 4254.197870] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 19:15:57 (1787872557) [ 4269.942307] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4269.974282] alg: No test for adler32 (adler32-zlib) [ 4271.012913] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [ 4271.226115] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [ 4272.895183] Key type lgssc registered [ 4274.565714] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4315.630801] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4410.746628] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4415.881232] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4436.926445] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4438.696774] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 4441.006384] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 4442.861605] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 19:19:06 (1787872746) [ 4692.895575] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4703.314533] Lustre: Unmounted lustre-client [ 4819.691385] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4824.716737] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4846.420321] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4897.091729] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 19:26:40 (1787873200) [ 4990.862846] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4996.913628] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5022.143437] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5022.871336] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 5027.261275] Lustre: Unmounted lustre-client [ 5065.661242] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 19:29:29 (1787873369) [ 5158.513744] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5164.799899] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5189.240821] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5189.848898] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 5194.534493] Lustre: Unmounted lustre-client [ 5221.782947] Key type lgssc unregistered [ 5222.086315] LNet: 122674:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5222.092652] LNetError: 122674:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5222.117328] LNet: Removed LNI 192.168.206.36@tcp [ 5222.884160] Key type .llcrypt unregistered [ 5222.891076] Key type ._llcrypt unregistered [ 5237.953581] Key type ._llcrypt registered [ 5237.955764] Key type .llcrypt registered [ 5239.247763] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 19:32:22 (1787873542) [ 5241.445962] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5241.508994] alg: No test for adler32 (adler32-zlib) [ 5242.590029] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [ 5242.862296] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [ 5244.704625] Key type lgssc registered [ 5246.074398] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5295.276918] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5300.698082] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5321.472777] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5323.055498] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 5336.982456] Lustre: Unmounted lustre-client [ 5365.117607] Key type lgssc unregistered [ 5365.400964] LNet: 126272:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5365.407417] LNetError: 126272:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5365.429661] LNet: Removed LNI 192.168.206.36@tcp [ 5366.400148] Key type .llcrypt unregistered [ 5366.401849] Key type ._llcrypt unregistered [ 5384.933816] Key type ._llcrypt registered [ 5384.938394] Key type .llcrypt registered [ 5386.429281] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 19:34:49 (1787873689) [ 5388.667472] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5388.699377] alg: No test for adler32 (adler32-zlib) [ 5389.859489] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [ 5390.058293] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [ 5391.759205] Key type lgssc registered [ 5393.185550] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5444.934514] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5450.104230] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5469.843883] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5472.563493] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 5477.866355] Lustre: lustre-OST0000-osc-ffff9e4d879ca000: Connection to lustre-OST0000 (at 192.168.206.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5489.257607] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff9e4d879ca000.ost_server_uuid 50 [ 5490.843506] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 5502.086737] Lustre: Unmounted lustre-client [ 5524.127642] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 19:37:07 (1787873827) [ 5554.572306] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5560.566413] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5582.910345] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5604.466104] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5605.227107] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 5610.487582] Lustre: lustre-OST0000-osc-ffff9e4d87243000: Connection to lustre-OST0000 (at 192.168.206.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5645.352379] Lustre: Unmounted lustre-client [ 5693.774536] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 19:39:57 (1787873997) [ 5728.537215] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5734.832075] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5755.743788] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5777.334088] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5778.115185] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 5783.535144] Lustre: lustre-OST0000-osc-ffff9e4d85318000: Connection to lustre-OST0000 (at 192.168.206.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5799.741460] Lustre: Unmounted lustre-client [ 5845.251164] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 19:42:29 (1787874149) [ 5924.390786] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5928.745577] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5949.780475] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6014.873478] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6020.179696] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6039.142857] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6066.853186] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 6113.332369] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6114.613426] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6115.989321] Lustre: Unmounted lustre-client [ 6146.349451] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 19:47:29 (1787874449) [ 6181.160692] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6186.715438] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6207.401447] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6239.671658] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6269.754121] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 6311.600967] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6313.335689] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6316.008914] Lustre: lustre-OST0001-osc-ffff9e4d87247800: Connection to lustre-OST0001 (at 192.168.206.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6324.762134] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6335.098295] Lustre: Unmounted lustre-client [ 6394.295359] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 19:51:37 (1787874697) [ 6424.832923] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6430.833285] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6452.466835] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6453.090368] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 6475.842886] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6477.453168] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6493.778832] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9e4d8824e000.ost_server_uuid 50 [ 6495.268803] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9e4d8824e000.ost_server_uuid in IDLE state after 0 sec [ 6498.772872] Lustre: Unmounted lustre-client [ 6499.038909] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6499.045315] Lustre: Skipped 3 previous similar messages [ 6499.171333] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 6500.525764] LustreError: 149692:0:(sec.c:403:import_sec_validate_get()) import 00000000395a3ed5 (NEW) with no sec [ 6570.202338] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 19:54:33 (1787874873) [ 6608.746851] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6614.996293] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6637.779966] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6662.108781] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6662.485491] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 6662.645485] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 6678.099893] Lustre: Unmounted lustre-client [ 6678.104531] Lustre: Skipped 1 previous similar message [ 6726.608533] Key type lgssc unregistered [ 6727.120553] LNet: 153129:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6727.139965] LNetError: 153129:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6727.175598] LNet: Removed LNI 192.168.206.36@tcp [ 6727.901658] Key type .llcrypt unregistered [ 6727.904251] Key type ._llcrypt unregistered [ 6746.398086] Key type ._llcrypt registered [ 6746.400035] Key type .llcrypt registered [ 6747.493206] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 19:57:31 (1787875051) [ 6749.060634] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6749.088386] alg: No test for adler32 (adler32-zlib) [ 6750.376129] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [ 6750.712385] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [ 6752.431175] Key type lgssc registered [ 6753.772028] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6809.550845] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6816.042691] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6836.964949] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6864.813151] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6865.380088] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 6866.564227] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 6873.621301] LustreError: lustre-MDT0001-mdc-ffff9e4d87efd000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 6873.645888] Lustre: lustre-MDT0001-mdc-ffff9e4d87efd000: Connection restored to 192.168.206.136@tcp (at 192.168.206.136@tcp) [ 6879.874960] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6881.453085] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6901.756886] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 6905.951168] Lustre: Unmounted lustre-client [ 6934.924757] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 20:00:38 (1787875238) [ 7057.389376] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7063.185641] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7084.145650] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7084.739413] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 7108.387372] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7109.990587] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7113.190215] Lustre: lustre-OST0001-osc-ffff9e4da0a6e000: Connection to lustre-OST0001 (at 192.168.206.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7121.422777] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7131.769974] Lustre: Unmounted lustre-client [ 7164.668566] Key type lgssc unregistered [ 7165.025937] LNet: 162767:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7165.038436] LNetError: 162767:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7165.075226] LNet: Removed LNI 192.168.206.36@tcp [ 7166.116909] Key type .llcrypt unregistered [ 7166.121604] Key type ._llcrypt unregistered [ 7196.990491] Key type ._llcrypt registered [ 7196.992593] Key type .llcrypt registered [ 7198.511452] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 20:05:01 (1787875501) [ 7200.757546] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7200.812055] alg: No test for adler32 (adler32-zlib) [ 7202.045177] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [ 7202.301138] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [ 7204.015390] Key type lgssc registered [ 7205.334441] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7255.810767] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7261.965955] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7283.197578] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7284.956465] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 7330.774583] Lustre: Unmounted lustre-client [ 7362.522851] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7363.130260] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 7399.020062] Lustre: Unmounted lustre-client [ 7424.448688] Key type lgssc unregistered [ 7424.700903] LNet: 167228:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7424.710795] LNetError: 167228:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7424.733652] LNet: Removed LNI 192.168.206.36@tcp [ 7425.242292] Key type .llcrypt unregistered [ 7425.244440] Key type ._llcrypt unregistered [ 7439.617716] Key type ._llcrypt registered [ 7439.622764] Key type .llcrypt registered [ 7440.980203] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 20:09:04 (1787875744) [ 7443.558057] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7443.713818] alg: No test for adler32 (adler32-zlib) [ 7444.906079] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [ 7445.232373] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [ 7446.967217] Key type lgssc registered [ 7448.144296] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7488.166784] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7492.507870] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7510.736665] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7516.727375] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 7535.264692] Lustre: Unmounted lustre-client [ 7567.840918] Key type lgssc unregistered [ 7568.153261] LNet: 170983:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7568.174324] LNetError: 170983:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7568.208963] LNet: Removed LNI 192.168.206.36@tcp [ 7569.387822] Key type .llcrypt unregistered [ 7569.390293] Key type ._llcrypt unregistered [ 7584.660356] Key type ._llcrypt registered [ 7584.669536] Key type .llcrypt registered [ 7584.990292] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7585.009877] alg: No test for adler32 (adler32-zlib) [ 7586.151604] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [ 7586.444088] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [ 7588.127233] Key type lgssc registered [ 7589.397513] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7652.206574] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7658.148789] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7687.622519] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7693.976282] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 7701.705351] Lustre: Unmounted lustre-client [ 7730.250980] Key type lgssc unregistered [ 7730.698631] LNet: 174054:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7730.737415] LNetError: 174054:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7730.771668] LNet: Removed LNI 192.168.206.36@tcp [ 7731.705084] Key type .llcrypt unregistered [ 7731.709707] Key type ._llcrypt unregistered [ 7749.724630] Key type ._llcrypt registered [ 7749.726575] Key type .llcrypt registered [ 7751.574808] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 20:14:14 (1787876054) [ 7754.235192] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7754.286642] alg: No test for adler32 (adler32-zlib) [ 7755.383517] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [ 7755.596922] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [ 7757.287321] Key type lgssc registered [ 7758.698367] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7808.248707] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7814.591625] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7835.546345] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7838.283544] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 7860.770928] Lustre: Unmounted lustre-client [ 7888.031933] Key type lgssc unregistered [ 7888.259922] LNet: 177811:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7888.265192] LNetError: 177811:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7888.281093] LNet: Removed LNI 192.168.206.36@tcp [ 7889.057641] Key type .llcrypt unregistered [ 7889.059662] Key type ._llcrypt unregistered [ 7900.232690] Key type ._llcrypt registered [ 7900.236679] Key type .llcrypt registered [ 7900.609216] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7900.619668] alg: No test for adler32 (adler32-zlib) [ 7901.701847] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [ 7901.945381] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [ 7903.655192] Key type lgssc registered [ 7904.873731] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7955.489551] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7961.590535] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7986.315392] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7988.464320] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 8000.944596] Lustre: Unmounted lustre-client [ 8037.019439] Key type lgssc unregistered [ 8037.314741] LNet: 180877:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8037.322750] LNetError: 180877:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8037.346439] LNet: Removed LNI 192.168.206.36@tcp [ 8038.234227] Key type .llcrypt unregistered [ 8038.236966] Key type ._llcrypt unregistered [ 8053.334469] Key type ._llcrypt registered [ 8053.343656] Key type .llcrypt registered [ 8054.864782] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 20:19:18 (1787876358) [ 8076.412731] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8076.455308] alg: No test for adler32 (adler32-zlib) [ 8077.625925] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [ 8077.844882] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [ 8079.543242] Key type lgssc registered [ 8081.147543] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8171.192845] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8177.462820] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8200.318803] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8244.802280] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 20:22:27 (1787876547) [ 8277.381651] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8283.562861] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8303.825274] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8305.689367] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 8311.071139] Lustre: Unmounted lustre-client [ 8339.493526] Key type lgssc unregistered [ 8339.696631] LNet: 187977:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8339.703576] LNetError: 187977:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8339.726943] LNet: Removed LNI 192.168.206.36@tcp [ 8340.452959] Key type .llcrypt unregistered [ 8340.454753] Key type ._llcrypt unregistered [ 8358.649684] Key type ._llcrypt registered [ 8358.652299] Key type .llcrypt registered [ 8360.289949] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 8362.573266] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 20:24:25 (1787876665) [ 8380.634167] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8380.693839] alg: No test for adler32 (adler32-zlib) [ 8381.792041] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [ 8382.011103] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [ 8383.639858] Key type lgssc registered [ 8384.955702] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8470.611910] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8476.283458] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8497.506798] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8519.602979] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8521.295820] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 8541.871135] Lustre: Unmounted lustre-client [ 8686.017686] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8691.732411] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8717.724928] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8769.098626] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 20:31:12 (1787877072) [ 8772.138695] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 8773.718545] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 20:31:17 (1787877077) [ 8874.160141] Key type lgssc unregistered [ 8874.606952] LNet: 199427:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8874.631238] LNetError: 199427:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8874.664028] LNet: Removed LNI 192.168.206.36@tcp [ 8875.979616] Key type .llcrypt unregistered [ 8875.984730] Key type ._llcrypt unregistered [ 8892.532682] Key type ._llcrypt registered [ 8892.534712] Key type .llcrypt registered [ 8894.127944] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 20:33:17 (1787877197) [ 8934.978562] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8935.000977] alg: No test for adler32 (adler32-zlib) [ 8936.140122] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [ 8936.388261] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [ 8938.127184] Key type lgssc registered [ 8939.289105] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8982.154729] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8987.577774] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9027.506629] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9057.944328] Key type lgssc unregistered [ 9058.204717] LNet: 203164:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9058.215980] LNetError: 203164:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9058.238670] LNet: Removed LNI 192.168.206.36@tcp [ 9058.802277] Key type .llcrypt unregistered [ 9058.806308] Key type ._llcrypt unregistered [ 9079.401656] Key type ._llcrypt registered [ 9079.408528] Key type .llcrypt registered [ 9081.653589] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 20:36:24 (1787877384) [ 9085.392905] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9085.406518] alg: No test for adler32 (adler32-zlib) [ 9086.744600] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [ 9087.143807] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [ 9088.983196] Key type lgssc registered [ 9090.331566] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9154.257500] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9160.437695] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9183.828347] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9191.010600] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 9201.158297] Lustre: lustre-MDT0000-mdc-ffff9e4d878be000: Connection to lustre-MDT0000 (at 192.168.206.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9221.614530] Lustre: lustre-MDT0001-mdc-ffff9e4d878be000: Connection to lustre-MDT0001 (at 192.168.206.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9232.671389] Lustre: 204160:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787877521/real 1787877521] req@000000005faa5007 x1874725324277120/t0(0) o400->MGC192.168.206.136@tcp@192.168.206.136@tcp:26/25 lens 224/224 e 0 to 1 dl 1787877537 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9232.723067] LustreError: MGC192.168.206.136@tcp: Connection to MGS (at 192.168.206.136@tcp) was lost; in progress operations using this service will fail [ 9242.984076] Lustre: Evicted from MGS (at 192.168.206.136@tcp) after server handle changed from 0xecbaea7d74bc1a98 to 0xecbaea7d74bc3938 [ 9243.001210] Lustre: MGC192.168.206.136@tcp: Connection restored to 192.168.206.136@tcp (at 192.168.206.136@tcp) [ 9261.552203] LustreError: lustre-MDT0000-mdc-ffff9e4d878be000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 9261.570826] Lustre: lustre-MDT0000-mdc-ffff9e4d878be000: Connection restored to 192.168.206.136@tcp (at 192.168.206.136@tcp) [ 9266.952498] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9268.903481] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9274.956623] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9276.616972] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9280.368175] Lustre: Unmounted lustre-client [ 9326.533648] Key type lgssc unregistered [ 9326.850620] LNet: 208122:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9326.856388] LNetError: 208122:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9326.882646] LNet: Removed LNI 192.168.206.36@tcp [ 9327.834137] Key type .llcrypt unregistered [ 9327.836941] Key type ._llcrypt unregistered [ 9346.955583] Key type ._llcrypt registered [ 9346.958277] Key type .llcrypt registered [ 9348.360928] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 20:40:51 (1787877651) [ 9351.302561] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9351.541061] alg: No test for adler32 (adler32-zlib) [ 9352.731600] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [ 9353.030511] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [ 9354.743246] Key type lgssc registered [ 9355.980608] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9415.888858] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9422.671245] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9484.451534] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9489.286163] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9526.453571] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9555.636961] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9594.320939] Key type lgssc unregistered [ 9594.734977] LNet: 213425:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9594.743833] LNetError: 213425:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9594.767356] LNet: Removed LNI 192.168.206.36@tcp [ 9595.378186] Key type .llcrypt unregistered [ 9595.379630] Key type ._llcrypt unregistered [ 9643.638532] Key type ._llcrypt registered [ 9643.641834] Key type .llcrypt registered [ 9645.234380] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 20:45:48 (1787877948) [ 9681.695953] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9681.734324] alg: No test for adler32 (adler32-zlib) [ 9682.789321] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [ 9683.088291] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [ 9684.848272] Key type lgssc registered [ 9686.332088] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9771.634459] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9778.448038] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9802.536739] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9845.330414] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 20:49:08 (1787878148) [ 9853.211510] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 20:49:16 (1787878156) [ 9888.860622] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9895.181946] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9916.324912] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9918.032194] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [ 9923.318689] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [ 9924.943920] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 9926.655508] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 9928.216036] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [ 9933.289498] Lustre: lustre-MDT0000-mdc-ffff9e4d8470c800: Connection to lustre-MDT0000 (at 192.168.206.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9949.663264] Lustre: 215321:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787878238/real 1787878238] req@000000008754b03d x1874725949154944/t0(0) o400->MGC192.168.206.136@tcp@192.168.206.136@tcp:26/25 lens 224/224 e 0 to 1 dl 1787878254 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9949.698931] LustreError: MGC192.168.206.136@tcp: Connection to MGS (at 192.168.206.136@tcp) was lost; in progress operations using this service will fail [ 9959.936432] Lustre: Evicted from MGS (at 192.168.206.136@tcp) after server handle changed from 0xc62d3a0a30f44e20 to 0xc62d3a0a30f4508f [ 9959.956239] Lustre: MGC192.168.206.136@tcp: Connection restored to 192.168.206.136@tcp (at 192.168.206.136@tcp) [ 9965.274285] Lustre: lustre-MDT0000-mdc-ffff9e4d8470c800: Connection restored to 192.168.206.136@tcp (at 192.168.206.136@tcp) [ 9975.186133] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9977.031767] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9980.769041] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [ 9985.526708] Lustre: lustre-MDT0000-mdc-ffff9e4d8470c800: Connection to lustre-MDT0000 (at 192.168.206.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [10001.890458] Lustre: 215322:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787878290/real 1787878290] req@00000000b94412d4 x1874725949164288/t0(0) o400->MGC192.168.206.136@tcp@192.168.206.136@tcp:26/25 lens 224/224 e 0 to 1 dl 1787878306 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10001.945232] LustreError: MGC192.168.206.136@tcp: Connection to MGS (at 192.168.206.136@tcp) was lost; in progress operations using this service will fail [10011.128147] Lustre: Evicted from MGS (at 192.168.206.136@tcp) after server handle changed from 0xc62d3a0a30f4508f to 0xc62d3a0a30f452a3 [10011.146625] Lustre: MGC192.168.206.136@tcp: Connection restored to 192.168.206.136@tcp (at 192.168.206.136@tcp) [10017.590457] Lustre: lustre-MDT0000-mdc-ffff9e4d8470c800: Connection restored to 192.168.206.136@tcp (at 192.168.206.136@tcp) [10030.727969] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10032.683209] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10037.053766] Lustre: Unmounted lustre-client [10109.997567] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10114.992780] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10137.377057] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10138.147216] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [10141.342243] Lustre: Unmounted lustre-client [10179.752938] Key type lgssc unregistered [10180.261527] LNet: 225918:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10180.293695] LNetError: 225918:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10180.330059] LNet: Removed LNI 192.168.206.36@tcp [10181.424563] Key type .llcrypt unregistered [10181.428762] Key type ._llcrypt unregistered [10198.274643] Key type ._llcrypt registered [10198.276745] Key type .llcrypt registered [10199.598389] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 20:55:03 (1787878503) [10203.602629] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10203.715199] alg: No test for adler32 (adler32-zlib) [10205.060413] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [10205.466278] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [10207.199230] Key type lgssc registered [10208.199982] Lustre: Echo OBD driver; http://www.lustre.org/ [10271.396648] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10279.519737] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10304.265576] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10311.263959] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [10318.134174] Lustre: Unmounted lustre-client [10388.099680] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10394.740249] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10420.846828] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10432.088183] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [10443.219382] Lustre: Unmounted lustre-client [10512.192071] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10518.720672] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10544.029746] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10545.136489] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [10548.172632] Lustre: Unmounted lustre-client [10578.334578] Key type lgssc unregistered [10578.756948] LNet: 232989:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10578.771603] LNetError: 232989:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10578.808228] LNet: Removed LNI 192.168.206.36@tcp [10579.681769] Key type .llcrypt unregistered [10579.683690] Key type ._llcrypt unregistered [10598.096563] Key type ._llcrypt registered [10598.100888] Key type .llcrypt registered [10599.359377] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 21:01:42 (1787878902) [10603.121851] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10603.344778] alg: No test for adler32 (adler32-zlib) [10604.527609] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [10604.980895] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [10606.751691] Key type lgssc registered [10608.021201] Lustre: Echo OBD driver; http://www.lustre.org/ [10651.191484] Key type lgssc unregistered [10651.412854] LNet: 235317:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10651.428243] LNetError: 235317:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10651.450497] LNet: Removed LNI 192.168.206.36@tcp [10652.105848] Key type .llcrypt unregistered [10652.107499] Key type ._llcrypt unregistered [10678.935614] Key type ._llcrypt registered [10678.946024] Key type .llcrypt registered [10679.165385] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10679.184204] alg: No test for adler32 (adler32-zlib) [10680.347307] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [10680.690639] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [10682.479188] Key type lgssc registered [10683.514223] Lustre: Echo OBD driver; http://www.lustre.org/ [10767.514494] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10772.954836] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10795.257152] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10844.947922] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [10847.022972] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 21:05:50 (1787879150) [10882.093835] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10888.893155] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10911.269078] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10934.524721] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10936.481067] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [10941.954309] Lustre: lustre-OST0001-osc-ffff9e4d87240800: Connection to lustre-OST0001 (at 192.168.206.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [10949.602238] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [10959.986268] Lustre: Unmounted lustre-client [11005.866777] Key type lgssc unregistered [11006.094529] LNet: 242436:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11006.122901] LNetError: 242436:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11006.152543] LNet: Removed LNI 192.168.206.36@tcp [11007.038376] Key type .llcrypt unregistered [11007.041791] Key type ._llcrypt unregistered [11037.274176] Key type ._llcrypt registered [11037.278490] Key type .llcrypt registered [11038.827882] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 21:09:02 (1787879342) [11052.077253] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11052.131117] alg: No test for adler32 (adler32-zlib) [11053.223350] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [11053.558294] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [11055.263477] Key type lgssc registered [11056.829581] Lustre: Echo OBD driver; http://www.lustre.org/ [11109.737701] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11116.182605] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11139.341435] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 21:10:42 (1787879442) [11171.498413] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11177.111446] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11197.607942] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11200.629835] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [11212.833384] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [11221.551848] Lustre: Unmounted lustre-client [11323.482298] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11329.982214] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11390.869344] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11391.531719] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [11395.198552] Lustre: Unmounted lustre-client [11434.670458] Key type lgssc unregistered [11435.026969] LNet: 250182:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11435.044839] LNetError: 250182:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11435.083918] LNet: Removed LNI 192.168.206.36@tcp [11436.017457] Key type .llcrypt unregistered [11436.026062] Key type ._llcrypt unregistered [11466.569895] Key type ._llcrypt registered [11466.574019] Key type .llcrypt registered [11466.953828] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11466.970217] alg: No test for adler32 (adler32-zlib) [11468.085248] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [11468.373437] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [11470.087182] Key type lgssc registered [11471.505273] Lustre: Echo OBD driver; http://www.lustre.org/ [11549.842095] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11555.386993] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11575.841690] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11625.795936] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [11628.141855] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 21:18:51 (1787879931) [11660.952822] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11666.814800] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11691.686887] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11696.083220] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [11699.405467] Lustre: Unmounted lustre-client [11734.755588] Key type lgssc unregistered [11735.033202] LNet: 256826:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11735.039239] LNetError: 256826:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11735.067569] LNet: Removed LNI 192.168.206.36@tcp [11735.870315] Key type .llcrypt unregistered [11735.871976] Key type ._llcrypt unregistered [11752.227686] Key type ._llcrypt registered [11752.229768] Key type .llcrypt registered [11753.434744] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [11755.110454] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 21:20:58 (1787880058) [11764.008403] Key type .llcrypt unregistered [11764.010637] Key type ._llcrypt unregistered [11772.517128] Key type ._llcrypt registered [11772.519333] Key type .llcrypt registered [11772.971423] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11772.993979] alg: No test for adler32 (adler32-zlib) [11774.228543] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [11774.584413] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [11776.281525] Key type lgssc registered [11777.753495] Lustre: Echo OBD driver; http://www.lustre.org/ [11826.799160] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11841.385669] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [11844.097515] Lustre: Unmounted lustre-client [11869.422668] Key type lgssc unregistered [11869.669524] LNet: 260303:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11869.676628] LNetError: 260303:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11869.689861] LNet: Removed LNI 192.168.206.36@tcp [11870.237345] Key type .llcrypt unregistered [11870.240339] Key type ._llcrypt unregistered [11884.247842] Key type ._llcrypt registered [11884.255128] Key type .llcrypt registered [11885.675869] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 21:23:09 (1787880189) [11887.998436] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11888.051127] alg: No test for adler32 (adler32-zlib) [11889.241612] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [11889.607266] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [11891.296462] Key type lgssc registered [11892.642777] Lustre: Echo OBD driver; http://www.lustre.org/ [11975.478099] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12005.033177] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12010.611254] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12013.315672] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [12016.035729] Lustre: Unmounted lustre-client [12032.842351] Key type lgssc unregistered [12033.116703] LNet: 263845:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12033.126479] LNetError: 263845:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12033.158922] LNet: Removed LNI 192.168.206.36@tcp [12034.071058] Key type .llcrypt unregistered [12034.072483] Key type ._llcrypt unregistered [12051.941773] Key type ._llcrypt registered [12051.944267] Key type .llcrypt registered [12053.746350] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 21:25:56 (1787880356) [12056.854030] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12056.899789] alg: No test for adler32 (adler32-zlib) [12058.014480] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [12058.331250] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [12060.112105] Key type lgssc registered [12061.435495] Lustre: Echo OBD driver; http://www.lustre.org/ [12108.191126] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12113.593590] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12132.720551] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12134.312244] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [12139.496397] Lustre: lustre-MDT0000-mdc-ffff9e4da0a6e800: Connection to lustre-MDT0000 (at 192.168.206.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [12145.502068] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12145.543316] LustreError: 267001:0:(file.c:6184:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [12146.087493] LustreError: 267020:0:(file.c:6184:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [12146.097413] LustreError: 267020:0:(file.c:6184:ll_inode_revalidate_fini()) Skipped 5 previous similar messages [12146.145219] LustreError: MGC192.168.206.136@tcp: Connection to MGS (at 192.168.206.136@tcp) was lost; in progress operations using this service will fail [12146.248576] Lustre: Unmounted lustre-client [12171.315892] Key type lgssc unregistered [12171.822539] LNet: 267418:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12171.836939] LNetError: 267418:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12171.867880] LNet: Removed LNI 192.168.206.36@tcp [12173.011520] Key type .llcrypt unregistered [12173.014612] Key type ._llcrypt unregistered [12188.374916] Key type ._llcrypt registered [12188.377343] Key type .llcrypt registered [12189.907912] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 21:28:13 (1787880493) [12192.151034] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12192.222700] alg: No test for adler32 (adler32-zlib) [12193.385371] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [12193.736520] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [12195.521298] Key type lgssc registered [12196.928971] Lustre: Echo OBD driver; http://www.lustre.org/ [12248.607839] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12254.724476] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12283.266624] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12286.201431] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [12291.590645] Lustre: lustre-MDT0001-mdc-ffff9e4da08ff800: Connection to lustre-MDT0001 (at 192.168.206.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [12297.290681] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [12300.068758] Lustre: Unmounted lustre-client [12322.549957] Key type lgssc unregistered [12322.919659] LNet: 271021:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12322.926266] LNetError: 271021:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12322.955946] LNet: Removed LNI 192.168.206.36@tcp [12323.921777] Key type .llcrypt unregistered [12323.925156] Key type ._llcrypt unregistered [12341.091905] Key type ._llcrypt registered [12341.094617] Key type .llcrypt registered [12342.546453] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 21:30:45 (1787880645) [12361.924818] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12362.022760] alg: No test for adler32 (adler32-zlib) [12363.173862] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [12363.558033] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [12365.369216] Key type lgssc registered [12367.019470] Lustre: Echo OBD driver; http://www.lustre.org/ [12476.435755] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12479.211577] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [12519.913409] Lustre: lustre-MDT0001-mdc-ffff9e4d90a13000: Connection to lustre-MDT0001 (at 192.168.206.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [12525.684126] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12536.066767] Lustre: Unmounted lustre-client [12562.842845] Key type lgssc unregistered [12563.178058] LNet: 276814:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12563.185036] LNetError: 276814:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12563.206303] LNet: Removed LNI 192.168.206.36@tcp [12564.058106] Key type .llcrypt unregistered [12564.062784] Key type ._llcrypt unregistered [12580.907965] Key type ._llcrypt registered [12580.909579] Key type .llcrypt registered [12582.178912] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 21:34:45 (1787880885) [12583.742480] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [12585.821155] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 21:34:49 (1787880889) [12587.862072] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [12589.521385] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 21:34:53 (1787880893) [12591.081783] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [12592.793403] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 21:34:56 (1787880896) [12594.511631] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [12596.378426] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 21:34:59 (1787880899) [12598.498533] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [12600.457936] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 21:35:03 (1787880903) [12609.831359] Key type .llcrypt unregistered [12609.837426] Key type ._llcrypt unregistered [12616.963890] Key type ._llcrypt registered [12616.966778] Key type .llcrypt registered [12617.442539] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12617.463259] alg: No test for adler32 (adler32-zlib) [12618.583930] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [12618.868351] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [12620.591384] Key type lgssc registered [12621.659694] Lustre: Echo OBD driver; http://www.lustre.org/ [12701.862749] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12708.249104] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12731.585388] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12733.163283] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [12735.462732] Lustre: Unmounted lustre-client [12771.123710] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 21:37:54 (1787881074) [12805.999831] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12811.054282] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12845.472893] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12851.300586] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [12854.645737] Lustre: Unmounted lustre-client [12906.655158] LNet: 1 local NIs in recovery (showing 1): 192.168.206.36@tcp [12906.950838] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 21:40:10 (1787881210) [13555.931838] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13561.666594] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13597.844245] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13694.170953] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 21:53:17 (1787881997) [13705.870308] Key type lgssc unregistered [13706.140611] LNet: 290346:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13706.147909] LNetError: 290346:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13706.167905] LNet: Removed LNI 192.168.206.36@tcp [13706.719343] Key type .llcrypt unregistered [13706.721289] Key type ._llcrypt unregistered [13846.741184] Key type ._llcrypt registered [13846.751993] Key type .llcrypt registered [13847.431514] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13847.455188] alg: No test for adler32 (adler32-zlib) [13848.782936] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [13849.161120] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [13850.935205] Key type lgssc registered [13852.648566] Lustre: Echo OBD driver; http://www.lustre.org/ [13946.044094] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13952.993329] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13976.433828] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14012.164194] Key type lgssc unregistered [14012.529228] LNet: 294047:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14012.534986] LNetError: 294047:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14012.558705] LNet: Removed LNI 192.168.206.36@tcp [14013.320403] Key type .llcrypt unregistered [14013.323727] Key type ._llcrypt unregistered [14054.399763] Key type ._llcrypt registered [14054.402389] Key type .llcrypt registered [14055.841666] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 21:59:19 (1787882359) [14081.559783] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14081.631597] alg: No test for adler32 (adler32-zlib) [14082.723767] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [14082.954271] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [14084.655163] Key type lgssc registered [14086.076481] Lustre: Echo OBD driver; http://www.lustre.org/ [14142.522967] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 22:00:45 (1787882445) [14156.157854] Key type lgssc unregistered [14156.647250] LNet: 297614:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14156.662178] LNetError: 297614:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14156.700684] LNet: Removed LNI 192.168.206.36@tcp [14157.565189] Key type .llcrypt unregistered [14157.566563] Key type ._llcrypt unregistered [14169.464769] Key type ._llcrypt registered [14169.521748] Key type .llcrypt registered [14169.961455] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14169.985740] alg: No test for adler32 (adler32-zlib) [14171.036946] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [14171.534301] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [14173.399917] Key type lgssc registered [14174.791781] Lustre: Echo OBD driver; http://www.lustre.org/ [14237.459500] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14243.184766] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14268.484808] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14542.908656] LNet: Added LNI 192.168.206.36@tcp50 [8/256/0/180] [14545.023809] LNet: Removed LNI 192.168.206.36@tcp [14547.451760] Lustre: 302034:0:(ldlm_lib.c:599:client_obd_setup()) lustre-MDT0000-mdc-ffff9e4d87a8f800: config has no valid NIDs, force dynamic NIDs [14548.672654] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [14548.855191] Lustre: Unmounted lustre-client [14551.918829] Key type lgssc unregistered [14552.330206] LNet: 302296:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14552.336725] LNetError: 302296:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14553.383466] LNet: Removed LNI 192.168.206.36@tcp50 [14553.931412] Key type .llcrypt unregistered [14553.938720] Key type ._llcrypt unregistered [14572.673690] Key type ._llcrypt registered [14572.679392] Key type .llcrypt registered [14573.377272] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14573.404311] alg: No test for adler32 (adler32-zlib) [14574.704872] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [14575.166390] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [14576.969059] Key type lgssc registered [14578.442306] Lustre: Echo OBD driver; http://www.lustre.org/ [14641.450410] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14648.110730] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14656.154150] LNet: Added LNI 192.168.206.36@tcp50 [8/256/0/180] [14656.959236] LNet: Removed LNI 192.168.206.36@tcp [14658.149535] Lustre: 304986:0:(ldlm_lib.c:599:client_obd_setup()) lustre-MDT0000-mdc-ffff9e4d879ce800: config has no valid NIDs, force dynamic NIDs [14659.433485] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [14660.046194] Lustre: Unmounted lustre-client [14695.672641] Key type lgssc unregistered [14696.129597] LNet: 305861:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14696.141026] LNetError: 305861:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14696.165645] LNet: Removed LNI 192.168.206.36@tcp50 [14696.868768] Key type .llcrypt unregistered [14696.871358] Key type ._llcrypt unregistered [14708.645688] Key type ._llcrypt registered [14708.648201] Key type .llcrypt registered [14710.022664] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 22:10:13 (1787883013) [14728.435397] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14728.457494] alg: No test for adler32 (adler32-zlib) [14729.635756] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [14729.927116] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [14731.591192] Key type lgssc registered [14732.510980] Lustre: Echo OBD driver; http://www.lustre.org/ [14820.570339] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14826.724362] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14853.387386] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14896.148652] Key type lgssc unregistered [14896.530092] LNet: 310610:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14896.547586] LNetError: 310610:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14896.564324] LNet: Removed LNI 192.168.206.36@tcp [14897.629448] Key type .llcrypt unregistered [14897.632944] Key type ._llcrypt unregistered [14910.538446] Key type ._llcrypt registered [14910.541114] Key type .llcrypt registered [14911.907368] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 22:13:35 (1787883215) [14914.469911] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14914.484953] alg: No test for adler32 (adler32-zlib) [14915.747449] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [14915.964690] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [14917.639426] Key type lgssc registered [14919.001643] Lustre: Echo OBD driver; http://www.lustre.org/ [14967.384936] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14973.463513] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14995.161617] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14997.842266] Lustre: Mounted lustre-client - version 2.17.57_99_gb2219ab [15060.719217] Lustre: Mounted fs15246-client - version 2.17.57_99_gb2219ab [15074.505634] Lustre: Unmounted fs15246-client [15101.850215] Lustre: Unmounted lustre-client [15130.015590] Key type lgssc unregistered [15130.432899] LNet: 315472:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15130.442226] LNetError: 315472:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15130.476788] LNet: Removed LNI 192.168.206.36@tcp [15131.339247] Key type .llcrypt unregistered [15131.351096] Key type ._llcrypt unregistered [15144.409668] Key type ._llcrypt registered [15144.413574] Key type .llcrypt registered [15146.173953] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 22:17:29 (1787883449) [15148.161473] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15148.238069] alg: No test for adler32 (adler32-zlib) [15149.393786] Lustre: Lustre: Build Version: 2.17.57_99_gb2219ab [15149.683833] LNet: Added LNI 192.168.206.36@tcp [8/256/0/180] [15151.399306] Key type lgssc registered [15153.060426] Lustre: Echo OBD driver; http://www.lustre.org/ [15303.408197] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15309.354528] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15335.205718] Lustre: DEBUG MARKER: oleg636-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15444.411491] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 15148 sec ========= 22:22:27 (1787883747) [15446.149707] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 22:22:29 (1787883749) === [15450.884835] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 22:22:33 (1787883753) === [15491.229677] Key type lgssc unregistered [15491.557624] LNet: 324744:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15491.571229] LNetError: 324744:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15491.593973] LNet: Removed LNI 192.168.206.36@tcp [15492.573025] Key type .llcrypt unregistered [15492.576937] Key type ._llcrypt unregistered