[ 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 471366938 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001012] APIC: Switch to symmetric I/O mode setup [ 0.003308] x2apic enabled [ 0.004008] Switched APIC routing to physical x2apic. [ 0.005013] kvm-guest: setup PV IPIs [ 0.008418] ..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.009021] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010015] pid_max: default: 32768 minimum: 301 [ 0.011131] LSM: Security Framework initializing [ 0.012052] Yama: becoming mindful. [ 0.013040] SELinux: Initializing. [ 0.014081] *** VALIDATE selinux *** [ 0.022501] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.027169] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.029048] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030122] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031132] *** VALIDATE tmpfs *** [ 0.032543] *** VALIDATE proc *** [ 0.033271] *** VALIDATE cgroup *** [ 0.034009] *** VALIDATE cgroup2 *** [ 0.035273] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.037125] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.038008] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.039025] Spectre V2 : User space: Vulnerable [ 0.040007] Speculative Store Bypass: Vulnerable [ 0.043340] debug: unmapping init [mem 0xffffffffbe259000-0xffffffffbe260fff] [ 0.046144] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.047675] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.048023] ... version: 2 [ 0.049011] ... bit width: 48 [ 0.050013] ... generic registers: 4 [ 0.051013] ... value mask: 0000ffffffffffff [ 0.052015] ... max period: 00007fffffffffff [ 0.053012] ... fixed-purpose events: 3 [ 0.054012] ... event mask: 000000070000000f [ 0.055290] rcu: Hierarchical SRCU implementation. [ 0.057458] smp: Bringing up secondary CPUs ... [ 0.058606] x86: Booting SMP configuration: [ 0.059029] .... node #0, CPUs: #1 #2 #3 [ 0.062738] smp: Brought up 1 node, 4 CPUs [ 0.064012] smpboot: Max logical packages: 1 [ 0.065016] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.147253] node 0 deferred pages initialised in 80ms [ 0.151285] devtmpfs: initialized [ 0.152246] x86/mm: Memory block size: 128MB [ 0.155415] gcov: version magic: 0x41383552 [ 0.158441] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.159075] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.161315] pinctrl core: initialized pinctrl subsystem [ 0.164214] [ 0.164827] ************************************************************* [ 0.167015] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.169011] ** ** [ 0.171015] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.174017] ** ** [ 0.177012] ** This means that this kernel is built to expose internal ** [ 0.179012] ** IOMMU data structures, which may compromise security on ** [ 0.182013] ** your system. ** [ 0.184012] ** ** [ 0.186011] ** If you see this message and you are not debugging the ** [ 0.187010] ** kernel, report this immediately to your vendor! ** [ 0.189012] ** ** [ 0.191012] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.192010] ************************************************************* [ 0.194556] NET: Registered protocol family 16 [ 0.196441] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.200072] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.202054] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.205179] cpuidle: using governor menu [ 0.206535] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.208561] PCI: Using configuration type 1 for base access [ 0.211138] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.221218] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.224085] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.227059] cryptd: max_cpu_qlen set to 1000 [ 0.229338] ACPI: Added _OSI(Module Device) [ 0.230036] ACPI: Added _OSI(Processor Device) [ 0.231021] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.233025] ACPI: Added _OSI(Processor Aggregator Device) [ 0.239034] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.246371] ACPI: Interpreter enabled [ 0.247063] ACPI: PM: (supports S0 S3 S4 S5) [ 0.249035] ACPI: Using IOAPIC for interrupt routing [ 0.251193] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.255497] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.266325] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.269064] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.272032] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.274193] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.279735] acpiphp: Slot [2] registered [ 0.281207] acpiphp: Slot [5] registered [ 0.283251] acpiphp: Slot [6] registered [ 0.285185] acpiphp: Slot [3] registered [ 0.287226] acpiphp: Slot [4] registered [ 0.288122] acpiphp: Slot [7] registered [ 0.289108] acpiphp: Slot [8] registered [ 0.291193] acpiphp: Slot [9] registered [ 0.292127] acpiphp: Slot [10] registered [ 0.294126] acpiphp: Slot [11] registered [ 0.296095] acpiphp: Slot [12] registered [ 0.297111] acpiphp: Slot [13] registered [ 0.299082] acpiphp: Slot [14] registered [ 0.300054] acpiphp: Slot [15] registered [ 0.301080] acpiphp: Slot [16] registered [ 0.303136] acpiphp: Slot [17] registered [ 0.305150] acpiphp: Slot [18] registered [ 0.306100] acpiphp: Slot [19] registered [ 0.308098] acpiphp: Slot [20] registered [ 0.309103] acpiphp: Slot [21] registered [ 0.310100] acpiphp: Slot [22] registered [ 0.313154] acpiphp: Slot [23] registered [ 0.314101] acpiphp: Slot [24] registered [ 0.316113] acpiphp: Slot [25] registered [ 0.317096] acpiphp: Slot [26] registered [ 0.318124] acpiphp: Slot [27] registered [ 0.320091] acpiphp: Slot [28] registered [ 0.321077] acpiphp: Slot [29] registered [ 0.322106] acpiphp: Slot [30] registered [ 0.323179] acpiphp: Slot [31] registered [ 0.325095] PCI host bridge to bus 0000:00 [ 0.327032] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.329097] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.332033] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.335036] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.338034] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.340031] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.342218] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.346176] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.349496] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.357028] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.361066] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.365028] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.367039] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.370029] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.373676] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.375987] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.379061] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.381879] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.387017] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.396682] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.400020] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.406080] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.413022] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.420020] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.438022] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.449094] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.456017] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.466018] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.483019] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.494496] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.496481] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.499449] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.502420] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.505255] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.510046] iommu: Default domain type: Passthrough [ 0.512419] SCSI subsystem initialized [ 0.514188] ACPI: bus type USB registered [ 0.516190] usbcore: registered new interface driver usbfs [ 0.518130] usbcore: registered new interface driver hub [ 0.520113] usbcore: registered new device driver usb [ 0.522259] pps_core: LinuxPPS API ver. 1 registered [ 0.523012] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.527099] PTP clock support registered [ 0.529181] EDAC MC: Ver: 3.0.0 [ 0.530465] PCI: Using ACPI for IRQ routing [ 0.531779] NetLabel: Initializing [ 0.532015] NetLabel: domain hash size = 128 [ 0.533012] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.534130] NetLabel: unlabeled traffic allowed by default [ 0.536176] vgaarb: loaded [ 0.538362] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.540020] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.547394] clocksource: Switched to clocksource kvm-clock [ 0.658783] VFS: Disk quotas dquot_6.6.0 [ 0.661323] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.664372] *** VALIDATE ramfs *** [ 0.665667] *** VALIDATE hugetlbfs *** [ 0.667613] pnp: PnP ACPI init [ 0.670387] pnp: PnP ACPI: found 6 devices [ 0.689566] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.693459] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.695863] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.698660] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.701509] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.704141] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.707321] NET: Registered protocol family 2 [ 0.710172] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.716088] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.721450] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.727763] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.731664] TCP: Hash tables configured (established 65536 bind 65536) [ 0.734925] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.738770] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.742060] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.745559] NET: Registered protocol family 1 [ 0.748534] RPC: Registered named UNIX socket transport module. [ 0.750845] RPC: Registered udp transport module. [ 0.752901] RPC: Registered tcp transport module. [ 0.754860] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.757571] NET: Registered protocol family 44 [ 0.759481] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.761677] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.764446] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.766791] PCI: CLS 0 bytes, default 64 [ 0.768444] Unpacking initramfs... [ 2.249990] debug: unmapping init [mem 0xffff900b3cc64000-0xffff900b3ffcffff] [ 2.258251] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.260746] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.265567] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.788407] Initialise system trusted keyrings [ 2.790475] Key type blacklist registered [ 2.791897] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.803442] zbud: loaded [ 2.807063] *** VALIDATE nfs *** [ 2.808567] *** VALIDATE nfs4 *** [ 2.810357] pstore: using deflate compression [ 2.814054] Platform Keyring initialized [ 2.926365] NET: Registered protocol family 38 [ 2.927791] Key type asymmetric registered [ 2.929018] Asymmetric key parser 'x509' registered [ 2.930481] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.933329] io scheduler mq-deadline registered [ 2.934870] io scheduler kyber registered [ 2.936321] io scheduler bfq registered [ 2.939283] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.942447] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.945584] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.948413] ACPI: Power Button [PWRF] [ 2.955162] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.963498] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.978054] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.005660] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.033858] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.039534] Non-volatile memory driver v1.3 [ 3.042186] Linux agpgart interface v0.103 [ 3.077643] virtio_blk virtio1: [vda] 146016 512-byte logical blocks (74.8 MB/71.3 MiB) [ 3.081112] vda: detected capacity change from 0 to 74760192 [ 3.098767] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.102253] vdb: detected capacity change from 0 to 1073741824 [ 3.110614] libphy: Fixed MDIO Bus: probed [ 3.116124] usbcore: registered new interface driver usbserial_generic [ 3.119198] usbserial: USB Serial support registered for generic [ 3.121409] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.125463] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.127558] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.130705] mousedev: PS/2 mouse device common for all mice [ 3.133313] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.134560] rtc_cmos 00:05: RTC can wake from S4 [ 3.141239] rtc_cmos 00:05: registered as rtc0 [ 3.142243] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.143337] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.153193] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.153569] intel_pstate: CPU model not supported [ 3.162407] hid: raw HID events driver (C) Jiri Kosina [ 3.167337] usbcore: registered new interface driver usbhid [ 3.169646] usbhid: USB HID core driver [ 3.170861] drop_monitor: Initializing network drop monitor service [ 3.173291] Initializing XFRM netlink socket [ 3.177086] NET: Registered protocol family 10 [ 3.180155] Segment Routing with IPv6 [ 3.181791] NET: Registered protocol family 17 [ 3.183972] mpls_gso: MPLS GSO support [ 3.190554] RAS: Correctable Errors collector initialized. [ 3.194454] AVX version of gcm_enc/dec engaged. [ 3.196892] AES CTR mode by8 optimization enabled [ 3.281084] sched_clock: Marking stable (3280974689, 0)->(4207211105, -926236416) [ 3.285455] registered taskstats version 1 [ 3.289112] Loading compiled-in X.509 certificates [ 3.291432] zswap: loaded using pool lzo/zbud [ 3.316982] Key type big_key registered [ 3.330674] Key type encrypted registered [ 3.332568] ima: No TPM chip found, activating TPM-bypass! [ 3.334685] ima: Allocated hash algorithm: sha1 [ 3.336411] ima: No architecture policies found [ 3.337849] evm: Initialising EVM extended attributes: [ 3.339390] evm: security.selinux [ 3.340425] evm: security.ima [ 3.341443] evm: security.capability [ 3.342957] evm: HMAC attrs: 0x1 [ 3.345260] rtc_cmos 00:05: setting system clock to 2026-08-31 04:50:35 UTC (1788151835) [ 3.351823] debug: unmapping init [mem 0xffffffffbf203000-0xffffffffbf3fffff] [ 3.355023] debug: unmapping init [mem 0xffffffffbdf82000-0xffffffffbe258fff] [ 3.363141] Write protecting the kernel read-only data: 28672k [ 3.366578] debug: unmapping init [mem 0xffffffffbc603000-0xffffffffbc7fffff] [ 3.369820] debug: unmapping init [mem 0xffffffffbcf14000-0xffffffffbcffffff] [ 3.404928] 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.413234] systemd[1]: Detected virtualization kvm. [ 3.415416] systemd[1]: Detected architecture x86-64. [ 3.417418] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.443372] systemd[1]: No hostname configured. [ 3.445265] systemd[1]: Set hostname to . [ 3.447484] random: systemd: uninitialized urandom read (16 bytes read) [ 3.449825] systemd[1]: Initializing machine ID from random generator. [ 3.496032] random: ln: uninitialized urandom read (6 bytes read) [ 3.579635] random: systemd: uninitialized urandom read (16 bytes read) [ 3.582431] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ 3.586789] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 3.593395] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on udev Control Socket. [ OK ] Listening on Journal Socket. Starting Setup Virtual Console... Starting Create list of required st…ce nodes for the current kernel... Starting Apply Kernel Variables... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Slices. [ OK ] Reached target Swap. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Sockets. Starting Journal Service... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Started Setup Virtual Console. [ 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. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.223754] device-mapper: uevent: version 1.0.3 [ 4.226744] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization.[ 4.984548] random: fast init done [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 5.063564] virtio_net virtio0 ens2: renamed from eth0 [ 5.122601] scsi host0: ata_piix [ 5.149079] scsi host1: ata_piix [ 5.150791] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.153698] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.911031] dracut-initqueue[588]: RTNETLINK answers: File exists [ 9.854906] random: crng init done [ 9.856545] 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.361741] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target 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 Paths. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ 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 File Systems. [ OK ] Stopped target Sockets. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.531699] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.817448] SELinux: Disabled at runtime. [ 11.877678] 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.887708] systemd[1]: Detected virtualization kvm. [ 11.889885] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.382953] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.387486] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.392904] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.397879] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.400354] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.409230] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.413143] systemd[1]: proc-sys-fs-binfmt_misc.automount: Refusing to start, unit to trigger not loaded. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Listening on udev Kernel Socket. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... Starting Apply Kernel Variables... Activating swap /dev/disk/by-label/SWAP... Mounting Huge Pages File System... [ OK ] Reached target Local Encrypted Volumes. [ 12.506838] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Listening on Process Core Dump Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice system-getty.slice. Starting Remount Root and Kernel File Systems... Mounting Kernel Debug File System... [ OK ] Reached target rpc_pipefs.target. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Stopped target Initrd File Systems. [ OK ] Reached target RPC Port Mapper. [ OK ] Listening on initctl Compatibility Named Pipe. Mounting POSIX Message Queue File System... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Started Journal Service. [ OK ] Started Apply Kernel Variables. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Huge Pages File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted POSIX Message Queue File System. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started udev Coldplug all Devices. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Mounted /mnt. [ 12.952779] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 13.249454] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.288522] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.397947] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.412147] EDAC sbridge: Ver: 1.1.2 [ 14.812679] Key type dns_resolver registered [ 15.118796] NFS: Registering the id_resolver key type [ 15.121194] Key type id_resolver registered [ 15.123011] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Login Service... Starting Restore /run/initramfs on shutdown... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started irqbalance daemon. [ OK ] Reached target sshd-keygen.target. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting System Logging Service... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg425-client login: [ 39.422430] hrtimer: interrupt took 6696693 ns [ 65.145450] libcfs: loading out-of-tree module taints kernel. [ 65.317982] Key type ._llcrypt registered [ 65.321728] Key type .llcrypt registered [ 66.090885] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 66.120137] alg: No test for adler32 (adler32-zlib) [ 67.639500] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 68.568244] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [ 70.351186] Key type lgssc registered [ 72.539905] Lustre: Echo OBD driver; http://www.lustre.org/ [ 258.562983] Lustre: Mounted lustre-client [ 264.052405] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 280.686939] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing check_logdir /tmp/testlogs/ [ 284.127957] Lustre: lustre-OST0000-osc-ffff900b8580c000: disconnect after 23s idle [ 286.083309] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing yml_node [ 291.516984] Lustre: DEBUG MARKER: Client: 2.17.55.27 [ 294.569226] Lustre: DEBUG MARKER: MDS: 2.17.55.27 [ 297.607287] Lustre: DEBUG MARKER: OSS: 2.17.55.27 [ 299.731114] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Mon Aug 31 00:55:29 EDT 2026 [ 320.042370] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 322.275175] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 325.564944] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 335.896666] Lustre: Unmounted lustre-client [ 469.783991] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 477.695426] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 504.452624] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 552.461574] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 00:59:42 (1788152382) [ 555.122356] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 557.471655] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 00:59:47 (1788152387) [ 592.318805] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 598.948381] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 625.557409] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 626.446607] Lustre: Mounted lustre-client [ 632.658975] Lustre: Unmounted lustre-client [ 661.526727] Key type lgssc unregistered [ 661.852308] LNet: 15068:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 661.859512] LNetError: 15068:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 661.889335] LNet: Removed LNI 192.168.204.25@tcp [ 662.863164] Key type .llcrypt unregistered [ 662.864776] Key type ._llcrypt unregistered [ 679.692975] Key type ._llcrypt registered [ 679.694598] Key type .llcrypt registered [ 681.320433] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 01:01:51 (1788152511) [ 683.404139] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 683.475951] alg: No test for adler32 (adler32-zlib) [ 684.698498] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 685.001388] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [ 686.695313] Key type lgssc registered [ 688.347473] Lustre: Echo OBD driver; http://www.lustre.org/ [ 770.147164] Lustre: Mounted lustre-client [ 855.281478] Lustre: Unmounted lustre-client [ 887.590451] Key type lgssc unregistered [ 887.922812] LNet: 18876:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 887.939275] LNetError: 18876:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 887.954926] LNet: Removed LNI 192.168.204.25@tcp [ 889.047151] Key type .llcrypt unregistered [ 889.054700] Key type ._llcrypt unregistered [ 899.891930] Key type ._llcrypt registered [ 899.894321] Key type .llcrypt registered [ 901.475885] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 01:05:31 (1788152731) [ 904.244662] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 904.279840] alg: No test for adler32 (adler32-zlib) [ 905.464494] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 905.724922] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [ 907.439247] Key type lgssc registered [ 908.716081] Lustre: Echo OBD driver; http://www.lustre.org/ [ 956.502260] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 963.361074] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 983.759931] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 985.496143] Lustre: Mounted lustre-client [ 1000.280555] Lustre: Unmounted lustre-client [ 1024.598655] Key type lgssc unregistered [ 1024.875859] LNet: 22493:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1024.889054] LNetError: 22493:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1024.910070] LNet: Removed LNI 192.168.204.25@tcp [ 1025.567491] Key type .llcrypt unregistered [ 1025.569440] Key type ._llcrypt unregistered [ 1036.570428] Key type ._llcrypt registered [ 1036.572779] Key type .llcrypt registered [ 1037.844487] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 01:07:48 (1788152868) [ 1040.031988] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1040.077162] alg: No test for adler32 (adler32-zlib) [ 1041.138537] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 1041.341548] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [ 1043.031372] Key type lgssc registered [ 1044.260191] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1088.022514] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1092.794320] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1109.531241] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1116.714325] Lustre: Mounted lustre-client [ 1121.685511] Lustre: Unmounted lustre-client [ 1156.096487] Key type lgssc unregistered [ 1156.286667] LNet: 25679:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1156.294479] LNetError: 25679:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1156.308780] LNet: Removed LNI 192.168.204.25@tcp [ 1156.832810] Key type .llcrypt unregistered [ 1156.835545] Key type ._llcrypt unregistered [ 1169.794450] Key type ._llcrypt registered [ 1169.796277] Key type .llcrypt registered [ 1170.958794] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 01:10:01 (1788153001) [ 1172.715022] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1172.748436] alg: No test for adler32 (adler32-zlib) [ 1173.800361] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 1174.050074] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [ 1175.751350] Key type lgssc registered [ 1176.658257] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1221.907682] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1227.089894] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1245.353724] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1247.991785] Lustre: Mounted lustre-client [ 1253.350521] Lustre: lustre-MDT0000-mdc-ffff900ba0032000: Connection to lustre-MDT0000 (at 192.168.204.125@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1258.471974] Lustre: lustre-MDT0001-mdc-ffff900ba0032000: Connection to lustre-MDT0001 (at 192.168.204.125@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1266.123289] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1274.849894] Lustre: 26636:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788153090/real 1788153090] req@00000000c7e8cfb6 x1875014326425088/t0(0) o400->MGC192.168.204.125@tcp@192.168.204.125@tcp:26/25 lens 224/224 e 0 to 1 dl 1788153106 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1274.895138] LustreError: MGC192.168.204.125@tcp: Connection to MGS (at 192.168.204.125@tcp) was lost; in progress operations using this service will fail [ 1276.513589] Lustre: Unmounted lustre-client [ 1295.855472] Key type lgssc unregistered [ 1296.120616] LNet: 29328:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1296.131783] LNetError: 29328:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1296.160812] LNet: Removed LNI 192.168.204.25@tcp [ 1297.020275] Key type .llcrypt unregistered [ 1297.022867] Key type ._llcrypt unregistered [ 1311.965697] Key type ._llcrypt registered [ 1311.972849] Key type .llcrypt registered [ 1313.244358] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 01:12:23 (1788153143) [ 1315.486473] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1315.501942] alg: No test for adler32 (adler32-zlib) [ 1316.698314] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 1317.095899] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [ 1318.927359] Key type lgssc registered [ 1320.438926] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1372.861294] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1379.404730] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1402.735687] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1404.696546] Lustre: Mounted lustre-client [ 1410.039063] Lustre: lustre-OST0000-osc-ffff900ba01c0000: Connection to lustre-OST0000 (at 192.168.204.125@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1424.685962] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1435.048278] Lustre: Unmounted lustre-client [ 1468.958210] Key type lgssc unregistered [ 1469.294804] LNet: 32941:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1469.304197] LNetError: 32941:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1469.332782] LNet: Removed LNI 192.168.204.25@tcp [ 1470.291375] Key type .llcrypt unregistered [ 1470.293065] Key type ._llcrypt unregistered [ 1487.072569] Key type ._llcrypt registered [ 1487.074841] Key type .llcrypt registered [ 1488.614093] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 01:15:19 (1788153319) [ 1491.168698] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1491.265916] alg: No test for adler32 (adler32-zlib) [ 1492.404621] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 1492.666604] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [ 1494.335730] Key type lgssc registered [ 1495.554352] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1544.625799] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1550.761526] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1575.798815] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1578.554966] Lustre: Mounted lustre-client [ 1582.886729] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1587.366983] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1599.569302] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-08-31 1h17m09s [ 1602.459740] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1609.188259] Lustre: lustre-MDT0000-mdc-ffff900b92c2f800: Connection to lustre-MDT0000 (at 192.168.204.125@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1613.570830] LustreError: 36730:0:(lmv_obd.c:1468:lmv_statfs()) lustre-MDT0000-mdc-ffff900b92c2f800: can't stat MDS #0: rc = -19 [ 1615.699336] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1627.631325] LustreError: MGC192.168.204.125@tcp: Connection to MGS (at 192.168.204.125@tcp) was lost; in progress operations using this service will fail [ 1627.644215] Lustre: Evicted from MGS (at 192.168.204.125@tcp) after server handle changed from 0xd6d205c3b8c4c556 to 0xd6d205c3b8c4c804 [ 1627.656977] Lustre: MGC192.168.204.125@tcp: Connection restored to 192.168.204.125@tcp (at 192.168.204.125@tcp) [ 1628.744407] LustreError: lustre-MDT0000-mdc-ffff900b92c2f800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1628.781490] Lustre: lustre-MDT0000-mdc-ffff900b92c2f800: Connection restored to 192.168.204.125@tcp (at 192.168.204.125@tcp) [ 1631.511303] Lustre: DEBUG MARKER: Wait for df (36730) ... [ 1632.901455] Lustre: DEBUG MARKER: done [ 1635.689350] Lustre: Unmounted lustre-client [ 1663.719903] Key type lgssc unregistered [ 1664.014183] LNet: 37747:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1664.024473] LNetError: 37747:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1664.043956] LNet: Removed LNI 192.168.204.25@tcp [ 1664.721741] Key type .llcrypt unregistered [ 1664.724772] Key type ._llcrypt unregistered [ 1691.510218] Key type ._llcrypt registered [ 1691.513955] Key type .llcrypt registered [ 1692.805288] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 01:18:43 (1788153523) [ 1696.415357] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1696.440700] alg: No test for adler32 (adler32-zlib) [ 1697.640575] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 1698.105267] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [ 1699.823388] Key type lgssc registered [ 1701.452474] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1755.196919] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1760.629406] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1780.045383] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1782.928242] Lustre: Mounted lustre-client [ 1788.363606] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-08-31 1h20m18s [ 1790.166727] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1797.243916] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1800.352768] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1820.639395] Lustre: 41895:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788153632/real 1788153632] req@00000000fec34961 x1875014875880064/t0(0) o101->lustre-MDT0000-mdc-ffff900b92c2e000@192.168.204.125@tcp:12/10 lens 576/1152 e 0 to 1 dl 1788153652 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1820.689320] Lustre: lustre-MDT0000-mdc-ffff900b92c2e000: Connection to lustre-MDT0000 (at 192.168.204.125@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1820.723048] Lustre: lustre-MDT0000-mdc-ffff900b92c2e000: Connection restored to 192.168.204.125@tcp (at 192.168.204.125@tcp) [ 1842.384434] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1845.051495] Lustre: DEBUG MARKER: done [ 1848.218648] Lustre: Unmounted lustre-client [ 1874.584487] Key type lgssc unregistered [ 1874.867638] LNet: 42773:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1874.878744] LNetError: 42773:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1874.905789] LNet: Removed LNI 192.168.204.25@tcp [ 1875.650498] Key type .llcrypt unregistered [ 1875.654954] Key type ._llcrypt unregistered [ 1905.885553] Key type ._llcrypt registered [ 1905.887624] Key type .llcrypt registered [ 1907.038565] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 01:22:17 (1788153737) [ 1908.534834] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1910.344861] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 01:22:21 (1788153741) [ 1914.172204] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1914.220416] alg: No test for adler32 (adler32-zlib) [ 1915.304838] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 1915.600521] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [ 1917.367157] Key type lgssc registered [ 1918.592344] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1944.380592] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 01:22:55 (1788153775) [ 1971.907555] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1977.635679] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1997.760182] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2000.472342] Lustre: Mounted lustre-client [ 2003.649734] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 2006.936727] Lustre: Unmounted lustre-client [ 2016.925450] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 2062.443777] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2067.932819] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2068.635529] Lustre: Mounted lustre-client [ 2073.659354] Lustre: Unmounted lustre-client [ 2134.186293] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2140.414773] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2141.069910] Lustre: Mounted lustre-client [ 2146.184832] Lustre: Unmounted lustre-client [ 2178.861829] Lustre: DEBUG MARKER: files compared the same [ 2202.863963] Key type lgssc unregistered [ 2203.239376] LNet: 50396:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2203.251666] LNetError: 50396:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2203.285878] LNet: Removed LNI 192.168.204.25@tcp [ 2204.094949] Key type .llcrypt unregistered [ 2204.098527] Key type ._llcrypt unregistered [ 2220.811321] Key type ._llcrypt registered [ 2220.820338] Key type .llcrypt registered [ 2222.554197] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 01:27:32 (1788154052) [ 2225.087672] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2225.122302] alg: No test for adler32 (adler32-zlib) [ 2226.275804] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 2226.593848] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [ 2228.263169] Key type lgssc registered [ 2229.920760] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2285.036619] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2291.295584] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2312.025634] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2314.831152] Lustre: Mounted lustre-client [ 2322.526721] Lustre: Unmounted lustre-client [ 2351.130402] Key type lgssc unregistered [ 2351.688413] LNet: 53980:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2351.712364] LNetError: 53980:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2351.761264] LNet: Removed LNI 192.168.204.25@tcp [ 2352.741339] Key type .llcrypt unregistered [ 2352.744674] Key type ._llcrypt unregistered [ 2371.328255] Key type ._llcrypt registered [ 2371.334104] Key type .llcrypt registered [ 2373.294460] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 01:30:03 (1788154203) [ 2375.770903] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2375.806881] alg: No test for adler32 (adler32-zlib) [ 2376.899810] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 2377.221430] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [ 2379.031479] Key type lgssc registered [ 2380.268423] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2466.055265] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2503.587250] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2510.502768] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2549.375433] Key type lgssc unregistered [ 2549.697901] LNet: 57473:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2549.710899] LNetError: 57473:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2549.755804] LNet: Removed LNI 192.168.204.25@tcp [ 2550.773658] Key type .llcrypt unregistered [ 2550.777514] Key type ._llcrypt unregistered [ 2568.251686] Key type ._llcrypt registered [ 2568.261120] Key type .llcrypt registered [ 2569.824657] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 01:33:20 (1788154400) [ 2573.926347] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2574.073701] alg: No test for adler32 (adler32-zlib) [ 2575.244498] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 2575.618558] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [ 2577.409218] Key type lgssc registered [ 2579.034841] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2662.714940] Lustre: Mounted lustre-client [ 2670.614420] Lustre: Unmounted lustre-client [ 2719.067481] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 01:35:49 (1788154549) [ 2848.013188] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2870.891184] Lustre: Mounted lustre-client [ 2878.224036] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2878.234823] LustreError: 63947:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff900b88687800: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2878.255636] Lustre: 58459:0:(llite_lib.c:4339:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.204.125@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2888.627445] Lustre: Unmounted lustre-client [ 2932.347292] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 01:39:23 (1788154763) [ 2943.433394] Key type lgssc unregistered [ 2943.663493] LNet: 65158:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2943.673265] LNetError: 65158:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2943.685874] LNet: Removed LNI 192.168.204.25@tcp [ 2944.308345] Key type .llcrypt unregistered [ 2944.310244] Key type ._llcrypt unregistered [ 2955.758082] Key type ._llcrypt registered [ 2955.765296] Key type .llcrypt registered [ 2956.210634] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2956.242919] alg: No test for adler32 (adler32-zlib) [ 2957.312199] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 2957.613670] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [ 2959.319237] Key type lgssc registered [ 2960.711650] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3010.109408] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3016.601827] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3037.796992] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3039.674297] Lustre: Mounted lustre-client [ 3046.619227] Lustre: Unmounted lustre-client [ 3082.041492] Key type lgssc unregistered [ 3082.325317] LNet: 68182:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3082.330152] LNetError: 68182:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3082.342822] LNet: Removed LNI 192.168.204.25@tcp [ 3083.240789] Key type .llcrypt unregistered [ 3083.244353] Key type ._llcrypt unregistered [ 3096.520391] Key type ._llcrypt registered [ 3096.527053] Key type .llcrypt registered [ 3097.019981] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3097.043407] alg: No test for adler32 (adler32-zlib) [ 3098.075222] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 3098.326144] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [ 3099.977345] Key type lgssc registered [ 3101.340624] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3224.920269] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3231.780421] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3255.879678] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3257.680320] Lustre: Mounted lustre-client [ 3262.290485] Lustre: Unmounted lustre-client [ 3291.714355] Key type lgssc unregistered [ 3292.065833] LNet: 71614:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3292.073551] LNetError: 71614:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3292.100691] LNet: Removed LNI 192.168.204.25@tcp [ 3292.781511] Key type .llcrypt unregistered [ 3292.789753] Key type ._llcrypt unregistered [ 3310.225958] Key type ._llcrypt registered [ 3310.231196] Key type .llcrypt registered [ 3311.569622] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 01:45:42 (1788155142) [ 3313.944540] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3314.028743] alg: No test for adler32 (adler32-zlib) [ 3315.262183] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 3315.529194] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [ 3317.215242] Key type lgssc registered [ 3318.642112] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3370.954839] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3376.530600] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3398.683721] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3400.377615] Lustre: Mounted lustre-client [ 3410.013608] Lustre: Unmounted lustre-client [ 3410.614864] Lustre: Mounted lustre-client [ 3413.650835] Lustre: Unmounted lustre-client [ 3450.170441] Key type lgssc unregistered [ 3450.488948] LNet: 75318:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3450.504638] LNetError: 75318:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3450.537776] LNet: Removed LNI 192.168.204.25@tcp [ 3451.260901] Key type .llcrypt unregistered [ 3451.267461] Key type ._llcrypt unregistered [ 3463.299782] Key type ._llcrypt registered [ 3463.311969] Key type .llcrypt registered [ 3464.010441] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3464.023283] alg: No test for adler32 (adler32-zlib) [ 3465.426344] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 3465.837686] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [ 3467.567331] Key type lgssc registered [ 3468.824342] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3522.420994] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3528.234122] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3550.156651] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3551.778620] Lustre: Mounted lustre-client [ 3561.033726] Lustre: Unmounted lustre-client [ 3588.778087] Key type lgssc unregistered [ 3589.090210] LNet: 78348:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3589.101717] LNetError: 78348:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3589.120760] LNet: Removed LNI 192.168.204.25@tcp [ 3589.967545] Key type .llcrypt unregistered [ 3589.974230] Key type ._llcrypt unregistered [ 3604.086256] Key type ._llcrypt registered [ 3604.090202] Key type .llcrypt registered [ 3605.451210] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 01:50:36 (1788155436) [ 3607.769079] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3607.896703] alg: No test for adler32 (adler32-zlib) [ 3608.928681] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 3609.173723] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [ 3610.847249] Key type lgssc registered [ 3612.043146] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3659.806588] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3665.870851] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3686.525832] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3688.218140] Lustre: Mounted lustre-client [ 3704.890178] Lustre: lustre: nosquash_nids is cleared [ 3704.923543] Lustre: lustre: root_squash is set to 500:500 [ 3724.341203] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.204.25@tcp 192.168.0.[2,10]@tcp [ 3730.072785] Lustre: lustre: nosquash_nids set to 192.168.204.25/24@tcp [ 3742.583641] Lustre: Unmounted lustre-client [ 3770.047863] Key type lgssc unregistered [ 3770.298834] LNet: 82346:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3770.308352] LNetError: 82346:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3770.331404] LNet: Removed LNI 192.168.204.25@tcp [ 3771.056242] Key type .llcrypt unregistered [ 3771.062086] Key type ._llcrypt unregistered [ 3785.718458] Key type ._llcrypt registered [ 3785.721959] Key type .llcrypt registered [ 3787.066719] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 01:53:37 (1788155617) [ 3789.285554] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3791.119310] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 01:53:41 (1788155621) [ 3793.002353] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3793.153586] alg: No test for adler32 (adler32-zlib) [ 3794.274924] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 3794.509850] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [ 3796.167194] Key type lgssc registered [ 3797.256312] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3849.567757] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3855.848106] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3876.624781] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3878.165450] Lustre: lustre: root_squash is set to 500:500 [ 3878.193991] Lustre: lustre: nosquash_nids set to 192.168.204.25/24@tcp [ 3879.313936] Lustre: Mounted lustre-client [ 3889.668616] Lustre: Unmounted lustre-client [ 3919.375753] Key type lgssc unregistered [ 3919.629852] LNet: 86305:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3919.646730] LNetError: 86305:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3919.679633] LNet: Removed LNI 192.168.204.25@tcp [ 3920.597803] Key type .llcrypt unregistered [ 3920.602708] Key type ._llcrypt unregistered [ 3938.127938] Key type ._llcrypt registered [ 3938.130710] Key type .llcrypt registered [ 3939.298638] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 3941.381750] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 01:56:11 (1788155771) [ 3960.011627] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3960.056537] alg: No test for adler32 (adler32-zlib) [ 3961.152083] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 3961.482541] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [ 3963.239257] Key type lgssc registered [ 3964.582783] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4047.364540] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4053.113777] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4077.074412] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4147.352673] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4153.488183] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4176.643693] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4188.182735] Lustre: Mounted lustre-client [ 4223.139818] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff900b910b0000.ost_server_uuid 50 [ 4224.381814] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff900b910b0000.ost_server_uuid in IDLE state after 0 sec [ 4224.945761] Lustre: Mounted lustre-client [ 4229.371659] Lustre: Unmounted lustre-client [ 4232.041150] Lustre: Unmounted lustre-client [ 4280.894722] Key type lgssc unregistered [ 4281.282788] LNet: 93982:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4281.295923] LNetError: 93982:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4281.330743] LNet: Removed LNI 192.168.204.25@tcp [ 4282.353387] Key type .llcrypt unregistered [ 4282.356257] Key type ._llcrypt unregistered [ 4317.278412] Key type ._llcrypt registered [ 4317.280224] Key type .llcrypt registered [ 4318.663305] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 02:02:29 (1788156149) [ 4340.128259] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4340.204127] alg: No test for adler32 (adler32-zlib) [ 4341.311932] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 4341.637583] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [ 4343.391728] Key type lgssc registered [ 4344.469657] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4429.095980] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4435.304654] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4459.858187] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4462.914096] Lustre: Mounted lustre-client [ 4484.576291] Lustre: 95347:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788156300/real 1788156300] req@0000000067715ad7 x1875017647265024/t0(0) o400->lustre-OST0000-osc-ffff900b87319000@192.168.204.125@tcp:28/4 lens 224/224 e 0 to 1 dl 1788156316 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4484.610813] Lustre: lustre-OST0000-osc-ffff900b87319000: Connection to lustre-OST0000 (at 192.168.204.125@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4490.719151] Lustre: 95348:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788156305/real 1788156305] req@00000000f9e5f2cf x1875017647265536/t0(0) o400->lustre-OST0000-osc-ffff900b87319000@192.168.204.125@tcp:28/4 lens 224/224 e 0 to 1 dl 1788156321 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4492.660376] Lustre: lustre-OST0000-osc-ffff900b87319000: Connection restored to 192.168.204.125@tcp (at 192.168.204.125@tcp) [ 4493.855365] Lustre: 95348:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788156310/real 1788156310] req@000000000374ad45 x1875017647266048/t0(0) o400->lustre-OST0000-osc-ffff900b87319000@192.168.204.125@tcp:28/4 lens 224/224 e 0 to 1 dl 1788156326 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4494.831530] Lustre: lustre-MDT0000-mdc-ffff900b87319000: Connection to lustre-MDT0000 (at 192.168.204.125@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4498.847302] Lustre: 95347:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788156315/real 1788156315] req@00000000f1f59dd5 x1875017647266560/t0(0) o400->lustre-OST0000-osc-ffff900b87319000@192.168.204.125@tcp:28/4 lens 224/224 e 0 to 1 dl 1788156331 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4510.181185] Lustre: 95348:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788156326/real 1788156326] req@000000004694a319 x1875017647268224/t0(0) o400->MGC192.168.204.125@tcp@192.168.204.125@tcp:26/25 lens 224/224 e 0 to 1 dl 1788156342 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4510.203732] LustreError: MGC192.168.204.125@tcp: Connection to MGS (at 192.168.204.125@tcp) was lost; in progress operations using this service will fail [ 4518.850623] Lustre: lustre-MDT0000-mdc-ffff900b87319000: Connection restored to 192.168.204.125@tcp (at 192.168.204.125@tcp) [ 4520.500939] Lustre: Evicted from MGS (at 192.168.204.125@tcp) after server handle changed from 0x7a9935834c1ccf10 to 0x7a9935834c1cd290 [ 4520.514381] Lustre: MGC192.168.204.125@tcp: Connection restored to 192.168.204.125@tcp (at 192.168.204.125@tcp) [ 4525.191165] Lustre: Unmounted lustre-client [ 4554.807040] Key type lgssc unregistered [ 4555.052911] LNet: 99645:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4555.057312] LNetError: 99645:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4555.079609] LNet: Removed LNI 192.168.204.25@tcp [ 4555.838851] Key type .llcrypt unregistered [ 4555.843584] Key type ._llcrypt unregistered [ 4574.249703] Key type ._llcrypt registered [ 4574.254853] Key type .llcrypt registered [ 4575.643897] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 02:06:46 (1788156406) [ 4590.920749] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4591.112677] alg: No test for adler32 (adler32-zlib) [ 4592.148296] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 4592.347437] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [ 4594.056394] Key type lgssc registered [ 4595.468115] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4633.668700] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4736.070237] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4742.355294] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4766.626837] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4769.456184] Lustre: Mounted lustre-client [ 4771.253827] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 4773.594362] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 02:10:04 (1788156604) [ 5048.386513] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5058.716783] Lustre: Unmounted lustre-client [ 5193.954563] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5200.582750] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5225.295606] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5272.233738] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 02:18:22 (1788157102) [ 5385.269078] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5392.546676] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5421.507679] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5422.421765] Lustre: Mounted lustre-client [ 5428.764564] Lustre: Unmounted lustre-client [ 5467.429835] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 02:21:37 (1788157297) [ 5571.655869] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5577.515986] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5605.423913] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5606.128536] Lustre: Mounted lustre-client [ 5611.084958] Lustre: Unmounted lustre-client [ 5659.038914] Key type lgssc unregistered [ 5659.377558] LNet: 122685:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5659.384473] LNetError: 122685:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5659.414795] LNet: Removed LNI 192.168.204.25@tcp [ 5660.195579] Key type .llcrypt unregistered [ 5660.198616] Key type ._llcrypt unregistered [ 5679.082946] Key type ._llcrypt registered [ 5679.084907] Key type .llcrypt registered [ 5680.444508] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 02:25:11 (1788157511) [ 5683.792365] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5683.812449] alg: No test for adler32 (adler32-zlib) [ 5685.103538] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 5685.485974] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [ 5687.234321] Key type lgssc registered [ 5688.626798] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5742.977065] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5749.745813] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5773.793751] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5775.489229] Lustre: Mounted lustre-client [ 5789.440884] Lustre: Unmounted lustre-client [ 5820.309961] Key type lgssc unregistered [ 5820.622882] LNet: 126276:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5820.629250] LNetError: 126276:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5820.663638] LNet: Removed LNI 192.168.204.25@tcp [ 5821.698163] Key type .llcrypt unregistered [ 5821.711094] Key type ._llcrypt unregistered [ 5840.281582] Key type ._llcrypt registered [ 5840.284687] Key type .llcrypt registered [ 5842.112678] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 02:27:52 (1788157672) [ 5845.051516] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5845.099288] alg: No test for adler32 (adler32-zlib) [ 5846.307668] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 5846.635789] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [ 5848.359336] Key type lgssc registered [ 5849.963792] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5902.927460] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5909.070887] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5933.211940] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5935.867136] Lustre: Mounted lustre-client [ 5946.354854] Lustre: lustre-OST0000-osc-ffff900b872f9800: Connection to lustre-OST0000 (at 192.168.204.125@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5959.204325] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff900b872f9800.ost_server_uuid 50 [ 5961.631791] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 5973.376330] Lustre: Unmounted lustre-client [ 5997.312546] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 02:30:27 (1788157827) [ 6032.408290] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6038.514363] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6059.536960] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6086.869138] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6087.757204] Lustre: Mounted lustre-client [ 6092.782566] Lustre: lustre-OST0000-osc-ffff900ba01e3800: Connection to lustre-OST0000 (at 192.168.204.125@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6118.373751] Lustre: Unmounted lustre-client [ 6166.975756] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 02:33:17 (1788157997) [ 6203.212729] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6209.792459] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6235.193512] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6259.192797] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6260.133546] Lustre: Mounted lustre-client [ 6265.323293] Lustre: lustre-OST0000-osc-ffff900ba01e7000: Connection to lustre-OST0000 (at 192.168.204.125@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6282.553170] Lustre: Unmounted lustre-client [ 6333.461550] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 02:36:03 (1788158163) [ 6441.492551] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6446.853996] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6473.841764] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6548.861621] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6554.754683] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6576.536724] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6608.686622] Lustre: Mounted lustre-client [ 6650.955186] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6654.634943] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 2 sec [ 6656.405347] Lustre: Unmounted lustre-client [ 6689.434161] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 02:42:00 (1788158520) [ 6725.715303] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6732.793912] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6754.305721] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6795.848660] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6826.490179] Lustre: Mounted lustre-client [ 6871.264844] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6872.610230] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6884.672225] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6895.030410] Lustre: Unmounted lustre-client [ 6966.653530] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 02:46:36 (1788158796) [ 7000.937516] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7007.225593] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7030.495455] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7031.356347] Lustre: Mounted lustre-client [ 7055.850748] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7057.319840] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7072.636384] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff900b83872000.ost_server_uuid 50 [ 7074.427426] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff900b83872000.ost_server_uuid in IDLE state after 0 sec [ 7077.686343] Lustre: Unmounted lustre-client [ 7077.951310] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 7077.955397] Lustre: Skipped 3 previous similar messages [ 7078.034235] Lustre: Mounted lustre-client [ 7079.192956] LustreError: 149710:0:(sec.c:413:import_sec_validate_get()) import 00000000a5a90577 (NEW) with no sec [ 7139.363858] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 02:49:29 (1788158969) [ 7179.004487] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7184.634498] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7211.632929] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7235.258195] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7235.790802] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 7235.967327] Lustre: Mounted lustre-client [ 7248.198232] Lustre: Unmounted lustre-client [ 7248.201981] Lustre: Skipped 1 previous similar message [ 7290.941646] Key type lgssc unregistered [ 7291.264168] LNet: 153122:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7291.275457] LNetError: 153122:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7291.303771] LNet: Removed LNI 192.168.204.25@tcp [ 7292.128480] Key type .llcrypt unregistered [ 7292.130960] Key type ._llcrypt unregistered [ 7309.961934] Key type ._llcrypt registered [ 7309.966128] Key type .llcrypt registered [ 7311.292354] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 02:52:21 (1788159141) [ 7312.535121] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7312.584706] alg: No test for adler32 (adler32-zlib) [ 7313.661435] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 7313.921209] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [ 7315.615251] Key type lgssc registered [ 7316.731775] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7372.210183] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7377.850638] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7401.894551] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7426.602450] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7428.477346] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7429.721956] Lustre: Mounted lustre-client [ 7436.890542] LustreError: lustre-MDT0001-mdc-ffff900b91ca9000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 7436.933467] Lustre: lustre-MDT0001-mdc-ffff900b91ca9000: Connection restored to 192.168.204.125@tcp (at 192.168.204.125@tcp) [ 7444.558592] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7446.140442] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7468.063568] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7472.427278] Lustre: Unmounted lustre-client [ 7498.869756] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 02:55:29 (1788159329) [ 7596.547230] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7603.441579] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7629.171996] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7629.984994] Lustre: Mounted lustre-client [ 7659.271399] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7661.346674] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7663.619841] Lustre: lustre-OST0001-osc-ffff900b837e8800: Connection to lustre-OST0001 (at 192.168.204.125@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7674.760908] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7685.112719] Lustre: Unmounted lustre-client [ 7731.205407] Key type lgssc unregistered [ 7731.411754] LNet: 162741:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7731.419490] LNetError: 162741:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7731.456798] LNet: Removed LNI 192.168.204.25@tcp [ 7732.225095] Key type .llcrypt unregistered [ 7732.228118] Key type ._llcrypt unregistered [ 7768.433516] Key type ._llcrypt registered [ 7768.436484] Key type .llcrypt registered [ 7769.747062] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 03:00:00 (1788159600) [ 7772.550921] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7772.592830] alg: No test for adler32 (adler32-zlib) [ 7773.894698] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 7774.499115] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [ 7776.247305] Key type lgssc registered [ 7777.632681] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7832.085662] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7838.925840] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7859.647490] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7861.268554] Lustre: Mounted lustre-client [ 7908.092085] Lustre: Unmounted lustre-client [ 7953.226576] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7953.727757] Lustre: Mounted lustre-client [ 7990.420797] Lustre: Unmounted lustre-client [ 8019.231608] Key type lgssc unregistered [ 8019.454691] LNet: 167202:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8019.463986] LNetError: 167202:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8019.479749] LNet: Removed LNI 192.168.204.25@tcp [ 8020.326545] Key type .llcrypt unregistered [ 8020.331543] Key type ._llcrypt unregistered [ 8037.571622] Key type ._llcrypt registered [ 8037.573993] Key type .llcrypt registered [ 8038.798091] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 03:04:29 (1788159869) [ 8041.784140] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8041.868769] alg: No test for adler32 (adler32-zlib) [ 8043.245359] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 8043.595876] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [ 8045.447233] Key type lgssc registered [ 8047.485994] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8104.237859] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8111.986743] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8135.048701] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8137.017728] Lustre: Mounted lustre-client [ 8161.820482] Lustre: Unmounted lustre-client [ 8191.533904] Key type lgssc unregistered [ 8191.858643] LNet: 170958:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8191.873039] LNetError: 170958:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8191.904369] LNet: Removed LNI 192.168.204.25@tcp [ 8192.695885] Key type .llcrypt unregistered [ 8192.700966] Key type ._llcrypt unregistered [ 8205.981691] Key type ._llcrypt registered [ 8205.989484] Key type .llcrypt registered [ 8206.541164] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8206.584210] alg: No test for adler32 (adler32-zlib) [ 8207.668159] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 8207.915326] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [ 8209.607325] Key type lgssc registered [ 8211.087830] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8264.720449] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8270.971687] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8294.487527] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8296.394338] Lustre: Mounted lustre-client [ 8304.834573] Lustre: Unmounted lustre-client [ 8334.497593] Key type lgssc unregistered [ 8334.913862] LNet: 174018:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8334.931044] LNetError: 174018:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8334.951368] LNet: Removed LNI 192.168.204.25@tcp [ 8335.765251] Key type .llcrypt unregistered [ 8335.776947] Key type ._llcrypt unregistered [ 8354.232758] Key type ._llcrypt registered [ 8354.236834] Key type .llcrypt registered [ 8355.808893] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 03:09:46 (1788160186) [ 8358.445060] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8358.513536] alg: No test for adler32 (adler32-zlib) [ 8359.706382] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 8360.038597] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [ 8361.695179] Key type lgssc registered [ 8362.737718] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8411.796105] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8417.026430] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8437.589215] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8440.283162] Lustre: Mounted lustre-client [ 8461.166284] Lustre: Unmounted lustre-client [ 8492.697647] Key type lgssc unregistered [ 8492.867523] LNet: 177773:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8492.877482] LNetError: 177773:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8492.902726] LNet: Removed LNI 192.168.204.25@tcp [ 8493.589930] Key type .llcrypt unregistered [ 8493.593852] Key type ._llcrypt unregistered [ 8507.243198] Key type ._llcrypt registered [ 8507.254211] Key type .llcrypt registered [ 8507.908875] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8507.927990] alg: No test for adler32 (adler32-zlib) [ 8509.183434] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 8509.491042] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [ 8511.248148] Key type lgssc registered [ 8513.008500] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8575.391307] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8581.950721] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8604.323268] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8616.497630] Lustre: Mounted lustre-client [ 8624.559651] Lustre: Unmounted lustre-client [ 8658.251768] Key type lgssc unregistered [ 8658.623141] LNet: 180840:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8658.641421] LNetError: 180840:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8658.662329] LNet: Removed LNI 192.168.204.25@tcp [ 8659.581157] Key type .llcrypt unregistered [ 8659.584197] Key type ._llcrypt unregistered [ 8678.811387] Key type ._llcrypt registered [ 8678.821055] Key type .llcrypt registered [ 8680.406810] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 03:15:10 (1788160510) [ 8705.754021] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8705.928961] alg: No test for adler32 (adler32-zlib) [ 8707.125881] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 8707.582909] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [ 8709.367221] Key type lgssc registered [ 8711.069235] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8805.453382] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8811.602204] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8836.070994] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8880.809190] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 03:18:31 (1788160711) [ 8918.706520] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8925.064616] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8949.282525] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8952.213656] Lustre: Mounted lustre-client [ 8958.448985] Lustre: Unmounted lustre-client [ 8988.472429] Key type lgssc unregistered [ 8988.824446] LNet: 187937:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8988.849719] LNetError: 187937:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8988.880985] LNet: Removed LNI 192.168.204.25@tcp [ 8989.637679] Key type .llcrypt unregistered [ 8989.645557] Key type ._llcrypt unregistered [ 9007.949898] Key type ._llcrypt registered [ 9007.951949] Key type .llcrypt registered [ 9009.908344] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 9011.974380] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 03:20:42 (1788160842) [ 9032.521985] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9032.567107] alg: No test for adler32 (adler32-zlib) [ 9033.717107] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 9033.985869] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [ 9035.687202] Key type lgssc registered [ 9037.308400] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9138.243603] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9145.065742] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9168.504558] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9192.373435] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9194.325785] Lustre: Mounted lustre-client [ 9220.241335] Lustre: Unmounted lustre-client [ 9353.097897] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9358.648693] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9383.968901] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9434.852309] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 03:27:44 (1788161264) [ 9436.510444] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 9438.618483] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 03:27:49 (1788161269) [ 9544.072797] Key type lgssc unregistered [ 9544.289600] LNet: 199394:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9544.296917] LNetError: 199394:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9544.328960] LNet: Removed LNI 192.168.204.25@tcp [ 9545.120159] Key type .llcrypt unregistered [ 9545.124717] Key type ._llcrypt unregistered [ 9568.033356] Key type ._llcrypt registered [ 9568.036144] Key type .llcrypt registered [ 9569.598038] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 03:29:59 (1788161399) [ 9612.605208] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9612.642478] alg: No test for adler32 (adler32-zlib) [ 9613.882521] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 9614.227057] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [ 9616.031987] Key type lgssc registered [ 9617.872418] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9672.189690] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9678.492309] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9716.422829] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9755.692424] Key type lgssc unregistered [ 9756.036967] LNet: 203147:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9756.054650] LNetError: 203147:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9756.076853] LNet: Removed LNI 192.168.204.25@tcp [ 9756.964989] Key type .llcrypt unregistered [ 9756.969083] Key type ._llcrypt unregistered [ 9773.260974] Key type ._llcrypt registered [ 9773.262822] Key type .llcrypt registered [ 9774.712238] Lustre: DEBUG MARKER: == conf-sanity test 57c: servicenode metadata preserves legacy discovery by default ========================================================== 03:33:25 (1788161605) [ 9785.703893] Key type .llcrypt unregistered [ 9785.705304] Key type ._llcrypt unregistered [ 9796.024556] Key type ._llcrypt registered [ 9796.027930] Key type .llcrypt registered [ 9796.367697] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9796.383796] alg: No test for adler32 (adler32-zlib) [ 9797.723234] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 9798.327777] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [ 9800.199208] Key type lgssc registered [ 9801.992320] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9853.656189] LNet: Added LNI 192.168.204.25@tcp1 [8/256/0/180] [ 9941.161717] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9948.865301] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10079.093953] Key type lgssc unregistered [10079.338069] LNet: 208735:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10079.349488] LNetError: 208735:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10080.351160] LNet: Removed LNI 192.168.204.25@tcp [10080.354860] LNetError: 208735:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10080.421325] LNet: Removed LNI 192.168.204.25@tcp1 [10081.205937] Key type .llcrypt unregistered [10081.211677] Key type ._llcrypt unregistered [10097.005116] Key type ._llcrypt registered [10097.013444] Key type .llcrypt registered [10098.439470] Lustre: DEBUG MARKER: == conf-sanity test 57d: servicenode policy splits OSS discovery views ========================================================== 03:38:48 (1788161928) [10108.162950] Key type .llcrypt unregistered [10108.164557] Key type ._llcrypt unregistered [10116.596391] Key type ._llcrypt registered [10116.610649] Key type .llcrypt registered [10116.958255] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10116.972974] alg: No test for adler32 (adler32-zlib) [10118.125809] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [10118.419278] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [10120.103334] Key type lgssc registered [10121.742487] Lustre: Echo OBD driver; http://www.lustre.org/ [10164.480364] LNet: Added LNI 192.168.204.25@tcp1 [8/256/0/180] [10242.982508] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10248.676809] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10366.444777] Key type lgssc unregistered [10366.748760] LNet: 213773:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10366.757870] LNetError: 213773:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10366.773611] LNet: Removed LNI 192.168.204.25@tcp [10367.860183] LNet: Removed LNI 192.168.204.25@tcp1 [10368.730308] Key type .llcrypt unregistered [10368.735558] Key type ._llcrypt unregistered [10381.257885] Key type ._llcrypt registered [10381.259926] Key type .llcrypt registered [10382.790678] Lustre: DEBUG MARKER: == conf-sanity test 57e: servicenode policy overrides self grouping ========================================================== 03:43:33 (1788162213) [10395.548618] Key type .llcrypt unregistered [10395.559442] Key type ._llcrypt unregistered [10407.182663] Key type ._llcrypt registered [10407.189661] Key type .llcrypt registered [10408.027761] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10408.072685] alg: No test for adler32 (adler32-zlib) [10409.603865] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [10410.000229] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [10411.823429] Key type lgssc registered [10413.913924] Lustre: Echo OBD driver; http://www.lustre.org/ [10458.031842] LNet: Added LNI 192.168.204.25@tcp1 [8/256/0/180] [10534.441259] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10541.913841] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10625.515926] Key type lgssc unregistered [10625.733773] LNet: 218481:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10625.750976] LNetError: 218481:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10625.758736] LNet: Removed LNI 192.168.204.25@tcp [10626.796798] LNet: Removed LNI 192.168.204.25@tcp1 [10627.450407] Key type .llcrypt unregistered [10627.453793] Key type ._llcrypt unregistered [10640.983667] Key type ._llcrypt registered [10640.989418] Key type .llcrypt registered [10642.298331] Lustre: DEBUG MARKER: == conf-sanity test 57f: servicenode policy overrides NUMA grouping ========================================================== 03:47:52 (1788162472) [10652.700541] Key type .llcrypt unregistered [10652.704408] Key type ._llcrypt unregistered [10659.752523] Key type ._llcrypt registered [10659.760525] Key type .llcrypt registered [10660.397895] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10660.423785] alg: No test for adler32 (adler32-zlib) [10661.608200] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [10661.868710] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [10663.575458] Key type lgssc registered [10664.829698] Lustre: Echo OBD driver; http://www.lustre.org/ [10706.063497] LNet: Added LNI 192.168.204.25@tcp1 [8/256/0/180] [10781.872995] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10787.033493] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10861.301497] Key type lgssc unregistered [10861.547061] LNet: 223189:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10861.555270] LNetError: 223189:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10861.566561] LNet: Removed LNI 192.168.204.25@tcp [10862.630140] LNet: Removed LNI 192.168.204.25@tcp1 [10863.277753] Key type .llcrypt unregistered [10863.280641] Key type ._llcrypt unregistered [10876.829600] Key type ._llcrypt registered [10876.835798] Key type .llcrypt registered [10878.375387] Lustre: DEBUG MARKER: == conf-sanity test 57g: servicenode policy keeps ungrouped NIDs discoverable ========================================================== 03:51:48 (1788162708) [10889.864412] Key type .llcrypt unregistered [10889.868986] Key type ._llcrypt unregistered [10898.023238] Key type ._llcrypt registered [10898.026969] Key type .llcrypt registered [10898.446138] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10898.475132] alg: No test for adler32 (adler32-zlib) [10899.615378] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [10899.994143] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [10901.783193] Key type lgssc registered [10902.741783] Lustre: Echo OBD driver; http://www.lustre.org/ [10940.835491] LNet: Added LNI 192.168.204.25@tcp1 [8/256/0/180] [11014.730832] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11020.033553] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11143.186896] Key type lgssc unregistered [11143.472531] LNet: 228244:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11143.482146] LNetError: 228244:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11143.494475] LNet: Removed LNI 192.168.204.25@tcp [11144.550699] LNet: Removed LNI 192.168.204.25@tcp1 [11145.326204] Key type .llcrypt unregistered [11145.327819] Key type ._llcrypt unregistered [11157.432098] Key type ._llcrypt registered [11157.437451] Key type .llcrypt registered [11158.823790] Lustre: DEBUG MARKER: == conf-sanity test 57h: servicenode policy is active before the first target mounts ========================================================== 03:56:29 (1788162989) [11168.249379] Key type .llcrypt unregistered [11168.250786] Key type ._llcrypt unregistered [11174.071360] Key type ._llcrypt registered [11174.074280] Key type .llcrypt registered [11174.511693] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11174.527746] alg: No test for adler32 (adler32-zlib) [11175.613519] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [11175.826724] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [11177.543535] Key type lgssc registered [11178.732990] Lustre: Echo OBD driver; http://www.lustre.org/ [11217.814383] LNet: Added LNI 192.168.204.25@tcp1 [8/256/0/180] [11243.178890] Key type lgssc unregistered [11243.457163] LNet: 231151:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11243.473862] LNetError: 231151:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11243.484163] LNet: Removed LNI 192.168.204.25@tcp [11244.516825] LNet: Removed LNI 192.168.204.25@tcp1 [11245.236243] Key type .llcrypt unregistered [11245.241233] Key type ._llcrypt unregistered [11257.311720] Key type ._llcrypt registered [11257.313751] Key type .llcrypt registered [11258.834444] Lustre: DEBUG MARKER: == conf-sanity test 57i: servicenode Push never falls back to the merged view ========================================================== 03:58:09 (1788163089) [11269.516377] Key type .llcrypt unregistered [11269.525092] Key type ._llcrypt unregistered [11275.771285] Key type ._llcrypt registered [11275.773216] Key type .llcrypt registered [11276.061426] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11276.075635] alg: No test for adler32 (adler32-zlib) [11277.132156] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [11277.430652] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [11279.145260] Key type lgssc registered [11280.360929] Lustre: Echo OBD driver; http://www.lustre.org/ [11319.548847] LNet: Added LNI 192.168.204.25@tcp1 [8/256/0/180] [11322.318653] LNet: Added LNI 192.168.204.25@tcp2 [8/256/0/180] [11407.995595] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11414.077300] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11544.489604] Key type lgssc unregistered [11544.867322] LNet: 236351:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11544.887215] LNetError: 236351:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11544.900854] LNet: Removed LNI 192.168.204.25@tcp [11545.951548] LNet: Removed LNI 192.168.204.25@tcp1 [11545.958538] LNetError: 236351:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11545.979077] LNetError: 236351:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Skipped 1 previous similar message [11546.002411] LNet: Removed LNI 192.168.204.25@tcp2 [11546.956799] Key type .llcrypt unregistered [11546.960615] Key type ._llcrypt unregistered [11561.757875] Key type ._llcrypt registered [11561.761786] Key type .llcrypt registered [11563.402931] Lustre: DEBUG MARKER: == conf-sanity test 57j: failnode targets contribute no servicenode group ========================================================== 04:03:13 (1788163393) [11573.054890] Key type .llcrypt unregistered [11573.065550] Key type ._llcrypt unregistered [11579.790290] Key type ._llcrypt registered [11579.794344] Key type .llcrypt registered [11580.189801] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11580.205921] alg: No test for adler32 (adler32-zlib) [11581.325804] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [11581.590343] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [11583.239612] Key type lgssc registered [11584.414204] Lustre: Echo OBD driver; http://www.lustre.org/ [11622.895610] LNet: Added LNI 192.168.204.25@tcp1 [8/256/0/180] [11702.578349] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11708.777236] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11834.118462] Key type lgssc unregistered [11834.426507] LNet: 241378:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11834.441410] LNetError: 241378:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11834.447269] LNet: Removed LNI 192.168.204.25@tcp [11835.504730] LNet: Removed LNI 192.168.204.25@tcp1 [11836.300101] Key type .llcrypt unregistered [11836.303724] Key type ._llcrypt unregistered [11849.702703] Key type ._llcrypt registered [11849.705752] Key type .llcrypt registered [11851.126951] Lustre: DEBUG MARKER: == conf-sanity test 57k: unknown target_nid_policy stops the module load ========================================================== 04:08:01 (1788163681) [11860.645482] Key type .llcrypt unregistered [11860.649404] Key type ._llcrypt unregistered [11888.220369] Key type ._llcrypt registered [11888.223663] Key type .llcrypt registered [11890.074376] Lustre: DEBUG MARKER: == conf-sanity test 57l: nodemap nidrange visibility filters a client class at runtime ========================================================== 04:08:40 (1788163720) [11901.778618] Key type .llcrypt unregistered [11901.781270] Key type ._llcrypt unregistered [11909.114086] Key type ._llcrypt registered [11909.118355] Key type .llcrypt registered [11909.683993] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11909.701316] alg: No test for adler32 (adler32-zlib) [11910.955175] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [11911.413234] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [11913.207187] Key type lgssc registered [11914.545863] Lustre: Echo OBD driver; http://www.lustre.org/ [11955.784472] LNet: Added LNI 192.168.204.25@tcp1 [8/256/0/180] [12038.467577] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12044.298965] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12170.460058] Key type lgssc unregistered [12170.988321] LNet: 247723:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12171.001169] LNetError: 247723:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12171.013442] LNet: Removed LNI 192.168.204.25@tcp [12172.077450] LNet: Removed LNI 192.168.204.25@tcp1 [12173.466754] Key type .llcrypt unregistered [12173.470272] Key type ._llcrypt unregistered [12188.270207] Key type ._llcrypt registered [12188.278461] Key type .llcrypt registered [12190.146992] Lustre: DEBUG MARKER: == conf-sanity test 57m: nodemap visibility class unions all configured client ranges ========================================================== 04:13:40 (1788164020) [12200.920753] Key type .llcrypt unregistered [12200.924244] Key type ._llcrypt unregistered [12207.982428] Key type ._llcrypt registered [12207.992028] Key type .llcrypt registered [12208.665372] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12208.677393] alg: No test for adler32 (adler32-zlib) [12209.826672] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [12210.138519] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [12211.831350] Key type lgssc registered [12213.113970] Lustre: Echo OBD driver; http://www.lustre.org/ [12253.934632] LNet: Added LNI 192.168.204.25@tcp1 [8/256/0/180] [12331.046471] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12337.653682] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12463.356950] Key type lgssc unregistered [12463.616311] LNet: 252779:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12463.627598] LNetError: 252779:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12463.635615] LNet: Removed LNI 192.168.204.25@tcp [12464.689890] LNet: Removed LNI 192.168.204.25@tcp1 [12465.328882] Key type .llcrypt unregistered [12465.332059] Key type ._llcrypt unregistered [12477.655472] Key type ._llcrypt registered [12477.659917] Key type .llcrypt registered [12479.079685] Lustre: DEBUG MARKER: == conf-sanity test 57n: independent nodemap visibility classes select independent views ========================================================== 04:18:29 (1788164309) [12487.190438] Key type .llcrypt unregistered [12487.196081] Key type ._llcrypt unregistered [12492.888319] Key type ._llcrypt registered [12492.890694] Key type .llcrypt registered [12493.195647] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12493.213331] alg: No test for adler32 (adler32-zlib) [12494.302983] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [12494.524698] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [12496.175282] Key type lgssc registered [12497.182968] Lustre: Echo OBD driver; http://www.lustre.org/ [12532.137155] LNet: Added LNI 192.168.204.25@tcp1 [8/256/0/180] [12603.082430] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12609.609515] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12741.829389] Key type lgssc unregistered [12742.252144] LNet: 257848:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12742.268367] LNetError: 257848:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12742.278847] LNet: Removed LNI 192.168.204.25@tcp [12743.335929] LNet: Removed LNI 192.168.204.25@tcp1 [12744.269820] Key type .llcrypt unregistered [12744.272229] Key type ._llcrypt unregistered [12755.984979] Key type ._llcrypt registered [12755.987970] Key type .llcrypt registered [12757.383064] Lustre: DEBUG MARKER: == conf-sanity test 57o: nodemap visibility composes with servicenode policy ========================================================== 04:23:07 (1788164587) [12768.399422] Key type .llcrypt unregistered [12768.401548] Key type ._llcrypt unregistered [12778.159062] Key type ._llcrypt registered [12778.161871] Key type .llcrypt registered [12778.443294] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12778.479094] alg: No test for adler32 (adler32-zlib) [12779.521776] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [12779.745109] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [12781.441261] Key type lgssc registered [12782.559669] Lustre: Echo OBD driver; http://www.lustre.org/ [12821.646898] LNet: Added LNI 192.168.204.25@tcp1 [8/256/0/180] [12897.487590] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12903.659347] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12993.259794] Key type lgssc unregistered [12993.545783] LNet: 262574:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12993.555196] LNetError: 262574:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12993.564315] LNet: Removed LNI 192.168.204.25@tcp [12994.613114] LNet: Removed LNI 192.168.204.25@tcp1 [12995.646452] Key type .llcrypt unregistered [12995.655956] Key type ._llcrypt unregistered [13011.263403] Key type ._llcrypt registered [13011.269396] Key type .llcrypt registered [13012.655539] Lustre: DEBUG MARKER: == conf-sanity test 57p: nodemap visibility includes server NIDs with routed reachability ========================================================== 04:27:23 (1788164843) [13022.645345] Key type .llcrypt unregistered [13022.647828] Key type ._llcrypt unregistered [13030.851340] Key type ._llcrypt registered [13030.854213] Key type .llcrypt registered [13031.255784] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13031.290567] alg: No test for adler32 (adler32-zlib) [13032.345865] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [13032.516358] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [13034.175860] Key type lgssc registered [13035.406114] Lustre: Echo OBD driver; http://www.lustre.org/ [13075.848141] LNet: Added LNI 192.168.204.25@tcp1 [8/256/0/180] [13077.821175] LNet: Added LNI 192.168.204.25@tcp2 [8/256/0/180] [13158.180886] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13164.170809] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13234.424648] Lustre: DEBUG MARKER: SKIP: conf-sanity test_57p need a remote client with no Lustre server role for routing [13273.371760] Key type lgssc unregistered [13273.645100] LNet: 267479:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13273.664040] LNetError: 267479:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13273.674385] LNet: Removed LNI 192.168.204.25@tcp [13273.679649] LNet: Removed LNI 192.168.204.25@tcp1 [13273.693248] LNet: Removed LNI 192.168.204.25@tcp2 [13274.382874] Key type .llcrypt unregistered [13274.387173] Key type ._llcrypt unregistered [13288.316188] Key type ._llcrypt registered [13288.320965] Key type .llcrypt registered [13290.232358] Lustre: DEBUG MARKER: == conf-sanity test 57q: active nodemap visibility fails closed while state is replaced ========================================================== 04:32:00 (1788165120) [13301.786382] Key type .llcrypt unregistered [13301.793119] Key type ._llcrypt unregistered [13311.128466] Key type ._llcrypt registered [13311.137476] Key type .llcrypt registered [13311.499546] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13311.551756] alg: No test for adler32 (adler32-zlib) [13312.744941] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [13313.032246] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [13314.760880] Key type lgssc registered [13316.278982] Lustre: Echo OBD driver; http://www.lustre.org/ [13360.312209] LNet: Added LNI 192.168.204.25@tcp1 [8/256/0/180] [13446.825333] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13453.281656] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13597.704799] Key type lgssc unregistered [13597.934831] LNet: 272583:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13597.939862] LNetError: 272583:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13597.944878] LNet: Removed LNI 192.168.204.25@tcp [13597.970081] LNet: Removed LNI 192.168.204.25@tcp1 [13598.557854] Key type .llcrypt unregistered [13598.561041] Key type ._llcrypt unregistered [13610.847416] Key type ._llcrypt registered [13610.853413] Key type .llcrypt registered [13612.770715] Lustre: DEBUG MARKER: == conf-sanity test 57r: MGS restart publishes persisted nodemap visibility ========================================================== 04:37:22 (1788165442) [13622.995645] Key type .llcrypt unregistered [13622.998115] Key type ._llcrypt unregistered [13630.157534] Key type ._llcrypt registered [13630.165543] Key type .llcrypt registered [13630.569183] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13630.585717] alg: No test for adler32 (adler32-zlib) [13631.711838] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [13632.000880] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [13633.783764] Key type lgssc registered [13635.280471] Lustre: Echo OBD driver; http://www.lustre.org/ [13672.703175] LNet: Added LNI 192.168.204.25@tcp1 [8/256/0/180] [13711.319166] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13717.154747] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13754.600537] Key type lgssc unregistered [13754.836617] LNet: 276133:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13754.848949] LNetError: 276133:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13754.858263] LNet: Removed LNI 192.168.204.25@tcp [13754.885127] LNet: Removed LNI 192.168.204.25@tcp1 [13755.665912] Key type .llcrypt unregistered [13755.668336] Key type ._llcrypt unregistered [13767.380550] Key type ._llcrypt registered [13767.385743] Key type .llcrypt registered [13767.955580] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13767.991316] alg: No test for adler32 (adler32-zlib) [13769.143155] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [13769.369470] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [13771.111201] Key type lgssc registered [13772.423986] Lustre: Echo OBD driver; http://www.lustre.org/ [13825.766161] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13832.111870] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13858.817315] Key type lgssc unregistered [13859.086676] LNet: 279054:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13859.100733] LNetError: 279054:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13859.122529] LNet: Removed LNI 192.168.204.25@tcp [13860.047722] Key type .llcrypt unregistered [13860.054595] Key type ._llcrypt unregistered [13872.991221] Key type ._llcrypt registered [13872.993214] Key type .llcrypt registered [13874.328340] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 04:41:44 (1788165704) [13877.485902] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13877.506762] alg: No test for adler32 (adler32-zlib) [13878.622827] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [13878.853102] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [13880.527635] Key type lgssc registered [13881.737372] Lustre: Echo OBD driver; http://www.lustre.org/ [13933.884956] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13939.659628] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13963.859662] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13965.433337] LNetError: 279628:0:(lib-move.c:2123:lnet_handle_find_routed_path()) peer 192.168.204.125@tcp1 has no available nets [13966.703050] Lustre: Mounted lustre-client [13977.070306] Lustre: lustre-MDT0000-mdc-ffff900b855df800: Connection to lustre-MDT0000 (at 192.168.204.125@tcp) was lost; in progress operations using this service will wait for recovery to complete [13987.310657] Lustre: lustre-MDT0001-mdc-ffff900b855df800: Connection to lustre-MDT0001 (at 192.168.204.125@tcp) was lost; in progress operations using this service will wait for recovery to complete [13998.561197] Lustre: 279632:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788165814/real 1788165814] req@0000000050d11dd5 x1875027647610880/t0(0) o400->MGC192.168.204.125@tcp@192.168.204.125@tcp:26/25 lens 224/224 e 0 to 1 dl 1788165830 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [13998.611229] LustreError: MGC192.168.204.125@tcp: Connection to MGS (at 192.168.204.125@tcp) was lost; in progress operations using this service will fail [14008.875361] Lustre: Evicted from MGS (at 192.168.204.125@tcp) after server handle changed from 0xe3be5037ce7b809c to 0xe3be5037ce7b99e0 [14008.890550] Lustre: MGC192.168.204.125@tcp: Connection restored to 192.168.204.125@tcp (at 192.168.204.125@tcp) [14032.366615] LustreError: lustre-MDT0000-mdc-ffff900b855df800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [14032.414122] Lustre: lustre-MDT0000-mdc-ffff900b855df800: Connection restored to 192.168.204.125@tcp (at 192.168.204.125@tcp) [14038.044812] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14039.862887] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [14045.971603] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14047.696549] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [14051.167495] Lustre: Unmounted lustre-client [14079.911948] Key type lgssc unregistered [14080.184336] LNet: 283589:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14080.192284] LNetError: 283589:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14080.227396] LNet: Removed LNI 192.168.204.25@tcp [14081.168527] Key type .llcrypt unregistered [14081.170726] Key type ._llcrypt unregistered [14099.779678] Key type ._llcrypt registered [14099.782578] Key type .llcrypt registered [14101.223578] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 04:45:31 (1788165931) [14104.803392] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14104.837442] alg: No test for adler32 (adler32-zlib) [14105.954427] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [14106.329587] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [14108.223582] Key type lgssc registered [14109.716113] Lustre: Echo OBD driver; http://www.lustre.org/ [14165.559817] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14172.093479] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14232.642846] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14241.304465] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14281.213781] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14304.849363] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14355.047474] Key type lgssc unregistered [14355.335359] LNet: 288885:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14355.340448] LNetError: 288885:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14355.358480] LNet: Removed LNI 192.168.204.25@tcp [14356.219183] Key type .llcrypt unregistered [14356.225146] Key type ._llcrypt unregistered [14399.068785] Key type ._llcrypt registered [14399.076474] Key type .llcrypt registered [14401.422384] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 04:50:30 (1788166230) [14443.914883] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14443.999749] alg: No test for adler32 (adler32-zlib) [14445.242438] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [14445.562871] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [14447.264348] Key type lgssc registered [14448.353660] Lustre: Echo OBD driver; http://www.lustre.org/ [14547.216036] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14554.094495] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14579.202517] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14624.587875] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 04:54:14 (1788166454) [14633.684466] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 04:54:24 (1788166464) [14667.488586] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14672.756821] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14694.656393] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14697.599576] Lustre: Mounted lustre-client [14703.119282] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [14704.848676] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [14706.660897] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [14708.517751] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [14712.817704] Lustre: lustre-MDT0000-mdc-ffff900b84898800: Connection to lustre-MDT0000 (at 192.168.204.125@tcp) was lost; in progress operations using this service will wait for recovery to complete [14728.160284] Lustre: 290781:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788166544/real 1788166544] req@0000000050d11dd5 x1875028242081408/t0(0) o400->MGC192.168.204.125@tcp@192.168.204.125@tcp:26/25 lens 224/224 e 0 to 1 dl 1788166560 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [14728.182192] LustreError: MGC192.168.204.125@tcp: Connection to MGS (at 192.168.204.125@tcp) was lost; in progress operations using this service will fail [14738.415163] Lustre: Evicted from MGS (at 192.168.204.125@tcp) after server handle changed from 0xe29b2027a87b73e2 to 0xe29b2027a87b7555 [14738.441139] Lustre: MGC192.168.204.125@tcp: Connection restored to 192.168.204.125@tcp (at 192.168.204.125@tcp) [14747.590309] Lustre: lustre-MDT0000-mdc-ffff900b84898800: Connection restored to 192.168.204.125@tcp (at 192.168.204.125@tcp) [14756.829696] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14758.722813] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [14762.712926] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [14769.145232] Lustre: lustre-MDT0000-mdc-ffff900b84898800: Connection to lustre-MDT0000 (at 192.168.204.125@tcp) was lost; in progress operations using this service will wait for recovery to complete [14785.507552] Lustre: 290783:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788166601/real 1788166601] req@00000000037779df x1875028242090752/t0(0) o400->MGC192.168.204.125@tcp@192.168.204.125@tcp:26/25 lens 224/224 e 0 to 1 dl 1788166617 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [14785.534342] LustreError: MGC192.168.204.125@tcp: Connection to MGS (at 192.168.204.125@tcp) was lost; in progress operations using this service will fail [14785.562167] Lustre: Evicted from MGS (at 192.168.204.125@tcp) after server handle changed from 0xe29b2027a87b7555 to 0xe29b2027a87b7865 [14785.578726] Lustre: MGC192.168.204.125@tcp: Connection restored to 192.168.204.125@tcp (at 192.168.204.125@tcp) [14799.364317] Lustre: lustre-MDT0000-mdc-ffff900b84898800: Connection restored to 192.168.204.125@tcp (at 192.168.204.125@tcp) [14809.340486] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14810.747400] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [14815.475143] Lustre: Unmounted lustre-client [14894.093428] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14900.860919] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14924.965545] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14925.701679] Lustre: Mounted lustre-client [14928.053515] Lustre: Unmounted lustre-client [14974.424459] Key type lgssc unregistered [14974.759742] LNet: 301368:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14974.770959] LNetError: 301368:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14974.799126] LNet: Removed LNI 192.168.204.25@tcp [14975.575818] Key type .llcrypt unregistered [14975.578083] Key type ._llcrypt unregistered [14995.647515] Key type ._llcrypt registered [14995.650039] Key type .llcrypt registered [14997.105786] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 05:00:27 (1788166827) [15002.197971] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15002.245989] alg: No test for adler32 (adler32-zlib) [15003.414055] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [15003.676200] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [15005.359270] Key type lgssc registered [15006.639546] Lustre: Echo OBD driver; http://www.lustre.org/ [15066.956792] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15073.075395] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15094.429473] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15106.631996] Lustre: Mounted lustre-client [15112.752919] Lustre: Unmounted lustre-client [15186.290089] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15193.308826] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15219.044593] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15230.004284] Lustre: Mounted lustre-client [15239.556942] Lustre: Unmounted lustre-client [15305.707957] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15311.122751] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15332.330223] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15332.984177] Lustre: Mounted lustre-client [15335.072128] Lustre: Unmounted lustre-client [15372.720968] Key type lgssc unregistered [15373.034316] LNet: 308434:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15373.046548] LNetError: 308434:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15373.066718] LNet: Removed LNI 192.168.204.25@tcp [15373.820149] Key type .llcrypt unregistered [15373.822085] Key type ._llcrypt unregistered [15391.798046] Key type ._llcrypt registered [15391.803932] Key type .llcrypt registered [15393.279735] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 05:07:03 (1788167223) [15397.388944] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15397.409197] alg: No test for adler32 (adler32-zlib) [15398.710076] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [15399.180514] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [15400.991208] Key type lgssc registered [15402.339690] Lustre: Echo OBD driver; http://www.lustre.org/ [15451.524418] Key type lgssc unregistered [15451.838515] LNet: 310757:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15451.854264] LNetError: 310757:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15451.883292] LNet: Removed LNI 192.168.204.25@tcp [15452.908769] Key type .llcrypt unregistered [15452.911714] Key type ._llcrypt unregistered [15486.297235] Key type ._llcrypt registered [15486.300458] Key type .llcrypt registered [15486.965307] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15486.981858] alg: No test for adler32 (adler32-zlib) [15488.315363] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [15488.718944] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [15490.450144] Key type lgssc registered [15492.041664] Lustre: Echo OBD driver; http://www.lustre.org/ [15575.564943] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15581.906720] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15605.366486] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15647.596584] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [15649.295918] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 05:11:20 (1788167480) [15685.461411] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15690.899759] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15712.211949] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15737.292176] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15739.132891] Lustre: Mounted lustre-client [15744.507816] Lustre: lustre-OST0001-osc-ffff900b83091800: Connection to lustre-OST0001 (at 192.168.204.125@tcp) was lost; in progress operations using this service will wait for recovery to complete [15751.493346] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [15761.845174] Lustre: Unmounted lustre-client [15805.638738] Key type lgssc unregistered [15805.925455] LNet: 317869:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15805.931186] LNetError: 317869:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15805.961876] LNet: Removed LNI 192.168.204.25@tcp [15806.671199] Key type .llcrypt unregistered [15806.673353] Key type ._llcrypt unregistered [15844.115405] Key type ._llcrypt registered [15844.117125] Key type .llcrypt registered [15845.899935] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 05:14:36 (1788167676) [15860.919688] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15860.929683] alg: No test for adler32 (adler32-zlib) [15862.012033] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [15862.373304] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [15864.127221] Key type lgssc registered [15865.772330] Lustre: Echo OBD driver; http://www.lustre.org/ [15918.686631] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15924.291984] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15947.364890] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 05:16:17 (1788167777) [15981.376227] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15987.734192] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16010.381775] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16013.574385] Lustre: Mounted lustre-client [16025.108104] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [16033.781402] Lustre: Unmounted lustre-client [16131.518796] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16137.309697] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16188.269477] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16188.777526] Lustre: Mounted lustre-client [16191.471249] Lustre: Unmounted lustre-client [16219.485277] Key type lgssc unregistered [16219.839732] LNet: 325574:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16219.855319] LNetError: 325574:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16220.911156] LNet: Removed LNI 192.168.204.25@tcp [16221.693960] Key type .llcrypt unregistered [16221.697184] Key type ._llcrypt unregistered [16253.194951] Key type ._llcrypt registered [16253.204322] Key type .llcrypt registered [16253.847528] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [16253.963829] alg: No test for adler32 (adler32-zlib) [16255.078687] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [16255.338205] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [16257.071401] Key type lgssc registered [16258.621224] Lustre: Echo OBD driver; http://www.lustre.org/ [16353.286874] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16359.277638] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16385.198537] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16435.250383] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [16437.143846] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 05:24:27 (1788168267) [16473.605630] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16480.396881] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16503.646789] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16508.467760] Lustre: Mounted lustre-client [16512.651339] Lustre: Unmounted lustre-client [16542.974135] Key type lgssc unregistered [16543.302409] LNet: 332213:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16543.318443] LNetError: 332213:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16543.333977] LNet: Removed LNI 192.168.204.25@tcp [16544.108337] Key type .llcrypt unregistered [16544.110583] Key type ._llcrypt unregistered [16564.449875] Key type ._llcrypt registered [16564.452711] Key type .llcrypt registered [16566.439781] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [16568.943568] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 05:26:39 (1788168399) [16582.184590] Key type .llcrypt unregistered [16582.188133] Key type ._llcrypt unregistered [16592.901230] Key type ._llcrypt registered [16592.910868] Key type .llcrypt registered [16593.285651] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [16593.312384] alg: No test for adler32 (adler32-zlib) [16594.687928] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [16594.976987] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [16596.759199] Key type lgssc registered [16598.458375] Lustre: Echo OBD driver; http://www.lustre.org/ [16659.337436] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16678.108293] Lustre: Mounted lustre-client [16682.048843] Lustre: Unmounted lustre-client [16729.578937] Key type lgssc unregistered [16729.937241] LNet: 335673:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16729.946795] LNetError: 335673:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16729.965897] LNet: Removed LNI 192.168.204.25@tcp [16730.833987] Key type .llcrypt unregistered [16730.836554] Key type ._llcrypt unregistered [16750.955635] Key type ._llcrypt registered [16750.959586] Key type .llcrypt registered [16752.397167] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 05:29:42 (1788168582) [16755.913731] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16755.966631] alg: No test for adler32 (adler32-zlib) [16757.285304] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [16757.718548] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [16759.543179] Key type lgssc registered [16761.708512] Lustre: Echo OBD driver; http://www.lustre.org/ [16857.366562] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16896.608432] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16902.381134] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16905.064250] Lustre: Mounted lustre-client [16908.674761] Lustre: Unmounted lustre-client [16936.570834] Key type lgssc unregistered [16936.889959] LNet: 339220:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16936.897630] LNetError: 339220:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16936.929053] LNet: Removed LNI 192.168.204.25@tcp [16937.591280] Key type .llcrypt unregistered [16937.594688] Key type ._llcrypt unregistered [16953.986984] Key type ._llcrypt registered [16953.991061] Key type .llcrypt registered [16955.475051] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 05:33:06 (1788168786) [16958.074539] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16958.104793] alg: No test for adler32 (adler32-zlib) [16959.191876] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [16959.440745] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [16961.111310] Key type lgssc registered [16962.112797] Lustre: Echo OBD driver; http://www.lustre.org/ [17012.442258] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [17017.939847] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [17037.469238] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [17039.968811] Lustre: Mounted lustre-client [17044.969704] Lustre: lustre-MDT0000-mdc-ffff900ba01e7000: Connection to lustre-MDT0000 (at 192.168.204.125@tcp) was lost; in progress operations using this service will wait for recovery to complete [17049.582726] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [17049.599505] LustreError: 342374:0:(file.c:6154:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [17049.960194] LustreError: MGC192.168.204.125@tcp: Connection to MGS (at 192.168.204.125@tcp) was lost; in progress operations using this service will fail [17050.043258] Lustre: Unmounted lustre-client [17073.183994] Key type lgssc unregistered [17073.565388] LNet: 342790:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [17073.580314] LNetError: 342790:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [17073.597168] LNet: Removed LNI 192.168.204.25@tcp [17074.419340] Key type .llcrypt unregistered [17074.428298] Key type ._llcrypt unregistered [17093.109789] Key type ._llcrypt registered [17093.112197] Key type .llcrypt registered [17094.873965] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 05:35:25 (1788168925) [17097.481850] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [17097.515810] alg: No test for adler32 (adler32-zlib) [17098.556316] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [17098.742046] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [17100.439271] Key type lgssc registered [17101.674059] Lustre: Echo OBD driver; http://www.lustre.org/ [17158.571797] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [17165.670710] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [17189.214820] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [17196.639180] Lustre: Mounted lustre-client [17201.652343] Lustre: lustre-MDT0001-mdc-ffff900b8747d000: Connection to lustre-MDT0001 (at 192.168.204.125@tcp) was lost; in progress operations using this service will wait for recovery to complete [17208.094714] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [17211.354931] Lustre: Unmounted lustre-client [17233.780551] Key type lgssc unregistered [17234.038823] LNet: 346372:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [17234.044683] LNetError: 346372:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [17234.055851] LNet: Removed LNI 192.168.204.25@tcp [17234.834954] Key type .llcrypt unregistered [17234.837703] Key type ._llcrypt unregistered [17254.419889] Key type ._llcrypt registered [17254.422501] Key type .llcrypt registered [17256.111317] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 05:38:06 (1788169086) [17277.650985] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [17277.705676] alg: No test for adler32 (adler32-zlib) [17278.815265] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [17279.178940] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [17280.920248] Key type lgssc registered [17282.224468] Lustre: Echo OBD driver; http://www.lustre.org/ [17410.762553] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [17412.546208] Lustre: Mounted lustre-client [17456.111390] Lustre: lustre-MDT0001-mdc-ffff900b887e9800: Connection to lustre-MDT0001 (at 192.168.204.125@tcp) was lost; in progress operations using this service will wait for recovery to complete [17466.130464] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [17476.523819] Lustre: Unmounted lustre-client [17506.237555] Key type lgssc unregistered [17506.788927] LNet: 352163:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [17506.797466] LNetError: 352163:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [17506.818351] LNet: Removed LNI 192.168.204.25@tcp [17508.082582] Key type .llcrypt unregistered [17508.086215] Key type ._llcrypt unregistered [17527.889796] Key type ._llcrypt registered [17527.892758] Key type .llcrypt registered [17529.469659] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 05:42:39 (1788169359) [17531.388790] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [17533.617110] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 05:42:43 (1788169363) [17535.370483] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [17537.659957] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 05:42:48 (1788169368) [17539.869653] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [17542.529894] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 05:42:52 (1788169372) [17544.365925] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [17546.939689] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 05:42:57 (1788169377) [17548.889625] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [17551.614260] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 05:43:01 (1788169381) [17561.619777] Key type .llcrypt unregistered [17561.625135] Key type ._llcrypt unregistered [17571.703991] Key type ._llcrypt registered [17571.717082] Key type .llcrypt registered [17572.571834] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [17572.588702] alg: No test for adler32 (adler32-zlib) [17573.951911] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [17574.435702] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [17576.191341] Key type lgssc registered [17577.528810] Lustre: Echo OBD driver; http://www.lustre.org/ [17663.804679] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [17671.380381] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [17697.148328] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [17700.088810] Lustre: Mounted lustre-client [17703.081396] Lustre: Unmounted lustre-client [17745.814557] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 05:46:15 (1788169575) [17787.883786] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [17795.842258] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [17834.820917] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [17835.857591] Lustre: Mounted lustre-client [17840.395142] Lustre: Unmounted lustre-client [17888.472160] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 05:48:38 (1788169718) [17889.823661] LNet: 1 local NIs in recovery (showing 1): 192.168.204.25@tcp [18548.407324] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [18555.626776] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [18595.128451] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [18711.371428] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 06:02:21 (1788170541) [18724.920831] Key type lgssc unregistered [18725.345590] LNet: 365709:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [18725.352215] LNetError: 365709:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [18725.367768] LNet: Removed LNI 192.168.204.25@tcp [18726.208977] Key type .llcrypt unregistered [18726.215722] Key type ._llcrypt unregistered [18842.394365] Key type ._llcrypt registered [18842.399154] Key type .llcrypt registered [18842.892174] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [18842.911910] alg: No test for adler32 (adler32-zlib) [18844.111950] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [18844.441490] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [18846.131512] Key type lgssc registered [18847.355260] Lustre: Echo OBD driver; http://www.lustre.org/ [18944.608575] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [18950.879873] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [18977.005514] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [19017.987414] Key type lgssc unregistered [19018.209885] LNet: 369407:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [19018.242733] LNetError: 369407:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [19018.269602] LNet: Removed LNI 192.168.204.25@tcp [19019.123652] Key type .llcrypt unregistered [19019.125836] Key type ._llcrypt unregistered [19057.147428] Key type ._llcrypt registered [19057.150055] Key type .llcrypt registered [19058.237338] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 06:08:09 (1788170889) [19080.052104] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [19080.123621] alg: No test for adler32 (adler32-zlib) [19081.296123] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [19081.562567] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [19083.247402] Key type lgssc registered [19084.899382] Lustre: Echo OBD driver; http://www.lustre.org/ [19135.909817] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 06:09:26 (1788170966) [19147.647263] Key type lgssc unregistered [19147.914400] LNet: 372961:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [19147.924612] LNetError: 372961:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [19147.946398] LNet: Removed LNI 192.168.204.25@tcp [19148.519092] Key type .llcrypt unregistered [19148.522068] Key type ._llcrypt unregistered [19160.924244] Key type ._llcrypt registered [19160.926815] Key type .llcrypt registered [19161.392420] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [19161.404995] alg: No test for adler32 (adler32-zlib) [19162.506396] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [19162.769403] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [19164.455221] Key type lgssc registered [19165.709697] Lustre: Echo OBD driver; http://www.lustre.org/ [19225.473719] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [19231.701483] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [19253.140930] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [19535.985896] LNet: Added LNI 192.168.204.25@tcp50 [8/256/0/180] [19536.785443] LNet: Removed LNI 192.168.204.25@tcp [19539.056922] Lustre: 377194:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff900b87b4d800: config has no valid NIDs, force dynamic NIDs [19540.321244] Lustre: Mounted lustre-client [19540.534914] Lustre: Unmounted lustre-client [19543.325300] Key type lgssc unregistered [19543.765457] LNet: 377452:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [19543.773688] LNetError: 377452:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [19543.815670] LNet: Removed LNI 192.168.204.25@tcp50 [19544.465794] Key type .llcrypt unregistered [19544.469141] Key type ._llcrypt unregistered [19561.683816] Key type ._llcrypt registered [19561.686098] Key type .llcrypt registered [19562.135740] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [19562.152816] alg: No test for adler32 (adler32-zlib) [19563.252823] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [19563.465560] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [19565.183209] Key type lgssc registered [19566.329435] Lustre: Echo OBD driver; http://www.lustre.org/ [19604.901792] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [19609.484789] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [19615.823142] LNet: Added LNI 192.168.204.25@tcp50 [8/256/0/180] [19616.441455] LNet: Removed LNI 192.168.204.25@tcp [19617.557712] Lustre: 380138:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff900b855dc000: config has no valid NIDs, force dynamic NIDs [19618.738511] Lustre: Mounted lustre-client [19619.309976] Lustre: Unmounted lustre-client [19657.752764] Key type lgssc unregistered [19658.096562] LNet: 381009:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [19658.101622] LNetError: 381009:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [19658.123870] LNet: Removed LNI 192.168.204.25@tcp50 [19658.628878] Key type .llcrypt unregistered [19658.634940] Key type ._llcrypt unregistered [19669.022970] Key type ._llcrypt registered [19669.026748] Key type .llcrypt registered [19670.365183] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 06:18:20 (1788171500) [19688.518922] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [19688.539356] alg: No test for adler32 (adler32-zlib) [19689.630744] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [19689.900757] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [19691.559184] Key type lgssc registered [19692.756935] Lustre: Echo OBD driver; http://www.lustre.org/ [19768.278951] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [19773.668736] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [19797.350821] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [19828.504623] Key type lgssc unregistered [19828.758453] LNet: 385753:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [19828.771778] LNetError: 385753:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [19828.810843] LNet: Removed LNI 192.168.204.25@tcp [19829.565622] Key type .llcrypt unregistered [19829.567569] Key type ._llcrypt unregistered [19842.027865] Key type ._llcrypt registered [19842.033385] Key type .llcrypt registered [19843.456965] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 06:21:14 (1788171674) [19845.845901] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [19845.900385] alg: No test for adler32 (adler32-zlib) [19847.056700] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [19847.361078] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [19849.041672] Key type lgssc registered [19850.133683] Lustre: Echo OBD driver; http://www.lustre.org/ [19896.582076] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [19902.237649] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [19922.967486] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [19925.753309] Lustre: Mounted lustre-client [19982.327579] Lustre: Mounted fs15246-client [19997.755302] Lustre: Unmounted fs15246-client [20023.449438] Lustre: Unmounted lustre-client [20059.001793] Key type lgssc unregistered [20059.305234] LNet: 390609:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [20059.315290] LNetError: 390609:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [20060.392087] LNet: Removed LNI 192.168.204.25@tcp [20061.178931] Key type .llcrypt unregistered [20061.187646] Key type ._llcrypt unregistered [20072.131479] Key type ._llcrypt registered [20072.134076] Key type .llcrypt registered [20073.702648] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 06:25:04 (1788171904) [20075.825684] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [20075.938324] alg: No test for adler32 (adler32-zlib) [20077.127414] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [20077.386576] LNet: Added LNI 192.168.204.25@tcp [8/256/0/180] [20079.183341] Key type lgssc registered [20080.600158] Lustre: Echo OBD driver; http://www.lustre.org/ [20213.269268] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [20218.045839] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [20240.772400] Lustre: DEBUG MARKER: oleg425-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [20354.286146] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 20052 sec ========= 06:29:44 (1788172184) [20356.362773] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 06:29:46 (1788172186) === [20360.800915] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 06:29:50 (1788172190) === [20403.969460] Key type lgssc unregistered [20404.264788] LNet: 399877:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [20404.283140] LNetError: 399877:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [20404.301645] LNet: Removed LNI 192.168.204.25@tcp [20404.944882] Key type .llcrypt unregistered [20404.948101] Key type ._llcrypt unregistered