[ 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 446749256 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.001010] APIC: Switch to symmetric I/O mode setup [ 0.003231] x2apic enabled [ 0.004008] Switched APIC routing to physical x2apic. [ 0.005014] kvm-guest: setup PV IPIs [ 0.008554] ..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.010013] pid_max: default: 32768 minimum: 301 [ 0.011134] LSM: Security Framework initializing [ 0.012080] Yama: becoming mindful. [ 0.013042] SELinux: Initializing. [ 0.015049] *** VALIDATE selinux *** [ 0.023817] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.028421] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.029147] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030241] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.032250] *** VALIDATE tmpfs *** [ 0.033456] *** VALIDATE proc *** [ 0.034227] *** VALIDATE cgroup *** [ 0.035009] *** VALIDATE cgroup2 *** [ 0.036289] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.038058] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.039010] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.040033] Spectre V2 : User space: Vulnerable [ 0.041008] Speculative Store Bypass: Vulnerable [ 0.044138] debug: unmapping init [mem 0xffffffff87e59000-0xffffffff87e60fff] [ 0.046152] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.047547] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.048022] ... version: 2 [ 0.049014] ... bit width: 48 [ 0.050013] ... generic registers: 4 [ 0.051012] ... value mask: 0000ffffffffffff [ 0.052014] ... max period: 00007fffffffffff [ 0.053011] ... fixed-purpose events: 3 [ 0.053927] ... event mask: 000000070000000f [ 0.055222] rcu: Hierarchical SRCU implementation. [ 0.057097] smp: Bringing up secondary CPUs ... [ 0.058722] x86: Booting SMP configuration: [ 0.059038] .... node #0, CPUs: #1 #2 #3 [ 0.063132] smp: Brought up 1 node, 4 CPUs [ 0.064928] smpboot: Max logical packages: 1 [ 0.065019] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.250280] node 0 deferred pages initialised in 183ms [ 0.254037] devtmpfs: initialized [ 0.255239] x86/mm: Memory block size: 128MB [ 0.257762] gcov: version magic: 0x41383552 [ 0.258608] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.262092] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.265375] pinctrl core: initialized pinctrl subsystem [ 0.267157] [ 0.267673] ************************************************************* [ 0.271014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.273012] ** ** [ 0.275014] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.278014] ** ** [ 0.281013] ** This means that this kernel is built to expose internal ** [ 0.283011] ** IOMMU data structures, which may compromise security on ** [ 0.285009] ** your system. ** [ 0.286012] ** ** [ 0.289015] ** If you see this message and you are not debugging the ** [ 0.294016] ** kernel, report this immediately to your vendor! ** [ 0.296011] ** ** [ 0.298008] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.300013] ************************************************************* [ 0.302530] NET: Registered protocol family 16 [ 0.304395] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.307060] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.310060] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.314042] cpuidle: using governor menu [ 0.316000] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.319247] PCI: Using configuration type 1 for base access [ 0.321159] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.332226] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.335026] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.339044] cryptd: max_cpu_qlen set to 1000 [ 0.341110] ACPI: Added _OSI(Module Device) [ 0.342025] ACPI: Added _OSI(Processor Device) [ 0.343026] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.345017] ACPI: Added _OSI(Processor Aggregator Device) [ 0.350912] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.356513] ACPI: Interpreter enabled [ 0.358056] ACPI: PM: (supports S0 S3 S4 S5) [ 0.360018] ACPI: Using IOAPIC for interrupt routing [ 0.362125] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.365593] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.376335] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.378043] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.380021] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.383090] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.389403] acpiphp: Slot [2] registered [ 0.391151] acpiphp: Slot [5] registered [ 0.393144] acpiphp: Slot [6] registered [ 0.394119] acpiphp: Slot [3] registered [ 0.396092] acpiphp: Slot [4] registered [ 0.397122] acpiphp: Slot [7] registered [ 0.399130] acpiphp: Slot [8] registered [ 0.401126] acpiphp: Slot [9] registered [ 0.402101] acpiphp: Slot [10] registered [ 0.404107] acpiphp: Slot [11] registered [ 0.405105] acpiphp: Slot [12] registered [ 0.407100] acpiphp: Slot [13] registered [ 0.408095] acpiphp: Slot [14] registered [ 0.410101] acpiphp: Slot [15] registered [ 0.412129] acpiphp: Slot [16] registered [ 0.413110] acpiphp: Slot [17] registered [ 0.415105] acpiphp: Slot [18] registered [ 0.417094] acpiphp: Slot [19] registered [ 0.419132] acpiphp: Slot [20] registered [ 0.420121] acpiphp: Slot [21] registered [ 0.423162] acpiphp: Slot [22] registered [ 0.425166] acpiphp: Slot [23] registered [ 0.427141] acpiphp: Slot [24] registered [ 0.429146] acpiphp: Slot [25] registered [ 0.430183] acpiphp: Slot [26] registered [ 0.432139] acpiphp: Slot [27] registered [ 0.434123] acpiphp: Slot [28] registered [ 0.435141] acpiphp: Slot [29] registered [ 0.437134] acpiphp: Slot [30] registered [ 0.438125] acpiphp: Slot [31] registered [ 0.440118] PCI host bridge to bus 0000:00 [ 0.442032] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.444037] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.446030] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.449056] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.452047] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.455060] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.457199] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.460084] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.464322] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.472000] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.476090] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.479021] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.482020] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.484024] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.488174] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.490723] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.494056] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.498779] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.503023] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.512746] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.517021] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.522854] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.530017] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.536015] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.552031] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.561222] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.567017] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.571016] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.582017] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.591457] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.595390] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.597260] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.600399] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.602277] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.608202] iommu: Default domain type: Passthrough [ 0.610412] SCSI subsystem initialized [ 0.611141] ACPI: bus type USB registered [ 0.613090] usbcore: registered new interface driver usbfs [ 0.614095] usbcore: registered new interface driver hub [ 0.616075] usbcore: registered new device driver usb [ 0.618177] pps_core: LinuxPPS API ver. 1 registered [ 0.619014] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.623076] PTP clock support registered [ 0.626065] EDAC MC: Ver: 3.0.0 [ 0.628143] PCI: Using ACPI for IRQ routing [ 0.629871] NetLabel: Initializing [ 0.632024] NetLabel: domain hash size = 128 [ 0.633015] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.635106] NetLabel: unlabeled traffic allowed by default [ 0.638079] vgaarb: loaded [ 0.639299] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.641020] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.647000] clocksource: Switched to clocksource kvm-clock [ 0.756676] VFS: Disk quotas dquot_6.6.0 [ 0.758605] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.761551] *** VALIDATE ramfs *** [ 0.763106] *** VALIDATE hugetlbfs *** [ 0.764916] pnp: PnP ACPI init [ 0.767943] pnp: PnP ACPI: found 6 devices [ 0.787254] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.792505] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.795328] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.798024] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.800670] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.803531] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.806859] NET: Registered protocol family 2 [ 0.810093] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.816105] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.820300] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.825570] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.829754] TCP: Hash tables configured (established 65536 bind 65536) [ 0.832939] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.838745] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.841331] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.844646] NET: Registered protocol family 1 [ 0.847853] RPC: Registered named UNIX socket transport module. [ 0.850289] RPC: Registered udp transport module. [ 0.852251] RPC: Registered tcp transport module. [ 0.854239] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.856649] NET: Registered protocol family 44 [ 0.858608] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.860501] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.862823] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.864805] PCI: CLS 0 bytes, default 64 [ 0.866284] Unpacking initramfs... [ 2.288181] debug: unmapping init [mem 0xffff9493bcc64000-0xffff9493bffcffff] [ 2.292787] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.295127] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.298180] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.818140] Initialise system trusted keyrings [ 2.820976] Key type blacklist registered [ 2.822969] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.839096] zbud: loaded [ 2.845698] *** VALIDATE nfs *** [ 2.847098] *** VALIDATE nfs4 *** [ 2.849058] pstore: using deflate compression [ 2.853415] Platform Keyring initialized [ 2.956129] NET: Registered protocol family 38 [ 2.958121] Key type asymmetric registered [ 2.960599] Asymmetric key parser 'x509' registered [ 2.977759] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.982722] io scheduler mq-deadline registered [ 2.984675] io scheduler kyber registered [ 2.987101] io scheduler bfq registered [ 2.989843] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.993902] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.997391] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.000981] ACPI: Power Button [PWRF] [ 3.009729] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.016368] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.025396] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.055651] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.086858] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.093487] Non-volatile memory driver v1.3 [ 3.095523] Linux agpgart interface v0.103 [ 3.131578] virtio_blk virtio1: [vda] 147232 512-byte logical blocks (75.4 MB/71.9 MiB) [ 3.134906] vda: detected capacity change from 0 to 75382784 [ 3.151966] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.155401] vdb: detected capacity change from 0 to 1073741824 [ 3.163044] libphy: Fixed MDIO Bus: probed [ 3.169916] usbcore: registered new interface driver usbserial_generic [ 3.172072] usbserial: USB Serial support registered for generic [ 3.174106] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.177866] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.179596] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.183120] mousedev: PS/2 mouse device common for all mice [ 3.186914] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.191236] rtc_cmos 00:05: RTC can wake from S4 [ 3.195189] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.198774] rtc_cmos 00:05: registered as rtc0 [ 3.202517] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.205964] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.206691] intel_pstate: CPU model not supported [ 3.211783] hid: raw HID events driver (C) Jiri Kosina [ 3.213334] usbcore: registered new interface driver usbhid [ 3.215156] usbhid: USB HID core driver [ 3.216342] drop_monitor: Initializing network drop monitor service [ 3.218723] Initializing XFRM netlink socket [ 3.220794] NET: Registered protocol family 10 [ 3.222971] Segment Routing with IPv6 [ 3.224037] NET: Registered protocol family 17 [ 3.225784] mpls_gso: MPLS GSO support [ 3.231193] RAS: Correctable Errors collector initialized. [ 3.232551] AVX version of gcm_enc/dec engaged. [ 3.233811] AES CTR mode by8 optimization enabled [ 3.314538] sched_clock: Marking stable (3314517187, 0)->(4218755059, -904237872) [ 3.317889] registered taskstats version 1 [ 3.320602] Loading compiled-in X.509 certificates [ 3.324417] zswap: loaded using pool lzo/zbud [ 3.349510] Key type big_key registered [ 3.362483] Key type encrypted registered [ 3.364477] ima: No TPM chip found, activating TPM-bypass! [ 3.366945] ima: Allocated hash algorithm: sha1 [ 3.369389] ima: No architecture policies found [ 3.371741] evm: Initialising EVM extended attributes: [ 3.374023] evm: security.selinux [ 3.375390] evm: security.ima [ 3.376631] evm: security.capability [ 3.378055] evm: HMAC attrs: 0x1 [ 3.381218] rtc_cmos 00:05: setting system clock to 2026-09-11 08:28:15 UTC (1789115295) [ 3.389388] debug: unmapping init [mem 0xffffffff88e03000-0xffffffff88ffffff] [ 3.392841] debug: unmapping init [mem 0xffffffff87b82000-0xffffffff87e58fff] [ 3.401075] Write protecting the kernel read-only data: 28672k [ 3.404619] debug: unmapping init [mem 0xffffffff86203000-0xffffffff863fffff] [ 3.407598] debug: unmapping init [mem 0xffffffff86b14000-0xffffffff86bfffff] [ 3.435909] 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.442148] systemd[1]: Detected virtualization kvm. [ 3.443467] systemd[1]: Detected architecture x86-64. [ 3.445173] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.476079] systemd[1]: No hostname configured. [ 3.478138] systemd[1]: Set hostname to . [ 3.480877] random: systemd: uninitialized urandom read (16 bytes read) [ 3.483564] systemd[1]: Initializing machine ID from random generator. [ 3.534920] random: ln: uninitialized urandom read (6 bytes read) [ 3.619984] random: systemd: uninitialized urandom read (16 bytes read) [ 3.624319] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 3.629851] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 3.633555] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ OK ] Reached target Timers. [ OK ] Listening on udev Control Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on Journal Socket. Starting Setup Virtual Console... Starting Apply Kernel Variables... [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Reached target Paths. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Sockets. Starting Journal Service... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create list of required sta…vice nodes for the current kernel. 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.292748] device-mapper: uevent: version 1.0.3 [ 4.294843] 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...[ 4.682382] random: fast init done Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 5.039889] virtio_net virtio0 ens2: renamed from eth0 [ 5.077378] scsi host0: ata_piix [ 5.101211] scsi host1: ata_piix [ 5.115735] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.119235] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.474498] dracut-initqueue[596]: RTNETLINK answers: File exists [ 9.884846] random: crng init done [ 9.886133] 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.356676] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ 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 Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.549154] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.821431] SELinux: Disabled at runtime. [ 11.889295] 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.899441] systemd[1]: Detected virtualization kvm. [ 11.901603] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.412040] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.414756] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.421418] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.424970] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.428834] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.437911] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.443057] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. Activating swap /dev/disk/by-label/SWAP... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on udev Kernel Socket. Starting Remount Root and Kernel File Systems... Mounting Kernel [ 12.483310] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Debug File System... [ OK ] Reached target rpc_pipefs.target. [ OK ] Created slice User and Session Slice. [ OK ] Created slice system-getty.slice. [ OK ] Reached target Slices. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. Mounting Huge Pages File System... [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. Starting Apply Kernel Variables... [ OK ] Listening on initctl Compatibility Named Pipe. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Starting Create list of required st…ce nodes for the current kernel... Mounting POSIX Message Queue File System... [ OK ] Stopped target Initrd File Systems. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Listening on Process Core Dump Socket. [ OK ] Started Journal Service. [ OK ] Activated swap /dev/disk/by-label/SWAP. [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 Huge Pages File System. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ 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 ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 12.939402] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 13.244955] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.269092] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.338744] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.359876] EDAC sbridge: Ver: 1.1.2 [ 14.631146] Key type dns_resolver registered [ 14.945766] NFS: Registering the id_resolver key type [ 14.948143] Key type id_resolver registered [ 14.949715] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started dnf makecache --timer. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... [ OK ] Started irqbalance daemon. Starting Login Service... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ 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 Crash recovery kernel arming... Starting System Logging Service... Starting Notify NFS peers of a restart... [ 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 oleg346-client login: [ 41.488465] libcfs: loading out-of-tree module taints kernel. [ 41.521633] Key type ._llcrypt registered [ 41.523591] Key type .llcrypt registered [ 41.899145] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 41.907819] alg: No test for adler32 (adler32-zlib) [ 42.968661] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 43.346579] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 44.984217] Key type lgssc registered [ 45.801732] Lustre: Echo OBD driver; http://www.lustre.org/ [ 98.526279] hrtimer: interrupt took 3092611 ns [ 204.217238] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 209.325435] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 219.981974] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing check_logdir /tmp/testlogs/ [ 223.520792] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing yml_node [ 228.193422] Lustre: DEBUG MARKER: Client: 2.17.57.142 [ 229.857707] Lustre: lustre-OST0000-osc-ffff9494182f9800: disconnect after 23s idle [ 230.393344] Lustre: DEBUG MARKER: MDS: 2.17.57.142 [ 232.603700] Lustre: DEBUG MARKER: OSS: 2.17.57.142 [ 234.085746] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Fri Sep 11 04:32:04 EDT 2026 [ 248.610181] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 249.750698] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 251.643327] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 261.968029] Lustre: Unmounted lustre-client [ 367.659962] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 373.683911] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 395.076949] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 432.244944] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 04:35:23 (1789115723) [ 434.929354] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 436.509354] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 04:35:27 (1789115727) [ 469.675408] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 475.004119] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 494.394469] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 495.148155] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 499.870641] Lustre: Unmounted lustre-client [ 533.911836] Key type lgssc unregistered [ 534.212573] LNet: 15026:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 534.228094] LNetError: 15026:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 534.242035] LNet: Removed LNI 192.168.203.46@tcp [ 535.089302] Key type .llcrypt unregistered [ 535.092169] Key type ._llcrypt unregistered [ 549.949212] Key type ._llcrypt registered [ 549.953387] Key type .llcrypt registered [ 551.408116] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 04:37:21 (1789115841) [ 552.987298] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 553.006501] alg: No test for adler32 (adler32-zlib) [ 553.967498] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 554.191694] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 555.848211] Key type lgssc registered [ 557.201787] Lustre: Echo OBD driver; http://www.lustre.org/ [ 628.163116] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 709.834873] Lustre: Unmounted lustre-client [ 736.162284] Key type lgssc unregistered [ 736.474925] LNet: 18816:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 736.485131] LNetError: 18816:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 736.503278] LNet: Removed LNI 192.168.203.46@tcp [ 737.329562] Key type .llcrypt unregistered [ 737.342586] Key type ._llcrypt unregistered [ 749.089456] Key type ._llcrypt registered [ 749.097919] Key type .llcrypt registered [ 750.689635] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 04:40:41 (1789116041) [ 753.249671] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 753.333576] alg: No test for adler32 (adler32-zlib) [ 754.443689] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 754.708038] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 756.376224] Key type lgssc registered [ 757.652886] Lustre: Echo OBD driver; http://www.lustre.org/ [ 807.922436] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 814.198976] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 835.350066] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 837.024768] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 850.847821] Lustre: Unmounted lustre-client [ 878.400992] Key type lgssc unregistered [ 878.724899] LNet: 22417:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 878.731106] LNetError: 22417:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 878.750147] LNet: Removed LNI 192.168.203.46@tcp [ 879.438538] Key type .llcrypt unregistered [ 879.442380] Key type ._llcrypt unregistered [ 888.562969] Key type ._llcrypt registered [ 888.565812] Key type .llcrypt registered [ 890.101520] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 04:43:00 (1789116180) [ 893.140977] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 893.154901] alg: No test for adler32 (adler32-zlib) [ 894.397636] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 894.613764] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 896.328240] Key type lgssc registered [ 897.986559] Lustre: Echo OBD driver; http://www.lustre.org/ [ 945.124391] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 949.958930] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 969.593280] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 972.276553] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 978.469788] Lustre: Unmounted lustre-client [ 1005.215492] Key type lgssc unregistered [ 1005.600863] LNet: 25610:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1005.605188] LNetError: 25610:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1005.619105] LNet: Removed LNI 192.168.203.46@tcp [ 1006.326621] Key type .llcrypt unregistered [ 1006.330757] Key type ._llcrypt unregistered [ 1022.011659] Key type ._llcrypt registered [ 1022.013769] Key type .llcrypt registered [ 1023.406573] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 04:45:14 (1789116314) [ 1025.606897] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1025.620260] alg: No test for adler32 (adler32-zlib) [ 1026.669589] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 1026.837312] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 1028.496170] Key type lgssc registered [ 1029.689542] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1080.822888] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1085.770855] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1105.462313] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1107.126723] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 1112.565810] Lustre: lustre-MDT0000-mdc-ffff949412aaf800: Connection to lustre-MDT0000 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1117.693365] Lustre: lustre-MDT0001-mdc-ffff949412aaf800: Connection to lustre-MDT0001 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1125.601569] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1133.026669] Lustre: 26568:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789116409/real 1789116409] req@000000003c9cb32e x1876024432268672/t0(0) o400->MGC192.168.203.146@tcp@192.168.203.146@tcp:26/25 lens 224/224 e 0 to 1 dl 1789116425 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1133.091738] LustreError: MGC192.168.203.146@tcp: Connection to MGS (at 192.168.203.146@tcp) was lost; in progress operations using this service will fail [ 1135.916280] Lustre: Unmounted lustre-client [ 1154.482942] Key type lgssc unregistered [ 1154.826827] LNet: 29264:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1154.843094] LNetError: 29264:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1154.873054] LNet: Removed LNI 192.168.203.46@tcp [ 1155.446742] Key type .llcrypt unregistered [ 1155.451288] Key type ._llcrypt unregistered [ 1170.599774] Key type ._llcrypt registered [ 1170.601506] Key type .llcrypt registered [ 1171.887536] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 04:47:42 (1789116462) [ 1173.925684] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1173.935822] alg: No test for adler32 (adler32-zlib) [ 1175.133806] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 1175.446103] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 1177.216249] Key type lgssc registered [ 1178.543124] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1228.887989] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1235.418553] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1256.671956] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1258.317549] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 1268.709703] Lustre: lustre-OST0000-osc-ffff9494100e2000: Connection to lustre-OST0000 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1273.487423] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1283.810760] Lustre: Unmounted lustre-client [ 1309.006983] Key type lgssc unregistered [ 1309.248122] LNet: 32881:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1309.251567] LNetError: 32881:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1309.282420] LNet: Removed LNI 192.168.203.46@tcp [ 1309.819716] Key type .llcrypt unregistered [ 1309.825183] Key type ._llcrypt unregistered [ 1328.460202] Key type ._llcrypt registered [ 1328.463375] Key type .llcrypt registered [ 1329.854731] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 04:50:20 (1789116620) [ 1332.158020] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1332.346242] alg: No test for adler32 (adler32-zlib) [ 1333.455695] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 1333.765677] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 1335.472233] Key type lgssc registered [ 1337.050416] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1392.689818] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1398.521057] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1421.219972] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1423.924600] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 1430.925872] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1434.754585] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1446.683495] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-09-11 4h52m17s [ 1448.775251] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1454.566217] Lustre: lustre-MDT0000-mdc-ffff949412aad800: Connection to lustre-MDT0000 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1459.149861] LustreError: 36669:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff949412aad800: can't stat MDS #0: rc = -19 [ 1460.502269] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1473.016893] LustreError: MGC192.168.203.146@tcp: Connection to MGS (at 192.168.203.146@tcp) was lost; in progress operations using this service will fail [ 1473.082322] Lustre: Evicted from MGS (at 192.168.203.146@tcp) after server handle changed from 0x6d97fe8f96f2b340 to 0x6d97fe8f96f2b60a [ 1473.103915] Lustre: MGC192.168.203.146@tcp: Connection restored to 192.168.203.146@tcp (at 192.168.203.146@tcp) [ 1474.113529] LustreError: lustre-MDT0000-mdc-ffff949412aad800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1474.133769] Lustre: lustre-MDT0000-mdc-ffff949412aad800: Connection restored to 192.168.203.146@tcp (at 192.168.203.146@tcp) [ 1478.814917] Lustre: DEBUG MARKER: Wait for df (36669) ... [ 1481.327855] Lustre: DEBUG MARKER: done [ 1484.008207] Lustre: Unmounted lustre-client [ 1513.150121] Key type lgssc unregistered [ 1513.443055] LNet: 37692:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1513.465022] LNetError: 37692:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1513.477993] LNet: Removed LNI 192.168.203.46@tcp [ 1514.256285] Key type .llcrypt unregistered [ 1514.259984] Key type ._llcrypt unregistered [ 1542.814561] Key type ._llcrypt registered [ 1542.818566] Key type .llcrypt registered [ 1544.030054] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 04:53:54 (1789116834) [ 1546.602696] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1546.644464] alg: No test for adler32 (adler32-zlib) [ 1547.704945] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 1547.989843] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 1549.720186] Key type lgssc registered [ 1550.995880] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1597.850630] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1604.339151] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1624.675756] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1627.296300] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 1631.960633] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-09-11 4h55m22s [ 1633.402223] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1640.114914] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1642.490681] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1662.944136] Lustre: 41830:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789116934/real 1789116934] req@00000000bd49aa7d x1876024979626240/t0(0) o101->lustre-MDT0000-mdc-ffff9494100e4800@192.168.203.146@tcp:12/10 lens 576/1152 e 0 to 1 dl 1789116954 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1662.971192] Lustre: lustre-MDT0000-mdc-ffff9494100e4800: Connection to lustre-MDT0000 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1663.003285] Lustre: lustre-MDT0000-mdc-ffff9494100e4800: Connection restored to 192.168.203.146@tcp (at 192.168.203.146@tcp) [ 1684.840118] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1688.340441] Lustre: DEBUG MARKER: done [ 1692.479436] Lustre: Unmounted lustre-client [ 1721.750100] Key type lgssc unregistered [ 1722.027610] LNet: 42720:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1722.034759] LNetError: 42720:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1722.058068] LNet: Removed LNI 192.168.203.46@tcp [ 1722.547400] Key type .llcrypt unregistered [ 1722.550782] Key type ._llcrypt unregistered [ 1751.025918] Key type ._llcrypt registered [ 1751.028229] Key type .llcrypt registered [ 1752.107163] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 04:57:23 (1789117043) [ 1753.585840] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1755.204685] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 04:57:25 (1789117045) [ 1758.722582] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1758.836928] alg: No test for adler32 (adler32-zlib) [ 1759.911983] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 1760.196554] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 1761.881312] Key type lgssc registered [ 1763.197851] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1792.548565] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 04:58:03 (1789117083) [ 1822.624532] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1828.479810] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1850.760920] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1853.536873] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 1858.702664] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1863.707619] Lustre: Unmounted lustre-client [ 1881.124560] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 1925.263912] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1930.685487] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1931.477318] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 1934.980025] Lustre: Unmounted lustre-client [ 1994.485825] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2000.012381] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2000.673256] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 2004.494477] Lustre: Unmounted lustre-client [ 2025.460148] Lustre: DEBUG MARKER: files compared the same [ 2046.368329] Key type lgssc unregistered [ 2046.580861] LNet: 50352:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2046.590459] LNetError: 50352:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2046.609049] LNet: Removed LNI 192.168.203.46@tcp [ 2047.192235] Key type .llcrypt unregistered [ 2047.204685] Key type ._llcrypt unregistered [ 2060.202333] Key type ._llcrypt registered [ 2060.204215] Key type .llcrypt registered [ 2061.321485] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 05:02:32 (1789117352) [ 2063.688098] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2063.699822] alg: No test for adler32 (adler32-zlib) [ 2064.750913] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 2064.999281] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 2066.672194] Key type lgssc registered [ 2067.965837] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2116.528810] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2122.312445] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2140.040960] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2142.652420] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 2147.348575] Lustre: Unmounted lustre-client [ 2173.493689] Key type lgssc unregistered [ 2173.744821] LNet: 53937:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2173.755791] LNetError: 53937:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2173.786718] LNet: Removed LNI 192.168.203.46@tcp [ 2174.442818] Key type .llcrypt unregistered [ 2174.444643] Key type ._llcrypt unregistered [ 2190.160087] Key type ._llcrypt registered [ 2190.163700] Key type .llcrypt registered [ 2191.766466] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 05:04:41 (1789117481) [ 2194.307755] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2194.370315] alg: No test for adler32 (adler32-zlib) [ 2195.728940] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 2196.106665] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 2197.864282] Key type lgssc registered [ 2199.946720] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2285.085936] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2317.731934] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2322.906806] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2348.672885] Key type lgssc unregistered [ 2348.996333] LNet: 57425:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2349.012164] LNetError: 57425:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2349.053471] LNet: Removed LNI 192.168.203.46@tcp [ 2349.852587] Key type .llcrypt unregistered [ 2349.857223] Key type ._llcrypt unregistered [ 2363.286382] Key type ._llcrypt registered [ 2363.289463] Key type .llcrypt registered [ 2364.404623] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 05:07:35 (1789117655) [ 2367.408028] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2367.424357] alg: No test for adler32 (adler32-zlib) [ 2368.508927] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 2368.770934] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 2370.432356] Key type lgssc registered [ 2371.499798] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2434.947529] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 2442.183353] Lustre: Unmounted lustre-client [ 2478.409489] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 05:09:29 (1789117769) [ 2598.148601] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2619.428252] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 2627.086667] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2627.096653] LustreError: 63898:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff9494204ae000: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2627.121683] Lustre: 58407:0:(llite_lib.c:4366:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.203.146@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2637.512250] Lustre: Unmounted lustre-client [ 2679.094752] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 05:12:49 (1789117969) [ 2688.564467] Key type lgssc unregistered [ 2688.820747] LNet: 65112:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2688.825807] LNetError: 65112:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2689.900638] LNet: Removed LNI 192.168.203.46@tcp [ 2690.431749] Key type .llcrypt unregistered [ 2690.433600] Key type ._llcrypt unregistered [ 2700.561333] Key type ._llcrypt registered [ 2700.565449] Key type .llcrypt registered [ 2701.029145] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2701.045559] alg: No test for adler32 (adler32-zlib) [ 2702.123487] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 2702.330913] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 2703.984272] Key type lgssc registered [ 2705.145647] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2752.484221] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2758.372497] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2777.445297] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2779.205278] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 2791.069112] Lustre: Unmounted lustre-client [ 2818.707675] Key type lgssc unregistered [ 2818.995386] LNet: 68131:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2819.012164] LNetError: 68131:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2819.037676] LNet: Removed LNI 192.168.203.46@tcp [ 2819.749385] Key type .llcrypt unregistered [ 2819.753119] Key type ._llcrypt unregistered [ 2830.157655] Key type ._llcrypt registered [ 2830.162958] Key type .llcrypt registered [ 2830.695513] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2830.708679] alg: No test for adler32 (adler32-zlib) [ 2831.843814] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 2832.094591] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 2833.752752] Key type lgssc registered [ 2834.850742] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2938.335217] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2943.683243] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2966.063868] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2967.695276] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 2970.147950] Lustre: Unmounted lustre-client [ 2994.865647] Key type lgssc unregistered [ 2995.189406] LNet: 71569:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2995.207685] LNetError: 71569:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2995.225516] LNet: Removed LNI 192.168.203.46@tcp [ 2995.917966] Key type .llcrypt unregistered [ 2995.926871] Key type ._llcrypt unregistered [ 3010.027717] Key type ._llcrypt registered [ 3010.029459] Key type .llcrypt registered [ 3011.080128] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 05:18:21 (1789118301) [ 3013.267526] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3013.289343] alg: No test for adler32 (adler32-zlib) [ 3014.249361] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 3014.577392] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 3016.289234] Key type lgssc registered [ 3017.439649] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3072.307632] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3077.973462] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3097.919875] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3099.678811] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 3104.373887] Lustre: Unmounted lustre-client [ 3104.789846] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 3107.619411] Lustre: Unmounted lustre-client [ 3128.668241] Key type lgssc unregistered [ 3128.913770] LNet: 75260:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3128.925645] LNetError: 75260:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3128.954771] LNet: Removed LNI 192.168.203.46@tcp [ 3129.755183] Key type .llcrypt unregistered [ 3129.757421] Key type ._llcrypt unregistered [ 3140.227790] Key type ._llcrypt registered [ 3140.233620] Key type .llcrypt registered [ 3140.595523] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3140.613760] alg: No test for adler32 (adler32-zlib) [ 3141.725384] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 3141.955806] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 3143.616183] Key type lgssc registered [ 3144.689628] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3193.048594] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3198.263582] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3217.669115] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3220.399973] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 3224.446617] Lustre: Unmounted lustre-client [ 3254.508296] Key type lgssc unregistered [ 3254.876111] LNet: 78302:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3254.889143] LNetError: 78302:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3254.912426] LNet: Removed LNI 192.168.203.46@tcp [ 3255.944960] Key type .llcrypt unregistered [ 3255.952045] Key type ._llcrypt unregistered [ 3269.891810] Key type ._llcrypt registered [ 3269.894875] Key type .llcrypt registered [ 3271.266557] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 05:22:41 (1789118561) [ 3273.665648] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3273.745925] alg: No test for adler32 (adler32-zlib) [ 3274.863830] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 3275.135665] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 3276.817366] Key type lgssc registered [ 3278.327135] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3322.264922] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3326.948186] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3345.356949] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3347.998595] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 3354.473668] Lustre: lustre: root_squash is set to 0:0 [ 3361.822768] Lustre: lustre: nosquash_nids is cleared [ 3361.857952] Lustre: lustre: root_squash is set to 500:500 [ 3380.776870] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.203.46@tcp 192.168.0.[2,10]@tcp [ 3394.589049] Lustre: lustre: nosquash_nids set to 192.168.203.46/24@tcp [ 3398.030240] Lustre: Unmounted lustre-client [ 3430.190963] Key type lgssc unregistered [ 3430.463991] LNet: 82326:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3430.471104] LNetError: 82326:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3430.491311] LNet: Removed LNI 192.168.203.46@tcp [ 3431.053797] Key type .llcrypt unregistered [ 3431.055954] Key type ._llcrypt unregistered [ 3446.627172] Key type ._llcrypt registered [ 3446.632529] Key type .llcrypt registered [ 3447.944594] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 05:25:38 (1789118738) [ 3450.431933] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3452.091511] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 05:25:42 (1789118742) [ 3453.951362] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3454.022382] alg: No test for adler32 (adler32-zlib) [ 3455.194670] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 3455.502385] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 3457.232250] Key type lgssc registered [ 3458.339423] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3506.105995] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3511.447130] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3532.069592] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3532.620282] Lustre: lustre: root_squash is set to 500:500 [ 3532.653652] Lustre: lustre: nosquash_nids set to 192.168.203.46/24@tcp [ 3533.736333] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 3539.267598] Lustre: Unmounted lustre-client [ 3564.625659] Key type lgssc unregistered [ 3564.860880] LNet: 86288:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3564.871535] LNetError: 86288:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3564.891649] LNet: Removed LNI 192.168.203.46@tcp [ 3565.494636] Key type .llcrypt unregistered [ 3565.496595] Key type ._llcrypt unregistered [ 3579.421642] Key type ._llcrypt registered [ 3579.424605] Key type .llcrypt registered [ 3580.664866] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 3582.046828] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 05:27:52 (1789118872) [ 3597.068966] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3597.161746] alg: No test for adler32 (adler32-zlib) [ 3598.197502] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 3598.446961] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 3600.121260] Key type lgssc registered [ 3601.239321] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3675.252406] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3680.129418] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3699.154617] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3771.459762] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3777.182499] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3795.407134] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3805.633152] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 3835.464927] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9494094c1000.ost_server_uuid 50 [ 3836.909230] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9494094c1000.ost_server_uuid in FULL state after 0 sec [ 3837.539724] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 3840.864138] Lustre: Unmounted lustre-client [ 3842.761841] Lustre: Unmounted lustre-client [ 3890.037885] Key type lgssc unregistered [ 3890.431172] LNet: 93963:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3890.441122] LNetError: 93963:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3890.460055] LNet: Removed LNI 192.168.203.46@tcp [ 3891.370622] Key type .llcrypt unregistered [ 3891.372732] Key type ._llcrypt unregistered [ 3921.455612] Key type ._llcrypt registered [ 3921.457394] Key type .llcrypt registered [ 3922.561738] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 05:33:33 (1789119213) [ 3938.360604] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3938.380210] alg: No test for adler32 (adler32-zlib) [ 3939.445967] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 3939.697228] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 3941.376164] Key type lgssc registered [ 3942.320533] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4025.480778] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4032.379987] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4053.494069] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4056.113296] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 4077.536381] Lustre: 95325:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789119353/real 1789119353] req@00000000985a60e6 x1876027486770432/t0(0) o400->lustre-OST0000-osc-ffff9494182fa800@192.168.203.146@tcp:28/4 lens 224/224 e 0 to 1 dl 1789119369 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4077.582265] Lustre: lustre-OST0000-osc-ffff9494182fa800: Connection to lustre-OST0000 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4082.081925] Lustre: 95325:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789119358/real 1789119358] req@000000008b2a0839 x1876027486770944/t0(0) o400->lustre-OST0000-osc-ffff9494182fa800@192.168.203.146@tcp:28/4 lens 224/224 e 0 to 1 dl 1789119374 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4087.264158] Lustre: 95325:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789119363/real 1789119363] req@0000000073bd8e25 x1876027486771456/t0(0) o400->lustre-OST0000-osc-ffff9494182fa800@192.168.203.146@tcp:28/4 lens 224/224 e 0 to 1 dl 1789119379 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4087.895327] Lustre: lustre-OST0000-osc-ffff9494182fa800: Connection restored to 192.168.203.146@tcp (at 192.168.203.146@tcp) [ 4092.000279] Lustre: 95324:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789119368/real 1789119368] req@000000002d21a500 x1876027486771968/t0(0) o400->lustre-OST0000-osc-ffff9494182fa800@192.168.203.146@tcp:28/4 lens 224/224 e 0 to 1 dl 1789119384 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4092.915353] Lustre: lustre-MDT0000-mdc-ffff9494182fa800: Connection to lustre-MDT0000 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4109.280149] Lustre: 95325:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789119385/real 1789119385] req@00000000ded33985 x1876027486774272/t0(0) o400->MGC192.168.203.146@tcp@192.168.203.146@tcp:26/25 lens 224/224 e 0 to 1 dl 1789119401 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4109.306756] LustreError: MGC192.168.203.146@tcp: Connection to MGS (at 192.168.203.146@tcp) was lost; in progress operations using this service will fail [ 4119.527741] Lustre: Evicted from MGS (at 192.168.203.146@tcp) after server handle changed from 0xcac4e0d63848c8df to 0xcac4e0d63848ca75 [ 4119.552632] Lustre: MGC192.168.203.146@tcp: Connection restored to 192.168.203.146@tcp (at 192.168.203.146@tcp) [ 4125.509683] Lustre: lustre-MDT0000-mdc-ffff9494182fa800: Connection restored to 192.168.203.146@tcp (at 192.168.203.146@tcp) [ 4129.676341] Lustre: Unmounted lustre-client [ 4163.521147] Key type lgssc unregistered [ 4163.786534] LNet: 99629:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4163.802716] LNetError: 99629:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4163.834477] LNet: Removed LNI 192.168.203.46@tcp [ 4164.545668] Key type .llcrypt unregistered [ 4164.548702] Key type ._llcrypt unregistered [ 4179.827394] Key type ._llcrypt registered [ 4179.829850] Key type .llcrypt registered [ 4181.129445] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 05:37:51 (1789119471) [ 4196.150357] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4196.164280] alg: No test for adler32 (adler32-zlib) [ 4197.230194] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 4197.463708] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 4199.164073] Key type lgssc registered [ 4200.119216] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4236.075787] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4324.033268] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4329.711427] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4352.734738] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4354.449439] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 4356.341711] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 4357.935204] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 05:40:48 (1789119648) [ 4576.074043] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4586.400655] Lustre: Unmounted lustre-client [ 4713.672581] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4720.259366] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4745.490941] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4784.725371] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 05:47:55 (1789120075) [ 4874.950857] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4880.941647] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4904.251220] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4905.098690] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 4908.713656] Lustre: Unmounted lustre-client [ 4942.790388] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 05:50:33 (1789120233) [ 5029.708266] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5036.799209] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5058.413379] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5058.969151] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 5062.248632] Lustre: Unmounted lustre-client [ 5091.187792] Key type lgssc unregistered [ 5091.407819] LNet: 122642:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5091.426061] LNetError: 122642:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5091.458417] LNet: Removed LNI 192.168.203.46@tcp [ 5092.064627] Key type .llcrypt unregistered [ 5092.067993] Key type ._llcrypt unregistered [ 5105.022276] Key type ._llcrypt registered [ 5105.024536] Key type .llcrypt registered [ 5106.165568] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 05:53:16 (1789120396) [ 5108.386888] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5108.399991] alg: No test for adler32 (adler32-zlib) [ 5109.444672] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 5109.695674] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 5111.376731] Key type lgssc registered [ 5112.515504] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5158.473600] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5164.344495] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5187.511345] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5190.364313] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 5207.478476] Lustre: Unmounted lustre-client [ 5244.446615] Key type lgssc unregistered [ 5244.700710] LNet: 126238:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5244.717720] LNetError: 126238:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5244.736920] LNet: Removed LNI 192.168.203.46@tcp [ 5245.341851] Key type .llcrypt unregistered [ 5245.344754] Key type ._llcrypt unregistered [ 5262.708740] Key type ._llcrypt registered [ 5262.710852] Key type .llcrypt registered [ 5264.017486] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 05:55:54 (1789120554) [ 5266.660870] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5266.686832] alg: No test for adler32 (adler32-zlib) [ 5267.874398] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 5268.194964] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 5269.904219] Key type lgssc registered [ 5271.468802] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5321.606126] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5327.979322] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5350.696236] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5352.292679] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 5362.668939] Lustre: lustre-OST0000-osc-ffff9494100b0000: Connection to lustre-OST0000 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5374.981438] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff9494100b0000.ost_server_uuid 50 [ 5376.675935] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 5389.244077] Lustre: Unmounted lustre-client [ 5412.180072] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 05:58:23 (1789120703) [ 5445.915559] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5451.232168] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5469.774298] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5498.962936] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5499.610857] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 5505.004726] Lustre: lustre-OST0000-osc-ffff9494111ed800: Connection to lustre-OST0000 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5529.943742] Lustre: Unmounted lustre-client [ 5579.471551] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 06:01:10 (1789120870) [ 5613.478714] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5619.120346] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5639.861923] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5662.809905] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5663.619450] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 5668.855338] Lustre: lustre-OST0000-osc-ffff949411000800: Connection to lustre-OST0000 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5684.649201] Lustre: Unmounted lustre-client [ 5730.116944] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 06:03:40 (1789121020) [ 5815.265383] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5820.996871] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5845.398980] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5920.929715] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5926.644457] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5951.639253] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5982.394115] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 6024.464728] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6029.514734] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 3 sec [ 6032.242585] Lustre: Unmounted lustre-client [ 6062.954809] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 06:09:13 (1789121353) [ 6097.737636] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6103.432935] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6124.240667] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6158.586443] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6188.698228] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 6232.273832] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6233.944445] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6245.415839] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6255.792378] Lustre: Unmounted lustre-client [ 6305.362822] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 06:13:16 (1789121596) [ 6332.558585] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6336.483524] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6354.973668] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6355.784320] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 6376.335548] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6377.575338] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6391.258850] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9494100b2800.ost_server_uuid 50 [ 6392.548168] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9494100b2800.ost_server_uuid in IDLE state after 0 sec [ 6394.869610] Lustre: Unmounted lustre-client [ 6395.166862] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6395.178600] Lustre: Skipped 3 previous similar messages [ 6395.277578] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 6396.286634] LustreError: 149687:0:(sec.c:403:import_sec_validate_get()) import 00000000154ca4d9 (NEW) with no sec [ 6449.150298] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 06:15:40 (1789121740) [ 6477.873074] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6483.559145] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6504.314363] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6527.428575] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6527.833050] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 6527.968678] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 6540.813937] Lustre: Unmounted lustre-client [ 6540.816611] Lustre: Skipped 1 previous similar message [ 6579.105764] Key type lgssc unregistered [ 6579.329150] LNet: 153108:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6579.334461] LNetError: 153108:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6579.356252] LNet: Removed LNI 192.168.203.46@tcp [ 6580.178637] Key type .llcrypt unregistered [ 6580.181658] Key type ._llcrypt unregistered [ 6593.039302] Key type ._llcrypt registered [ 6593.041175] Key type .llcrypt registered [ 6594.135783] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 06:18:05 (1789121885) [ 6595.220710] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6595.242831] alg: No test for adler32 (adler32-zlib) [ 6596.315956] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 6596.553851] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 6598.227254] Key type lgssc registered [ 6599.296895] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6641.905588] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6647.500661] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6665.929934] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6688.946881] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6690.962465] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 6692.171590] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 6700.049403] LustreError: lustre-MDT0001-mdc-ffff949405975800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 6700.068912] Lustre: lustre-MDT0001-mdc-ffff949405975800: Connection restored to 192.168.203.146@tcp (at 192.168.203.146@tcp) [ 6706.218169] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6707.812377] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6727.695794] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 6731.863799] Lustre: Unmounted lustre-client [ 6752.097968] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 06:20:43 (1789122043) [ 6836.457953] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6842.507085] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6867.382044] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6868.144684] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 6890.897228] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6892.543567] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6894.582994] Lustre: lustre-OST0001-osc-ffff9494204ad800: Connection to lustre-OST0001 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6904.479169] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6914.817176] Lustre: Unmounted lustre-client [ 6948.636681] Key type lgssc unregistered [ 6948.933729] LNet: 162720:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6948.954943] LNetError: 162720:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6948.976698] LNet: Removed LNI 192.168.203.46@tcp [ 6949.709458] Key type .llcrypt unregistered [ 6949.711790] Key type ._llcrypt unregistered [ 6980.098568] Key type ._llcrypt registered [ 6980.100672] Key type .llcrypt registered [ 6981.347897] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 06:24:32 (1789122272) [ 6984.006965] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6984.025245] alg: No test for adler32 (adler32-zlib) [ 6985.066822] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 6985.264627] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 6986.976160] Key type lgssc registered [ 6987.963467] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7036.314833] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7041.106593] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7066.279571] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7068.001435] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 7115.830405] Lustre: Unmounted lustre-client [ 7152.691996] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7153.243841] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 7189.142865] Lustre: Unmounted lustre-client [ 7217.772526] Key type lgssc unregistered [ 7217.982158] LNet: 167172:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7217.993651] LNetError: 167172:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7218.008829] LNet: Removed LNI 192.168.203.46@tcp [ 7218.531589] Key type .llcrypt unregistered [ 7218.536623] Key type ._llcrypt unregistered [ 7235.774545] Key type ._llcrypt registered [ 7235.778553] Key type .llcrypt registered [ 7237.636088] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 06:28:47 (1789122527) [ 7240.635781] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7240.733143] alg: No test for adler32 (adler32-zlib) [ 7241.905917] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 7242.217377] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 7244.008380] Key type lgssc registered [ 7245.613448] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7293.455965] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7298.711571] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7318.976482] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7320.757242] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 7338.038125] Lustre: Unmounted lustre-client [ 7366.444292] Key type lgssc unregistered [ 7366.735605] LNet: 170924:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7366.739680] LNetError: 170924:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7366.754979] LNet: Removed LNI 192.168.203.46@tcp [ 7367.245893] Key type .llcrypt unregistered [ 7367.259299] Key type ._llcrypt unregistered [ 7379.696814] Key type ._llcrypt registered [ 7379.707548] Key type .llcrypt registered [ 7380.030570] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7380.039611] alg: No test for adler32 (adler32-zlib) [ 7381.115461] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 7381.363489] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 7383.040200] Key type lgssc registered [ 7384.280654] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7431.801651] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7437.212589] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7456.034798] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7457.585527] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 7463.557416] Lustre: Unmounted lustre-client [ 7493.871903] Key type lgssc unregistered [ 7494.159749] LNet: 173986:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7494.163830] LNetError: 173986:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7494.200206] LNet: Removed LNI 192.168.203.46@tcp [ 7495.073320] Key type .llcrypt unregistered [ 7495.076198] Key type ._llcrypt unregistered [ 7511.612427] Key type ._llcrypt registered [ 7511.614871] Key type .llcrypt registered [ 7513.505160] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 06:33:23 (1789122803) [ 7516.561320] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7516.649574] alg: No test for adler32 (adler32-zlib) [ 7518.253630] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 7518.675529] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 7520.432195] Key type lgssc registered [ 7522.097592] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7573.381780] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7578.648537] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7596.956389] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7599.485648] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 7614.890419] Lustre: Unmounted lustre-client [ 7645.003321] Key type lgssc unregistered [ 7645.342399] LNet: 177740:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7645.352326] LNetError: 177740:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7645.372537] LNet: Removed LNI 192.168.203.46@tcp [ 7646.373722] Key type .llcrypt unregistered [ 7646.378300] Key type ._llcrypt unregistered [ 7655.931575] Key type ._llcrypt registered [ 7655.936594] Key type .llcrypt registered [ 7656.253680] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7656.261738] alg: No test for adler32 (adler32-zlib) [ 7657.309411] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 7657.568996] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 7659.312205] Key type lgssc registered [ 7660.444683] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7707.917197] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7713.809267] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7732.243257] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7733.857592] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 7740.862977] Lustre: Unmounted lustre-client [ 7769.623972] Key type lgssc unregistered [ 7769.999563] LNet: 180802:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7770.007804] LNetError: 180802:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7770.060223] LNet: Removed LNI 192.168.203.46@tcp [ 7770.887739] Key type .llcrypt unregistered [ 7770.892880] Key type ._llcrypt unregistered [ 7787.546191] Key type ._llcrypt registered [ 7787.549113] Key type .llcrypt registered [ 7788.568524] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 06:37:59 (1789123079) [ 7809.602101] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7809.651058] alg: No test for adler32 (adler32-zlib) [ 7810.683583] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 7810.956989] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 7812.600161] Key type lgssc registered [ 7813.510366] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7883.068608] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7887.536611] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7907.066870] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7944.317741] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 06:40:35 (1789123235) [ 7974.638474] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7979.946456] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8000.696336] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8003.336575] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 8008.444238] Lustre: Unmounted lustre-client [ 8034.858905] Key type lgssc unregistered [ 8035.143116] LNet: 187900:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8035.154516] LNetError: 187900:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8035.176258] LNet: Removed LNI 192.168.203.46@tcp [ 8035.965135] Key type .llcrypt unregistered [ 8035.968396] Key type ._llcrypt unregistered [ 8051.716678] Key type ._llcrypt registered [ 8051.725909] Key type .llcrypt registered [ 8053.136501] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 8054.655357] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 06:42:25 (1789123345) [ 8071.723311] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8071.748909] alg: No test for adler32 (adler32-zlib) [ 8072.801654] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 8072.958658] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 8074.616957] Key type lgssc registered [ 8075.596484] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8162.797659] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8168.254746] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8190.370073] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8210.261934] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8211.839317] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 8232.711664] Lustre: Unmounted lustre-client [ 8368.277649] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8374.982789] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8398.755445] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8437.973600] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 06:48:48 (1789123728) [ 8439.348316] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 8441.072937] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 06:48:51 (1789123731) [ 8532.673158] Key type lgssc unregistered [ 8532.897293] LNet: 199339:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8532.904982] LNetError: 199339:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8532.934644] LNet: Removed LNI 192.168.203.46@tcp [ 8533.582679] Key type .llcrypt unregistered [ 8533.589748] Key type ._llcrypt unregistered [ 8547.746472] Key type ._llcrypt registered [ 8547.749827] Key type .llcrypt registered [ 8548.995033] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 06:50:39 (1789123839) [ 8586.962100] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8587.021170] alg: No test for adler32 (adler32-zlib) [ 8588.108587] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 8588.325345] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 8589.969223] Key type lgssc registered [ 8590.914085] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8629.291876] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8634.180403] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8667.527734] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8695.635204] Key type lgssc unregistered [ 8695.889029] LNet: 203089:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8695.894135] LNetError: 203089:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8695.916903] LNet: Removed LNI 192.168.203.46@tcp [ 8696.889480] Key type .llcrypt unregistered [ 8696.905531] Key type ._llcrypt unregistered [ 8711.517118] Key type ._llcrypt registered [ 8711.520720] Key type .llcrypt registered [ 8712.525952] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 06:53:23 (1789124003) [ 8714.889643] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8714.932739] alg: No test for adler32 (adler32-zlib) [ 8715.963297] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 8716.162206] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 8717.784197] Key type lgssc registered [ 8718.966814] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8762.378154] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8768.394367] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8796.676916] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8799.548807] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 8809.983877] Lustre: lustre-MDT0000-mdc-ffff949405c42000: Connection to lustre-MDT0000 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8820.228712] Lustre: lustre-MDT0001-mdc-ffff949405c42000: Connection to lustre-MDT0001 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8831.393098] Lustre: 204078:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789124107/real 1789124107] req@0000000060bf7581 x1876032495894656/t0(0) o400->MGC192.168.203.146@tcp@192.168.203.146@tcp:26/25 lens 224/224 e 0 to 1 dl 1789124123 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 8831.433288] LustreError: MGC192.168.203.146@tcp: Connection to MGS (at 192.168.203.146@tcp) was lost; in progress operations using this service will fail [ 8841.704408] Lustre: Evicted from MGS (at 192.168.203.146@tcp) after server handle changed from 0xeecccc8a2dc71365 to 0xeecccc8a2dc72e8c [ 8841.718510] Lustre: MGC192.168.203.146@tcp: Connection restored to 192.168.203.146@tcp (at 192.168.203.146@tcp) [ 8865.270461] LustreError: lustre-MDT0000-mdc-ffff949405c42000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 8865.298972] Lustre: lustre-MDT0000-mdc-ffff949405c42000: Connection restored to 192.168.203.146@tcp (at 192.168.203.146@tcp) [ 8867.056595] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8868.524903] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 8873.990968] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8875.184219] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 8878.474711] Lustre: Unmounted lustre-client [ 8904.247982] Key type lgssc unregistered [ 8904.482972] LNet: 208042:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8904.492562] LNetError: 208042:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8904.506511] LNet: Removed LNI 192.168.203.46@tcp [ 8905.251791] Key type .llcrypt unregistered [ 8905.254536] Key type ._llcrypt unregistered [ 8921.811494] Key type ._llcrypt registered [ 8921.814176] Key type .llcrypt registered [ 8923.025401] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 06:56:53 (1789124213) [ 8925.160649] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8925.198418] alg: No test for adler32 (adler32-zlib) [ 8926.262217] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 8926.502979] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 8928.192488] Key type lgssc registered [ 8929.319331] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8975.143365] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8980.530983] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9033.706678] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9039.721360] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9073.919553] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9092.672697] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9132.449645] Key type lgssc unregistered [ 9132.726691] LNet: 213336:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9132.737544] LNetError: 213336:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9132.757542] LNet: Removed LNI 192.168.203.46@tcp [ 9133.529165] Key type .llcrypt unregistered [ 9133.532876] Key type ._llcrypt unregistered [ 9176.335403] Key type ._llcrypt registered [ 9176.338568] Key type .llcrypt registered [ 9177.794641] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 07:01:08 (1789124468) [ 9213.575064] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9213.604736] alg: No test for adler32 (adler32-zlib) [ 9214.600499] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 9214.825562] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 9216.496608] Key type lgssc registered [ 9217.478993] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9287.025069] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9292.697043] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9312.227570] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9353.139630] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 07:04:03 (1789124643) [ 9360.174572] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 07:04:11 (1789124651) [ 9395.377362] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9401.861603] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9422.079590] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9424.784796] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 9433.683836] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [ 9436.077857] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 9437.890570] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 9439.358807] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [ 9445.350996] Lustre: lustre-MDT0000-mdc-ffff9494105d9800: Connection to lustre-MDT0000 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9461.728166] Lustre: 215242:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789124737/real 1789124737] req@0000000040e244e0 x1876033018013824/t0(0) o400->MGC192.168.203.146@tcp@192.168.203.146@tcp:26/25 lens 224/224 e 0 to 1 dl 1789124753 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9461.757628] LustreError: MGC192.168.203.146@tcp: Connection to MGS (at 192.168.203.146@tcp) was lost; in progress operations using this service will fail [ 9461.781228] Lustre: Evicted from MGS (at 192.168.203.146@tcp) after server handle changed from 0x73213b9d7afb3d2f to 0x73213b9d7afb3f3c [ 9461.795875] Lustre: MGC192.168.203.146@tcp: Connection restored to 192.168.203.146@tcp (at 192.168.203.146@tcp) [ 9467.209727] Lustre: lustre-MDT0000-mdc-ffff9494105d9800: Connection restored to 192.168.203.146@tcp (at 192.168.203.146@tcp) [ 9473.957351] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9475.321021] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9478.527039] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [ 9482.244269] Lustre: lustre-MDT0000-mdc-ffff9494105d9800: Connection to lustre-MDT0000 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9498.599372] Lustre: 215241:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789124774/real 1789124774] req@00000000d7cacd36 x1876033018021632/t0(0) o400->MGC192.168.203.146@tcp@192.168.203.146@tcp:26/25 lens 224/224 e 0 to 1 dl 1789124790 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9498.632645] LustreError: MGC192.168.203.146@tcp: Connection to MGS (at 192.168.203.146@tcp) was lost; in progress operations using this service will fail [ 9504.090857] Lustre: lustre-MDT0000-mdc-ffff9494105d9800: Connection restored to 192.168.203.146@tcp (at 192.168.203.146@tcp) [ 9508.205615] Lustre: Evicted from MGS (at 192.168.203.146@tcp) after server handle changed from 0x73213b9d7afb3f3c to 0x73213b9d7afb43b8 [ 9508.224161] Lustre: MGC192.168.203.146@tcp: Connection restored to 192.168.203.146@tcp (at 192.168.203.146@tcp) [ 9511.779736] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9513.184786] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9516.691462] Lustre: Unmounted lustre-client [ 9582.505661] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9587.627424] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9607.512648] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9608.157826] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 9610.977710] Lustre: Unmounted lustre-client [ 9637.163602] Key type lgssc unregistered [ 9637.450902] LNet: 225830:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9637.458703] LNetError: 225830:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9637.495465] LNet: Removed LNI 192.168.203.46@tcp [ 9638.123865] Key type .llcrypt unregistered [ 9638.127830] Key type ._llcrypt unregistered [ 9653.038962] Key type ._llcrypt registered [ 9653.041526] Key type .llcrypt registered [ 9654.254893] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 07:09:04 (1789124944) [ 9658.878764] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9658.895648] alg: No test for adler32 (adler32-zlib) [ 9659.956486] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 9660.158544] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 9661.809325] Key type lgssc registered [ 9663.030839] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9708.307894] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9714.483658] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9733.143317] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9735.669152] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 9750.572992] Lustre: Unmounted lustre-client [ 9807.876917] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9815.154971] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9837.848722] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9838.594515] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 9847.470692] Lustre: Unmounted lustre-client [ 9908.672941] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9914.245421] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9933.141284] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9933.905464] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [ 9935.416956] Lustre: Unmounted lustre-client [ 9970.150479] Key type lgssc unregistered [ 9970.441363] LNet: 232873:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9970.451798] LNetError: 232873:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9970.468618] LNet: Removed LNI 192.168.203.46@tcp [ 9971.082657] Key type .llcrypt unregistered [ 9971.087892] Key type ._llcrypt unregistered [ 9984.908266] Key type ._llcrypt registered [ 9984.910645] Key type .llcrypt registered [ 9986.089965] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 07:14:36 (1789125276) [ 9988.977110] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9989.032620] alg: No test for adler32 (adler32-zlib) [ 9990.061741] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [ 9990.292756] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 9992.032286] Key type lgssc registered [ 9993.188197] Lustre: Echo OBD driver; http://www.lustre.org/ [10039.593368] Key type lgssc unregistered [10039.898386] LNet: 235195:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10039.917375] LNetError: 235195:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10039.937684] LNet: Removed LNI 192.168.203.46@tcp [10040.770835] Key type .llcrypt unregistered [10040.776495] Key type ._llcrypt unregistered [10071.746629] Key type ._llcrypt registered [10071.752656] Key type .llcrypt registered [10072.715537] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10072.730518] alg: No test for adler32 (adler32-zlib) [10073.705688] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [10073.938635] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [10075.648211] Key type lgssc registered [10076.533928] Lustre: Echo OBD driver; http://www.lustre.org/ [10154.054482] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10159.492470] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10180.905268] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10220.317228] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [10221.933599] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 07:18:32 (1789125512) [10252.378506] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10258.480450] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10278.122693] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10302.253480] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10303.915424] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [10309.120203] Lustre: lustre-OST0001-osc-ffff949408ee7800: Connection to lustre-OST0001 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [10316.187686] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [10326.494365] Lustre: Unmounted lustre-client [10360.823903] Key type lgssc unregistered [10361.126144] LNet: 242309:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10361.145359] LNetError: 242309:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10361.167787] LNet: Removed LNI 192.168.203.46@tcp [10362.000736] Key type .llcrypt unregistered [10362.002909] Key type ._llcrypt unregistered [10394.003889] Key type ._llcrypt registered [10394.012427] Key type .llcrypt registered [10395.502522] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 07:21:25 (1789125685) [10408.972664] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10408.999884] alg: No test for adler32 (adler32-zlib) [10410.116875] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [10410.392017] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [10412.048414] Key type lgssc registered [10413.341854] Lustre: Echo OBD driver; http://www.lustre.org/ [10473.714324] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10479.304994] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10500.638972] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 07:23:11 (1789125791) [10535.940873] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10542.657099] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10565.307660] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10568.311840] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [10579.509083] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [10587.321234] Lustre: Unmounted lustre-client [10681.854642] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10687.134143] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10737.006838] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10737.631430] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [10740.427609] Lustre: Unmounted lustre-client [10766.189567] Key type lgssc unregistered [10766.495123] LNet: 250029:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10766.511625] LNetError: 250029:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10766.533434] LNet: Removed LNI 192.168.203.46@tcp [10767.285635] Key type .llcrypt unregistered [10767.289738] Key type ._llcrypt unregistered [10793.533805] Key type ._llcrypt registered [10793.547964] Key type .llcrypt registered [10793.841909] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10793.853944] alg: No test for adler32 (adler32-zlib) [10794.827188] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [10795.118379] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [10796.816302] Key type lgssc registered [10797.706124] Lustre: Echo OBD driver; http://www.lustre.org/ [10874.045260] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10879.742452] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10903.912338] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10951.151389] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [10952.670430] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 07:30:43 (1789126243) [10984.400619] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10989.614531] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11007.485734] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11012.356425] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [11014.852530] Lustre: Unmounted lustre-client [11042.144641] Key type lgssc unregistered [11042.328911] LNet: 256665:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11042.339267] LNetError: 256665:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11042.359381] LNet: Removed LNI 192.168.203.46@tcp [11043.149803] Key type .llcrypt unregistered [11043.151717] Key type ._llcrypt unregistered [11058.040714] Key type ._llcrypt registered [11058.043965] Key type .llcrypt registered [11059.373338] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [11060.847291] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 07:32:31 (1789126351) [11068.242395] Key type .llcrypt unregistered [11068.248867] Key type ._llcrypt unregistered [11078.855712] Key type ._llcrypt registered [11078.860686] Key type .llcrypt registered [11079.656722] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11079.686642] alg: No test for adler32 (adler32-zlib) [11080.917209] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [11081.288085] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [11083.056201] Key type lgssc registered [11084.374905] Lustre: Echo OBD driver; http://www.lustre.org/ [11135.691749] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11148.684621] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [11151.095957] Lustre: Unmounted lustre-client [11185.146853] Key type lgssc unregistered [11185.431959] LNet: 260125:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11185.436509] LNetError: 260125:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11185.448907] LNet: Removed LNI 192.168.203.46@tcp [11186.187107] Key type .llcrypt unregistered [11186.193897] Key type ._llcrypt unregistered [11199.575239] Key type ._llcrypt registered [11199.577977] Key type .llcrypt registered [11200.641581] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 07:34:51 (1789126491) [11202.781344] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11202.892130] alg: No test for adler32 (adler32-zlib) [11203.925874] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [11204.105504] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [11205.744247] Key type lgssc registered [11206.719181] Lustre: Echo OBD driver; http://www.lustre.org/ [11285.604575] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11314.953749] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11319.473395] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11321.124599] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [11323.044678] Lustre: Unmounted lustre-client [11347.292943] Key type lgssc unregistered [11347.605121] LNet: 263668:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11347.610873] LNetError: 263668:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11347.634240] LNet: Removed LNI 192.168.203.46@tcp [11348.341526] Key type .llcrypt unregistered [11348.343969] Key type ._llcrypt unregistered [11361.609466] Key type ._llcrypt registered [11361.614382] Key type .llcrypt registered [11362.768889] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 07:37:33 (1789126653) [11364.670885] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11364.706952] alg: No test for adler32 (adler32-zlib) [11365.726314] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [11365.971722] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [11367.640413] Key type lgssc registered [11368.652934] Lustre: Echo OBD driver; http://www.lustre.org/ [11410.329964] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11416.194825] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11433.310297] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11434.706261] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [11440.108091] Lustre: lustre-MDT0000-mdc-ffff9494204aa000: Connection to lustre-MDT0000 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [11443.769762] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11443.789246] LustreError: 266828:0:(file.c:6184:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [11444.229750] LustreError: MGC192.168.203.146@tcp: Connection to MGS (at 192.168.203.146@tcp) was lost; in progress operations using this service will fail [11444.319097] Lustre: Unmounted lustre-client [11467.401191] Key type lgssc unregistered [11467.724056] LNet: 267246:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11467.730489] LNetError: 267246:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11467.757054] LNet: Removed LNI 192.168.203.46@tcp [11468.599728] Key type .llcrypt unregistered [11468.601364] Key type ._llcrypt unregistered [11481.088247] Key type ._llcrypt registered [11481.093439] Key type .llcrypt registered [11482.150418] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 07:39:33 (1789126773) [11484.108268] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11484.124627] alg: No test for adler32 (adler32-zlib) [11485.120492] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [11485.296570] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [11486.944230] Key type lgssc registered [11487.818366] Lustre: Echo OBD driver; http://www.lustre.org/ [11528.264476] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11533.607547] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11555.585470] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11557.463205] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [11562.473663] Lustre: lustre-MDT0001-mdc-ffff949404355800: Connection to lustre-MDT0001 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [11568.322565] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [11570.560612] Lustre: Unmounted lustre-client [11587.972259] Key type lgssc unregistered [11588.168550] LNet: 270829:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11588.174502] LNetError: 270829:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11588.191362] LNet: Removed LNI 192.168.203.46@tcp [11588.773846] Key type .llcrypt unregistered [11588.776123] Key type ._llcrypt unregistered [11599.156488] Key type ._llcrypt registered [11599.159107] Key type .llcrypt registered [11600.130457] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 07:41:31 (1789126891) [11613.087974] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11613.181644] alg: No test for adler32 (adler32-zlib) [11614.199903] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [11614.395201] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [11616.040185] Key type lgssc registered [11617.043814] Lustre: Echo OBD driver; http://www.lustre.org/ [11697.715729] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11700.298725] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [11734.507761] Lustre: lustre-MDT0001-mdc-ffff949402cf8800: Connection to lustre-MDT0001 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [11742.541540] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11752.917167] Lustre: Unmounted lustre-client [11778.230340] Key type lgssc unregistered [11778.480879] LNet: 276616:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11778.486919] LNetError: 276616:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11778.518471] LNet: Removed LNI 192.168.203.46@tcp [11778.997888] Key type .llcrypt unregistered [11779.006155] Key type ._llcrypt unregistered [11792.251300] Key type ._llcrypt registered [11792.252853] Key type .llcrypt registered [11793.372725] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 07:44:44 (1789127084) [11795.576990] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [11797.718718] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 07:44:48 (1789127088) [11799.341822] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [11801.060115] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 07:44:51 (1789127091) [11802.584708] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [11804.385819] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 07:44:54 (1789127094) [11806.814191] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [11808.793328] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 07:44:59 (1789127099) [11810.581091] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [11812.080941] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 07:45:02 (1789127102) [11819.176687] Key type .llcrypt unregistered [11819.181461] Key type ._llcrypt unregistered [11824.012124] Key type ._llcrypt registered [11824.017052] Key type .llcrypt registered [11824.217388] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11824.235675] alg: No test for adler32 (adler32-zlib) [11825.214955] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [11825.348653] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [11827.016210] Key type lgssc registered [11827.803938] Lustre: Echo OBD driver; http://www.lustre.org/ [11888.175240] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11892.670956] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11913.455397] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11915.057894] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [11917.296708] Lustre: Unmounted lustre-client [11954.418363] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 07:47:25 (1789127245) [11983.813475] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11988.552834] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12022.951296] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12034.100198] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [12036.021486] Lustre: Unmounted lustre-client [12072.257536] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 07:49:22 (1789127362) [12076.258199] LNet: 1 local NIs in recovery (showing 1): 192.168.203.46@tcp [12705.947711] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12711.976461] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12743.111312] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12837.473185] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 08:02:08 (1789128128) [12846.976105] Key type lgssc unregistered [12847.222529] LNet: 290160:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12847.227477] LNetError: 290160:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12847.250708] LNet: Removed LNI 192.168.203.46@tcp [12847.844859] Key type .llcrypt unregistered [12847.852255] Key type ._llcrypt unregistered [12988.052714] Key type ._llcrypt registered [12988.060716] Key type .llcrypt registered [12988.352807] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12988.367041] alg: No test for adler32 (adler32-zlib) [12989.593315] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [12989.869249] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [12991.600393] Key type lgssc registered [12992.921574] Lustre: Echo OBD driver; http://www.lustre.org/ [13082.231138] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13086.658209] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13107.705335] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13147.354783] Key type lgssc unregistered [13147.606799] LNet: 293871:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13147.618863] LNetError: 293871:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13147.639345] LNet: Removed LNI 192.168.203.46@tcp [13148.405653] Key type .llcrypt unregistered [13148.410349] Key type ._llcrypt unregistered [13183.536870] Key type ._llcrypt registered [13183.538476] Key type .llcrypt registered [13184.925158] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 08:07:55 (1789128475) [13204.842424] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13204.878621] alg: No test for adler32 (adler32-zlib) [13205.952911] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [13206.207803] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [13207.904515] Key type lgssc registered [13208.966677] Lustre: Echo OBD driver; http://www.lustre.org/ [13256.096561] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 08:09:06 (1789128546) [13268.164057] Key type lgssc unregistered [13268.382796] LNet: 297432:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13268.400924] LNetError: 297432:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13268.434753] LNet: Removed LNI 192.168.203.46@tcp [13269.023725] Key type .llcrypt unregistered [13269.028635] Key type ._llcrypt unregistered [13278.657376] Key type ._llcrypt registered [13278.665699] Key type .llcrypt registered [13279.213035] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13279.264355] alg: No test for adler32 (adler32-zlib) [13280.375626] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [13280.652655] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [13282.360400] Key type lgssc registered [13283.929553] Lustre: Echo OBD driver; http://www.lustre.org/ [13343.581874] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13349.196187] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13372.901338] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13667.086577] LNet: Added LNI 192.168.203.46@tcp50 [8/256/0/180] [13669.579719] LNet: Removed LNI 192.168.203.46@tcp [13671.783568] Lustre: 301830:0:(ldlm_lib.c:599:client_obd_setup()) lustre-MDT0000-mdc-ffff949405972800: config has no valid NIDs, force dynamic NIDs [13672.995129] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [13673.208392] Lustre: Unmounted lustre-client [13676.339911] Key type lgssc unregistered [13676.779374] LNet: 302092:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13676.787452] LNetError: 302092:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13676.806949] LNet: Removed LNI 192.168.203.46@tcp50 [13677.420071] Key type .llcrypt unregistered [13677.423235] Key type ._llcrypt unregistered [13697.420216] Key type ._llcrypt registered [13697.422591] Key type .llcrypt registered [13697.990442] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13698.002806] alg: No test for adler32 (adler32-zlib) [13699.197883] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [13699.598400] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [13701.352188] Key type lgssc registered [13702.722777] Lustre: Echo OBD driver; http://www.lustre.org/ [13763.775212] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13771.547221] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13780.541563] LNet: Added LNI 192.168.203.46@tcp50 [8/256/0/180] [13781.543402] LNet: Removed LNI 192.168.203.46@tcp [13782.765826] Lustre: 304784:0:(ldlm_lib.c:599:client_obd_setup()) lustre-MDT0000-mdc-ffff9494041c4800: config has no valid NIDs, force dynamic NIDs [13783.991026] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [13784.545184] Lustre: Unmounted lustre-client [13817.249283] Key type lgssc unregistered [13817.650713] LNet: 305658:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13817.669438] LNetError: 305658:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13817.696316] LNet: Removed LNI 192.168.203.46@tcp50 [13818.446104] Key type .llcrypt unregistered [13818.449966] Key type ._llcrypt unregistered [13830.514341] Key type ._llcrypt registered [13830.516872] Key type .llcrypt registered [13831.742878] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 08:18:42 (1789129122) [13849.782768] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13849.868492] alg: No test for adler32 (adler32-zlib) [13850.952347] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [13851.205742] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [13852.945231] Key type lgssc registered [13854.032955] Lustre: Echo OBD driver; http://www.lustre.org/ [13937.351774] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13943.348208] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13965.079873] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14004.225223] Key type lgssc unregistered [14004.434942] LNet: 310407:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14004.441794] LNetError: 310407:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14004.459402] LNet: Removed LNI 192.168.203.46@tcp [14005.082889] Key type .llcrypt unregistered [14005.086826] Key type ._llcrypt unregistered [14015.233743] Key type ._llcrypt registered [14015.235982] Key type .llcrypt registered [14016.639725] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 08:21:47 (1789129307) [14018.783950] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14018.806374] alg: No test for adler32 (adler32-zlib) [14019.871331] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [14020.116665] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [14021.808405] Key type lgssc registered [14022.967996] Lustre: Echo OBD driver; http://www.lustre.org/ [14070.143253] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14075.416425] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14094.624686] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14097.271207] Lustre: Mounted lustre-client - version 2.17.57_142_g748fc9f [14153.046292] Lustre: Mounted fs15246-client - version 2.17.57_142_g748fc9f [14170.720948] Lustre: Unmounted fs15246-client [14196.933749] Lustre: Unmounted lustre-client [14230.909969] Key type lgssc unregistered [14231.212575] LNet: 315267:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14231.222665] LNetError: 315267:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14231.253242] LNet: Removed LNI 192.168.203.46@tcp [14232.003795] Key type .llcrypt unregistered [14232.007172] Key type ._llcrypt unregistered [14243.554659] Key type ._llcrypt registered [14243.557751] Key type .llcrypt registered [14244.712723] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 08:25:35 (1789129535) [14246.278720] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14246.326734] alg: No test for adler32 (adler32-zlib) [14247.449240] Lustre: Lustre: Build Version: 2.17.57_142_g748fc9f [14247.755319] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [14249.448181] Key type lgssc registered [14250.739162] Lustre: Echo OBD driver; http://www.lustre.org/ [14373.339974] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14379.430613] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14401.892983] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14499.058685] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 14264 sec ========= 08:29:50 (1789129790) [14501.025228] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 08:29:51 (1789129791) === [14504.231796] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 08:29:54 (1789129794) === [14534.149914] Key type lgssc unregistered [14534.410452] LNet: 324532:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14534.416763] LNetError: 324532:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14534.432129] LNet: Removed LNI 192.168.203.46@tcp [14535.137815] Key type .llcrypt unregistered [14535.140616] Key type ._llcrypt unregistered