[ 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 413488039 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.002377] x2apic enabled [ 0.003007] Switched APIC routing to physical x2apic. [ 0.004017] kvm-guest: setup PV IPIs [ 0.007577] ..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.008021] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009013] pid_max: default: 32768 minimum: 301 [ 0.011068] LSM: Security Framework initializing [ 0.012056] Yama: becoming mindful. [ 0.013055] SELinux: Initializing. [ 0.014051] *** VALIDATE selinux *** [ 0.022169] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.027306] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.028137] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029103] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030125] *** VALIDATE tmpfs *** [ 0.031467] *** VALIDATE proc *** [ 0.032272] *** VALIDATE cgroup *** [ 0.033011] *** VALIDATE cgroup2 *** [ 0.035277] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.036158] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.037011] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.038029] Spectre V2 : User space: Vulnerable [ 0.039010] Speculative Store Bypass: Vulnerable [ 0.042184] debug: unmapping init [mem 0xffffffffb5659000-0xffffffffb5660fff] [ 0.044194] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.045720] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.046025] ... version: 2 [ 0.047015] ... bit width: 48 [ 0.048012] ... generic registers: 4 [ 0.049011] ... value mask: 0000ffffffffffff [ 0.050019] ... max period: 00007fffffffffff [ 0.051015] ... fixed-purpose events: 3 [ 0.052013] ... event mask: 000000070000000f [ 0.053279] rcu: Hierarchical SRCU implementation. [ 0.055443] smp: Bringing up secondary CPUs ... [ 0.056606] x86: Booting SMP configuration: [ 0.057032] .... node #0, CPUs: #1 #2 #3 [ 0.061346] smp: Brought up 1 node, 4 CPUs [ 0.063018] smpboot: Max logical packages: 1 [ 0.064021] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.228000] node 0 deferred pages initialised in 161ms [ 0.233128] devtmpfs: initialized [ 0.234408] x86/mm: Memory block size: 128MB [ 0.238949] gcov: version magic: 0x41383552 [ 0.241161] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.244103] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.247269] pinctrl core: initialized pinctrl subsystem [ 0.249140] [ 0.249624] ************************************************************* [ 0.252012] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.254018] ** ** [ 0.256014] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.259013] ** ** [ 0.262013] ** This means that this kernel is built to expose internal ** [ 0.264013] ** IOMMU data structures, which may compromise security on ** [ 0.267015] ** your system. ** [ 0.270012] ** ** [ 0.272012] ** If you see this message and you are not debugging the ** [ 0.275013] ** kernel, report this immediately to your vendor! ** [ 0.278013] ** ** [ 0.280012] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.283014] ************************************************************* [ 0.286711] NET: Registered protocol family 16 [ 0.288407] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.291057] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.293057] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.299026] cpuidle: using governor menu [ 0.300650] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.303375] PCI: Using configuration type 1 for base access [ 0.305246] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.314053] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.316121] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.320036] cryptd: max_cpu_qlen set to 1000 [ 0.323162] ACPI: Added _OSI(Module Device) [ 0.325014] ACPI: Added _OSI(Processor Device) [ 0.326012] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.327008] ACPI: Added _OSI(Processor Aggregator Device) [ 0.332690] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.338568] ACPI: Interpreter enabled [ 0.340063] ACPI: PM: (supports S0 S3 S4 S5) [ 0.341015] ACPI: Using IOAPIC for interrupt routing [ 0.343107] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.347492] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.358320] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.361047] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.364024] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.368078] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.373000] acpiphp: Slot [2] registered [ 0.374151] acpiphp: Slot [5] registered [ 0.376180] acpiphp: Slot [6] registered [ 0.377112] acpiphp: Slot [3] registered [ 0.379102] acpiphp: Slot [4] registered [ 0.380178] acpiphp: Slot [7] registered [ 0.382103] acpiphp: Slot [8] registered [ 0.384098] acpiphp: Slot [9] registered [ 0.385169] acpiphp: Slot [10] registered [ 0.387113] acpiphp: Slot [11] registered [ 0.389107] acpiphp: Slot [12] registered [ 0.390121] acpiphp: Slot [13] registered [ 0.392165] acpiphp: Slot [14] registered [ 0.393097] acpiphp: Slot [15] registered [ 0.395134] acpiphp: Slot [16] registered [ 0.397107] acpiphp: Slot [17] registered [ 0.398173] acpiphp: Slot [18] registered [ 0.400206] acpiphp: Slot [19] registered [ 0.402109] acpiphp: Slot [20] registered [ 0.404122] acpiphp: Slot [21] registered [ 0.406086] acpiphp: Slot [22] registered [ 0.407106] acpiphp: Slot [23] registered [ 0.409099] acpiphp: Slot [24] registered [ 0.410164] acpiphp: Slot [25] registered [ 0.412100] acpiphp: Slot [26] registered [ 0.413079] acpiphp: Slot [27] registered [ 0.414072] acpiphp: Slot [28] registered [ 0.416086] acpiphp: Slot [29] registered [ 0.419181] acpiphp: Slot [30] registered [ 0.420179] acpiphp: Slot [31] registered [ 0.422064] PCI host bridge to bus 0000:00 [ 0.423075] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.426026] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.428021] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.431025] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.434029] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.437047] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.439176] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.443060] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.446441] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.453535] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.458102] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.461020] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.463016] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.466018] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.469192] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.473076] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.475042] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.478856] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.482965] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.492016] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.498014] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.504238] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.509016] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.515016] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.540016] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.552439] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.564015] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.576014] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.603016] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.618896] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.621391] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.623347] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.626412] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.629247] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.634137] iommu: Default domain type: Passthrough [ 0.636423] SCSI subsystem initialized [ 0.638161] ACPI: bus type USB registered [ 0.639123] usbcore: registered new interface driver usbfs [ 0.642074] usbcore: registered new interface driver hub [ 0.644083] usbcore: registered new device driver usb [ 0.646166] pps_core: LinuxPPS API ver. 1 registered [ 0.648012] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.651058] PTP clock support registered [ 0.653231] EDAC MC: Ver: 3.0.0 [ 0.655031] PCI: Using ACPI for IRQ routing [ 0.656935] NetLabel: Initializing [ 0.657015] NetLabel: domain hash size = 128 [ 0.658018] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.659233] NetLabel: unlabeled traffic allowed by default [ 0.662039] vgaarb: loaded [ 0.663272] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.665021] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.671236] clocksource: Switched to clocksource kvm-clock [ 0.766918] VFS: Disk quotas dquot_6.6.0 [ 0.768562] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.771384] *** VALIDATE ramfs *** [ 0.772775] *** VALIDATE hugetlbfs *** [ 0.774366] pnp: PnP ACPI init [ 0.776759] pnp: PnP ACPI: found 6 devices [ 0.809396] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.813405] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.815578] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.817520] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.819829] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.822075] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.824354] NET: Registered protocol family 2 [ 0.826426] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.831051] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.834545] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.839869] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.843552] TCP: Hash tables configured (established 65536 bind 65536) [ 0.847076] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.850717] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.854022] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.857518] NET: Registered protocol family 1 [ 0.860606] RPC: Registered named UNIX socket transport module. [ 0.862749] RPC: Registered udp transport module. [ 0.864787] RPC: Registered tcp transport module. [ 0.866677] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.869430] NET: Registered protocol family 44 [ 0.871085] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.872752] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.874810] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.877188] PCI: CLS 0 bytes, default 64 [ 0.879479] Unpacking initramfs... [ 2.333348] debug: unmapping init [mem 0xffff8dc53cc64000-0xffff8dc53ffcffff] [ 2.340313] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.344064] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.349164] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.855905] Initialise system trusted keyrings [ 2.857958] Key type blacklist registered [ 2.860094] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.871230] zbud: loaded [ 2.880229] *** VALIDATE nfs *** [ 2.881748] *** VALIDATE nfs4 *** [ 2.885268] pstore: using deflate compression [ 2.895343] Platform Keyring initialized [ 2.988718] NET: Registered protocol family 38 [ 2.990809] Key type asymmetric registered [ 2.992638] Asymmetric key parser 'x509' registered [ 2.994933] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.999072] io scheduler mq-deadline registered [ 3.000893] io scheduler kyber registered [ 3.002674] io scheduler bfq registered [ 3.004721] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.006990] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.009831] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.013256] ACPI: Power Button [PWRF] [ 3.020663] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.027626] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.041348] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.070731] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.098197] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.102662] Non-volatile memory driver v1.3 [ 3.104273] Linux agpgart interface v0.103 [ 3.142874] virtio_blk virtio1: [vda] 146704 512-byte logical blocks (75.1 MB/71.6 MiB) [ 3.147798] vda: detected capacity change from 0 to 75112448 [ 3.162203] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.165754] vdb: detected capacity change from 0 to 1073741824 [ 3.172066] libphy: Fixed MDIO Bus: probed [ 3.177763] usbcore: registered new interface driver usbserial_generic [ 3.180593] usbserial: USB Serial support registered for generic [ 3.183232] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.189348] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.191371] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.194181] mousedev: PS/2 mouse device common for all mice [ 3.197317] rtc_cmos 00:05: RTC can wake from S4 [ 3.200347] rtc_cmos 00:05: registered as rtc0 [ 3.202961] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.203162] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.210487] intel_pstate: CPU model not supported [ 3.215428] hid: raw HID events driver (C) Jiri Kosina [ 3.216025] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.217388] usbcore: registered new interface driver usbhid [ 3.223698] usbhid: USB HID core driver [ 3.225942] drop_monitor: Initializing network drop monitor service [ 3.226075] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.228618] Initializing XFRM netlink socket [ 3.234696] NET: Registered protocol family 10 [ 3.237877] Segment Routing with IPv6 [ 3.239664] NET: Registered protocol family 17 [ 3.241623] mpls_gso: MPLS GSO support [ 3.246662] RAS: Correctable Errors collector initialized. [ 3.249090] AVX version of gcm_enc/dec engaged. [ 3.250590] AES CTR mode by8 optimization enabled [ 3.324717] sched_clock: Marking stable (3324698890, 0)->(4261318035, -936619145) [ 3.329724] registered taskstats version 1 [ 3.332944] Loading compiled-in X.509 certificates [ 3.335108] zswap: loaded using pool lzo/zbud [ 3.359743] Key type big_key registered [ 3.373720] Key type encrypted registered [ 3.375969] ima: No TPM chip found, activating TPM-bypass! [ 3.378133] ima: Allocated hash algorithm: sha1 [ 3.379894] ima: No architecture policies found [ 3.381870] evm: Initialising EVM extended attributes: [ 3.384154] evm: security.selinux [ 3.385658] evm: security.ima [ 3.386893] evm: security.capability [ 3.388500] evm: HMAC attrs: 0x1 [ 3.391074] rtc_cmos 00:05: setting system clock to 2026-09-19 01:25:09 UTC (1789781109) [ 3.397443] debug: unmapping init [mem 0xffffffffb6603000-0xffffffffb67fffff] [ 3.400986] debug: unmapping init [mem 0xffffffffb5382000-0xffffffffb5658fff] [ 3.410085] Write protecting the kernel read-only data: 28672k [ 3.413472] debug: unmapping init [mem 0xffffffffb3a03000-0xffffffffb3bfffff] [ 3.416027] debug: unmapping init [mem 0xffffffffb4314000-0xffffffffb43fffff] [ 3.448866] 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.458417] systemd[1]: Detected virtualization kvm. [ 3.460203] systemd[1]: Detected architecture x86-64. [ 3.462164] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.494373] systemd[1]: No hostname configured. [ 3.495879] systemd[1]: Set hostname to . [ 3.497535] random: systemd: uninitialized urandom read (16 bytes read) [ 3.499741] systemd[1]: Initializing machine ID from random generator. [ 3.546402] random: ln: uninitialized urandom read (6 bytes read) [ 3.640048] random: systemd: uninitialized urandom read (16 bytes read) [ 3.643075] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 3.647827] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 3.652116] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ OK ] Reached target Local File Systems. [ OK ] Reached target Swap. [ OK ] Reached target Initrd Root Device. [ OK ] Listening on udev Control Socket. [ OK ] Listening on Journal Socket. Starting Create Volatile Files and Directories... Starting Create list of required st…ce nodes for the current kernel... Starting Setup Virtual Console... [ OK ] Started Memstrack Anylazing Service. Starting Apply Kernel Variables... [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Sockets. Starting Journal Service... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.279487] device-mapper: uevent: version 1.0.3 [ 4.281754] 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... [ 4.939455] virtio_net virtio0 ens2: renamed from eth0 [ 5.021432] scsi host0: ata_piix [ 5.038344] scsi host1: ata_piix [ 5.039927] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.042310] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.427088] dracut-initqueue[587]: RTNETLINK answers: File exists [ 9.893651] random: crng init done [ 9.895101] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 10.097055] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped target Timers. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Apply Kernel Variables. [ 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.251556] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.502924] SELinux: Disabled at runtime. [ 11.560528] 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.571073] systemd[1]: Detected virtualization kvm. [ 11.573360] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.048292] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.052266] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.057671] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.064441] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.068686] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.075774] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.084084] systemd[1]: Listening on RPCbind Server Activation Socket. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Started Forward Password Requests to Wall Directory Watch. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Created slice system-serial\x2dgetty.slice. Activating swap /dev/disk/by-label/SWAP... Mounting Kernel Debug File System... [ OK ] Listening on initctl Compatibility Named Pipe. Starting Apply Kernel Variables... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ 12.147587] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Mounting Huge Pages File System... [ OK ] Listening on Process Core Dump Socket. [ OK ] Listening on udev Kernel Socket. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Created slice User and Session Slice. Mounting POSIX Message Queue File System... Starting Remount Root and Kernel File Systems... [ OK ] Reached target RPC Port Mapper. [ OK ] Created slice system-getty.slice. [ OK ] Reached target Slices. [ OK ] Reached target rpc_pipefs.target. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Started Journal Service. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted POSIX Message Queue File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Flush Journal to Persistent Storage. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Mounted /mnt. [ 12.526578] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Coldplug all Devices. [ OK ] Started udev Kernel Device Manager. [ 12.859045] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 12.871446] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 12.934783] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 12.950975] EDAC sbridge: Ver: 1.1.2 [ 14.178752] Key type dns_resolver registered [ 14.482081] NFS: Registering the id_resolver key type [ 14.486075] Key type id_resolver registered [ 14.487346] 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 Daily Cleanup of Temporary Directories. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Reached target Basic System. [ OK ] Started irqbalance daemon. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Restore /run/initramfs on shutdown... Starting Login Service... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Reached target sshd-keygen.target. [ 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 GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Network Manager Wait Online... [ 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... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg345-client login: [ 43.079005] hrtimer: interrupt took 4028153 ns [ 78.429410] libcfs: loading out-of-tree module taints kernel. [ 78.499847] Key type ._llcrypt registered [ 78.512810] Key type .llcrypt registered [ 79.168367] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 79.210407] alg: No test for adler32 (adler32-zlib) [ 81.028168] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [ 82.083736] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 83.864505] Key type lgssc registered [ 85.661550] Lustre: Echo OBD driver; http://www.lustre.org/ [ 253.270320] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 258.661839] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 271.456516] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing check_logdir /tmp/testlogs/ [ 277.505542] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing yml_node [ 279.009131] Lustre: lustre-OST0000-osc-ffff8dc588483000: disconnect after 24s idle [ 281.896855] Lustre: DEBUG MARKER: Client: 2.17.57.110 [ 285.072635] Lustre: DEBUG MARKER: MDS: 2.17.57.110 [ 287.974367] Lustre: DEBUG MARKER: OSS: 2.17.57.110 [ 290.113416] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Fri Sep 18 21:29:54 EDT 2026 [ 308.796914] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 310.758764] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 313.277518] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 323.619178] Lustre: Unmounted lustre-client [ 442.308908] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 448.956588] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 478.906628] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 524.374205] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 21:33:48 (1789781628) [ 526.782848] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 528.816042] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 21:33:53 (1789781633) [ 560.992710] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 566.933452] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 589.888944] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 590.465261] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 598.085484] Lustre: Unmounted lustre-client [ 626.827863] Key type lgssc unregistered [ 627.123371] LNet: 15051:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 627.130753] LNetError: 15051:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 627.152908] LNet: Removed LNI 192.168.203.45@tcp [ 627.961663] Key type .llcrypt unregistered [ 627.965464] Key type ._llcrypt unregistered [ 643.266210] Key type ._llcrypt registered [ 643.271117] Key type .llcrypt registered [ 644.855328] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 21:35:49 (1789781749) [ 646.516396] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 646.562372] alg: No test for adler32 (adler32-zlib) [ 647.720956] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [ 648.033271] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 649.697594] Key type lgssc registered [ 650.830387] Lustre: Echo OBD driver; http://www.lustre.org/ [ 722.375822] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 814.292515] Lustre: Unmounted lustre-client [ 845.142321] Key type lgssc unregistered [ 845.635993] LNet: 18836:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 845.653460] LNetError: 18836:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 845.678555] LNet: Removed LNI 192.168.203.45@tcp [ 846.569720] Key type .llcrypt unregistered [ 846.576203] Key type ._llcrypt unregistered [ 859.146153] Key type ._llcrypt registered [ 859.151297] Key type .llcrypt registered [ 860.448657] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 21:39:25 (1789781965) [ 863.076355] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 863.228866] alg: No test for adler32 (adler32-zlib) [ 864.333591] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [ 864.680841] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 866.361448] Key type lgssc registered [ 867.436492] Lustre: Echo OBD driver; http://www.lustre.org/ [ 917.165063] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 924.452944] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 946.308320] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 949.514815] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 965.844892] Lustre: Unmounted lustre-client [ 1003.458759] Key type lgssc unregistered [ 1003.753839] LNet: 22437:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1003.768976] LNetError: 22437:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1003.797751] LNet: Removed LNI 192.168.203.45@tcp [ 1004.457111] Key type .llcrypt unregistered [ 1004.460960] Key type ._llcrypt unregistered [ 1014.710561] Key type ._llcrypt registered [ 1014.714910] Key type .llcrypt registered [ 1016.351662] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 21:42:00 (1789782120) [ 1018.901397] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1018.946058] alg: No test for adler32 (adler32-zlib) [ 1020.139516] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [ 1020.416333] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 1022.136218] Key type lgssc registered [ 1023.362829] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1078.363456] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1084.268396] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1110.968812] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1113.067496] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 1119.243720] Lustre: Unmounted lustre-client [ 1146.524396] Key type lgssc unregistered [ 1146.885768] LNet: 25626:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1146.895065] LNetError: 25626:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1146.933564] LNet: Removed LNI 192.168.203.45@tcp [ 1147.710234] Key type .llcrypt unregistered [ 1147.711919] Key type ._llcrypt unregistered [ 1162.859069] Key type ._llcrypt registered [ 1162.861475] Key type .llcrypt registered [ 1164.229579] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 21:44:28 (1789782268) [ 1166.497867] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1166.618263] alg: No test for adler32 (adler32-zlib) [ 1167.768313] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [ 1168.066864] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 1169.737681] Key type lgssc registered [ 1170.939358] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1223.657466] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1230.734897] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1252.885730] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1255.666382] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 1261.067121] Lustre: lustre-MDT0000-mdc-ffff8dc592a72800: Connection to lustre-MDT0000 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1266.163406] Lustre: lustre-MDT0001-mdc-ffff8dc592a72800: Connection to lustre-MDT0001 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1277.283438] Lustre: 26582:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789782367/real 1789782367] req@0000000070634982 x1876722737746944/t0(0) o400->MGC192.168.203.145@tcp@192.168.203.145@tcp:26/25 lens 224/224 e 0 to 1 dl 1789782383 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1277.309803] LustreError: MGC192.168.203.145@tcp: Connection to MGS (at 192.168.203.145@tcp) was lost; in progress operations using this service will fail [ 1281.243935] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1291.591049] Lustre: Unmounted lustre-client [ 1313.120905] Key type lgssc unregistered [ 1313.567880] LNet: 29282:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1313.586344] LNetError: 29282:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1313.614857] LNet: Removed LNI 192.168.203.45@tcp [ 1314.688146] Key type .llcrypt unregistered [ 1314.695385] Key type ._llcrypt unregistered [ 1331.725605] Key type ._llcrypt registered [ 1331.728891] Key type .llcrypt registered [ 1333.786073] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 21:47:17 (1789782437) [ 1336.428232] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1336.486856] alg: No test for adler32 (adler32-zlib) [ 1338.169331] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [ 1338.768878] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 1340.536196] Key type lgssc registered [ 1342.351637] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1398.530247] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1406.275397] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1432.917237] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1440.326122] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 1450.473553] Lustre: lustre-OST0000-osc-ffff8dc592a71000: Connection to lustre-OST0000 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1454.151399] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1464.518718] Lustre: Unmounted lustre-client [ 1492.399440] Key type lgssc unregistered [ 1492.619550] LNet: 32894:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1492.635235] LNetError: 32894:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1492.676709] LNet: Removed LNI 192.168.203.45@tcp [ 1493.495621] Key type .llcrypt unregistered [ 1493.498404] Key type ._llcrypt unregistered [ 1510.884493] Key type ._llcrypt registered [ 1510.916888] Key type .llcrypt registered [ 1512.815755] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 21:50:16 (1789782616) [ 1515.742058] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1515.761970] alg: No test for adler32 (adler32-zlib) [ 1517.222454] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [ 1517.601755] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 1519.376315] Key type lgssc registered [ 1521.215627] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1576.591700] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1583.847869] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1610.156346] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1613.119339] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 1617.252876] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1621.228359] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1632.873081] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-09-1821h52m17s [ 1635.374223] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1638.901878] Lustre: lustre-MDT0000-mdc-ffff8dc592a74000: Connection to lustre-MDT0000 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1645.716501] LustreError: 36679:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff8dc592a74000: can't stat MDS #0: rc = -19 [ 1647.422566] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1656.302470] LustreError: MGC192.168.203.145@tcp: Connection to MGS (at 192.168.203.145@tcp) was lost; in progress operations using this service will fail [ 1656.320966] Lustre: Evicted from MGS (at 192.168.203.145@tcp) after server handle changed from 0xd289540742cb3154 to 0xd289540742cb33fb [ 1656.334929] Lustre: MGC192.168.203.145@tcp: Connection restored to 192.168.203.145@tcp (at 192.168.203.145@tcp) [ 1657.409327] LustreError: lustre-MDT0000-mdc-ffff8dc592a74000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1657.456946] Lustre: lustre-MDT0000-mdc-ffff8dc592a74000: Connection restored to 192.168.203.145@tcp (at 192.168.203.145@tcp) [ 1664.254080] Lustre: DEBUG MARKER: Wait for df (36679) ... [ 1665.654506] Lustre: DEBUG MARKER: done [ 1667.767329] Lustre: Unmounted lustre-client [ 1703.949366] Key type lgssc unregistered [ 1704.232265] LNet: 37696:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1704.245112] LNetError: 37696:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1704.262565] LNet: Removed LNI 192.168.203.45@tcp [ 1704.909924] Key type .llcrypt unregistered [ 1704.916376] Key type ._llcrypt unregistered [ 1739.809976] Key type ._llcrypt registered [ 1739.813682] Key type .llcrypt registered [ 1741.317189] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 21:54:05 (1789782845) [ 1744.406130] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1744.600609] alg: No test for adler32 (adler32-zlib) [ 1745.924881] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [ 1746.353473] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 1748.306107] Key type lgssc registered [ 1750.085895] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1807.015271] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1812.994811] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1837.820749] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1840.607866] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 1845.099632] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-09-1821h55m49s [ 1846.526188] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1853.499377] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1855.926208] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1876.448436] Lustre: 41836:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789782962/real 1789782962] req@0000000096f878d2 x1876723343825536/t0(0) o101->lustre-MDT0000-mdc-ffff8dc5853f5000@192.168.203.145@tcp:12/10 lens 576/1152 e 0 to 1 dl 1789782982 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1876.500917] Lustre: lustre-MDT0000-mdc-ffff8dc5853f5000: Connection to lustre-MDT0000 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1876.543657] Lustre: lustre-MDT0000-mdc-ffff8dc5853f5000: Connection restored to 192.168.203.145@tcp (at 192.168.203.145@tcp) [ 1897.814653] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1901.228937] Lustre: DEBUG MARKER: done [ 1905.145629] Lustre: Unmounted lustre-client [ 1939.569986] Key type lgssc unregistered [ 1939.804743] LNet: 42718:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1939.811986] LNetError: 42718:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1939.843917] LNet: Removed LNI 192.168.203.45@tcp [ 1940.947672] Key type .llcrypt unregistered [ 1940.952619] Key type ._llcrypt unregistered [ 1973.520927] Key type ._llcrypt registered [ 1973.525626] Key type .llcrypt registered [ 1975.268921] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 21:57:59 (1789783079) [ 1977.162955] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1979.122783] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 21:58:03 (1789783083) [ 1983.079145] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1983.189478] alg: No test for adler32 (adler32-zlib) [ 1984.355817] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [ 1984.585563] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 1986.296389] Key type lgssc registered [ 1987.901588] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2022.412503] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 21:58:47 (1789783127) [ 2054.059335] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2060.069601] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2080.320201] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2082.108550] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 2085.580784] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 2089.057128] Lustre: Unmounted lustre-client [ 2107.185317] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 2156.105568] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2162.737132] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2163.328117] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 2167.977620] Lustre: Unmounted lustre-client [ 2235.297404] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2241.918732] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2242.828631] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 2246.908548] Lustre: Unmounted lustre-client [ 2270.320236] Lustre: DEBUG MARKER: files compared the same [ 2294.088604] Key type lgssc unregistered [ 2294.365089] LNet: 50350:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2294.381640] LNetError: 50350:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2294.403504] LNet: Removed LNI 192.168.203.45@tcp [ 2295.255872] Key type .llcrypt unregistered [ 2295.261981] Key type ._llcrypt unregistered [ 2310.287333] Key type ._llcrypt registered [ 2310.298173] Key type .llcrypt registered [ 2311.696986] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 22:03:36 (1789783416) [ 2314.639514] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2314.700878] alg: No test for adler32 (adler32-zlib) [ 2315.763532] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [ 2316.120932] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 2317.881994] Key type lgssc registered [ 2319.303373] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2374.430266] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2381.431370] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2404.387789] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2406.059884] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 2414.705170] Lustre: Unmounted lustre-client [ 2443.261197] Key type lgssc unregistered [ 2443.547318] LNet: 53931:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2443.565867] LNetError: 53931:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2443.608143] LNet: Removed LNI 192.168.203.45@tcp [ 2444.357943] Key type .llcrypt unregistered [ 2444.360947] Key type ._llcrypt unregistered [ 2461.106539] Key type ._llcrypt registered [ 2461.108335] Key type .llcrypt registered [ 2462.973223] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 22:06:06 (1789783566) [ 2465.831357] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2465.884959] alg: No test for adler32 (adler32-zlib) [ 2467.016760] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [ 2467.412515] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 2469.161727] Key type lgssc registered [ 2471.107856] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2557.456363] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2590.911526] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2596.922906] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2629.570354] Key type lgssc unregistered [ 2629.918799] LNet: 57413:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2629.927846] LNetError: 57413:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2629.942318] LNet: Removed LNI 192.168.203.45@tcp [ 2631.040755] Key type .llcrypt unregistered [ 2631.043232] Key type ._llcrypt unregistered [ 2652.083612] Key type ._llcrypt registered [ 2652.090765] Key type .llcrypt registered [ 2653.768476] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 22:09:17 (1789783757) [ 2657.342969] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2657.618362] alg: No test for adler32 (adler32-zlib) [ 2658.815502] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [ 2659.078179] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 2660.848156] Key type lgssc registered [ 2662.447688] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2738.138259] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 2745.475143] Lustre: Unmounted lustre-client [ 2789.330930] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 22:11:33 (1789783893) [ 2916.298682] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2940.444631] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 2948.197626] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2948.202411] LustreError: 63886:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff8dc5911c6800: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2948.216269] Lustre: 58396:0:(llite_lib.c:4342:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.203.145@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2958.575938] Lustre: Unmounted lustre-client [ 3003.813858] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 22:15:08 (1789784108) [ 3018.117459] Key type lgssc unregistered [ 3018.441762] LNet: 65096:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3018.449560] LNetError: 65096:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3018.480094] LNet: Removed LNI 192.168.203.45@tcp [ 3019.462307] Key type .llcrypt unregistered [ 3019.468632] Key type ._llcrypt unregistered [ 3034.087218] Key type ._llcrypt registered [ 3034.090968] Key type .llcrypt registered [ 3034.792994] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3034.809423] alg: No test for adler32 (adler32-zlib) [ 3036.257937] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [ 3036.700693] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 3038.552190] Key type lgssc registered [ 3040.291346] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3093.215937] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3099.134383] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3120.461591] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3122.288727] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 3129.294539] Lustre: Unmounted lustre-client [ 3159.964687] Key type lgssc unregistered [ 3160.309655] LNet: 68105:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3160.321068] LNetError: 68105:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3160.344204] LNet: Removed LNI 192.168.203.45@tcp [ 3161.589482] Key type .llcrypt unregistered [ 3161.592183] Key type ._llcrypt unregistered [ 3172.158986] Key type ._llcrypt registered [ 3172.161354] Key type .llcrypt registered [ 3172.358179] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3172.373841] alg: No test for adler32 (adler32-zlib) [ 3173.560652] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [ 3173.843845] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 3175.560243] Key type lgssc registered [ 3177.227516] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3294.838734] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3300.199879] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3321.171855] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3322.941046] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 3330.275867] Lustre: Unmounted lustre-client [ 3360.551185] Key type lgssc unregistered [ 3360.981418] LNet: 71544:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3360.989579] LNetError: 71544:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3361.012524] LNet: Removed LNI 192.168.203.45@tcp [ 3361.950648] Key type .llcrypt unregistered [ 3361.955350] Key type ._llcrypt unregistered [ 3381.551166] Key type ._llcrypt registered [ 3381.555973] Key type .llcrypt registered [ 3383.584286] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 22:21:27 (1789784487) [ 3386.349099] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3386.416571] alg: No test for adler32 (adler32-zlib) [ 3387.519717] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [ 3387.812408] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 3389.520320] Key type lgssc registered [ 3390.986157] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3445.187815] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3451.547605] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3475.283658] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3478.045302] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 3485.050694] Lustre: Unmounted lustre-client [ 3485.715461] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 3489.155984] Lustre: Unmounted lustre-client [ 3525.858331] Key type lgssc unregistered [ 3526.204222] LNet: 75234:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3526.231319] LNetError: 75234:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3526.256733] LNet: Removed LNI 192.168.203.45@tcp [ 3527.231181] Key type .llcrypt unregistered [ 3527.234235] Key type ._llcrypt unregistered [ 3539.326493] Key type ._llcrypt registered [ 3539.334194] Key type .llcrypt registered [ 3539.808117] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3539.825191] alg: No test for adler32 (adler32-zlib) [ 3540.957926] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [ 3541.140799] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 3542.816895] Key type lgssc registered [ 3543.938359] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3595.916122] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3602.052703] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3623.228067] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3625.036304] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 3630.843603] Lustre: Unmounted lustre-client [ 3658.681304] Key type lgssc unregistered [ 3659.103282] LNet: 78272:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3659.123237] LNetError: 78272:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3659.148147] LNet: Removed LNI 192.168.203.45@tcp [ 3660.072718] Key type .llcrypt unregistered [ 3660.075199] Key type ._llcrypt unregistered [ 3678.689382] Key type ._llcrypt registered [ 3678.694169] Key type .llcrypt registered [ 3680.077885] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 22:26:24 (1789784784) [ 3683.329619] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3683.357852] alg: No test for adler32 (adler32-zlib) [ 3684.507232] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [ 3684.827571] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 3686.561648] Key type lgssc registered [ 3688.001530] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3740.518477] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3748.881557] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3770.571744] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3772.549444] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 3786.811566] Lustre: lustre: root_squash is set to 0:0 [ 3786.837188] Lustre: lustre: nosquash_nids is cleared [ 3793.489808] Lustre: lustre: root_squash is set to 500:500 [ 3808.870265] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.203.45@tcp 192.168.0.[2,10]@tcp [ 3819.056804] Lustre: lustre: nosquash_nids set to 192.168.203.45/24@tcp [ 3826.769896] Lustre: Unmounted lustre-client [ 3859.166267] Key type lgssc unregistered [ 3859.531234] LNet: 82249:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3859.550548] LNetError: 82249:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3859.574969] LNet: Removed LNI 192.168.203.45@tcp [ 3860.707945] Key type .llcrypt unregistered [ 3860.711911] Key type ._llcrypt unregistered [ 3877.976718] Key type ._llcrypt registered [ 3877.979334] Key type .llcrypt registered [ 3880.193881] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 22:29:43 (1789784983) [ 3883.924792] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3886.958986] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 22:29:50 (1789784990) [ 3889.959573] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3890.264612] alg: No test for adler32 (adler32-zlib) [ 3891.436400] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [ 3891.757548] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 3893.560216] Key type lgssc registered [ 3894.919809] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3945.512205] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3951.843651] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3973.737762] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3975.173882] Lustre: lustre: root_squash is set to 500:500 [ 3975.202064] Lustre: lustre: nosquash_nids set to 192.168.203.45/24@tcp [ 3976.294751] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 3985.016234] Lustre: Unmounted lustre-client [ 4012.013735] Key type lgssc unregistered [ 4012.239065] LNet: 86205:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4012.249628] LNetError: 86205:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4012.272719] LNet: Removed LNI 192.168.203.45@tcp [ 4012.969739] Key type .llcrypt unregistered [ 4012.972406] Key type ._llcrypt unregistered [ 4029.304469] Key type ._llcrypt registered [ 4029.306711] Key type .llcrypt registered [ 4030.741399] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 4032.866897] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 22:32:17 (1789785137) [ 4057.205333] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4057.243112] alg: No test for adler32 (adler32-zlib) [ 4058.549981] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [ 4058.812173] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 4060.504199] Key type lgssc registered [ 4062.429475] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4148.372194] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4153.578280] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4180.250395] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4247.661755] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4253.341858] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4279.287608] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4291.394144] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 4325.224646] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8dc58413f000.ost_server_uuid 50 [ 4326.810219] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8dc58413f000.ost_server_uuid in IDLE state after 0 sec [ 4327.324357] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 4331.255286] Lustre: Unmounted lustre-client [ 4333.022376] Lustre: Unmounted lustre-client [ 4381.478343] Key type lgssc unregistered [ 4381.694163] LNet: 93868:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4381.701969] LNetError: 93868:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4382.767170] LNet: Removed LNI 192.168.203.45@tcp [ 4383.398928] Key type .llcrypt unregistered [ 4383.402455] Key type ._llcrypt unregistered [ 4409.836381] Key type ._llcrypt registered [ 4409.838713] Key type .llcrypt registered [ 4410.939461] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 22:38:35 (1789785515) [ 4426.145218] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4426.247807] alg: No test for adler32 (adler32-zlib) [ 4427.572422] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [ 4427.958637] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 4429.728256] Key type lgssc registered [ 4431.317140] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4515.901552] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4522.406744] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4548.165656] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4550.941767] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 4572.640211] Lustre: 95227:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789785662/real 1789785662] req@00000000efbd0302 x1876726156104960/t0(0) o400->lustre-OST0000-osc-ffff8dc592217000@192.168.203.145@tcp:28/4 lens 224/224 e 0 to 1 dl 1789785678 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4572.664841] Lustre: lustre-OST0000-osc-ffff8dc592217000: Connection to lustre-OST0000 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4577.120369] Lustre: 95226:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789785667/real 1789785667] req@00000000cb90479d x1876726156105472/t0(0) o400->lustre-OST0000-osc-ffff8dc592217000@192.168.203.145@tcp:28/4 lens 224/224 e 0 to 1 dl 1789785683 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4582.881941] Lustre: 95227:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789785672/real 1789785672] req@000000003bed7407 x1876726156105984/t0(0) o400->lustre-OST0000-osc-ffff8dc592217000@192.168.203.145@tcp:28/4 lens 224/224 e 0 to 1 dl 1789785688 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4583.015222] Lustre: lustre-OST0000-osc-ffff8dc592217000: Connection restored to 192.168.203.145@tcp (at 192.168.203.145@tcp) [ 4587.040451] Lustre: 95227:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789785677/real 1789785677] req@00000000a3706447 x1876726156106496/t0(0) o400->lustre-OST0000-osc-ffff8dc592217000@192.168.203.145@tcp:28/4 lens 224/224 e 0 to 1 dl 1789785693 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4588.012904] Lustre: lustre-MDT0000-mdc-ffff8dc592217000: Connection to lustre-MDT0000 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4604.386949] Lustre: 95227:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789785694/real 1789785694] req@00000000cabbb176 x1876726156108800/t0(0) o400->MGC192.168.203.145@tcp@192.168.203.145@tcp:26/25 lens 224/224 e 0 to 1 dl 1789785710 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4604.435691] LustreError: MGC192.168.203.145@tcp: Connection to MGS (at 192.168.203.145@tcp) was lost; in progress operations using this service will fail [ 4614.652166] Lustre: Evicted from MGS (at 192.168.203.145@tcp) after server handle changed from 0x990bf2374368bd29 to 0x990bf2374368bfbb [ 4614.667660] Lustre: MGC192.168.203.145@tcp: Connection restored to 192.168.203.145@tcp (at 192.168.203.145@tcp) [ 4618.346036] Lustre: lustre-MDT0000-mdc-ffff8dc592217000: Connection restored to 192.168.203.145@tcp (at 192.168.203.145@tcp) [ 4625.492474] Lustre: Unmounted lustre-client [ 4654.589874] Key type lgssc unregistered [ 4654.887089] LNet: 99531:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4654.895114] LNetError: 99531:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4655.976586] LNet: Removed LNI 192.168.203.45@tcp [ 4657.042474] Key type .llcrypt unregistered [ 4657.052067] Key type ._llcrypt unregistered [ 4672.576705] Key type ._llcrypt registered [ 4672.579627] Key type .llcrypt registered [ 4673.958287] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 22:42:58 (1789785778) [ 4689.469662] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4689.506345] alg: No test for adler32 (adler32-zlib) [ 4690.591198] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [ 4690.909127] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 4692.617064] Key type lgssc registered [ 4694.281901] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4733.657272] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4838.919751] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4846.416206] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4874.917679] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4877.679364] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 4880.224078] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 4881.798486] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 22:46:26 (1789785986) [ 5142.986381] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5153.325056] Lustre: Unmounted lustre-client [ 5291.893971] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5298.895405] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5325.859981] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5369.826363] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 22:54:34 (1789786474) [ 5462.283241] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5468.389698] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5493.307573] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5494.119199] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 5498.024700] Lustre: Unmounted lustre-client [ 5537.483764] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 22:57:22 (1789786642) [ 5633.391605] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5639.119331] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5661.978132] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5662.437946] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 5666.786778] Lustre: Unmounted lustre-client [ 5694.782407] Key type lgssc unregistered [ 5695.200755] LNet: 122544:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5695.208409] LNetError: 122544:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5695.232753] LNet: Removed LNI 192.168.203.45@tcp [ 5696.049336] Key type .llcrypt unregistered [ 5696.052422] Key type ._llcrypt unregistered [ 5711.039698] Key type ._llcrypt registered [ 5711.041963] Key type .llcrypt registered [ 5712.373640] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 23:00:17 (1789786817) [ 5714.495069] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5714.564116] alg: No test for adler32 (adler32-zlib) [ 5715.642430] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [ 5715.851353] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 5717.504201] Key type lgssc registered [ 5718.644693] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5764.620764] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5769.998440] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5787.655383] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5790.346234] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 5803.323364] Lustre: Unmounted lustre-client [ 5829.525316] Key type lgssc unregistered [ 5829.751838] LNet: 126144:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5829.766271] LNetError: 126144:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5829.784463] LNet: Removed LNI 192.168.203.45@tcp [ 5830.438859] Key type .llcrypt unregistered [ 5830.441448] Key type ._llcrypt unregistered [ 5844.742503] Key type ._llcrypt registered [ 5844.745394] Key type .llcrypt registered [ 5845.917296] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 23:02:30 (1789786950) [ 5848.217144] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5848.272134] alg: No test for adler32 (adler32-zlib) [ 5849.328277] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [ 5849.588159] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 5851.280229] Key type lgssc registered [ 5852.375552] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5904.555520] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5910.774282] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5930.345425] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5933.058528] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 5943.277919] Lustre: lustre-OST0000-osc-ffff8dc58324b000: Connection to lustre-OST0000 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5961.974254] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff8dc58324b000.ost_server_uuid 50 [ 5963.452313] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 5974.867260] Lustre: Unmounted lustre-client [ 5995.030668] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 23:04:59 (1789787099) [ 6025.536952] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6031.601839] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6051.732933] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6073.786884] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6074.669676] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 6079.983468] Lustre: lustre-OST0000-osc-ffff8dc58751c800: Connection to lustre-OST0000 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6105.340867] Lustre: Unmounted lustre-client [ 6150.673450] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 23:07:35 (1789787255) [ 6182.850931] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6188.250817] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6209.629853] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6233.528203] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6234.329226] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 6239.737143] Lustre: lustre-OST0000-osc-ffff8dc58413c800: Connection to lustre-OST0000 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6255.398560] Lustre: Unmounted lustre-client [ 6305.808116] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 23:10:10 (1789787410) [ 6396.732247] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6402.755515] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6425.476679] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6492.444653] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6497.516877] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6516.225966] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6547.631797] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 6589.456160] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6594.082823] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 3 sec [ 6596.064707] Lustre: Unmounted lustre-client [ 6626.507690] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 23:15:31 (1789787731) [ 6659.043677] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6664.538752] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6688.088713] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6729.359575] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6769.265380] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 6814.530425] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6816.291099] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6828.422459] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6838.792537] Lustre: Unmounted lustre-client [ 6905.058189] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 23:20:09 (1789788009) [ 6944.113227] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6950.925117] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6971.627314] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6972.315244] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 6994.572476] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6996.247777] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7010.690661] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8dc5913e9000.ost_server_uuid 50 [ 7012.264830] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8dc5913e9000.ost_server_uuid in IDLE state after 0 sec [ 7015.601357] Lustre: Unmounted lustre-client [ 7015.891587] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 7015.897783] Lustre: Skipped 3 previous similar messages [ 7016.028674] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 7017.245481] LustreError: 149558:0:(sec.c:403:import_sec_validate_get()) import 000000004d631c4c (NEW) with no sec [ 7079.400654] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 23:23:03 (1789788183) [ 7118.914498] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7125.289307] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7146.630222] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7170.887449] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7171.490038] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 7171.715760] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 7187.948652] Lustre: Unmounted lustre-client [ 7187.957329] Lustre: Skipped 1 previous similar message [ 7239.392813] Key type lgssc unregistered [ 7239.764936] LNet: 152973:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7239.784617] LNetError: 152973:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7239.814309] LNet: Removed LNI 192.168.203.45@tcp [ 7240.901775] Key type .llcrypt unregistered [ 7240.907654] Key type ._llcrypt unregistered [ 7259.159510] Key type ._llcrypt registered [ 7259.162709] Key type .llcrypt registered [ 7260.838430] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 23:26:05 (1789788365) [ 7262.302688] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7262.369293] alg: No test for adler32 (adler32-zlib) [ 7263.553696] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [ 7263.868690] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 7265.641967] Key type lgssc registered [ 7267.049663] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7322.146769] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7328.121625] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7346.747427] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7373.540849] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7375.709614] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7376.897634] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 7382.101210] LustreError: lustre-MDT0001-mdc-ffff8dc58413b000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 7382.146826] Lustre: lustre-MDT0001-mdc-ffff8dc58413b000: Connection restored to 192.168.203.145@tcp (at 192.168.203.145@tcp) [ 7389.238357] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7390.883975] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7416.373765] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7421.261526] Lustre: Unmounted lustre-client [ 7451.531286] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 23:29:16 (1789788556) [ 7546.614695] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7554.476927] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7578.930960] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7579.528262] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 7604.341386] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7606.053230] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7610.348674] Lustre: lustre-OST0001-osc-ffff8dc5853f2800: Connection to lustre-OST0001 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7618.439064] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7628.820935] Lustre: Unmounted lustre-client [ 7670.831737] Key type lgssc unregistered [ 7671.045668] LNet: 162589:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7671.051926] LNetError: 162589:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7671.071724] LNet: Removed LNI 192.168.203.45@tcp [ 7671.679651] Key type .llcrypt unregistered [ 7671.684343] Key type ._llcrypt unregistered [ 7701.335636] Key type ._llcrypt registered [ 7701.342570] Key type .llcrypt registered [ 7702.736963] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 23:33:27 (1789788807) [ 7705.015922] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7705.083411] alg: No test for adler32 (adler32-zlib) [ 7706.184931] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [ 7706.440633] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 7708.104564] Key type lgssc registered [ 7709.237882] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7758.350042] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7764.587121] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7786.661785] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7789.350299] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 7835.655892] Lustre: Unmounted lustre-client [ 7873.556451] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7874.679875] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 7911.816651] Lustre: Unmounted lustre-client [ 7942.155564] Key type lgssc unregistered [ 7942.427086] LNet: 167037:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7942.432212] LNetError: 167037:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7942.457975] LNet: Removed LNI 192.168.203.45@tcp [ 7943.310390] Key type .llcrypt unregistered [ 7943.314973] Key type ._llcrypt unregistered [ 7958.873928] Key type ._llcrypt registered [ 7958.878969] Key type .llcrypt registered [ 7960.215137] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 23:37:44 (1789789064) [ 7962.541817] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7962.562491] alg: No test for adler32 (adler32-zlib) [ 7963.642321] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [ 7963.886628] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 7965.576280] Key type lgssc registered [ 7966.793767] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8014.995452] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8021.821822] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8044.994708] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8047.758759] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 8070.860107] Lustre: Unmounted lustre-client [ 8097.256522] Key type lgssc unregistered [ 8097.471729] LNet: 170787:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8097.480897] LNetError: 170787:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8097.509022] LNet: Removed LNI 192.168.203.45@tcp [ 8098.179171] Key type .llcrypt unregistered [ 8098.188725] Key type ._llcrypt unregistered [ 8109.451365] Key type ._llcrypt registered [ 8109.453131] Key type .llcrypt registered [ 8110.201250] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8110.234302] alg: No test for adler32 (adler32-zlib) [ 8111.465301] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [ 8111.944626] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 8113.712202] Key type lgssc registered [ 8115.295388] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8167.506112] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8172.566147] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8195.374042] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8198.186851] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 8205.286618] Lustre: Unmounted lustre-client [ 8233.729344] Key type lgssc unregistered [ 8233.994745] LNet: 173846:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8234.006627] LNetError: 173846:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8234.043608] LNet: Removed LNI 192.168.203.45@tcp [ 8234.759280] Key type .llcrypt unregistered [ 8234.761802] Key type ._llcrypt unregistered [ 8250.505398] Key type ._llcrypt registered [ 8250.506833] Key type .llcrypt registered [ 8251.776887] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 23:42:36 (1789789356) [ 8253.625628] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8253.680122] alg: No test for adler32 (adler32-zlib) [ 8254.775851] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [ 8255.026809] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 8256.704297] Key type lgssc registered [ 8257.729479] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8299.909493] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8305.588674] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8323.757298] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8325.442225] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 8342.455313] Lustre: Unmounted lustre-client [ 8368.495049] Key type lgssc unregistered [ 8368.710277] LNet: 177587:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8368.718184] LNetError: 177587:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8368.728762] LNet: Removed LNI 192.168.203.45@tcp [ 8369.339713] Key type .llcrypt unregistered [ 8369.348087] Key type ._llcrypt unregistered [ 8380.133731] Key type ._llcrypt registered [ 8380.160502] Key type .llcrypt registered [ 8380.349663] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8380.366092] alg: No test for adler32 (adler32-zlib) [ 8381.434026] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [ 8381.684839] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 8383.416940] Key type lgssc registered [ 8384.612506] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8435.053604] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8440.070406] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8459.176727] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8460.723716] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 8466.306595] Lustre: Unmounted lustre-client [ 8495.484214] Key type lgssc unregistered [ 8495.893281] LNet: 180647:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8495.900989] LNetError: 180647:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8495.922118] LNet: Removed LNI 192.168.203.45@tcp [ 8496.644869] Key type .llcrypt unregistered [ 8496.647447] Key type ._llcrypt unregistered [ 8512.777440] Key type ._llcrypt registered [ 8512.780504] Key type .llcrypt registered [ 8514.119491] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 23:46:58 (1789789618) [ 8535.138424] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8535.305089] alg: No test for adler32 (adler32-zlib) [ 8536.356093] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [ 8536.612394] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 8538.320157] Key type lgssc registered [ 8539.525865] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8615.612680] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8621.340881] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8641.463922] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8680.776686] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 23:49:45 (1789789785) [ 8715.825525] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8721.273650] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8739.812751] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8742.512581] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 8747.520423] Lustre: Unmounted lustre-client [ 8773.819638] Key type lgssc unregistered [ 8774.151183] LNet: 187731:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8774.161225] LNetError: 187731:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8774.195871] LNet: Removed LNI 192.168.203.45@tcp [ 8774.945182] Key type .llcrypt unregistered [ 8774.947915] Key type ._llcrypt unregistered [ 8794.070245] Key type ._llcrypt registered [ 8794.072898] Key type .llcrypt registered [ 8795.868777] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 8797.720500] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 23:51:42 (1789789902) [ 8816.773769] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8817.022497] alg: No test for adler32 (adler32-zlib) [ 8818.228371] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [ 8818.564308] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 8820.289066] Key type lgssc registered [ 8821.707489] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8918.222690] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8922.815620] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8942.372299] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8958.978932] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8961.282849] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 8981.757363] Lustre: Unmounted lustre-client [ 9090.292371] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9095.654866] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9119.605626] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9175.706404] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 23:58:00 (1789790280) [ 9177.147938] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 9178.505517] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 23:58:03 (1789790283) [ 9267.985049] Key type lgssc unregistered [ 9268.225363] LNet: 199169:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9268.237586] LNetError: 199169:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9268.259890] LNet: Removed LNI 192.168.203.45@tcp [ 9269.007462] Key type .llcrypt unregistered [ 9269.009399] Key type ._llcrypt unregistered [ 9282.501275] Key type ._llcrypt registered [ 9282.505351] Key type .llcrypt registered [ 9283.649351] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 23:59:48 (1789790388) [ 9323.849091] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9323.873196] alg: No test for adler32 (adler32-zlib) [ 9324.874879] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [ 9325.170548] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 9326.913225] Key type lgssc registered [ 9328.097614] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9376.654713] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9382.025993] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9420.721950] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9455.752381] Key type lgssc unregistered [ 9455.990123] LNet: 202915:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9456.002236] LNetError: 202915:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9456.015625] LNet: Removed LNI 192.168.203.45@tcp [ 9456.637477] Key type .llcrypt unregistered [ 9456.642506] Key type ._llcrypt unregistered [ 9470.094139] Key type ._llcrypt registered [ 9470.096543] Key type .llcrypt registered [ 9471.397074] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 00:02:56 (1789790576) [ 9473.452378] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9473.477663] alg: No test for adler32 (adler32-zlib) [ 9474.680757] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [ 9474.959495] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 9476.688317] Key type lgssc registered [ 9477.883521] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9532.177944] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9538.319484] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9561.260385] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9563.919872] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [ 9574.381129] Lustre: lustre-MDT0000-mdc-ffff8dc5920cf800: Connection to lustre-MDT0000 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9584.613394] Lustre: lustre-MDT0001-mdc-ffff8dc5920cf800: Connection to lustre-MDT0001 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9595.880273] Lustre: 203900:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789790685/real 1789790685] req@00000000b0ff04a9 x1876731448344704/t0(0) o400->MGC192.168.203.145@tcp@192.168.203.145@tcp:26/25 lens 224/224 e 0 to 1 dl 1789790701 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9595.926780] LustreError: MGC192.168.203.145@tcp: Connection to MGS (at 192.168.203.145@tcp) was lost; in progress operations using this service will fail [ 9606.200612] Lustre: Evicted from MGS (at 192.168.203.145@tcp) after server handle changed from 0x5f921406ff7fceb7 to 0x5f921406ff7feb58 [ 9606.225830] Lustre: MGC192.168.203.145@tcp: Connection restored to 192.168.203.145@tcp (at 192.168.203.145@tcp) [ 9629.180233] LustreError: lustre-MDT0000-mdc-ffff8dc5920cf800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 9629.219226] Lustre: lustre-MDT0000-mdc-ffff8dc5920cf800: Connection restored to 192.168.203.145@tcp (at 192.168.203.145@tcp) [ 9630.835228] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9632.223824] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9637.336331] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9638.818751] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9641.385414] Lustre: Unmounted lustre-client [ 9669.017288] Key type lgssc unregistered [ 9669.291485] LNet: 207852:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9669.300273] LNetError: 207852:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9669.325566] LNet: Removed LNI 192.168.203.45@tcp [ 9670.085184] Key type .llcrypt unregistered [ 9670.089346] Key type ._llcrypt unregistered [ 9686.196651] Key type ._llcrypt registered [ 9686.198675] Key type .llcrypt registered [ 9687.416590] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 00:06:32 (1789790792) [ 9690.039884] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9690.071345] alg: No test for adler32 (adler32-zlib) [ 9691.199214] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [ 9691.589413] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 9693.288232] Key type lgssc registered [ 9694.560735] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9742.761168] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9748.577085] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9811.314840] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9817.639322] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9853.524770] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9875.429994] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9922.026870] Key type lgssc unregistered [ 9922.281216] LNet: 213143:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9922.293421] LNetError: 213143:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9922.309688] LNet: Removed LNI 192.168.203.45@tcp [ 9923.246824] Key type .llcrypt unregistered [ 9923.251861] Key type ._llcrypt unregistered [ 9964.292097] Key type ._llcrypt registered [ 9964.302980] Key type .llcrypt registered [ 9965.813785] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 00:11:10 (1789791070) [10006.150415] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10006.194190] alg: No test for adler32 (adler32-zlib) [10007.372926] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [10007.685965] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [10009.456466] Key type lgssc registered [10011.030168] Lustre: Echo OBD driver; http://www.lustre.org/ [10092.445431] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10098.123508] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10122.276256] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10161.764071] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 00:14:26 (1789791266) [10169.616256] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 00:14:34 (1789791274) [10202.487404] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10208.717973] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10234.505243] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10237.712713] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [10243.016527] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [10244.990473] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [10247.141524] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [10249.264878] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [10253.287036] Lustre: lustre-MDT0000-mdc-ffff8dc58751f800: Connection to lustre-MDT0000 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [10269.665044] Lustre: 215034:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789791359/real 1789791359] req@00000000f15e6f2b x1876732006115072/t0(0) o400->MGC192.168.203.145@tcp@192.168.203.145@tcp:26/25 lens 224/224 e 0 to 1 dl 1789791375 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10269.693972] LustreError: MGC192.168.203.145@tcp: Connection to MGS (at 192.168.203.145@tcp) was lost; in progress operations using this service will fail [10275.944913] Lustre: lustre-MDT0000-mdc-ffff8dc58751f800: Connection restored to 192.168.203.145@tcp (at 192.168.203.145@tcp) [10279.026410] Lustre: Evicted from MGS (at 192.168.203.145@tcp) after server handle changed from 0x895ae56e99941e44 to 0x895ae56e999421af [10279.049765] Lustre: MGC192.168.203.145@tcp: Connection restored to 192.168.203.145@tcp (at 192.168.203.145@tcp) [10283.788733] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10285.320943] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10288.935402] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [10294.769415] Lustre: lustre-MDT0000-mdc-ffff8dc58751f800: Connection to lustre-MDT0000 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [10310.112634] Lustre: 215036:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789791400/real 1789791400] req@00000000b411a5ad x1876732006123136/t0(0) o400->MGC192.168.203.145@tcp@192.168.203.145@tcp:26/25 lens 224/224 e 0 to 1 dl 1789791416 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10310.146174] LustreError: MGC192.168.203.145@tcp: Connection to MGS (at 192.168.203.145@tcp) was lost; in progress operations using this service will fail [10316.378884] Lustre: lustre-MDT0000-mdc-ffff8dc58751f800: Connection restored to 192.168.203.145@tcp (at 192.168.203.145@tcp) [10320.486513] Lustre: Evicted from MGS (at 192.168.203.145@tcp) after server handle changed from 0x895ae56e999421af to 0x895ae56e9994251a [10320.505726] Lustre: MGC192.168.203.145@tcp: Connection restored to 192.168.203.145@tcp (at 192.168.203.145@tcp) [10324.878870] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10326.073582] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10329.498667] Lustre: Unmounted lustre-client [10395.470581] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10401.508959] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10420.967689] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10421.750912] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [10424.241184] Lustre: Unmounted lustre-client [10452.573152] Key type lgssc unregistered [10452.878862] LNet: 225616:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10452.897874] LNetError: 225616:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10452.914414] LNet: Removed LNI 192.168.203.45@tcp [10453.866274] Key type .llcrypt unregistered [10453.880221] Key type ._llcrypt unregistered [10471.711953] Key type ._llcrypt registered [10471.714226] Key type .llcrypt registered [10473.333988] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 00:19:37 (1789791577) [10477.199042] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10477.242348] alg: No test for adler32 (adler32-zlib) [10478.429348] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [10478.788130] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [10480.488210] Key type lgssc registered [10481.740686] Lustre: Echo OBD driver; http://www.lustre.org/ [10526.523786] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10532.261690] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10551.004806] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10553.827147] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [10559.527600] Lustre: Unmounted lustre-client [10622.622073] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10628.698765] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10648.465893] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10649.062030] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [10658.077796] Lustre: Unmounted lustre-client [10724.330746] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10729.935462] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10748.654774] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10749.244430] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [10750.881479] Lustre: Unmounted lustre-client [10779.175808] Key type lgssc unregistered [10779.556761] LNet: 232658:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10779.567583] LNetError: 232658:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10779.585990] LNet: Removed LNI 192.168.203.45@tcp [10780.694409] Key type .llcrypt unregistered [10780.696877] Key type ._llcrypt unregistered [10798.618677] Key type ._llcrypt registered [10798.622209] Key type .llcrypt registered [10799.986294] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 00:25:04 (1789791904) [10803.789573] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10803.856719] alg: No test for adler32 (adler32-zlib) [10804.872626] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [10805.090463] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [10806.768994] Key type lgssc registered [10808.029421] Lustre: Echo OBD driver; http://www.lustre.org/ [10852.935215] Key type lgssc unregistered [10853.143687] LNet: 234976:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10853.156231] LNetError: 234976:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10853.184881] LNet: Removed LNI 192.168.203.45@tcp [10853.818719] Key type .llcrypt unregistered [10853.823964] Key type ._llcrypt unregistered [10881.588138] Key type ._llcrypt registered [10881.607673] Key type .llcrypt registered [10882.151413] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10882.173200] alg: No test for adler32 (adler32-zlib) [10883.445905] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [10883.826793] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [10885.584307] Key type lgssc registered [10887.132825] Lustre: Echo OBD driver; http://www.lustre.org/ [10965.307213] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10971.495181] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10996.290237] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11035.545965] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [11037.986981] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 00:29:02 (1789792142) [11073.612346] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11079.955449] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11101.884757] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11125.902392] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11127.507698] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [11132.918733] Lustre: lustre-OST0001-osc-ffff8dc592a3c000: Connection to lustre-OST0001 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [11139.947454] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11150.298946] Lustre: Unmounted lustre-client [11192.816323] Key type lgssc unregistered [11193.076656] LNet: 242087:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11193.086871] LNetError: 242087:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11194.158598] LNet: Removed LNI 192.168.203.45@tcp [11194.851199] Key type .llcrypt unregistered [11194.855419] Key type ._llcrypt unregistered [11227.118932] Key type ._llcrypt registered [11227.123195] Key type .llcrypt registered [11228.663475] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 00:32:13 (1789792333) [11241.096436] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11241.140992] alg: No test for adler32 (adler32-zlib) [11242.255993] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [11242.548798] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [11244.289058] Key type lgssc registered [11245.611688] Lustre: Echo OBD driver; http://www.lustre.org/ [11292.398924] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11297.472251] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11327.416353] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 00:33:52 (1789792432) [11358.842512] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11364.591423] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11384.819961] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11387.534815] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [11400.695969] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [11401.590123] Lustre: Unmounted lustre-client [11512.376879] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11518.663423] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11569.939647] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11570.534808] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [11573.512241] Lustre: Unmounted lustre-client [11609.317135] Key type lgssc unregistered [11609.560223] LNet: 249786:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11609.567205] LNetError: 249786:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11609.582429] LNet: Removed LNI 192.168.203.45@tcp [11610.385285] Key type .llcrypt unregistered [11610.389886] Key type ._llcrypt unregistered [11639.014040] Key type ._llcrypt registered [11639.017337] Key type .llcrypt registered [11639.454327] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11639.473702] alg: No test for adler32 (adler32-zlib) [11640.557850] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [11640.893671] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [11642.632231] Key type lgssc registered [11643.975738] Lustre: Echo OBD driver; http://www.lustre.org/ [11730.473403] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11736.535885] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11758.797176] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11801.377771] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [11803.339567] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 00:41:47 (1789792907) [11837.760244] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11844.175487] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11866.424597] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11870.975769] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [11874.910995] Lustre: Unmounted lustre-client [11903.384982] Key type lgssc unregistered [11903.624370] LNet: 256417:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11903.639162] LNetError: 256417:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11903.669940] LNet: Removed LNI 192.168.203.45@tcp [11905.003413] Key type .llcrypt unregistered [11905.005711] Key type ._llcrypt unregistered [11925.721402] Key type ._llcrypt registered [11925.723365] Key type .llcrypt registered [11927.339226] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [11928.988957] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 00:43:53 (1789793033) [11938.671904] Key type .llcrypt unregistered [11938.675612] Key type ._llcrypt unregistered [11947.809172] Key type ._llcrypt registered [11947.835801] Key type .llcrypt registered [11948.434424] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11948.447649] alg: No test for adler32 (adler32-zlib) [11949.585303] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [11949.939117] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [11951.664995] Key type lgssc registered [11953.296964] Lustre: Echo OBD driver; http://www.lustre.org/ [12006.693295] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12025.653631] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [12028.636239] Lustre: Unmounted lustre-client [12058.636775] Key type lgssc unregistered [12058.929868] LNet: 259868:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12058.941974] LNetError: 259868:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12058.968191] LNet: Removed LNI 192.168.203.45@tcp [12059.821553] Key type .llcrypt unregistered [12059.828443] Key type ._llcrypt unregistered [12075.200966] Key type ._llcrypt registered [12075.204298] Key type .llcrypt registered [12076.429233] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 00:46:21 (1789793181) [12078.473903] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12078.494777] alg: No test for adler32 (adler32-zlib) [12079.651025] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [12079.926267] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [12081.648237] Key type lgssc registered [12082.766650] Lustre: Echo OBD driver; http://www.lustre.org/ [12171.319613] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12202.302254] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12207.950571] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12210.686113] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [12213.904637] Lustre: Unmounted lustre-client [12232.391319] Key type lgssc unregistered [12232.636024] LNet: 263410:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12232.646453] LNetError: 263410:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12232.676630] LNet: Removed LNI 192.168.203.45@tcp [12233.474216] Key type .llcrypt unregistered [12233.478572] Key type ._llcrypt unregistered [12250.838946] Key type ._llcrypt registered [12250.841612] Key type .llcrypt registered [12252.345905] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 00:49:16 (1789793356) [12254.574958] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12254.741986] alg: No test for adler32 (adler32-zlib) [12255.837398] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [12256.052065] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [12257.736491] Key type lgssc registered [12258.877690] Lustre: Echo OBD driver; http://www.lustre.org/ [12312.345146] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12317.915382] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12338.672258] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12341.098930] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [12346.349414] Lustre: lustre-MDT0000-mdc-ffff8dc5920ca000: Connection to lustre-MDT0000 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [12352.013479] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12352.066091] LustreError: 266569:0:(file.c:6167:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [12353.034298] LustreError: 266580:0:(file.c:6167:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [12353.049020] LustreError: 266580:0:(file.c:6167:ll_inode_revalidate_fini()) Skipped 3 previous similar messages [12353.178513] LustreError: MGC192.168.203.145@tcp: Connection to MGS (at 192.168.203.145@tcp) was lost; in progress operations using this service will fail [12353.266192] Lustre: Unmounted lustre-client [12374.071975] Key type lgssc unregistered [12374.443227] LNet: 266981:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12374.452311] LNetError: 266981:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12374.472869] LNet: Removed LNI 192.168.203.45@tcp [12375.237731] Key type .llcrypt unregistered [12375.240435] Key type ._llcrypt unregistered [12393.121936] Key type ._llcrypt registered [12393.126877] Key type .llcrypt registered [12394.550771] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 00:51:39 (1789793499) [12397.056939] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12397.119481] alg: No test for adler32 (adler32-zlib) [12398.265203] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [12398.592763] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [12400.360427] Key type lgssc registered [12401.769129] Lustre: Echo OBD driver; http://www.lustre.org/ [12456.649617] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12463.001371] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12485.508564] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12487.210592] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [12498.256806] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [12500.645243] Lustre: Unmounted lustre-client [12522.827757] Key type lgssc unregistered [12523.304788] LNet: 270553:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12523.323847] LNetError: 270553:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12523.351987] LNet: Removed LNI 192.168.203.45@tcp [12524.232834] Key type .llcrypt unregistered [12524.246972] Key type ._llcrypt unregistered [12543.778799] Key type ._llcrypt registered [12543.780451] Key type .llcrypt registered [12545.149893] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 00:54:09 (1789793649) [12566.302607] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12566.321679] alg: No test for adler32 (adler32-zlib) [12567.576169] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [12567.962067] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [12569.648192] Key type lgssc registered [12570.828472] Lustre: Echo OBD driver; http://www.lustre.org/ [12687.321089] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12689.136061] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [12735.482666] Lustre: lustre-MDT0001-mdc-ffff8dc583887000: Connection to lustre-MDT0001 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [12743.453130] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12753.755210] Lustre: Unmounted lustre-client [12781.612240] Key type lgssc unregistered [12781.865486] LNet: 276335:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12781.879270] LNetError: 276335:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12781.915248] LNet: Removed LNI 192.168.203.45@tcp [12782.825603] Key type .llcrypt unregistered [12782.830129] Key type ._llcrypt unregistered [12799.372146] Key type ._llcrypt registered [12799.377067] Key type .llcrypt registered [12800.855823] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 00:58:25 (1789793905) [12803.020818] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [12804.927723] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 00:58:29 (1789793909) [12807.055206] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [12809.192244] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 00:58:33 (1789793913) [12811.047306] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [12813.251894] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 00:58:37 (1789793917) [12815.056679] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [12816.849882] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 00:58:41 (1789793921) [12818.599937] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [12820.463709] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 00:58:44 (1789793924) [12830.287582] Key type .llcrypt unregistered [12830.290557] Key type ._llcrypt unregistered [12837.201720] Key type ._llcrypt registered [12837.206296] Key type .llcrypt registered [12837.840641] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12837.876456] alg: No test for adler32 (adler32-zlib) [12838.949248] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [12839.170356] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [12840.848231] Key type lgssc registered [12842.374321] Lustre: Echo OBD driver; http://www.lustre.org/ [12910.352467] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12915.994588] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12937.206321] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12940.042680] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [12943.071145] Lustre: Unmounted lustre-client [12978.721585] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 01:01:23 (1789794083) [13008.676473] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13013.448980] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13045.544613] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13061.705482] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [13063.775888] Lustre: Unmounted lustre-client [13102.379229] LNet: 1 local NIs in recovery (showing 1): 192.168.203.45@tcp [13105.204197] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 01:03:29 (1789794209) [13755.090349] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13761.060967] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13798.779932] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13897.038618] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 01:16:41 (1789795001) [13907.451420] Key type lgssc unregistered [13907.753293] LNet: 289866:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13907.760532] LNetError: 289866:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13907.791305] LNet: Removed LNI 192.168.203.45@tcp [13908.450977] Key type .llcrypt unregistered [13908.453843] Key type ._llcrypt unregistered [14047.192870] Key type ._llcrypt registered [14047.199730] Key type .llcrypt registered [14047.480693] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14047.510529] alg: No test for adler32 (adler32-zlib) [14048.573311] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [14048.804970] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [14050.448240] Key type lgssc registered [14051.535584] Lustre: Echo OBD driver; http://www.lustre.org/ [14117.947261] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14122.246100] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14141.084083] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14168.500564] Key type lgssc unregistered [14168.687355] LNet: 293552:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14168.694911] LNetError: 293552:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14168.713390] LNet: Removed LNI 192.168.203.45@tcp [14169.193773] Key type .llcrypt unregistered [14169.197234] Key type ._llcrypt unregistered [14202.009423] Key type ._llcrypt registered [14202.011759] Key type .llcrypt registered [14203.126303] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 01:21:47 (1789795307) [14217.920901] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14217.943621] alg: No test for adler32 (adler32-zlib) [14218.999117] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [14219.193593] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [14220.840251] Key type lgssc registered [14221.805456] Lustre: Echo OBD driver; http://www.lustre.org/ [14261.491761] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 01:22:46 (1789795366) [14271.154564] Key type lgssc unregistered [14271.380785] LNet: 297102:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14271.387136] LNetError: 297102:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14271.398957] LNet: Removed LNI 192.168.203.45@tcp [14271.927326] Key type .llcrypt unregistered [14271.943404] Key type ._llcrypt unregistered [14279.541987] Key type ._llcrypt registered [14279.550365] Key type .llcrypt registered [14279.779713] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14279.801624] alg: No test for adler32 (adler32-zlib) [14280.802954] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [14281.010331] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [14282.672226] Key type lgssc registered [14283.668531] Lustre: Echo OBD driver; http://www.lustre.org/ [14326.719275] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14331.327189] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14348.861655] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14542.207527] LNet: Added LNI 192.168.203.45@tcp50 [8/256/0/180] [14543.928982] LNet: Removed LNI 192.168.203.45@tcp [14545.751669] Lustre: 301491:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff8dc590370000: config has no valid NIDs, force dynamic NIDs [14546.870362] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [14546.963296] Lustre: Unmounted lustre-client [14548.708819] Key type lgssc unregistered [14548.991419] LNet: 301750:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14548.996331] LNetError: 301750:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14549.018486] LNet: Removed LNI 192.168.203.45@tcp50 [14549.517866] Key type .llcrypt unregistered [14549.520483] Key type ._llcrypt unregistered [14559.420377] Key type ._llcrypt registered [14559.451487] Key type .llcrypt registered [14559.721659] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14559.735734] alg: No test for adler32 (adler32-zlib) [14560.713612] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [14560.875118] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [14562.504355] Key type lgssc registered [14563.341204] Lustre: Echo OBD driver; http://www.lustre.org/ [14599.100256] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14602.595661] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14608.013418] LNet: Added LNI 192.168.203.45@tcp50 [8/256/0/180] [14608.577737] LNet: Removed LNI 192.168.203.45@tcp [14609.757193] Lustre: 304442:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff8dc586086000: config has no valid NIDs, force dynamic NIDs [14610.908304] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [14611.324288] Lustre: Unmounted lustre-client [14633.350331] Key type lgssc unregistered [14633.646306] LNet: 305309:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14633.657129] LNetError: 305309:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14633.672532] LNet: Removed LNI 192.168.203.45@tcp50 [14634.137240] Key type .llcrypt unregistered [14634.138794] Key type ._llcrypt unregistered [14642.443925] Key type ._llcrypt registered [14642.448416] Key type .llcrypt registered [14643.421571] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 01:29:08 (1789795748) [14657.551336] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14657.570601] alg: No test for adler32 (adler32-zlib) [14658.543089] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [14658.694961] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [14660.312157] Key type lgssc registered [14661.147896] Lustre: Echo OBD driver; http://www.lustre.org/ [14726.197979] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14730.746759] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14749.544431] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14777.589299] Key type lgssc unregistered [14777.816958] LNet: 310047:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14777.824510] LNetError: 310047:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14777.840602] LNet: Removed LNI 192.168.203.45@tcp [14778.375913] Key type .llcrypt unregistered [14778.381685] Key type ._llcrypt unregistered [14786.778618] Key type ._llcrypt registered [14786.781517] Key type .llcrypt registered [14787.815214] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 01:31:32 (1789795892) [14789.710971] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14789.733640] alg: No test for adler32 (adler32-zlib) [14790.740238] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [14790.921461] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [14792.576138] Key type lgssc registered [14793.516234] Lustre: Echo OBD driver; http://www.lustre.org/ [14830.260518] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14834.438048] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14849.350043] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14861.331156] Lustre: Mounted lustre-client - version 2.17.57_110_g5f5d390 [14901.049491] Lustre: Mounted fs15246-client - version 2.17.57_110_g5f5d390 [14911.077907] Lustre: Unmounted fs15246-client [14930.298178] Lustre: Unmounted lustre-client [14953.795986] Key type lgssc unregistered [14954.008634] LNet: 314896:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14954.017889] LNetError: 314896:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14954.041399] LNet: Removed LNI 192.168.203.45@tcp [14954.639938] Key type .llcrypt unregistered [14954.642884] Key type ._llcrypt unregistered [14962.547582] Key type ._llcrypt registered [14962.549727] Key type .llcrypt registered [14963.550838] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 01:34:28 (1789796068) [14964.474515] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14964.491471] alg: No test for adler32 (adler32-zlib) [14965.494186] Lustre: Lustre: Build Version: 2.17.57_110_g5f5d390 [14965.654392] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [14967.288188] Key type lgssc registered [14968.183069] Lustre: Echo OBD driver; http://www.lustre.org/ [15056.736958] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15060.372600] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15075.483956] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15138.835792] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 14848 sec ========= 01:37:24 (1789796244) [15139.811138] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 01:37:25 (1789796245) === [15142.017190] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 01:37:27 (1789796247) === [15160.783977] Key type lgssc unregistered [15160.975493] LNet: 324136:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15160.993758] LNetError: 324136:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15161.009046] LNet: Removed LNI 192.168.203.45@tcp [15161.467764] Key type .llcrypt unregistered [15161.472327] Key type ._llcrypt unregistered