[ 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 435017132 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524584K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001010] APIC: Switch to symmetric I/O mode setup [ 0.003150] x2apic enabled [ 0.004008] Switched APIC routing to physical x2apic. [ 0.005015] kvm-guest: setup PV IPIs [ 0.008316] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.009022] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010013] pid_max: default: 32768 minimum: 301 [ 0.011127] LSM: Security Framework initializing [ 0.012057] Yama: becoming mindful. [ 0.013043] SELinux: Initializing. [ 0.015063] *** VALIDATE selinux *** [ 0.022785] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.028618] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.029150] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030105] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031114] *** VALIDATE tmpfs *** [ 0.032445] *** VALIDATE proc *** [ 0.033246] *** VALIDATE cgroup *** [ 0.034010] *** VALIDATE cgroup2 *** [ 0.036084] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.037152] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.038011] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.039026] Spectre V2 : User space: Vulnerable [ 0.040009] Speculative Store Bypass: Vulnerable [ 0.042963] debug: unmapping init [mem 0xffffffff84a59000-0xffffffff84a60fff] [ 0.044791] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.045700] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.046026] ... version: 2 [ 0.047012] ... bit width: 48 [ 0.048015] ... generic registers: 4 [ 0.049013] ... value mask: 0000ffffffffffff [ 0.050016] ... max period: 00007fffffffffff [ 0.051012] ... fixed-purpose events: 3 [ 0.052013] ... event mask: 000000070000000f [ 0.053310] rcu: Hierarchical SRCU implementation. [ 0.055443] smp: Bringing up secondary CPUs ... [ 0.056571] x86: Booting SMP configuration: [ 0.057034] .... node #0, CPUs: #1 #2 #3 [ 0.061011] smp: Brought up 1 node, 4 CPUs [ 0.063016] smpboot: Max logical packages: 1 [ 0.064012] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.135017] node 0 deferred pages initialised in 68ms [ 0.138038] devtmpfs: initialized [ 0.139210] x86/mm: Memory block size: 128MB [ 0.141442] gcov: version magic: 0x41383552 [ 0.144255] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.145070] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.146234] pinctrl core: initialized pinctrl subsystem [ 0.147173] [ 0.147678] ************************************************************* [ 0.148024] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.149011] ** ** [ 0.150013] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.151011] ** ** [ 0.152012] ** This means that this kernel is built to expose internal ** [ 0.153011] ** IOMMU data structures, which may compromise security on ** [ 0.154012] ** your system. ** [ 0.155014] ** ** [ 0.156021] ** If you see this message and you are not debugging the ** [ 0.157011] ** kernel, report this immediately to your vendor! ** [ 0.158014] ** ** [ 0.159011] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.160012] ************************************************************* [ 0.161403] NET: Registered protocol family 16 [ 0.162000] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.165053] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.167055] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.170159] cpuidle: using governor menu [ 0.174153] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.176540] PCI: Using configuration type 1 for base access [ 0.178120] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.188115] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.190033] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.193084] cryptd: max_cpu_qlen set to 1000 [ 0.196236] ACPI: Added _OSI(Module Device) [ 0.198021] ACPI: Added _OSI(Processor Device) [ 0.199012] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.201013] ACPI: Added _OSI(Processor Aggregator Device) [ 0.205029] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.211599] ACPI: Interpreter enabled [ 0.212060] ACPI: PM: (supports S0 S3 S4 S5) [ 0.214013] ACPI: Using IOAPIC for interrupt routing [ 0.215095] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.218379] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.229073] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.232064] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.234022] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.236107] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.242597] acpiphp: Slot [2] registered [ 0.244139] acpiphp: Slot [5] registered [ 0.245067] acpiphp: Slot [6] registered [ 0.246183] acpiphp: Slot [3] registered [ 0.248155] acpiphp: Slot [4] registered [ 0.249131] acpiphp: Slot [7] registered [ 0.251122] acpiphp: Slot [8] registered [ 0.252086] acpiphp: Slot [9] registered [ 0.254097] acpiphp: Slot [10] registered [ 0.255067] acpiphp: Slot [11] registered [ 0.257111] acpiphp: Slot [12] registered [ 0.258119] acpiphp: Slot [13] registered [ 0.260106] acpiphp: Slot [14] registered [ 0.262116] acpiphp: Slot [15] registered [ 0.263100] acpiphp: Slot [16] registered [ 0.265141] acpiphp: Slot [17] registered [ 0.266165] acpiphp: Slot [18] registered [ 0.267143] acpiphp: Slot [19] registered [ 0.269144] acpiphp: Slot [20] registered [ 0.270160] acpiphp: Slot [21] registered [ 0.272143] acpiphp: Slot [22] registered [ 0.274137] acpiphp: Slot [23] registered [ 0.275147] acpiphp: Slot [24] registered [ 0.277135] acpiphp: Slot [25] registered [ 0.279177] acpiphp: Slot [26] registered [ 0.280111] acpiphp: Slot [27] registered [ 0.282112] acpiphp: Slot [28] registered [ 0.283140] acpiphp: Slot [29] registered [ 0.285110] acpiphp: Slot [30] registered [ 0.286092] acpiphp: Slot [31] registered [ 0.287063] PCI host bridge to bus 0000:00 [ 0.289021] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.291019] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.293024] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.295022] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.298045] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.301053] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.305214] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.308131] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.312458] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.320032] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.324112] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.328038] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.330026] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.333026] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.336664] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.339864] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.342045] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.344929] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.348975] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.360016] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.364015] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.371030] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.379021] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.390022] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.411021] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.421754] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.426972] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.438000] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.452027] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.463420] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.465527] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.468456] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.471401] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.473248] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.479185] iommu: Default domain type: Passthrough [ 0.481600] SCSI subsystem initialized [ 0.483155] ACPI: bus type USB registered [ 0.484113] usbcore: registered new interface driver usbfs [ 0.486083] usbcore: registered new interface driver hub [ 0.488108] usbcore: registered new device driver usb [ 0.490160] pps_core: LinuxPPS API ver. 1 registered [ 0.492012] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.496058] PTP clock support registered [ 0.498137] EDAC MC: Ver: 3.0.0 [ 0.500140] PCI: Using ACPI for IRQ routing [ 0.501555] NetLabel: Initializing [ 0.503019] NetLabel: domain hash size = 128 [ 0.504010] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.507077] NetLabel: unlabeled traffic allowed by default [ 0.509172] vgaarb: loaded [ 0.510294] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.512016] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.517363] clocksource: Switched to clocksource kvm-clock [ 0.625804] VFS: Disk quotas dquot_6.6.0 [ 0.627659] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.630497] *** VALIDATE ramfs *** [ 0.631898] *** VALIDATE hugetlbfs *** [ 0.634392] pnp: PnP ACPI init [ 0.637077] pnp: PnP ACPI: found 6 devices [ 0.657223] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.660748] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.663400] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.665760] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.668119] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.670314] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.673155] NET: Registered protocol family 2 [ 0.675641] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.681153] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.684561] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.690515] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.694351] TCP: Hash tables configured (established 65536 bind 65536) [ 0.697459] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.700831] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.704531] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.710099] NET: Registered protocol family 1 [ 0.712636] RPC: Registered named UNIX socket transport module. [ 0.714564] RPC: Registered udp transport module. [ 0.716051] RPC: Registered tcp transport module. [ 0.717850] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.720779] NET: Registered protocol family 44 [ 0.723206] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.725483] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.728341] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.730918] PCI: CLS 0 bytes, default 64 [ 0.732867] Unpacking initramfs... [ 2.190371] debug: unmapping init [mem 0xffff98e2bcc64000-0xffff98e2bffcffff] [ 2.194710] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.197117] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.201279] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.724248] Initialise system trusted keyrings [ 2.726204] Key type blacklist registered [ 2.728632] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.737285] zbud: loaded [ 2.740551] *** VALIDATE nfs *** [ 2.742033] *** VALIDATE nfs4 *** [ 2.744203] pstore: using deflate compression [ 2.748066] Platform Keyring initialized [ 2.862990] NET: Registered protocol family 38 [ 2.864852] Key type asymmetric registered [ 2.868689] Asymmetric key parser 'x509' registered [ 2.870104] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.872501] io scheduler mq-deadline registered [ 2.874748] io scheduler kyber registered [ 2.876317] io scheduler bfq registered [ 2.878227] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.881589] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.884132] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.886498] ACPI: Power Button [PWRF] [ 2.891865] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.898672] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.917042] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.947319] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.980170] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.986900] Non-volatile memory driver v1.3 [ 2.988357] Linux agpgart interface v0.103 [ 3.020496] virtio_blk virtio1: [vda] 146144 512-byte logical blocks (74.8 MB/71.4 MiB) [ 3.023446] vda: detected capacity change from 0 to 74825728 [ 3.039936] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.043397] vdb: detected capacity change from 0 to 1073741824 [ 3.049596] libphy: Fixed MDIO Bus: probed [ 3.054680] usbcore: registered new interface driver usbserial_generic [ 3.057238] usbserial: USB Serial support registered for generic [ 3.059345] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.063763] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.065337] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.067703] mousedev: PS/2 mouse device common for all mice [ 3.070903] rtc_cmos 00:05: RTC can wake from S4 [ 3.074212] rtc_cmos 00:05: registered as rtc0 [ 3.074324] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.075452] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.081043] intel_pstate: CPU model not supported [ 3.082641] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.086620] hid: raw HID events driver (C) Jiri Kosina [ 3.087615] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.088779] usbcore: registered new interface driver usbhid [ 3.092862] usbhid: USB HID core driver [ 3.094612] drop_monitor: Initializing network drop monitor service [ 3.096423] Initializing XFRM netlink socket [ 3.098085] NET: Registered protocol family 10 [ 3.100738] Segment Routing with IPv6 [ 3.102317] NET: Registered protocol family 17 [ 3.104607] mpls_gso: MPLS GSO support [ 3.111226] RAS: Correctable Errors collector initialized. [ 3.113442] AVX version of gcm_enc/dec engaged. [ 3.114981] AES CTR mode by8 optimization enabled [ 3.195090] sched_clock: Marking stable (3195037612, 0)->(4114142593, -919104981) [ 3.198803] registered taskstats version 1 [ 3.200891] Loading compiled-in X.509 certificates [ 3.203219] zswap: loaded using pool lzo/zbud [ 3.231593] Key type big_key registered [ 3.243802] Key type encrypted registered [ 3.245270] ima: No TPM chip found, activating TPM-bypass! [ 3.247387] ima: Allocated hash algorithm: sha1 [ 3.249946] ima: No architecture policies found [ 3.251794] evm: Initialising EVM extended attributes: [ 3.253834] evm: security.selinux [ 3.255007] evm: security.ima [ 3.255904] evm: security.capability [ 3.257369] evm: HMAC attrs: 0x1 [ 3.259665] rtc_cmos 00:05: setting system clock to 2026-08-24 23:17:54 UTC (1787613474) [ 3.265393] debug: unmapping init [mem 0xffffffff85a03000-0xffffffff85bfffff] [ 3.269129] debug: unmapping init [mem 0xffffffff84782000-0xffffffff84a58fff] [ 3.279073] Write protecting the kernel read-only data: 28672k [ 3.283030] debug: unmapping init [mem 0xffffffff82e03000-0xffffffff82ffffff] [ 3.286319] debug: unmapping init [mem 0xffffffff83714000-0xffffffff837fffff] [ 3.317779] 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.326937] systemd[1]: Detected virtualization kvm. [ 3.328769] systemd[1]: Detected architecture x86-64. [ 3.330338] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.354936] systemd[1]: No hostname configured. [ 3.356517] systemd[1]: Set hostname to . [ 3.358600] random: systemd: uninitialized urandom read (16 bytes read) [ 3.360880] systemd[1]: Initializing machine ID from random generator. [ 3.404406] random: ln: uninitialized urandom read (6 bytes read) [ 3.496864] random: systemd: uninitialized urandom read (16 bytes read) [ 3.499733] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 3.504069] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 3.510295] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Slices. Starting Apply Kernel Variables... [ OK ] Listening on udev Control Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Timers. [ OK ] Reached target Paths. Starting Create Volatile Files and Directories... [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Initrd Root Device. Starting Setup Virtual Console... [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Sockets. Starting Journal Service... [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Setup Virtual Console. 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.175584] device-mapper: uevent: version 1.0.3 [ 4.177854] 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.998136] virtio_net virtio0 ens2: renamed from eth0 [ 5.066207] scsi host0: ata_piix [ 5.125236] scsi host1: ata_piix [ 5.138673] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.142053] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.241512] random: fast init done [ 9.378973] dracut-initqueue[590]: RTNETLINK answers: File exists [ 9.768560] random: crng init done [ 9.770161] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 10.215906] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ 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 Apply Kernel Variables. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Slices. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.346650] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.639218] SELinux: Disabled at runtime. [ 11.703275] 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.712051] systemd[1]: Detected virtualization kvm. [ 11.713755] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 13.003646] systemd[1]: initrd-switch-root.service: Succeeded. [ 13.014627] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 13.027941] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 13.037366] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 13.041930] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 13.081113] systemd[1]: Starting Journal Service... Starting Journal Service... [ 13.145927] systemd[1]: Listening on Process Core Dump Socket. [ OK ] Listening on Process Core Dump Socket. Mounting POSIX Message Queue File System... Starting Remount Root and Kernel File Systems... Mounting Kernel Debug File System... [ OK ] Reached target rpc_pipefs.target. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Created slice system-getty.slice. [ OK ] Created slice User and Session Slice. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Created slice system-serial\x2dgetty.slice. Activating swap /dev/disk/by-label/SWAP... [ OK ] Reached target Slices. [ OK ] Reached target RPC Port Mapper. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Created slice system-sshd\x2dkeygen.slice. Starting Apply Kernel Variables... [ 13.662207] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Mounting Huge Pages File System... [ OK ] Stopped target Initrd File Systems. [ OK ] Started Journal Service. [ 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 ] Mounted Kernel Debug File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Huge Pages File System. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started udev Coldplug all Devices. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... [ OK ] Mounted /mnt. [ 14.443502] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 15.393849] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 15.410624] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 15.814116] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 15.881132] EDAC sbridge: Ver: 1.1.2 [ 19.419528] Key type dns_resolver registered [ 20.165245] NFS: Registering the id_resolver key type [ 20.168138] Key type id_resolver registered [ 20.170310] Key type id_legacy registered [* ] A start job is running for Configur…-only root support (7s / no limit) [** ] A start job is running for Configur…-only root support (7s / no limit) [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ 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 ] Reached target Basic System. [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... Starting Login Service... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started dnf makecache --timer. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Reached target sshd-keygen.target. Starting Restore /run/initramfs on shutdown... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg620-client login: [ 87.874274] libcfs: loading out-of-tree module taints kernel. [ 88.250402] Key type ._llcrypt registered [ 88.257768] Key type .llcrypt registered [ 88.901396] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 88.920034] alg: No test for adler32 (adler32-zlib) [ 90.651924] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [ 91.890181] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [ 93.697102] Key type lgssc registered [ 95.993311] Lustre: Echo OBD driver; http://www.lustre.org/ [ 139.789413] hrtimer: interrupt took 10382299 ns [ 292.240909] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 297.869782] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 310.867627] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing check_logdir /tmp/testlogs/ [ 316.405247] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing yml_node [ 317.924120] Lustre: lustre-OST0000-osc-ffff98e309281800: disconnect after 23s idle [ 321.162542] Lustre: DEBUG MARKER: Client: 2.17.57.80 [ 323.583249] Lustre: DEBUG MARKER: MDS: 2.17.57.80 [ 326.614466] Lustre: DEBUG MARKER: OSS: 2.17.57.80 [ 328.919161] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Mon Aug 24 19:23:18 EDT 2026 [ 346.778080] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 348.302333] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 351.740125] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 362.096088] Lustre: Unmounted lustre-client [ 477.201540] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 483.127370] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 510.041722] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 550.159716] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 19:26:59 (1787614019) [ 552.662227] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 554.722776] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 19:27:04 (1787614024) [ 591.265835] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 597.478996] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 619.445842] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 620.066173] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 628.846370] Lustre: Unmounted lustre-client [ 665.676914] Key type lgssc unregistered [ 666.048074] LNet: 15042:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 666.061131] LNetError: 15042:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 667.130420] LNet: Removed LNI 192.168.206.20@tcp [ 668.095671] Key type .llcrypt unregistered [ 668.099885] Key type ._llcrypt unregistered [ 684.932603] Key type ._llcrypt registered [ 684.940088] Key type .llcrypt registered [ 686.673836] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 19:29:15 (1787614155) [ 688.960179] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 688.986496] alg: No test for adler32 (adler32-zlib) [ 690.238905] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [ 690.633335] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [ 692.416878] Key type lgssc registered [ 694.238662] Lustre: Echo OBD driver; http://www.lustre.org/ [ 770.439366] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 859.379731] Lustre: Unmounted lustre-client [ 889.115940] Key type lgssc unregistered [ 889.398354] LNet: 18894:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 889.406201] LNetError: 18894:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 889.426569] LNet: Removed LNI 192.168.206.20@tcp [ 890.174975] Key type .llcrypt unregistered [ 890.181170] Key type ._llcrypt unregistered [ 900.271863] Key type ._llcrypt registered [ 900.276244] Key type .llcrypt registered [ 901.516628] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 19:32:51 (1787614371) [ 904.260295] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 904.342548] alg: No test for adler32 (adler32-zlib) [ 905.416508] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [ 905.712941] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [ 907.441680] Key type lgssc registered [ 908.818816] Lustre: Echo OBD driver; http://www.lustre.org/ [ 958.064968] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 963.525444] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 985.050376] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 987.835183] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 998.952376] Lustre: Unmounted lustre-client [ 1026.687471] Key type lgssc unregistered [ 1027.098154] LNet: 22496:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1027.110568] LNetError: 22496:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1027.144920] LNet: Removed LNI 192.168.206.20@tcp [ 1027.981221] Key type .llcrypt unregistered [ 1027.985700] Key type ._llcrypt unregistered [ 1037.663706] Key type ._llcrypt registered [ 1037.665967] Key type .llcrypt registered [ 1039.211800] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 19:35:08 (1787614508) [ 1041.706687] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1041.724590] alg: No test for adler32 (adler32-zlib) [ 1042.889529] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [ 1043.220326] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [ 1044.936716] Key type lgssc registered [ 1046.375508] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1095.799420] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1102.184177] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1124.727701] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1127.409568] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 1136.285112] Lustre: Unmounted lustre-client [ 1163.283305] Key type lgssc unregistered [ 1163.540488] LNet: 25685:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1163.552201] LNetError: 25685:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1163.569732] LNet: Removed LNI 192.168.206.20@tcp [ 1164.303086] Key type .llcrypt unregistered [ 1164.305879] Key type ._llcrypt unregistered [ 1179.282321] Key type ._llcrypt registered [ 1179.284086] Key type .llcrypt registered [ 1180.475867] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 19:37:30 (1787614650) [ 1182.621799] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1182.661043] alg: No test for adler32 (adler32-zlib) [ 1183.704233] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [ 1183.949538] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [ 1185.785707] Key type lgssc registered [ 1187.205179] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1237.664780] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1243.555558] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1262.032090] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1264.929401] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 1275.370400] Lustre: lustre-MDT0000-mdc-ffff98e3119bf800: Connection to lustre-MDT0000 (at 192.168.206.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1285.611655] Lustre: lustre-MDT0001-mdc-ffff98e3119bf800: Connection to lustre-MDT0001 (at 192.168.206.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1295.840300] Lustre: 26642:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787614751/real 1787614751] req@00000000fb0a8675 x1874449824487552/t0(0) o400->MGC192.168.206.120@tcp@192.168.206.120@tcp:26/25 lens 224/224 e 0 to 1 dl 1787614767 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1295.871889] LustreError: MGC192.168.206.120@tcp: Connection to MGS (at 192.168.206.120@tcp) was lost; in progress operations using this service will fail [ 1299.127516] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1309.464378] Lustre: Unmounted lustre-client [ 1323.417544] Key type lgssc unregistered [ 1323.741668] LNet: 29341:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1323.762749] LNetError: 29341:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1324.838813] LNet: Removed LNI 192.168.206.20@tcp [ 1325.453564] Key type .llcrypt unregistered [ 1325.458918] Key type ._llcrypt unregistered [ 1342.410439] Key type ._llcrypt registered [ 1342.413224] Key type .llcrypt registered [ 1343.899729] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 19:40:13 (1787614813) [ 1346.087939] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1346.105313] alg: No test for adler32 (adler32-zlib) [ 1347.223114] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [ 1347.499785] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [ 1349.152195] Key type lgssc registered [ 1350.436850] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1398.777098] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1405.083835] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1426.712868] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1429.401758] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 1434.614948] Lustre: lustre-OST0000-osc-ffff98e3079a1800: Connection to lustre-OST0000 (at 192.168.206.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1441.318684] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1451.713262] Lustre: Unmounted lustre-client [ 1478.960494] Key type lgssc unregistered [ 1479.331299] LNet: 32951:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1479.351785] LNetError: 32951:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1479.371389] LNet: Removed LNI 192.168.206.20@tcp [ 1480.173269] Key type .llcrypt unregistered [ 1480.176861] Key type ._llcrypt unregistered [ 1496.801282] Key type ._llcrypt registered [ 1496.803448] Key type .llcrypt registered [ 1497.948893] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 19:42:47 (1787614967) [ 1500.311583] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1500.335916] alg: No test for adler32 (adler32-zlib) [ 1501.336215] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [ 1501.614802] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [ 1503.312239] Key type lgssc registered [ 1504.381169] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1556.694366] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1562.753875] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1584.891224] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1587.817465] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 1591.244305] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1594.901598] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1606.535454] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-08-2419h44m36s [ 1608.074342] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1613.288433] Lustre: lustre-MDT0000-mdc-ffff98e30a2dd000: Connection to lustre-MDT0000 (at 192.168.206.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1617.813503] LustreError: 36738:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff98e30a2dd000: can't stat MDS #0: rc = -19 [ 1619.428311] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1630.695890] LustreError: MGC192.168.206.120@tcp: Connection to MGS (at 192.168.206.120@tcp) was lost; in progress operations using this service will fail [ 1630.720868] Lustre: Evicted from MGS (at 192.168.206.120@tcp) after server handle changed from 0x35e485cedb0e2a2c to 0x35e485cedb0e2cf6 [ 1630.731309] Lustre: MGC192.168.206.120@tcp: Connection restored to 192.168.206.120@tcp (at 192.168.206.120@tcp) [ 1631.781441] LustreError: lustre-MDT0000-mdc-ffff98e30a2dd000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1631.801459] Lustre: lustre-MDT0000-mdc-ffff98e30a2dd000: Connection restored to 192.168.206.120@tcp (at 192.168.206.120@tcp) [ 1635.653468] Lustre: DEBUG MARKER: Wait for df (36738) ... [ 1637.159811] Lustre: DEBUG MARKER: done [ 1639.306287] Lustre: Unmounted lustre-client [ 1675.301885] Key type lgssc unregistered [ 1675.613275] LNet: 37756:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1675.627305] LNetError: 37756:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1675.654804] LNet: Removed LNI 192.168.206.20@tcp [ 1676.346760] Key type .llcrypt unregistered [ 1676.354387] Key type ._llcrypt unregistered [ 1706.033513] Key type ._llcrypt registered [ 1706.036576] Key type .llcrypt registered [ 1707.164749] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 19:46:17 (1787615177) [ 1709.464717] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1709.543647] alg: No test for adler32 (adler32-zlib) [ 1710.704667] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [ 1710.935370] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [ 1712.593341] Key type lgssc registered [ 1713.623872] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1761.297923] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1766.965037] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1787.355319] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1790.014876] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 1795.121543] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-08-2419h47m44s [ 1796.886454] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1803.813149] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1806.283346] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1826.784350] Lustre: 41909:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787615277/real 1787615277] req@0000000019951dcd x1874450377087744/t0(0) o101->lustre-MDT0000-mdc-ffff98e3054a2800@192.168.206.120@tcp:12/10 lens 576/1152 e 0 to 1 dl 1787615297 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1826.813380] Lustre: lustre-MDT0000-mdc-ffff98e3054a2800: Connection to lustre-MDT0000 (at 192.168.206.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1826.859033] Lustre: lustre-MDT0000-mdc-ffff98e3054a2800: Connection restored to 192.168.206.120@tcp (at 192.168.206.120@tcp) [ 1848.596595] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1851.836224] Lustre: DEBUG MARKER: done [ 1855.669587] Lustre: Unmounted lustre-client [ 1898.877917] Key type lgssc unregistered [ 1899.115623] LNet: 42794:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1899.127556] LNetError: 42794:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1899.141881] LNet: Removed LNI 192.168.206.20@tcp [ 1899.805533] Key type .llcrypt unregistered [ 1899.808776] Key type ._llcrypt unregistered [ 1927.789207] Key type ._llcrypt registered [ 1927.795180] Key type .llcrypt registered [ 1928.895326] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 19:49:58 (1787615398) [ 1931.308626] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1933.694813] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 19:50:03 (1787615403) [ 1937.958322] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1937.975231] alg: No test for adler32 (adler32-zlib) [ 1939.330855] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [ 1939.810132] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [ 1941.544176] Key type lgssc registered [ 1943.403089] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1970.816693] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 19:50:40 (1787615440) [ 2003.680633] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2008.869397] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2028.589310] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2030.206782] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 2033.755465] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 2037.225329] Lustre: Unmounted lustre-client [ 2053.798888] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 2101.104931] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2106.294766] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2106.882333] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 2110.968369] Lustre: Unmounted lustre-client [ 2169.150891] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2175.081694] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2175.704915] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 2179.433299] Lustre: Unmounted lustre-client [ 2195.838548] Lustre: DEBUG MARKER: files compared the same [ 2219.370380] Key type lgssc unregistered [ 2219.619400] LNet: 50410:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2219.633341] LNetError: 50410:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2219.669687] LNet: Removed LNI 192.168.206.20@tcp [ 2220.586081] Key type .llcrypt unregistered [ 2220.587666] Key type ._llcrypt unregistered [ 2236.041927] Key type ._llcrypt registered [ 2236.045582] Key type .llcrypt registered [ 2237.569841] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 19:55:07 (1787615707) [ 2240.418608] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2240.442446] alg: No test for adler32 (adler32-zlib) [ 2241.557690] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [ 2241.833513] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [ 2243.473438] Key type lgssc registered [ 2245.026407] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2291.842352] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2297.316805] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2316.895777] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2318.530699] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 2322.294909] Lustre: Unmounted lustre-client [ 2348.298860] Key type lgssc unregistered [ 2348.557992] LNet: 53991:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2348.590260] LNetError: 53991:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2349.615587] LNet: Removed LNI 192.168.206.20@tcp [ 2350.295746] Key type .llcrypt unregistered [ 2350.299935] Key type ._llcrypt unregistered [ 2364.449406] Key type ._llcrypt registered [ 2364.451374] Key type .llcrypt registered [ 2365.778413] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 19:57:15 (1787615835) [ 2367.701146] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2367.741383] alg: No test for adler32 (adler32-zlib) [ 2368.752388] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [ 2369.052114] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [ 2370.776242] Key type lgssc registered [ 2371.859883] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2456.328524] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2487.773489] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2494.065825] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2536.873636] Key type lgssc unregistered [ 2537.156842] LNet: 57485:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2537.167385] LNetError: 57485:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2537.185724] LNet: Removed LNI 192.168.206.20@tcp [ 2537.949677] Key type .llcrypt unregistered [ 2537.954557] Key type ._llcrypt unregistered [ 2555.088184] Key type ._llcrypt registered [ 2555.090652] Key type .llcrypt registered [ 2556.436888] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 20:00:26 (1787616026) [ 2559.759043] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2559.830483] alg: No test for adler32 (adler32-zlib) [ 2560.964820] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [ 2561.226932] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [ 2562.889625] Key type lgssc registered [ 2564.229393] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2629.247463] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 2636.714177] Lustre: Unmounted lustre-client [ 2680.575484] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 20:02:30 (1787616150) [ 2791.907122] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2808.652577] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 2815.829762] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2815.848863] LustreError: 63975:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff98e310e8f000: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2815.881439] Lustre: 58469:0:(llite_lib.c:4342:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.206.120@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2826.227122] Lustre: Unmounted lustre-client [ 2884.932683] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 20:05:54 (1787616354) [ 2898.782963] Key type lgssc unregistered [ 2899.126935] LNet: 65192:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2899.135226] LNetError: 65192:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2899.155262] LNet: Removed LNI 192.168.206.20@tcp [ 2899.886294] Key type .llcrypt unregistered [ 2899.895192] Key type ._llcrypt unregistered [ 2914.042619] Key type ._llcrypt registered [ 2914.045070] Key type .llcrypt registered [ 2914.194253] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2914.212971] alg: No test for adler32 (adler32-zlib) [ 2915.399957] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [ 2915.741500] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [ 2917.536735] Key type lgssc registered [ 2918.663109] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2965.515204] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2971.777709] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2992.476973] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2994.133436] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 2998.401342] Lustre: Unmounted lustre-client [ 3026.816705] Key type lgssc unregistered [ 3027.107172] LNet: 68207:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3027.121588] LNetError: 68207:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3027.144534] LNet: Removed LNI 192.168.206.20@tcp [ 3028.073649] Key type .llcrypt unregistered [ 3028.076641] Key type ._llcrypt unregistered [ 3038.691621] Key type ._llcrypt registered [ 3038.696995] Key type .llcrypt registered [ 3039.019458] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3039.035466] alg: No test for adler32 (adler32-zlib) [ 3040.160433] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [ 3040.377400] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [ 3042.072681] Key type lgssc registered [ 3043.637085] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3168.463453] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3174.398919] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3196.141825] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3198.021587] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 3205.956138] Lustre: Unmounted lustre-client [ 3233.644181] Key type lgssc unregistered [ 3233.920974] LNet: 71639:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3233.931245] LNetError: 71639:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3233.946683] LNet: Removed LNI 192.168.206.20@tcp [ 3234.748883] Key type .llcrypt unregistered [ 3234.752233] Key type ._llcrypt unregistered [ 3251.292871] Key type ._llcrypt registered [ 3251.303300] Key type .llcrypt registered [ 3252.963371] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 20:12:02 (1787616722) [ 3255.229510] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3255.250061] alg: No test for adler32 (adler32-zlib) [ 3256.336618] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [ 3256.580665] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [ 3258.240245] Key type lgssc registered [ 3259.379185] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3316.859285] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3322.698835] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3341.949667] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3344.739970] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 3353.519048] Lustre: Unmounted lustre-client [ 3353.989877] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 3358.252158] Lustre: Unmounted lustre-client [ 3385.484496] Key type lgssc unregistered [ 3385.799696] LNet: 75325:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3385.806815] LNetError: 75325:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3385.837587] LNet: Removed LNI 192.168.206.20@tcp [ 3386.680707] Key type .llcrypt unregistered [ 3386.683718] Key type ._llcrypt unregistered [ 3399.398874] Key type ._llcrypt registered [ 3399.434602] Key type .llcrypt registered [ 3399.963902] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3399.993402] alg: No test for adler32 (adler32-zlib) [ 3401.168219] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [ 3401.452388] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [ 3403.217252] Key type lgssc registered [ 3404.334570] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3456.597818] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3462.948176] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3485.234177] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3487.039515] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 3495.896829] Lustre: Unmounted lustre-client [ 3526.553373] Key type lgssc unregistered [ 3526.787386] LNet: 78358:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3526.794471] LNetError: 78358:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3526.831960] LNet: Removed LNI 192.168.206.20@tcp [ 3527.623647] Key type .llcrypt unregistered [ 3527.626649] Key type ._llcrypt unregistered [ 3541.379903] Key type ._llcrypt registered [ 3541.385195] Key type .llcrypt registered [ 3542.632121] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 20:16:52 (1787617012) [ 3544.938671] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3545.060774] alg: No test for adler32 (adler32-zlib) [ 3546.106867] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [ 3546.306184] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [ 3548.000255] Key type lgssc registered [ 3548.979874] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3598.507183] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3604.943309] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3629.568813] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3631.525548] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 3639.567748] Lustre: lustre: root_squash is set to 0:0 [ 3649.612987] Lustre: lustre: nosquash_nids is cleared [ 3649.642822] Lustre: lustre: root_squash is set to 500:500 [ 3664.960387] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.206.20@tcp 192.168.0.[2,10]@tcp [ 3675.670865] Lustre: lustre: nosquash_nids set to 192.168.206.20/24@tcp [ 3679.068971] Lustre: Unmounted lustre-client [ 3715.935992] Key type lgssc unregistered [ 3716.206889] LNet: 82344:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3716.213604] LNetError: 82344:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3716.227562] LNet: Removed LNI 192.168.206.20@tcp [ 3717.048463] Key type .llcrypt unregistered [ 3717.054377] Key type ._llcrypt unregistered [ 3732.500472] Key type ._llcrypt registered [ 3732.501910] Key type .llcrypt registered [ 3733.743442] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 20:20:03 (1787617203) [ 3736.269188] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3737.832479] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 20:20:07 (1787617207) [ 3740.222660] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3740.246629] alg: No test for adler32 (adler32-zlib) [ 3741.416762] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [ 3741.752992] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [ 3743.528509] Key type lgssc registered [ 3745.002472] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3799.506661] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3806.159609] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3828.586319] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3830.347760] Lustre: lustre: root_squash is set to 500:500 [ 3830.388979] Lustre: lustre: nosquash_nids set to 192.168.206.20/24@tcp [ 3831.489280] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 3837.394245] Lustre: Unmounted lustre-client [ 3865.452373] Key type lgssc unregistered [ 3865.681527] LNet: 86315:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3865.689881] LNetError: 86315:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3865.708773] LNet: Removed LNI 192.168.206.20@tcp [ 3866.364490] Key type .llcrypt unregistered [ 3866.369086] Key type ._llcrypt unregistered [ 3881.235696] Key type ._llcrypt registered [ 3881.238258] Key type .llcrypt registered [ 3882.729818] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 3884.594275] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 20:22:34 (1787617354) [ 3903.422378] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3903.677035] alg: No test for adler32 (adler32-zlib) [ 3904.901713] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [ 3905.222218] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [ 3906.897103] Key type lgssc registered [ 3908.094572] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3986.975365] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3993.397474] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4017.382040] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4083.304083] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4088.097263] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4110.617969] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4120.480299] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 4156.268688] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff98e308a22000.ost_server_uuid 50 [ 4157.922213] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff98e308a22000.ost_server_uuid in IDLE state after 0 sec [ 4158.347513] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 4162.992185] Lustre: Unmounted lustre-client [ 4166.160775] Lustre: Unmounted lustre-client [ 4230.233602] Key type lgssc unregistered [ 4230.567501] LNet: 93991:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4230.588435] LNetError: 93991:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4230.609586] LNet: Removed LNI 192.168.206.20@tcp [ 4231.475728] Key type .llcrypt unregistered [ 4231.478855] Key type ._llcrypt unregistered [ 4261.606815] Key type ._llcrypt registered [ 4261.609344] Key type .llcrypt registered [ 4262.716707] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 20:28:52 (1787617732) [ 4279.855351] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4279.877838] alg: No test for adler32 (adler32-zlib) [ 4280.924259] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [ 4281.161118] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [ 4282.816208] Key type lgssc registered [ 4284.148881] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4360.403698] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4365.957288] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4388.684662] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4391.127966] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 4411.874424] Lustre: 95355:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787617867/real 1787617867] req@00000000585e6bde x1874453071926528/t0(0) o400->lustre-OST0000-osc-ffff98e3119bc800@192.168.206.120@tcp:28/4 lens 224/224 e 0 to 1 dl 1787617883 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4411.893339] Lustre: lustre-OST0000-osc-ffff98e3119bc800: Connection to lustre-OST0000 (at 192.168.206.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4416.992579] Lustre: 95356:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787617872/real 1787617872] req@000000004e13c381 x1874453071927040/t0(0) o400->lustre-OST0000-osc-ffff98e3119bc800@192.168.206.120@tcp:28/4 lens 224/224 e 0 to 1 dl 1787617888 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4422.112319] Lustre: 95355:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787617877/real 1787617877] req@000000008d565ffa x1874453071927552/t0(0) o400->lustre-OST0000-osc-ffff98e3119bc800@192.168.206.120@tcp:28/4 lens 224/224 e 0 to 1 dl 1787617893 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4422.184633] Lustre: lustre-OST0000-osc-ffff98e3119bc800: Connection restored to 192.168.206.120@tcp (at 192.168.206.120@tcp) [ 4427.246710] Lustre: lustre-MDT0000-mdc-ffff98e3119bc800: Connection to lustre-MDT0000 (at 192.168.206.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4428.257441] Lustre: 95356:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787617883/real 1787617883] req@00000000bd6775e3 x1874453071928064/t0(0) o400->lustre-OST0000-osc-ffff98e3119bc800@192.168.206.120@tcp:28/4 lens 224/224 e 0 to 1 dl 1787617899 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4442.599798] LustreError: MGC192.168.206.120@tcp: Connection to MGS (at 192.168.206.120@tcp) was lost; in progress operations using this service will fail [ 4442.619070] Lustre: Evicted from MGS (at 192.168.206.120@tcp) after server handle changed from 0xa6d82b2be4b10a43 to 0xa6d82b2be4b10cd5 [ 4442.624479] Lustre: MGC192.168.206.120@tcp: Connection restored to 192.168.206.120@tcp (at 192.168.206.120@tcp) [ 4447.061711] Lustre: lustre-MDT0000-mdc-ffff98e3119bc800: Connection restored to 192.168.206.120@tcp (at 192.168.206.120@tcp) [ 4448.882662] Lustre: Unmounted lustre-client [ 4478.596906] Key type lgssc unregistered [ 4478.913925] LNet: 99660:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4478.940131] LNetError: 99660:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4478.964402] LNet: Removed LNI 192.168.206.20@tcp [ 4479.461950] Key type .llcrypt unregistered [ 4479.465756] Key type ._llcrypt unregistered [ 4492.144834] Key type ._llcrypt registered [ 4492.146703] Key type .llcrypt registered [ 4493.642518] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 20:32:43 (1787617963) [ 4509.348971] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4509.382658] alg: No test for adler32 (adler32-zlib) [ 4510.549379] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [ 4510.843468] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [ 4512.512341] Key type lgssc registered [ 4513.870734] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4545.629883] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4638.266677] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4643.730899] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4666.354482] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4667.967456] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 4669.898845] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 4671.145866] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 20:35:41 (1787618141) [ 4907.605541] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4917.894164] Lustre: Unmounted lustre-client [ 5036.252492] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5042.219257] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5066.501336] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5114.111662] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 20:43:03 (1787618583) [ 5203.675286] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5209.049469] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5230.659375] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5231.177367] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 5235.316389] Lustre: Unmounted lustre-client [ 5258.611332] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 20:45:28 (1787618728) [ 5347.784626] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5352.556731] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5374.127907] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5375.097962] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 5380.184522] Lustre: Unmounted lustre-client [ 5409.344772] Key type lgssc unregistered [ 5409.612589] LNet: 122675:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5409.619829] LNetError: 122675:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5409.640189] LNet: Removed LNI 192.168.206.20@tcp [ 5410.577450] Key type .llcrypt unregistered [ 5410.583315] Key type ._llcrypt unregistered [ 5425.655154] Key type ._llcrypt registered [ 5425.657286] Key type .llcrypt registered [ 5427.363025] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 20:48:16 (1787618896) [ 5429.901590] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5429.963204] alg: No test for adler32 (adler32-zlib) [ 5431.225629] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [ 5431.703659] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [ 5433.448514] Key type lgssc registered [ 5435.926723] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5491.186475] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5498.688662] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5519.117135] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5520.715526] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 5537.932421] Lustre: Unmounted lustre-client [ 5564.286774] Key type lgssc unregistered [ 5564.601265] LNet: 126268:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5564.614688] LNetError: 126268:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5565.672057] LNet: Removed LNI 192.168.206.20@tcp [ 5566.331475] Key type .llcrypt unregistered [ 5566.338920] Key type ._llcrypt unregistered [ 5581.080649] Key type ._llcrypt registered [ 5581.089227] Key type .llcrypt registered [ 5582.511976] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 20:50:52 (1787619052) [ 5585.345918] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5585.383293] alg: No test for adler32 (adler32-zlib) [ 5586.614530] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [ 5586.952487] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [ 5588.664523] Key type lgssc registered [ 5590.411862] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5641.853752] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5646.846769] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5667.955556] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5670.788440] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 5676.010992] Lustre: lustre-OST0000-osc-ffff98e309283000: Connection to lustre-OST0000 (at 192.168.206.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5687.970222] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff98e309283000.ost_server_uuid 50 [ 5689.507872] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 5700.993163] Lustre: Unmounted lustre-client [ 5722.646365] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 20:53:12 (1787619192) [ 5755.644518] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5760.998830] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5782.779464] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5814.242786] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5814.807526] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 5819.880599] Lustre: lustre-OST0000-osc-ffff98e320399800: Connection to lustre-OST0000 (at 192.168.206.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5843.497514] Lustre: Unmounted lustre-client [ 5884.648295] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 20:55:54 (1787619354) [ 5910.929719] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5915.500858] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5939.166745] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5956.718346] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5957.354974] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 5962.747100] Lustre: lustre-OST0000-osc-ffff98e30858d000: Connection to lustre-OST0000 (at 192.168.206.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5977.742934] Lustre: Unmounted lustre-client [ 6020.630879] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 20:58:10 (1787619490) [ 6096.823085] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6102.094371] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6122.112601] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6181.853441] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6185.643777] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6206.571443] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6235.101842] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 6277.708062] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6282.813746] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 3 sec [ 6285.231520] Lustre: Unmounted lustre-client [ 6326.505837] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 21:03:15 (1787619795) [ 6367.259279] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6374.830672] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6398.378826] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6437.383797] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6469.094567] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 6512.799109] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6515.083708] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6528.028129] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6538.382840] Lustre: Unmounted lustre-client [ 6599.111943] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 21:07:48 (1787620068) [ 6630.508367] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6636.749191] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6660.566354] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6661.227819] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 6686.194636] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6688.236892] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6702.518777] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff98e30a2dd000.ost_server_uuid 50 [ 6704.296096] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff98e30a2dd000.ost_server_uuid in IDLE state after 0 sec [ 6706.851834] Lustre: Unmounted lustre-client [ 6707.195298] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6707.203601] Lustre: Skipped 3 previous similar messages [ 6707.290197] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 6708.344345] LustreError: 149697:0:(sec.c:403:import_sec_validate_get()) import 00000000127fd122 (NEW) with no sec [ 6774.991740] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 21:10:44 (1787620244) [ 6810.198253] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6818.132820] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6848.969910] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6870.898963] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6871.441101] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 6871.605515] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 6886.743292] Lustre: Unmounted lustre-client [ 6886.746312] Lustre: Skipped 1 previous similar message [ 6923.361486] Key type lgssc unregistered [ 6923.652538] LNet: 153108:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6923.662864] LNetError: 153108:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6923.687058] LNet: Removed LNI 192.168.206.20@tcp [ 6924.301861] Key type .llcrypt unregistered [ 6924.305737] Key type ._llcrypt unregistered [ 6940.629272] Key type ._llcrypt registered [ 6940.633159] Key type .llcrypt registered [ 6942.011056] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 21:13:31 (1787620411) [ 6943.590149] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6943.764677] alg: No test for adler32 (adler32-zlib) [ 6944.937703] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [ 6945.147562] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [ 6946.800225] Key type lgssc registered [ 6948.267184] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7006.708345] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7012.910550] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7037.011313] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7058.441600] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7058.945562] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7060.108979] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 7066.702282] LustreError: lustre-MDT0001-mdc-ffff98e309280000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 7066.765606] Lustre: lustre-MDT0001-mdc-ffff98e309280000: Connection restored to 192.168.206.120@tcp (at 192.168.206.120@tcp) [ 7074.415454] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7076.589510] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7099.903776] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7110.226150] Lustre: Unmounted lustre-client [ 7142.755477] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 21:16:51 (1787620611) [ 7241.802187] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7248.299165] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7269.657860] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7270.181788] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 7295.456548] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7297.214774] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7299.573272] Lustre: lustre-OST0001-osc-ffff98e308d55000: Connection to lustre-OST0001 (at 192.168.206.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7309.198138] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7319.544994] Lustre: Unmounted lustre-client [ 7352.935090] Key type lgssc unregistered [ 7353.216351] LNet: 162739:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7353.232926] LNetError: 162739:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7353.259298] LNet: Removed LNI 192.168.206.20@tcp [ 7353.863522] Key type .llcrypt unregistered [ 7353.873359] Key type ._llcrypt unregistered [ 7386.220351] Key type ._llcrypt registered [ 7386.224896] Key type .llcrypt registered [ 7388.292361] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 21:20:57 (1787620857) [ 7390.530902] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7390.555621] alg: No test for adler32 (adler32-zlib) [ 7391.669596] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [ 7392.021651] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [ 7393.808174] Key type lgssc registered [ 7395.038459] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7448.479428] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7453.443680] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7477.635114] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7479.815117] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 7532.779640] Lustre: Unmounted lustre-client [ 7576.339483] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7577.186847] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 7612.689501] Lustre: Unmounted lustre-client [ 7639.749732] Key type lgssc unregistered [ 7640.026658] LNet: 167193:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7640.034142] LNetError: 167193:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7640.055455] LNet: Removed LNI 192.168.206.20@tcp [ 7640.850564] Key type .llcrypt unregistered [ 7640.855536] Key type ._llcrypt unregistered [ 7655.559435] Key type ._llcrypt registered [ 7655.563427] Key type .llcrypt registered [ 7656.933404] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 21:25:26 (1787621126) [ 7659.749692] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7659.772730] alg: No test for adler32 (adler32-zlib) [ 7660.829194] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [ 7661.031644] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [ 7662.688561] Key type lgssc registered [ 7663.770636] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7715.108233] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7722.349901] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7745.707582] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7747.332535] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 7766.527198] Lustre: Unmounted lustre-client [ 7793.491155] Key type lgssc unregistered [ 7793.726253] LNet: 170951:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7793.731951] LNetError: 170951:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7793.747731] LNet: Removed LNI 192.168.206.20@tcp [ 7794.402492] Key type .llcrypt unregistered [ 7794.411485] Key type ._llcrypt unregistered [ 7805.921726] Key type ._llcrypt registered [ 7805.931136] Key type .llcrypt registered [ 7806.425106] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7806.439752] alg: No test for adler32 (adler32-zlib) [ 7807.489857] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [ 7807.755948] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [ 7809.456178] Key type lgssc registered [ 7810.463764] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7858.659934] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7864.588753] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7885.364565] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7887.015383] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 7897.063553] Lustre: Unmounted lustre-client [ 7925.769340] Key type lgssc unregistered [ 7926.080085] LNet: 174010:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7926.091672] LNetError: 174010:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7926.130136] LNet: Removed LNI 192.168.206.20@tcp [ 7926.995137] Key type .llcrypt unregistered [ 7927.000645] Key type ._llcrypt unregistered [ 7940.717484] Key type ._llcrypt registered [ 7940.720318] Key type .llcrypt registered [ 7942.229471] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 21:30:11 (1787621411) [ 7944.687161] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7944.713157] alg: No test for adler32 (adler32-zlib) [ 7945.783619] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [ 7946.008977] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [ 7947.664284] Key type lgssc registered [ 7948.727387] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7997.658317] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8003.216684] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8029.398913] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8031.099382] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 8048.698467] Lustre: Unmounted lustre-client [ 8075.971930] Key type lgssc unregistered [ 8076.336758] LNet: 177765:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8076.348912] LNetError: 177765:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8076.379304] LNet: Removed LNI 192.168.206.20@tcp [ 8077.277745] Key type .llcrypt unregistered [ 8077.283862] Key type ._llcrypt unregistered [ 8091.033518] Key type ._llcrypt registered [ 8091.042102] Key type .llcrypt registered [ 8091.628550] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8091.648242] alg: No test for adler32 (adler32-zlib) [ 8092.772766] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [ 8093.086687] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [ 8094.984432] Key type lgssc registered [ 8096.671723] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8155.966550] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8162.241962] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8184.700136] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8186.642397] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 8194.795617] Lustre: Unmounted lustre-client [ 8223.428531] Key type lgssc unregistered [ 8223.713235] LNet: 180830:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8223.730268] LNetError: 180830:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8223.750582] LNet: Removed LNI 192.168.206.20@tcp [ 8224.646893] Key type .llcrypt unregistered [ 8224.651175] Key type ._llcrypt unregistered [ 8241.722310] Key type ._llcrypt registered [ 8241.728637] Key type .llcrypt registered [ 8242.946784] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 21:35:12 (1787621712) [ 8266.710167] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8266.819650] alg: No test for adler32 (adler32-zlib) [ 8267.897721] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [ 8268.078839] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [ 8269.802685] Key type lgssc registered [ 8270.925541] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8356.417979] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8363.149416] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8388.770331] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8433.951850] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 21:38:23 (1787621903) [ 8470.458664] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8476.666703] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8500.295918] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8503.030395] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 8508.920117] Lustre: Unmounted lustre-client [ 8530.906056] Key type lgssc unregistered [ 8531.185414] LNet: 187928:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8531.200644] LNetError: 187928:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8531.226966] LNet: Removed LNI 192.168.206.20@tcp [ 8531.925328] Key type .llcrypt unregistered [ 8531.930693] Key type ._llcrypt unregistered [ 8549.733297] Key type ._llcrypt registered [ 8549.737192] Key type .llcrypt registered [ 8551.412255] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 8553.093966] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 21:40:22 (1787622022) [ 8571.284937] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8571.336887] alg: No test for adler32 (adler32-zlib) [ 8572.423192] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [ 8572.606731] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [ 8574.264185] Key type lgssc registered [ 8575.468716] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8666.331845] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8672.369491] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8698.062677] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8720.356396] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8723.193891] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 8748.510564] Lustre: Unmounted lustre-client [ 8898.462806] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8904.842723] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8931.373566] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8976.939378] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 21:47:26 (1787622446) [ 8978.548550] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 8980.648815] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 21:47:30 (1787622450) [ 9079.652888] Key type lgssc unregistered [ 9079.940882] LNet: 199373:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9079.965307] LNetError: 199373:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9079.993038] LNet: Removed LNI 192.168.206.20@tcp [ 9081.128347] Key type .llcrypt unregistered [ 9081.138838] Key type ._llcrypt unregistered [ 9098.466677] Key type ._llcrypt registered [ 9098.470357] Key type .llcrypt registered [ 9099.970454] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 21:49:29 (1787622569) [ 9144.097158] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9144.196804] alg: No test for adler32 (adler32-zlib) [ 9145.355450] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [ 9145.750073] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [ 9147.528302] Key type lgssc registered [ 9148.734280] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9189.973583] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9196.440786] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9236.180072] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9267.171341] Key type lgssc unregistered [ 9267.517967] LNet: 203108:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9267.533028] LNetError: 203108:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9267.551906] LNet: Removed LNI 192.168.206.20@tcp [ 9268.340792] Key type .llcrypt unregistered [ 9268.343651] Key type ._llcrypt unregistered [ 9284.220386] Key type ._llcrypt registered [ 9284.224957] Key type .llcrypt registered [ 9286.290430] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 21:52:35 (1787622755) [ 9288.857666] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9288.927709] alg: No test for adler32 (adler32-zlib) [ 9290.087861] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [ 9290.442193] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [ 9292.136202] Key type lgssc registered [ 9293.645939] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9352.648854] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9358.651917] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9382.591579] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9385.248990] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [ 9395.709788] Lustre: lustre-MDT0000-mdc-ffff98e3048d4000: Connection to lustre-MDT0000 (at 192.168.206.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9405.940649] Lustre: lustre-MDT0001-mdc-ffff98e3048d4000: Connection to lustre-MDT0001 (at 192.168.206.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9417.184327] Lustre: 204097:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787622872/real 1787622872] req@0000000069b26ac8 x1874458324320384/t0(0) o400->MGC192.168.206.120@tcp@192.168.206.120@tcp:26/25 lens 224/224 e 0 to 1 dl 1787622888 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9417.216886] LustreError: MGC192.168.206.120@tcp: Connection to MGS (at 192.168.206.120@tcp) was lost; in progress operations using this service will fail [ 9427.506902] Lustre: Evicted from MGS (at 192.168.206.120@tcp) after server handle changed from 0x10749b8b8d8d41a0 to 0x10749b8b8d8d5ec6 [ 9427.519286] Lustre: MGC192.168.206.120@tcp: Connection restored to 192.168.206.120@tcp (at 192.168.206.120@tcp) [ 9452.019212] LustreError: lustre-MDT0000-mdc-ffff98e3048d4000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 9452.036894] Lustre: lustre-MDT0000-mdc-ffff98e3048d4000: Connection restored to 192.168.206.120@tcp (at 192.168.206.120@tcp) [ 9455.388974] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9457.438479] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9463.737712] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9465.290481] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9468.173309] Lustre: Unmounted lustre-client [ 9505.159226] Key type lgssc unregistered [ 9505.358349] LNet: 208056:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9505.367735] LNetError: 208056:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9505.392873] LNet: Removed LNI 192.168.206.20@tcp [ 9506.255985] Key type .llcrypt unregistered [ 9506.258160] Key type ._llcrypt unregistered [ 9521.713450] Key type ._llcrypt registered [ 9521.716453] Key type .llcrypt registered [ 9523.352889] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 21:56:32 (1787622992) [ 9526.330227] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9526.351560] alg: No test for adler32 (adler32-zlib) [ 9527.457308] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [ 9527.811325] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [ 9529.573931] Key type lgssc registered [ 9530.680738] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9583.610354] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9591.602365] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9643.153932] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9649.032158] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9697.316098] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9727.306305] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9776.986564] Key type lgssc unregistered [ 9777.232077] LNet: 213358:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9777.238028] LNetError: 213358:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9777.255622] LNet: Removed LNI 192.168.206.20@tcp [ 9777.951515] Key type .llcrypt unregistered [ 9777.958222] Key type ._llcrypt unregistered [ 9818.637931] Key type ._llcrypt registered [ 9818.639618] Key type .llcrypt registered [ 9820.013360] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 22:01:29 (1787623289) [ 9863.374825] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9863.467084] alg: No test for adler32 (adler32-zlib) [ 9864.920817] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [ 9865.271752] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [ 9867.208197] Key type lgssc registered [ 9868.995616] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9968.309584] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9975.299793] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9998.340472] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10048.314051] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 22:05:17 (1787623517) [10056.113920] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 22:05:26 (1787623526) [10089.887771] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10096.015178] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10118.332591] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10121.116892] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [10126.172830] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [10128.178522] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [10129.731844] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [10131.233363] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [10136.561845] Lustre: lustre-MDT0000-mdc-ffff98e3119bb800: Connection to lustre-MDT0000 (at 192.168.206.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [10151.911476] Lustre: 215265:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787623607/real 1787623607] req@000000008bcb24d9 x1874458927179136/t0(0) o400->MGC192.168.206.120@tcp@192.168.206.120@tcp:26/25 lens 224/224 e 0 to 1 dl 1787623623 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10151.961747] LustreError: MGC192.168.206.120@tcp: Connection to MGS (at 192.168.206.120@tcp) was lost; in progress operations using this service will fail [10162.170859] Lustre: Evicted from MGS (at 192.168.206.120@tcp) after server handle changed from 0x99296bdb6fdd25c2 to 0x99296bdb6fdd2797 [10162.187906] Lustre: MGC192.168.206.120@tcp: Connection restored to 192.168.206.120@tcp (at 192.168.206.120@tcp) [10167.683581] Lustre: lustre-MDT0000-mdc-ffff98e3119bb800: Connection restored to 192.168.206.120@tcp (at 192.168.206.120@tcp) [10177.751806] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10179.524765] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10183.593074] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [10187.750740] Lustre: lustre-MDT0000-mdc-ffff98e3119bb800: Connection to lustre-MDT0000 (at 192.168.206.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [10203.105385] Lustre: 215265:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787623658/real 1787623658] req@000000003ec752a5 x1874458927188096/t0(0) o400->MGC192.168.206.120@tcp@192.168.206.120@tcp:26/25 lens 224/224 e 0 to 1 dl 1787623674 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10203.190395] LustreError: MGC192.168.206.120@tcp: Connection to MGS (at 192.168.206.120@tcp) was lost; in progress operations using this service will fail [10212.860502] Lustre: Evicted from MGS (at 192.168.206.120@tcp) after server handle changed from 0x99296bdb6fdd2797 to 0x99296bdb6fdd2a45 [10212.870566] Lustre: MGC192.168.206.120@tcp: Connection restored to 192.168.206.120@tcp (at 192.168.206.120@tcp) [10221.473558] Lustre: lustre-MDT0000-mdc-ffff98e3119bb800: Connection restored to 192.168.206.120@tcp (at 192.168.206.120@tcp) [10235.637740] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10237.296692] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10242.173286] Lustre: Unmounted lustre-client [10322.541610] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10329.398559] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10352.826420] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10353.553139] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [10361.077618] Lustre: Unmounted lustre-client [10391.821801] Key type lgssc unregistered [10392.297623] LNet: 225853:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10392.305881] LNetError: 225853:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10392.341945] LNet: Removed LNI 192.168.206.20@tcp [10393.437452] Key type .llcrypt unregistered [10393.439215] Key type ._llcrypt unregistered [10416.246823] Key type ._llcrypt registered [10416.248444] Key type .llcrypt registered [10417.405185] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 22:11:27 (1787623887) [10421.230308] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10421.261618] alg: No test for adler32 (adler32-zlib) [10422.682234] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [10423.083956] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [10424.802058] Key type lgssc registered [10426.530418] Lustre: Echo OBD driver; http://www.lustre.org/ [10476.438652] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10485.536499] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10525.480555] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10529.013248] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [10539.571467] Lustre: Unmounted lustre-client [10620.886661] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10626.831843] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10651.896553] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10652.960097] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [10662.450929] Lustre: Unmounted lustre-client [10734.984737] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10741.855613] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10762.668360] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10763.243655] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [10765.424484] Lustre: Unmounted lustre-client [10795.601156] Key type lgssc unregistered [10795.888470] LNet: 232908:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10795.907082] LNetError: 232908:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10795.936472] LNet: Removed LNI 192.168.206.20@tcp [10796.705261] Key type .llcrypt unregistered [10796.711634] Key type ._llcrypt unregistered [10814.115808] Key type ._llcrypt registered [10814.118613] Key type .llcrypt registered [10815.642785] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 22:18:05 (1787624285) [10819.089342] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10819.256122] alg: No test for adler32 (adler32-zlib) [10820.372316] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [10820.758722] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [10822.448175] Key type lgssc registered [10823.863563] Lustre: Echo OBD driver; http://www.lustre.org/ [10873.525969] Key type lgssc unregistered [10873.910577] LNet: 235234:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10873.925374] LNetError: 235234:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10873.937254] LNet: Removed LNI 192.168.206.20@tcp [10875.001448] Key type .llcrypt unregistered [10875.009400] Key type ._llcrypt unregistered [10907.860534] Key type ._llcrypt registered [10907.927373] Key type .llcrypt registered [10908.329850] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10908.349190] alg: No test for adler32 (adler32-zlib) [10909.668590] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [10910.206528] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [10912.120263] Key type lgssc registered [10913.884723] Lustre: Echo OBD driver; http://www.lustre.org/ [11031.197165] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11038.925758] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11071.020306] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11113.887953] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [11116.126325] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 22:23:05 (1787624585) [11150.020875] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11156.309431] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11177.945255] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11200.104990] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11201.833068] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [11207.163870] Lustre: lustre-OST0001-osc-ffff98e3120c0000: Connection to lustre-OST0001 (at 192.168.206.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [11213.770595] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11224.176037] Lustre: Unmounted lustre-client [11262.946975] Key type lgssc unregistered [11263.176226] LNet: 242357:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11263.184452] LNetError: 242357:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11263.198541] LNet: Removed LNI 192.168.206.20@tcp [11263.768862] Key type .llcrypt unregistered [11263.774984] Key type ._llcrypt unregistered [11294.222780] Key type ._llcrypt registered [11294.227450] Key type .llcrypt registered [11295.582162] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 22:26:05 (1787624765) [11308.484498] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11308.495396] alg: No test for adler32 (adler32-zlib) [11309.518250] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [11309.741120] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [11311.376179] Key type lgssc registered [11312.381437] Lustre: Echo OBD driver; http://www.lustre.org/ [11363.378383] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11368.666873] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11390.285310] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 22:27:40 (1787624860) [11425.178448] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11431.022531] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11453.072434] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11454.489969] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [11461.068107] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [11471.321987] Lustre: Unmounted lustre-client [11575.340324] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11580.807631] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11634.540179] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11635.380816] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [11638.651486] Lustre: Unmounted lustre-client [11676.407171] Key type lgssc unregistered [11676.702072] LNet: 250053:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11676.715606] LNetError: 250053:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11676.735436] LNet: Removed LNI 192.168.206.20@tcp [11677.504503] Key type .llcrypt unregistered [11677.506669] Key type ._llcrypt unregistered [11706.425880] Key type ._llcrypt registered [11706.450096] Key type .llcrypt registered [11707.070789] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11707.087753] alg: No test for adler32 (adler32-zlib) [11708.151145] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [11708.393403] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [11710.080259] Key type lgssc registered [11711.251241] Lustre: Echo OBD driver; http://www.lustre.org/ [11787.174105] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11795.055244] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11816.893957] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11856.244696] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [11857.708815] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 22:35:27 (1787625327) [11890.532326] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11896.128281] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11915.597730] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11919.459515] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [11927.382171] Lustre: Unmounted lustre-client [11955.861201] Key type lgssc unregistered [11956.117225] LNet: 256686:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11956.132232] LNetError: 256686:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11956.149577] LNet: Removed LNI 192.168.206.20@tcp [11956.912724] Key type .llcrypt unregistered [11956.916223] Key type ._llcrypt unregistered [11973.324990] Key type ._llcrypt registered [11973.328377] Key type .llcrypt registered [11974.694657] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [11976.247633] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 22:37:26 (1787625446) [11985.465522] Key type .llcrypt unregistered [11985.472932] Key type ._llcrypt unregistered [11993.864126] Key type ._llcrypt registered [11993.882890] Key type .llcrypt registered [11994.379200] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11994.393369] alg: No test for adler32 (adler32-zlib) [11995.502315] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [11995.749521] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [11997.440175] Key type lgssc registered [11998.512734] Lustre: Echo OBD driver; http://www.lustre.org/ [12043.099944] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12058.127207] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [12060.549706] Lustre: Unmounted lustre-client [12086.656659] Key type lgssc unregistered [12086.932779] LNet: 260143:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12086.940593] LNetError: 260143:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12087.979350] LNet: Removed LNI 192.168.206.20@tcp [12088.602475] Key type .llcrypt unregistered [12088.604425] Key type ._llcrypt unregistered [12102.629302] Key type ._llcrypt registered [12102.632166] Key type .llcrypt registered [12103.715775] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 22:39:33 (1787625573) [12106.139532] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12106.147772] alg: No test for adler32 (adler32-zlib) [12107.258469] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [12107.483593] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [12109.144387] Key type lgssc registered [12110.297595] Lustre: Echo OBD driver; http://www.lustre.org/ [12196.417538] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12226.323853] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12231.113725] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12232.561786] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [12235.000792] Lustre: Unmounted lustre-client [12270.896050] Key type lgssc unregistered [12271.271722] LNet: 263688:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12271.278279] LNetError: 263688:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12271.290770] LNet: Removed LNI 192.168.206.20@tcp [12271.910506] Key type .llcrypt unregistered [12271.913159] Key type ._llcrypt unregistered [12287.586173] Key type ._llcrypt registered [12287.591606] Key type .llcrypt registered [12288.889645] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 22:42:38 (1787625758) [12290.644353] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12290.735817] alg: No test for adler32 (adler32-zlib) [12291.839717] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [12292.119473] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [12293.800785] Key type lgssc registered [12294.814473] Lustre: Echo OBD driver; http://www.lustre.org/ [12342.230585] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12349.312257] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12370.826133] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12373.450034] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [12378.604917] Lustre: lustre-MDT0000-mdc-ffff98e303a98800: Connection to lustre-MDT0000 (at 192.168.206.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [12383.680425] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12383.736626] LustreError: 266842:0:(file.c:6167:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [12384.181954] LustreError: MGC192.168.206.120@tcp: Connection to MGS (at 192.168.206.120@tcp) was lost; in progress operations using this service will fail [12384.240778] Lustre: Unmounted lustre-client [12403.582056] Key type lgssc unregistered [12403.812290] LNet: 267257:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12403.817357] LNetError: 267257:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12403.832449] LNet: Removed LNI 192.168.206.20@tcp [12404.436213] Key type .llcrypt unregistered [12404.441380] Key type ._llcrypt unregistered [12417.899755] Key type ._llcrypt registered [12417.901389] Key type .llcrypt registered [12419.259992] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 22:44:49 (1787625889) [12421.350772] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12421.497427] alg: No test for adler32 (adler32-zlib) [12422.604900] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [12422.862328] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [12424.528258] Key type lgssc registered [12425.623952] Lustre: Echo OBD driver; http://www.lustre.org/ [12473.206680] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12478.576537] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12498.574596] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12500.298393] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [12505.573880] Lustre: lustre-MDT0001-mdc-ffff98e3120c3000: Connection to lustre-MDT0001 (at 192.168.206.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [12511.097865] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [12513.255733] Lustre: Unmounted lustre-client [12534.394470] Key type lgssc unregistered [12534.665946] LNet: 270836:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12534.673399] LNetError: 270836:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12534.695365] LNet: Removed LNI 192.168.206.20@tcp [12535.444812] Key type .llcrypt unregistered [12535.448330] Key type ._llcrypt unregistered [12550.288628] Key type ._llcrypt registered [12550.297510] Key type .llcrypt registered [12551.488866] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 22:47:01 (1787626021) [12567.419362] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12567.567097] alg: No test for adler32 (adler32-zlib) [12568.689875] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [12569.042778] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [12570.737116] Key type lgssc registered [12571.925625] Lustre: Echo OBD driver; http://www.lustre.org/ [12673.160629] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12675.681327] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [12716.006866] Lustre: lustre-MDT0001-mdc-ffff98e3079a1800: Connection to lustre-MDT0001 (at 192.168.206.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [12722.694844] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12733.018185] Lustre: Unmounted lustre-client [12774.767400] Key type lgssc unregistered [12774.995164] LNet: 276622:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12775.015472] LNetError: 276622:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12775.035112] LNet: Removed LNI 192.168.206.20@tcp [12775.793367] Key type .llcrypt unregistered [12775.798323] Key type ._llcrypt unregistered [12792.019281] Key type ._llcrypt registered [12792.024024] Key type .llcrypt registered [12793.188698] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 22:51:03 (1787626263) [12794.728188] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [12796.490317] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 22:51:06 (1787626266) [12798.063542] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [12799.732934] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 22:51:09 (1787626269) [12801.385538] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [12803.194743] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 22:51:12 (1787626272) [12804.654686] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [12806.122435] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 22:51:16 (1787626276) [12807.625360] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [12809.718795] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 22:51:19 (1787626279) [12818.825862] Key type .llcrypt unregistered [12818.827994] Key type ._llcrypt unregistered [12827.407225] Key type ._llcrypt registered [12827.409941] Key type .llcrypt registered [12827.795303] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12827.933553] alg: No test for adler32 (adler32-zlib) [12828.953644] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [12829.182143] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [12830.841492] Key type lgssc registered [12832.202373] Lustre: Echo OBD driver; http://www.lustre.org/ [12897.130748] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12902.500033] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12925.284077] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12926.957101] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [12929.937596] Lustre: Unmounted lustre-client [12966.674994] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 22:53:56 (1787626436) [12996.093784] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13002.169270] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13035.078988] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13046.336437] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [13048.392112] Lustre: Unmounted lustre-client [13093.408370] LNet: 1 local NIs in recovery (showing 1): 192.168.206.20@tcp [13106.950631] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 22:56:16 (1787626576) [13737.520933] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13742.205573] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13777.379929] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13895.614414] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 23:09:24 (1787627364) [13908.534521] Key type lgssc unregistered [13908.849859] LNet: 290162:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13908.861380] LNetError: 290162:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13908.882553] LNet: Removed LNI 192.168.206.20@tcp [13909.881286] Key type .llcrypt unregistered [13909.884076] Key type ._llcrypt unregistered [14026.155594] Key type ._llcrypt registered [14026.158140] Key type .llcrypt registered [14026.841557] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14026.876239] alg: No test for adler32 (adler32-zlib) [14028.249795] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [14028.740389] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [14030.497490] Key type lgssc registered [14032.067945] Lustre: Echo OBD driver; http://www.lustre.org/ [14115.277625] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14121.585608] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14150.385692] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14182.936334] Key type lgssc unregistered [14183.230435] LNet: 293857:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14183.235318] LNetError: 293857:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14183.249275] LNet: Removed LNI 192.168.206.20@tcp [14183.950605] Key type .llcrypt unregistered [14183.954082] Key type ._llcrypt unregistered [14220.022825] Key type ._llcrypt registered [14220.025421] Key type .llcrypt registered [14221.203972] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 23:14:51 (1787627691) [14241.765479] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14241.826821] alg: No test for adler32 (adler32-zlib) [14242.999337] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [14243.265229] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [14244.952364] Key type lgssc registered [14246.174641] Lustre: Echo OBD driver; http://www.lustre.org/ [14299.353085] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 23:16:08 (1787627768) [14312.055805] Key type lgssc unregistered [14312.316634] LNet: 297419:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14312.323768] LNetError: 297419:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14312.340539] LNet: Removed LNI 192.168.206.20@tcp [14313.238196] Key type .llcrypt unregistered [14313.239806] Key type ._llcrypt unregistered [14324.621273] Key type ._llcrypt registered [14324.631644] Key type .llcrypt registered [14325.182623] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14325.232584] alg: No test for adler32 (adler32-zlib) [14326.359819] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [14326.668526] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [14328.512844] Key type lgssc registered [14330.183953] Lustre: Echo OBD driver; http://www.lustre.org/ [14384.118613] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14389.802478] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14416.461842] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14706.688124] LNet: Added LNI 192.168.206.20@tcp50 [8/256/0/180] [14709.839726] LNet: Removed LNI 192.168.206.20@tcp [14712.289394] Lustre: 301815:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff98e309286000: config has no valid NIDs, force dynamic NIDs [14713.521759] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [14713.728882] Lustre: Unmounted lustre-client [14716.259729] Key type lgssc unregistered [14716.698408] LNet: 302077:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14716.705966] LNetError: 302077:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14716.738266] LNet: Removed LNI 192.168.206.20@tcp50 [14717.461617] Key type .llcrypt unregistered [14717.463708] Key type ._llcrypt unregistered [14735.236175] Key type ._llcrypt registered [14735.238261] Key type .llcrypt registered [14735.592781] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14735.604437] alg: No test for adler32 (adler32-zlib) [14736.751130] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [14737.077783] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [14738.792567] Key type lgssc registered [14740.091881] Lustre: Echo OBD driver; http://www.lustre.org/ [14805.122584] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14812.734667] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14821.968889] LNet: Added LNI 192.168.206.20@tcp50 [8/256/0/180] [14823.171273] LNet: Removed LNI 192.168.206.20@tcp [14824.555222] Lustre: 304769:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff98e308d52000: config has no valid NIDs, force dynamic NIDs [14825.840259] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [14826.655859] Lustre: Unmounted lustre-client [14860.520200] Key type lgssc unregistered [14860.908205] LNet: 305642:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14860.916219] LNetError: 305642:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14860.931195] LNet: Removed LNI 192.168.206.20@tcp50 [14861.474686] Key type .llcrypt unregistered [14861.476482] Key type ._llcrypt unregistered [14871.837543] Key type ._llcrypt registered [14871.839631] Key type .llcrypt registered [14873.414664] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 23:25:42 (1787628342) [14895.072082] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14895.194687] alg: No test for adler32 (adler32-zlib) [14896.267643] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [14896.582898] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [14898.290746] Key type lgssc registered [14899.656722] Lustre: Echo OBD driver; http://www.lustre.org/ [14983.014238] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14988.529883] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15011.349427] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15045.977802] Key type lgssc unregistered [15046.323196] LNet: 310390:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15046.336485] LNetError: 310390:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15046.352490] LNet: Removed LNI 192.168.206.20@tcp [15047.705620] Key type .llcrypt unregistered [15047.711108] Key type ._llcrypt unregistered [15059.938685] Key type ._llcrypt registered [15059.952177] Key type .llcrypt registered [15061.754583] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 23:28:51 (1787628531) [15064.190643] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15064.223364] alg: No test for adler32 (adler32-zlib) [15065.322608] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [15065.576409] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [15067.288223] Key type lgssc registered [15068.644218] Lustre: Echo OBD driver; http://www.lustre.org/ [15117.670760] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15123.154969] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15143.324458] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15145.981833] Lustre: Mounted lustre-client - version 2.17.57_80_g74d5152 [15198.981512] Lustre: Mounted fs15246-client - version 2.17.57_80_g74d5152 [15214.322210] Lustre: Unmounted fs15246-client [15240.484180] Lustre: Unmounted lustre-client [15270.161455] Key type lgssc unregistered [15270.577496] LNet: 315246:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15270.591393] LNetError: 315246:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15270.610288] LNet: Removed LNI 192.168.206.20@tcp [15271.468180] Key type .llcrypt unregistered [15271.472643] Key type ._llcrypt unregistered [15285.632457] Key type ._llcrypt registered [15285.635358] Key type .llcrypt registered [15286.921024] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 23:32:36 (1787628756) [15289.746865] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15289.764241] alg: No test for adler32 (adler32-zlib) [15290.799516] Lustre: Lustre: Build Version: 2.17.57_80_g74d5152 [15291.142381] LNet: Added LNI 192.168.206.20@tcp [8/256/0/180] [15292.864456] Key type lgssc registered [15294.358353] Lustre: Echo OBD driver; http://www.lustre.org/ [15430.617450] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15435.887417] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15458.241395] Lustre: DEBUG MARKER: oleg620-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15555.909710] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 15225 sec ========= 23:37:05 (1787629025) [15557.538794] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 23:37:07 (1787629027) === [15561.623715] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 23:37:10 (1787629030) === [15596.733909] Key type lgssc unregistered [15597.177912] LNet: 324514:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15597.206539] LNetError: 324514:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15597.233506] LNet: Removed LNI 192.168.206.20@tcp [15598.251677] Key type .llcrypt unregistered [15598.254909] Key type ._llcrypt unregistered