[ 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 489813666 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524584K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001013] APIC: Switch to symmetric I/O mode setup [ 0.003040] x2apic enabled [ 0.004008] Switched APIC routing to physical x2apic. [ 0.005015] kvm-guest: setup PV IPIs [ 0.008406] ..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.010012] pid_max: default: 32768 minimum: 301 [ 0.011134] LSM: Security Framework initializing [ 0.013015] Yama: becoming mindful. [ 0.014036] SELinux: Initializing. [ 0.015074] *** VALIDATE selinux *** [ 0.023267] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.028033] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.030024] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031111] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.032108] *** VALIDATE tmpfs *** [ 0.033403] *** VALIDATE proc *** [ 0.034243] *** VALIDATE cgroup *** [ 0.035009] *** VALIDATE cgroup2 *** [ 0.037146] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.038152] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.039009] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.040027] Spectre V2 : User space: Vulnerable [ 0.041007] Speculative Store Bypass: Vulnerable [ 0.044182] debug: unmapping init [mem 0xffffffff9e059000-0xffffffff9e060fff] [ 0.047000] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.047667] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.048022] ... version: 2 [ 0.049015] ... bit width: 48 [ 0.050009] ... generic registers: 4 [ 0.051012] ... value mask: 0000ffffffffffff [ 0.052013] ... max period: 00007fffffffffff [ 0.053013] ... fixed-purpose events: 3 [ 0.054015] ... event mask: 000000070000000f [ 0.056231] rcu: Hierarchical SRCU implementation. [ 0.058439] smp: Bringing up secondary CPUs ... [ 0.059556] x86: Booting SMP configuration: [ 0.060020] .... node #0, CPUs: #1 #2 #3 [ 0.063528] smp: Brought up 1 node, 4 CPUs [ 0.065015] smpboot: Max logical packages: 1 [ 0.066019] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.272029] node 0 deferred pages initialised in 202ms [ 0.276238] devtmpfs: initialized [ 0.277275] x86/mm: Memory block size: 128MB [ 0.279932] gcov: version magic: 0x41383552 [ 0.281254] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.282093] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.283262] pinctrl core: initialized pinctrl subsystem [ 0.284222] [ 0.284882] ************************************************************* [ 0.285021] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.286014] ** ** [ 0.287014] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.288012] ** ** [ 0.289011] ** This means that this kernel is built to expose internal ** [ 0.290018] ** IOMMU data structures, which may compromise security on ** [ 0.291017] ** your system. ** [ 0.292016] ** ** [ 0.293016] ** If you see this message and you are not debugging the ** [ 0.294016] ** kernel, report this immediately to your vendor! ** [ 0.295013] ** ** [ 0.296015] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.297013] ************************************************************* [ 0.298654] NET: Registered protocol family 16 [ 0.299426] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.300051] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.301067] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.302460] cpuidle: using governor menu [ 0.304838] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.306559] PCI: Using configuration type 1 for base access [ 0.309156] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.317154] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.318035] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.320030] cryptd: max_cpu_qlen set to 1000 [ 0.321262] ACPI: Added _OSI(Module Device) [ 0.322016] ACPI: Added _OSI(Processor Device) [ 0.323010] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.324012] ACPI: Added _OSI(Processor Aggregator Device) [ 0.327130] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.330569] ACPI: Interpreter enabled [ 0.331081] ACPI: PM: (supports S0 S3 S4 S5) [ 0.332019] ACPI: Using IOAPIC for interrupt routing [ 0.333115] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.334395] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.343637] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.344045] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.345024] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.346085] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.348490] acpiphp: Slot [2] registered [ 0.349211] acpiphp: Slot [5] registered [ 0.350179] acpiphp: Slot [6] registered [ 0.351133] acpiphp: Slot [3] registered [ 0.352122] acpiphp: Slot [4] registered [ 0.353111] acpiphp: Slot [7] registered [ 0.354132] acpiphp: Slot [8] registered [ 0.355132] acpiphp: Slot [9] registered [ 0.356112] acpiphp: Slot [10] registered [ 0.357115] acpiphp: Slot [11] registered [ 0.358115] acpiphp: Slot [12] registered [ 0.359125] acpiphp: Slot [13] registered [ 0.360111] acpiphp: Slot [14] registered [ 0.361101] acpiphp: Slot [15] registered [ 0.362201] acpiphp: Slot [16] registered [ 0.363000] acpiphp: Slot [17] registered [ 0.365147] acpiphp: Slot [18] registered [ 0.366174] acpiphp: Slot [19] registered [ 0.368162] acpiphp: Slot [20] registered [ 0.370159] acpiphp: Slot [21] registered [ 0.371138] acpiphp: Slot [22] registered [ 0.371922] acpiphp: Slot [23] registered [ 0.373078] acpiphp: Slot [24] registered [ 0.374140] acpiphp: Slot [25] registered [ 0.375117] acpiphp: Slot [26] registered [ 0.377138] acpiphp: Slot [27] registered [ 0.379308] acpiphp: Slot [28] registered [ 0.381182] acpiphp: Slot [29] registered [ 0.382120] acpiphp: Slot [30] registered [ 0.384167] acpiphp: Slot [31] registered [ 0.386126] PCI host bridge to bus 0000:00 [ 0.387031] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.390035] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.392040] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.395036] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.397033] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.400043] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.402228] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.406159] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.409413] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.417575] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.421112] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.424028] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.426025] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.429026] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.431519] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.435735] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.438057] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.441813] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.444872] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.453880] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.459020] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.465652] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.475029] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.484026] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.508037] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.518837] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.526022] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.544034] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.589028] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.608268] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.611524] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.613504] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.616438] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.618235] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.622143] iommu: Default domain type: Passthrough [ 0.625106] SCSI subsystem initialized [ 0.626202] ACPI: bus type USB registered [ 0.628170] usbcore: registered new interface driver usbfs [ 0.630124] usbcore: registered new interface driver hub [ 0.633167] usbcore: registered new device driver usb [ 0.635234] pps_core: LinuxPPS API ver. 1 registered [ 0.637031] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.640359] PTP clock support registered [ 0.643086] EDAC MC: Ver: 3.0.0 [ 0.644500] PCI: Using ACPI for IRQ routing [ 0.646092] NetLabel: Initializing [ 0.647017] NetLabel: domain hash size = 128 [ 0.648014] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.650109] NetLabel: unlabeled traffic allowed by default [ 0.653163] vgaarb: loaded [ 0.654303] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.656023] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.664239] clocksource: Switched to clocksource kvm-clock [ 0.776192] VFS: Disk quotas dquot_6.6.0 [ 0.778629] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.782205] *** VALIDATE ramfs *** [ 0.783873] *** VALIDATE hugetlbfs *** [ 0.785953] pnp: PnP ACPI init [ 0.788836] pnp: PnP ACPI: found 6 devices [ 0.813469] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.816836] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.818552] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.819953] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.821830] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.823886] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.826498] NET: Registered protocol family 2 [ 0.828900] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.833370] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.836760] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.842267] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.845633] TCP: Hash tables configured (established 65536 bind 65536) [ 0.847928] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.850621] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.852760] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.854834] NET: Registered protocol family 1 [ 0.856739] RPC: Registered named UNIX socket transport module. [ 0.858246] RPC: Registered udp transport module. [ 0.859361] RPC: Registered tcp transport module. [ 0.860574] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.862980] NET: Registered protocol family 44 [ 0.864478] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.866197] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.867922] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.870228] PCI: CLS 0 bytes, default 64 [ 0.872187] Unpacking initramfs... [ 2.281597] debug: unmapping init [mem 0xffff9a4ebcc64000-0xffff9a4ebffcffff] [ 2.285598] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.288111] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.291497] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.815759] Initialise system trusted keyrings [ 2.817488] Key type blacklist registered [ 2.819418] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.829675] zbud: loaded [ 2.833189] *** VALIDATE nfs *** [ 2.834618] *** VALIDATE nfs4 *** [ 2.836390] pstore: using deflate compression [ 2.840684] Platform Keyring initialized [ 2.956051] NET: Registered protocol family 38 [ 2.958191] Key type asymmetric registered [ 2.959397] Asymmetric key parser 'x509' registered [ 2.962964] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.968840] io scheduler mq-deadline registered [ 2.971106] io scheduler kyber registered [ 2.972995] io scheduler bfq registered [ 2.975171] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.978213] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.981271] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.984220] ACPI: Power Button [PWRF] [ 2.990329] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.997167] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.023598] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.053321] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.083561] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.089828] Non-volatile memory driver v1.3 [ 3.091829] Linux agpgart interface v0.103 [ 3.136652] virtio_blk virtio1: [vda] 145920 512-byte logical blocks (74.7 MB/71.3 MiB) [ 3.139781] vda: detected capacity change from 0 to 74711040 [ 3.176381] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.179837] vdb: detected capacity change from 0 to 1073741824 [ 3.202192] libphy: Fixed MDIO Bus: probed [ 3.211345] usbcore: registered new interface driver usbserial_generic [ 3.213996] usbserial: USB Serial support registered for generic [ 3.216385] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.221603] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.223448] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.226405] mousedev: PS/2 mouse device common for all mice [ 3.229178] rtc_cmos 00:05: RTC can wake from S4 [ 3.232737] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.236702] rtc_cmos 00:05: registered as rtc0 [ 3.241089] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.242675] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.248914] intel_pstate: CPU model not supported [ 3.254744] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.256723] hid: raw HID events driver (C) Jiri Kosina [ 3.261066] usbcore: registered new interface driver usbhid [ 3.264442] usbhid: USB HID core driver [ 3.267320] drop_monitor: Initializing network drop monitor service [ 3.269926] Initializing XFRM netlink socket [ 3.272062] NET: Registered protocol family 10 [ 3.275219] Segment Routing with IPv6 [ 3.276703] NET: Registered protocol family 17 [ 3.278949] mpls_gso: MPLS GSO support [ 3.285944] RAS: Correctable Errors collector initialized. [ 3.288175] AVX version of gcm_enc/dec engaged. [ 3.289697] AES CTR mode by8 optimization enabled [ 3.390368] sched_clock: Marking stable (3390294373, 0)->(4294971809, -904677436) [ 3.394986] registered taskstats version 1 [ 3.396968] Loading compiled-in X.509 certificates [ 3.398442] zswap: loaded using pool lzo/zbud [ 3.426668] Key type big_key registered [ 3.439927] Key type encrypted registered [ 3.441373] ima: No TPM chip found, activating TPM-bypass! [ 3.443223] ima: Allocated hash algorithm: sha1 [ 3.444722] ima: No architecture policies found [ 3.446255] evm: Initialising EVM extended attributes: [ 3.447995] evm: security.selinux [ 3.449237] evm: security.ima [ 3.450325] evm: security.capability [ 3.451559] evm: HMAC attrs: 0x1 [ 3.455572] rtc_cmos 00:05: setting system clock to 2026-08-19 05:50:27 UTC (1787118627) [ 3.461866] debug: unmapping init [mem 0xffffffff9f003000-0xffffffff9f1fffff] [ 3.464773] debug: unmapping init [mem 0xffffffff9dd82000-0xffffffff9e058fff] [ 3.472540] Write protecting the kernel read-only data: 28672k [ 3.475801] debug: unmapping init [mem 0xffffffff9c403000-0xffffffff9c5fffff] [ 3.478548] debug: unmapping init [mem 0xffffffff9cd14000-0xffffffff9cdfffff] [ 3.513345] 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.521678] systemd[1]: Detected virtualization kvm. [ 3.523466] systemd[1]: Detected architecture x86-64. [ 3.525228] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.554384] systemd[1]: No hostname configured. [ 3.555928] systemd[1]: Set hostname to . [ 3.557613] random: systemd: uninitialized urandom read (16 bytes read) [ 3.559399] systemd[1]: Initializing machine ID from random generator. [ 3.617691] random: ln: uninitialized urandom read (6 bytes read) [ 3.733515] random: systemd: uninitialized urandom read (16 bytes read) [ 3.735903] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ 3.740346] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 3.745218] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on Journal Socket. [ OK ] Started Memstrack Anylazing Service. Starting Create list of required st…ce nodes for the current kernel... Starting Create Volatile Files and Directories... [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Swap. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Slices. Starting Apply Kernel Variables... Starting Journal Service... [ OK ] Reached target Sockets. [ OK ] Reached target Initrd Root Device. Starting Setup Virtual Console... [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.536735] device-mapper: uevent: version 1.0.3 [ 4.539435] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 5.413242] virtio_net virtio0 ens2: renamed from eth0 [ 5.424766] scsi host0: ata_piix [ 5.432209] scsi host1: ata_piix [ 5.437358] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.440820] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.959268] random: crng init done [ 9.960645] random: 7 urandom warning(s) missed due to ratelimiting [ 10.009562] dracut-initqueue[591]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 10.644108] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Remote File Systems (Pre). [ 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 Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Swap. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev 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.873763] printk: systemd: 26 output lines suppressed due to ratelimiting [ 12.116972] SELinux: Disabled at runtime. [ 12.175090] 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) [ 12.185162] systemd[1]: Detected virtualization kvm. [ 12.187348] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.737398] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.741141] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.747967] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.752043] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.755086] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.761822] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.766789] systemd[1]: Created slice User and Session Slice. [ OK ] Created slice User and Session Slice. Starting Apply Kernel Variables... [ OK ] Listening on udev Kernel Socket. Mounting Huge Pages File System... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on udev Control Socket. Activating swap /dev/disk/by-label/SWAP... [ OK ] Stopped target Switch Root. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. Starting udev Coldplug all Devices... [ OK ] Reached target Paths. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapp[ 12.855481] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS er. Mounting POSIX Message Queue File System... [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. Starting Remount Root and Kernel File Systems... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Slices. [ OK ] Listening on Process Core Dump Socket. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Created slice system-getty.slice. Mounting Kernel Debug File System... [ OK ] Reached target rpc_pipefs.target. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Started Journal Service. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Huge Pages File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted POSIX Message Queue File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Kernel Debug File System. Starting Create Static Device Nodes in /dev... Starting Configure read-only root support... [ 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. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 13.311630] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 13.716282] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.743572] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.890968] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.910640] EDAC sbridge: Ver: 1.1.2 [ 15.190075] Key type dns_resolver registered [ 15.528161] NFS: Registering the id_resolver key type [ 15.530810] Key type id_resolver registered [ 15.532404] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... Starting Mark the need to relabel after reboot... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started irqbalance daemon. Starting Restore /run/initramfs on shutdown... Starting Login Service... [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started D-Bus System Message Bus. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. Starting Network Manager... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting Hostname Service... [ 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 OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting System Logging Service... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg251-client login: [ 104.725960] libcfs: loading out-of-tree module taints kernel. [ 104.864522] Key type ._llcrypt registered [ 104.866814] Key type .llcrypt registered [ 105.641965] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 105.682150] alg: No test for adler32 (adler32-zlib) [ 107.474433] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [ 109.238610] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [ 111.224191] Key type lgssc registered [ 112.902828] hrtimer: interrupt took 6506312 ns [ 114.264270] Lustre: Echo OBD driver; http://www.lustre.org/ [ 299.591620] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 304.944221] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 318.790818] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing check_logdir /tmp/testlogs/ [ 323.638611] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing yml_node [ 325.088857] Lustre: lustre-OST0000-osc-ffff9a4f0646b800: disconnect after 23s idle [ 328.253876] Lustre: DEBUG MARKER: Client: 2.17.57.3 [ 330.783812] Lustre: DEBUG MARKER: MDS: 2.17.57.3 [ 332.649604] Lustre: DEBUG MARKER: OSS: 2.17.57.3 [ 334.522663] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Wed Aug 19 01:55:57 EDT 2026 [ 353.522543] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 355.143642] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 358.452740] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 368.790800] Lustre: Unmounted lustre-client [ 473.689294] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 480.281819] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 505.381148] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 541.637126] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 01:59:24 (1787119164) [ 544.273708] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 546.552672] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 01:59:28 (1787119168) [ 581.693263] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 587.408229] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 611.924797] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 612.804384] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 621.147182] Lustre: Unmounted lustre-client [ 650.140678] Key type lgssc unregistered [ 650.491144] LNet: 15078:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 650.498031] LNetError: 15078:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 650.528734] LNet: Removed LNI 192.168.202.51@tcp [ 651.376216] Key type .llcrypt unregistered [ 651.380757] Key type ._llcrypt unregistered [ 670.632773] Key type ._llcrypt registered [ 670.635436] Key type .llcrypt registered [ 672.043724] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 02:01:34 (1787119294) [ 674.157679] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 674.186421] alg: No test for adler32 (adler32-zlib) [ 675.308550] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [ 675.569579] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [ 677.285768] Key type lgssc registered [ 678.724660] Lustre: Echo OBD driver; http://www.lustre.org/ [ 758.586067] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 844.393158] Lustre: Unmounted lustre-client [ 871.904411] Key type lgssc unregistered [ 872.233485] LNet: 18872:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 872.239931] LNetError: 18872:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 872.259536] LNet: Removed LNI 192.168.202.51@tcp [ 872.955546] Key type .llcrypt unregistered [ 872.957355] Key type ._llcrypt unregistered [ 883.751995] Key type ._llcrypt registered [ 883.754737] Key type .llcrypt registered [ 885.133975] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 02:05:07 (1787119507) [ 887.460222] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 887.545979] alg: No test for adler32 (adler32-zlib) [ 888.570349] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [ 888.751327] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [ 890.409650] Key type lgssc registered [ 891.333514] Lustre: Echo OBD driver; http://www.lustre.org/ [ 940.178397] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 944.867322] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 964.920714] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 967.528583] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 977.978522] Lustre: Unmounted lustre-client [ 1004.825142] Key type lgssc unregistered [ 1005.096864] LNet: 22476:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1005.108651] LNetError: 22476:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1005.141587] LNet: Removed LNI 192.168.202.51@tcp [ 1005.681989] Key type .llcrypt unregistered [ 1005.686670] Key type ._llcrypt unregistered [ 1016.826553] Key type ._llcrypt registered [ 1016.828349] Key type .llcrypt registered [ 1017.991135] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 02:07:20 (1787119640) [ 1020.770439] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1021.035918] alg: No test for adler32 (adler32-zlib) [ 1022.296156] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [ 1022.586467] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [ 1024.360237] Key type lgssc registered [ 1025.994120] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1078.883174] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1083.882159] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1105.791478] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1108.316634] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 1114.148718] Lustre: Unmounted lustre-client [ 1140.969417] Key type lgssc unregistered [ 1141.252966] LNet: 25670:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1141.260063] LNetError: 25670:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1141.290274] LNet: Removed LNI 192.168.202.51@tcp [ 1142.010380] Key type .llcrypt unregistered [ 1142.018583] Key type ._llcrypt unregistered [ 1158.096525] Key type ._llcrypt registered [ 1158.097963] Key type .llcrypt registered [ 1159.495595] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 02:09:42 (1787119782) [ 1161.833920] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1161.894161] alg: No test for adler32 (adler32-zlib) [ 1163.037517] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [ 1163.296347] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [ 1165.024443] Key type lgssc registered [ 1166.454737] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1220.938130] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1226.880829] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1250.342252] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1253.036842] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 1258.473923] Lustre: lustre-MDT0000-mdc-ffff9a4f08967800: Connection to lustre-MDT0000 (at 192.168.202.151@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1263.603267] Lustre: lustre-MDT0001-mdc-ffff9a4f08967800: Connection to lustre-MDT0001 (at 192.168.202.151@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1271.734577] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1279.968263] Lustre: 26629:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787119887/real 1787119887] req@000000007704c9ab x1873930917779072/t0(0) o400->MGC192.168.202.151@tcp@192.168.202.151@tcp:26/25 lens 224/224 e 0 to 1 dl 1787119903 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1280.012070] LustreError: MGC192.168.202.151@tcp: Connection to MGS (at 192.168.202.151@tcp) was lost; in progress operations using this service will fail [ 1282.152680] Lustre: Unmounted lustre-client [ 1309.980930] Key type lgssc unregistered [ 1310.287347] LNet: 29326:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1310.296036] LNetError: 29326:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1310.309292] LNet: Removed LNI 192.168.202.51@tcp [ 1311.119420] Key type .llcrypt unregistered [ 1311.124949] Key type ._llcrypt unregistered [ 1326.853203] Key type ._llcrypt registered [ 1326.855145] Key type .llcrypt registered [ 1327.969740] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 02:12:30 (1787119950) [ 1330.270187] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1330.369079] alg: No test for adler32 (adler32-zlib) [ 1331.355095] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [ 1331.560789] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [ 1333.242155] Key type lgssc registered [ 1334.756866] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1390.240424] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1395.620717] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1417.471959] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1419.395806] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 1424.374361] Lustre: lustre-OST0000-osc-ffff9a4f082e6000: Connection to lustre-OST0000 (at 192.168.202.151@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1432.363992] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1442.788935] Lustre: Unmounted lustre-client [ 1472.041325] Key type lgssc unregistered [ 1472.347395] LNet: 32940:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1472.358437] LNetError: 32940:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1472.389486] LNet: Removed LNI 192.168.202.51@tcp [ 1472.918726] Key type .llcrypt unregistered [ 1472.920490] Key type ._llcrypt unregistered [ 1492.538561] Key type ._llcrypt registered [ 1492.540883] Key type .llcrypt registered [ 1493.810714] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 02:15:16 (1787120116) [ 1497.036679] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1497.086802] alg: No test for adler32 (adler32-zlib) [ 1498.185894] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [ 1498.653189] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [ 1500.416204] Key type lgssc registered [ 1501.229255] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1555.023158] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1563.351440] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1584.037270] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1585.581768] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 1592.188657] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1595.380858] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1607.299765] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-08-19 2h17m09s [ 1609.788884] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1616.368789] Lustre: lustre-MDT0000-mdc-ffff9a4f184a0000: Connection to lustre-MDT0000 (at 192.168.202.151@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1620.529908] LustreError: 36727:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff9a4f184a0000: can't stat MDS #0: rc = -19 [ 1622.463239] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1628.663265] LustreError: MGC192.168.202.151@tcp: Connection to MGS (at 192.168.202.151@tcp) was lost; in progress operations using this service will fail [ 1628.678986] Lustre: Evicted from MGS (at 192.168.202.151@tcp) after server handle changed from 0xfa67ba322fb59be0 to 0xfa67ba322fb59cc7 [ 1628.691040] Lustre: MGC192.168.202.151@tcp: Connection restored to 192.168.202.151@tcp (at 192.168.202.151@tcp) [ 1629.764439] LustreError: lustre-MDT0000-mdc-ffff9a4f184a0000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1629.798088] Lustre: lustre-MDT0000-mdc-ffff9a4f184a0000: Connection restored to 192.168.202.151@tcp (at 192.168.202.151@tcp) [ 1636.504969] Lustre: DEBUG MARKER: Wait for df (36727) ... [ 1637.761858] Lustre: DEBUG MARKER: done [ 1639.496179] Lustre: Unmounted lustre-client [ 1666.323974] Key type lgssc unregistered [ 1666.572319] LNet: 37745:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1666.576798] LNetError: 37745:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1666.591367] LNet: Removed LNI 192.168.202.51@tcp [ 1667.231318] Key type .llcrypt unregistered [ 1667.232959] Key type ._llcrypt unregistered [ 1696.993464] Key type ._llcrypt registered [ 1696.995710] Key type .llcrypt registered [ 1698.236458] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 02:18:40 (1787120320) [ 1701.329235] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1701.431829] alg: No test for adler32 (adler32-zlib) [ 1702.455687] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [ 1702.773603] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [ 1704.489288] Key type lgssc registered [ 1705.565577] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1753.380581] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1758.541470] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1776.556886] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1779.182773] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 1783.296712] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-08-19 2h20m05s [ 1784.543900] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1790.391843] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1792.203257] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1812.448290] Lustre: 41892:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787120416/real 1787120416] req@00000000253bb6a2 x1873931482962048/t0(0) o101->lustre-MDT0000-mdc-ffff9a4f102d7800@192.168.202.151@tcp:12/10 lens 576/1152 e 0 to 1 dl 1787120436 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1812.467290] Lustre: lustre-MDT0000-mdc-ffff9a4f102d7800: Connection to lustre-MDT0000 (at 192.168.202.151@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1812.486834] Lustre: lustre-MDT0000-mdc-ffff9a4f102d7800: Connection restored to 192.168.202.151@tcp (at 192.168.202.151@tcp) [ 1833.786328] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1836.508365] Lustre: DEBUG MARKER: done [ 1840.917083] Lustre: Unmounted lustre-client [ 1863.733403] Key type lgssc unregistered [ 1864.019153] LNet: 42772:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1864.034584] LNetError: 42772:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1864.053065] LNet: Removed LNI 192.168.202.51@tcp [ 1864.752322] Key type .llcrypt unregistered [ 1864.754402] Key type ._llcrypt unregistered [ 1893.150108] Key type ._llcrypt registered [ 1893.153282] Key type .llcrypt registered [ 1894.440353] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 02:21:56 (1787120516) [ 1895.837084] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1897.599318] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 02:22:00 (1787120520) [ 1901.440038] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1901.488582] alg: No test for adler32 (adler32-zlib) [ 1902.595737] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [ 1902.835117] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [ 1904.513088] Key type lgssc registered [ 1905.656656] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1933.831669] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 02:22:36 (1787120556) [ 1967.969567] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1973.768846] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1992.018390] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1993.460918] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 1996.480235] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1999.236321] Lustre: Unmounted lustre-client [ 2009.743875] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 2059.958090] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2065.792225] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2066.494115] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 2069.947114] Lustre: Unmounted lustre-client [ 2128.050307] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2133.578345] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2134.225720] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 2137.997500] Lustre: Unmounted lustre-client [ 2167.636734] Lustre: DEBUG MARKER: files compared the same [ 2178.844529] Key type lgssc unregistered [ 2179.081381] LNet: 50387:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2179.086298] LNetError: 50387:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2179.106515] LNet: Removed LNI 192.168.202.51@tcp [ 2179.770566] Key type .llcrypt unregistered [ 2179.772370] Key type ._llcrypt unregistered [ 2193.229371] Key type ._llcrypt registered [ 2193.236621] Key type .llcrypt registered [ 2194.551654] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 02:26:57 (1787120817) [ 2196.976670] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2196.990681] alg: No test for adler32 (adler32-zlib) [ 2198.079694] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [ 2198.259141] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [ 2199.921491] Key type lgssc registered [ 2201.401485] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2251.918658] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2256.911445] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2277.423124] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2280.106577] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 2283.773694] Lustre: Unmounted lustre-client [ 2311.769746] Key type lgssc unregistered [ 2311.980861] LNet: 53973:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2311.987246] LNetError: 53973:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2313.064256] LNet: Removed LNI 192.168.202.51@tcp [ 2313.888647] Key type .llcrypt unregistered [ 2313.891380] Key type ._llcrypt unregistered [ 2333.172243] Key type ._llcrypt registered [ 2333.174631] Key type .llcrypt registered [ 2334.585246] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 02:29:17 (1787120957) [ 2337.681907] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2337.737851] alg: No test for adler32 (adler32-zlib) [ 2338.947977] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [ 2339.269941] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [ 2340.984948] Key type lgssc registered [ 2342.614952] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2432.843982] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2469.068870] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2474.205280] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2503.467595] Key type lgssc unregistered [ 2503.815898] LNet: 57472:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2503.823530] LNetError: 57472:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2503.835785] LNet: Removed LNI 192.168.202.51@tcp [ 2504.696621] Key type .llcrypt unregistered [ 2504.700709] Key type ._llcrypt unregistered [ 2523.112416] Key type ._llcrypt registered [ 2523.114440] Key type .llcrypt registered [ 2524.509216] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 02:32:26 (1787121146) [ 2527.667083] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2527.725585] alg: No test for adler32 (adler32-zlib) [ 2528.954899] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [ 2529.169526] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [ 2530.857329] Key type lgssc registered [ 2532.244491] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2623.133063] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 2631.403602] Lustre: Unmounted lustre-client [ 2674.062744] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 02:34:56 (1787121296) [ 2804.541444] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2826.424533] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 2833.559952] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2833.563712] LustreError: 63951:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff9a4f08faa800: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2833.576382] Lustre: 58456:0:(llite_lib.c:4340:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.202.151@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2844.015176] Lustre: Unmounted lustre-client [ 2894.675775] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 02:38:36 (1787121516) [ 2913.727266] Key type lgssc unregistered [ 2913.908377] LNet: 65167:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2913.928071] LNetError: 65167:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2913.940423] LNet: Removed LNI 192.168.202.51@tcp [ 2914.488448] Key type .llcrypt unregistered [ 2914.490171] Key type ._llcrypt unregistered [ 2929.709507] Key type ._llcrypt registered [ 2929.711796] Key type .llcrypt registered [ 2930.317163] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2930.330308] alg: No test for adler32 (adler32-zlib) [ 2931.557367] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [ 2931.944388] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [ 2933.760227] Key type lgssc registered [ 2935.131641] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2994.188661] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3000.559036] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3023.631849] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3025.537462] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 3032.918006] Lustre: Unmounted lustre-client [ 3062.524511] Key type lgssc unregistered [ 3062.850854] LNet: 68183:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3062.859224] LNetError: 68183:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3062.872160] LNet: Removed LNI 192.168.202.51@tcp [ 3063.654730] Key type .llcrypt unregistered [ 3063.658414] Key type ._llcrypt unregistered [ 3077.595722] Key type ._llcrypt registered [ 3077.597173] Key type .llcrypt registered [ 3078.355985] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3078.373707] alg: No test for adler32 (adler32-zlib) [ 3079.474233] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [ 3079.710688] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [ 3081.481451] Key type lgssc registered [ 3083.062963] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3210.090496] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3216.677256] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3238.893232] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3240.559621] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 3243.877648] Lustre: Unmounted lustre-client [ 3283.036798] Key type lgssc unregistered [ 3283.419806] LNet: 71623:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3283.425959] LNetError: 71623:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3283.436805] LNet: Removed LNI 192.168.202.51@tcp [ 3284.640994] Key type .llcrypt unregistered [ 3284.650232] Key type ._llcrypt unregistered [ 3303.989669] Key type ._llcrypt registered [ 3303.995683] Key type .llcrypt registered [ 3305.756963] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 02:45:27 (1787121927) [ 3309.113733] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3309.145321] alg: No test for adler32 (adler32-zlib) [ 3310.195818] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [ 3310.555160] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [ 3312.272222] Key type lgssc registered [ 3314.327898] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3373.624812] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3380.106913] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3403.891167] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3406.707561] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 3412.213141] Lustre: Unmounted lustre-client [ 3412.582958] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 3416.339268] Lustre: Unmounted lustre-client [ 3453.989107] Key type lgssc unregistered [ 3454.237039] LNet: 75312:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3454.255635] LNetError: 75312:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3454.287301] LNet: Removed LNI 192.168.202.51@tcp [ 3455.093987] Key type .llcrypt unregistered [ 3455.096674] Key type ._llcrypt unregistered [ 3470.046862] Key type ._llcrypt registered [ 3470.048873] Key type .llcrypt registered [ 3470.750189] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3470.784691] alg: No test for adler32 (adler32-zlib) [ 3472.125575] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [ 3472.494682] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [ 3474.240306] Key type lgssc registered [ 3475.948630] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3532.437389] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3538.307165] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3561.391984] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3563.100809] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 3570.407436] Lustre: Unmounted lustre-client [ 3602.053666] Key type lgssc unregistered [ 3602.456406] LNet: 78348:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3602.464380] LNetError: 78348:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3602.482270] LNet: Removed LNI 192.168.202.51@tcp [ 3603.084951] Key type .llcrypt unregistered [ 3603.087775] Key type ._llcrypt unregistered [ 3620.188648] Key type ._llcrypt registered [ 3620.193151] Key type .llcrypt registered [ 3621.879947] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 02:50:43 (1787122243) [ 3624.774030] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3625.005469] alg: No test for adler32 (adler32-zlib) [ 3626.321781] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [ 3626.806247] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [ 3628.536626] Key type lgssc registered [ 3630.199154] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3683.888064] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3689.718936] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3708.835928] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3711.516576] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 3724.862666] Lustre: lustre: root_squash is set to 0:0 [ 3724.898515] Lustre: lustre: nosquash_nids is cleared [ 3733.563596] Lustre: lustre: root_squash is set to 500:500 [ 3740.714595] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.202.51@tcp 192.168.0.[2,10]@tcp [ 3748.008982] Lustre: lustre: nosquash_nids set to 192.168.202.51/24@tcp [ 3752.767198] Lustre: Unmounted lustre-client [ 3778.921504] Key type lgssc unregistered [ 3779.176025] LNet: 82298:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3779.182388] LNetError: 82298:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3780.198938] LNet: Removed LNI 192.168.202.51@tcp [ 3780.867766] Key type .llcrypt unregistered [ 3780.870336] Key type ._llcrypt unregistered [ 3795.946799] Key type ._llcrypt registered [ 3795.950253] Key type .llcrypt registered [ 3797.135912] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 02:53:39 (1787122419) [ 3799.988573] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3801.562963] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 02:53:44 (1787122424) [ 3803.934762] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3804.088748] alg: No test for adler32 (adler32-zlib) [ 3805.206510] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [ 3805.527219] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [ 3807.224182] Key type lgssc registered [ 3808.389056] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3856.860763] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3864.150885] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3880.724394] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3882.272169] Lustre: lustre: root_squash is set to 500:500 [ 3882.295524] Lustre: lustre: nosquash_nids set to 192.168.202.51/24@tcp [ 3883.400973] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 3897.881189] Lustre: Unmounted lustre-client [ 3925.872852] Key type lgssc unregistered [ 3926.276832] LNet: 86257:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3926.286498] LNetError: 86257:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3926.304734] LNet: Removed LNI 192.168.202.51@tcp [ 3927.293732] Key type .llcrypt unregistered [ 3927.296579] Key type ._llcrypt unregistered [ 3941.245331] Key type ._llcrypt registered [ 3941.247334] Key type .llcrypt registered [ 3942.641624] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 3944.124681] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 02:56:07 (1787122567) [ 3963.669058] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3963.713727] alg: No test for adler32 (adler32-zlib) [ 3964.983316] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [ 3965.266705] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [ 3966.920228] Key type lgssc registered [ 3968.914486] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4056.857952] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4061.949890] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4086.952940] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4163.584605] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4169.387956] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4195.728873] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4205.968352] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 4248.573466] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9a4f118dd800.ost_server_uuid 50 [ 4250.494696] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9a4f118dd800.ost_server_uuid in IDLE state after 0 sec [ 4251.092477] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 4254.681320] Lustre: Unmounted lustre-client [ 4256.812782] Lustre: Unmounted lustre-client [ 4301.053206] Key type lgssc unregistered [ 4301.331691] LNet: 93949:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4301.339558] LNetError: 93949:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4301.358629] LNet: Removed LNI 192.168.202.51@tcp [ 4302.183600] Key type .llcrypt unregistered [ 4302.190116] Key type ._llcrypt unregistered [ 4338.453407] Key type ._llcrypt registered [ 4338.457885] Key type .llcrypt registered [ 4340.229240] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 03:02:42 (1787122962) [ 4363.193509] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4363.249593] alg: No test for adler32 (adler32-zlib) [ 4364.398033] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [ 4364.762556] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [ 4366.584194] Key type lgssc registered [ 4368.088320] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4459.365301] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4465.581695] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4496.159342] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4497.765801] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 4519.392213] Lustre: 95311:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787123127/real 1787123127] req@0000000073143292 x1873934274270336/t0(0) o400->lustre-OST0000-osc-ffff9a4f06d88000@192.168.202.151@tcp:28/4 lens 224/224 e 0 to 1 dl 1787123143 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4519.418827] Lustre: lustre-OST0000-osc-ffff9a4f06d88000: Connection to lustre-OST0000 (at 192.168.202.151@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4525.536181] Lustre: 95311:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787123132/real 1787123132] req@00000000ea91dc92 x1873934274270848/t0(0) o400->lustre-OST0000-osc-ffff9a4f06d88000@192.168.202.151@tcp:28/4 lens 224/224 e 0 to 1 dl 1787123148 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4526.082601] Lustre: lustre-OST0000-osc-ffff9a4f06d88000: Connection restored to 192.168.202.151@tcp (at 192.168.202.151@tcp) [ 4529.632178] Lustre: 95311:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787123137/real 1787123137] req@00000000abc7deb8 x1873934274271360/t0(0) o400->lustre-OST0000-osc-ffff9a4f06d88000@192.168.202.151@tcp:28/4 lens 224/224 e 0 to 1 dl 1787123153 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4529.658093] Lustre: lustre-MDT0000-mdc-ffff9a4f06d88000: Connection to lustre-MDT0000 (at 192.168.202.151@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4535.776760] Lustre: 95311:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787123142/real 1787123142] req@00000000d329c5e9 x1873934274271872/t0(0) o400->lustre-OST0000-osc-ffff9a4f06d88000@192.168.202.151@tcp:28/4 lens 224/224 e 0 to 1 dl 1787123158 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4545.505404] Lustre: 98487:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787123153/real 1787123153] req@00000000e3cd8a2e x1873934274273408/t0(0) o101->MGC192.168.202.151@tcp@192.168.202.151@tcp:26/25 lens 328/344 e 0 to 1 dl 1787123169 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4545.566829] LustreError: MGC192.168.202.151@tcp: Connection to MGS (at 192.168.202.151@tcp) was lost; in progress operations using this service will fail [ 4545.624788] Lustre: 98487:0:(mgc_request.c:1899:mgc_process_log()) MGC192.168.202.151@tcp: IR log lustre-cliir failed, not fatal: rc = -5 [ 4555.786941] Lustre: Evicted from MGS (at 192.168.202.151@tcp) after server handle changed from 0x97f61c13f10ec5c7 to 0x97f61c13f10ec898 [ 4555.831083] Lustre: MGC192.168.202.151@tcp: Connection restored to 192.168.202.151@tcp (at 192.168.202.151@tcp) [ 4560.220409] Lustre: lustre-MDT0000-mdc-ffff9a4f06d88000: Connection restored to 192.168.202.151@tcp (at 192.168.202.151@tcp) [ 4567.850925] Lustre: Unmounted lustre-client [ 4597.438394] Key type lgssc unregistered [ 4597.691876] LNet: 99620:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4597.698364] LNetError: 99620:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4597.721968] LNet: Removed LNI 192.168.202.51@tcp [ 4598.499634] Key type .llcrypt unregistered [ 4598.504388] Key type ._llcrypt unregistered [ 4616.885672] Key type ._llcrypt registered [ 4616.891808] Key type .llcrypt registered [ 4618.206598] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 03:07:20 (1787123240) [ 4633.984096] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4634.041343] alg: No test for adler32 (adler32-zlib) [ 4635.382746] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [ 4635.735415] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [ 4637.392268] Key type lgssc registered [ 4638.360815] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4680.322698] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4789.620172] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4795.820212] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4821.777262] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4824.387384] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 4826.652633] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 4828.622500] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 03:10:51 (1787123451) [ 5060.071061] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5070.434645] Lustre: Unmounted lustre-client [ 5203.491499] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5211.723737] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5238.593263] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5282.849155] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 03:18:25 (1787123905) [ 5380.699506] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5387.909759] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5411.213916] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5412.069210] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 5416.729158] Lustre: Unmounted lustre-client [ 5453.009359] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 03:21:15 (1787124075) [ 5551.383716] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5557.791805] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5583.672210] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5584.496887] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 5590.201139] Lustre: Unmounted lustre-client [ 5619.995054] Key type lgssc unregistered [ 5620.245171] LNet: 122644:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5620.251375] LNetError: 122644:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5620.263444] LNet: Removed LNI 192.168.202.51@tcp [ 5621.011988] Key type .llcrypt unregistered [ 5621.018127] Key type ._llcrypt unregistered [ 5638.146917] Key type ._llcrypt registered [ 5638.151062] Key type .llcrypt registered [ 5639.732216] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 03:24:22 (1787124262) [ 5642.412780] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5642.451979] alg: No test for adler32 (adler32-zlib) [ 5643.552974] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [ 5644.045956] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [ 5645.840772] Key type lgssc registered [ 5647.451389] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5705.622527] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5712.482222] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5733.276051] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5735.115807] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 5752.043087] Lustre: Unmounted lustre-client [ 5789.851985] Key type lgssc unregistered [ 5790.353968] LNet: 126240:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5790.377633] LNetError: 126240:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5790.396494] LNet: Removed LNI 192.168.202.51@tcp [ 5791.461277] Key type .llcrypt unregistered [ 5791.465866] Key type ._llcrypt unregistered [ 5810.113817] Key type ._llcrypt registered [ 5810.115732] Key type .llcrypt registered [ 5811.071260] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 03:27:14 (1787124434) [ 5813.812807] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5813.857215] alg: No test for adler32 (adler32-zlib) [ 5814.862761] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [ 5814.998418] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [ 5816.608182] Key type lgssc registered [ 5817.746219] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5875.015194] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5881.665635] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5907.000292] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5918.272709] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 5934.377440] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff9a4f10318800.ost_server_uuid 50 [ 5944.800395] Lustre: 127201:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787124552/real 1787124552] req@000000000eff4060 x1873935795755264/t0(0) o400->lustre-OST0000-osc-ffff9a4f10318800@192.168.202.151@tcp:28/4 lens 224/224 e 0 to 1 dl 1787124568 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5944.832596] Lustre: lustre-OST0000-osc-ffff9a4f10318800: Connection to lustre-OST0000 (at 192.168.202.151@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5947.238283] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 5949.728174] Lustre: 127200:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787124557/real 1787124557] req@00000000140c2288 x1873935795755776/t0(0) o400->lustre-OST0000-osc-ffff9a4f10318800@192.168.202.151@tcp:28/4 lens 224/224 e 0 to 1 dl 1787124573 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5954.017917] Lustre: 127201:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787124562/real 1787124562] req@000000004db9bc93 x1873935795756288/t0(0) o400->lustre-OST0000-osc-ffff9a4f10318800@192.168.202.151@tcp:28/4 lens 224/224 e 0 to 1 dl 1787124578 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5959.183873] Lustre: 127201:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787124567/real 1787124567] req@000000009f8b4d28 x1873935795756800/t0(0) o400->lustre-OST0000-osc-ffff9a4f10318800@192.168.202.151@tcp:28/4 lens 224/224 e 0 to 1 dl 1787124583 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5960.443498] Lustre: Unmounted lustre-client [ 5985.422089] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 03:30:07 (1787124607) [ 6024.584389] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6033.456356] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6059.827119] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6086.372221] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6087.322731] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 6092.803423] Lustre: lustre-OST0000-osc-ffff9a4f0443f800: Connection to lustre-OST0000 (at 192.168.202.151@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6121.275159] Lustre: Unmounted lustre-client [ 6176.247509] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 03:33:18 (1787124798) [ 6216.002569] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6222.458751] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6247.356428] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6271.161230] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6271.796222] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 6277.114971] Lustre: lustre-OST0000-osc-ffff9a4f04abb800: Connection to lustre-OST0000 (at 192.168.202.151@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6303.302132] Lustre: Unmounted lustre-client [ 6355.766528] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 03:36:18 (1787124978) [ 6448.952167] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6456.344333] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6484.452985] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6567.983931] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6575.193381] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6604.268351] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6639.398680] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 6687.057255] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6689.264452] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6691.892909] Lustre: Unmounted lustre-client [ 6728.202478] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 03:42:30 (1787125350) [ 6765.965819] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6773.077652] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6796.112669] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6832.759900] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6864.239989] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 6909.688784] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6911.473526] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6926.276632] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6936.595752] Lustre: Unmounted lustre-client [ 7007.401143] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 03:47:09 (1787125629) [ 7049.184988] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7056.212700] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7085.687820] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7086.560624] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 7115.556176] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7117.593471] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7136.438557] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9a4f048f9800.ost_server_uuid 50 [ 7139.238778] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9a4f048f9800.ost_server_uuid in IDLE state after 0 sec [ 7143.416142] Lustre: Unmounted lustre-client [ 7143.733947] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 7143.747848] Lustre: Skipped 3 previous similar messages [ 7143.811570] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 7145.004507] LustreError: 149745:0:(sec.c:403:import_sec_validate_get()) import 0000000003ab5519 (NEW) with no sec [ 7216.180885] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 03:50:38 (1787125838) [ 7258.304114] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7264.509908] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7289.259327] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7317.179542] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7318.159492] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 7318.392831] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 7333.449229] Lustre: Unmounted lustre-client [ 7333.451021] Lustre: Skipped 1 previous similar message [ 7384.351974] Key type lgssc unregistered [ 7384.753553] LNet: 153175:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7384.762841] LNetError: 153175:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7385.832971] LNet: Removed LNI 192.168.202.51@tcp [ 7387.419950] Key type .llcrypt unregistered [ 7387.421773] Key type ._llcrypt unregistered [ 7406.661574] Key type ._llcrypt registered [ 7406.663564] Key type .llcrypt registered [ 7408.030433] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 03:53:50 (1787126030) [ 7408.884849] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7408.907945] alg: No test for adler32 (adler32-zlib) [ 7410.059957] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [ 7410.373369] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [ 7411.992466] Key type lgssc registered [ 7413.856083] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7474.958841] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7480.778320] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7506.881752] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7535.475392] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7537.130795] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7538.340866] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 7544.058911] LustreError: lustre-MDT0001-mdc-ffff9a4f08fad000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 7544.096979] Lustre: lustre-MDT0001-mdc-ffff9a4f08fad000: Connection restored to 192.168.202.151@tcp (at 192.168.202.151@tcp) [ 7552.610147] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7554.837865] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7577.613864] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7588.031599] Lustre: Unmounted lustre-client [ 7620.997531] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 03:57:23 (1787126243) [ 7743.539510] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7750.324319] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7778.268540] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7779.111396] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 7810.891987] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7812.809996] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7826.565342] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7836.971699] Lustre: Unmounted lustre-client [ 7875.872279] Key type lgssc unregistered [ 7876.186883] LNet: 162801:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7876.194716] LNetError: 162801:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7876.228892] LNet: Removed LNI 192.168.202.51@tcp [ 7877.185268] Key type .llcrypt unregistered [ 7877.186851] Key type ._llcrypt unregistered [ 7920.908801] Key type ._llcrypt registered [ 7920.917667] Key type .llcrypt registered [ 7923.142882] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 04:02:24 (1787126544) [ 7926.229090] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7926.294902] alg: No test for adler32 (adler32-zlib) [ 7927.762692] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [ 7928.009230] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [ 7929.626097] Key type lgssc registered [ 7930.787359] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7998.660454] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8005.669477] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8031.796042] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8035.218518] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 8091.053320] Lustre: Unmounted lustre-client [ 8136.745765] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8137.447549] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 8174.088327] Lustre: Unmounted lustre-client [ 8206.752696] Key type lgssc unregistered [ 8207.021616] LNet: 167258:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8207.039610] LNetError: 167258:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8207.064218] LNet: Removed LNI 192.168.202.51@tcp [ 8207.909525] Key type .llcrypt unregistered [ 8207.914070] Key type ._llcrypt unregistered [ 8227.081167] Key type ._llcrypt registered [ 8227.083619] Key type .llcrypt registered [ 8228.353528] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 04:07:30 (1787126850) [ 8231.305425] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8231.326502] alg: No test for adler32 (adler32-zlib) [ 8232.513453] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [ 8232.823514] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [ 8234.552515] Key type lgssc registered [ 8236.204362] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8296.324868] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8304.238676] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8330.266408] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8336.466869] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 8359.394744] Lustre: Unmounted lustre-client [ 8389.904492] Key type lgssc unregistered [ 8390.352770] LNet: 171018:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8390.362302] LNetError: 171018:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8390.389461] LNet: Removed LNI 192.168.202.51@tcp [ 8391.468498] Key type .llcrypt unregistered [ 8391.474840] Key type ._llcrypt unregistered [ 8406.270465] Key type ._llcrypt registered [ 8406.274981] Key type .llcrypt registered [ 8406.924731] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8406.938244] alg: No test for adler32 (adler32-zlib) [ 8408.057650] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [ 8408.274785] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [ 8409.970752] Key type lgssc registered [ 8411.377799] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8466.669138] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8472.881156] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8496.805760] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8498.740095] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 8507.187621] Lustre: Unmounted lustre-client [ 8540.727726] Key type lgssc unregistered [ 8541.036465] LNet: 174089:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8541.062059] LNetError: 174089:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8541.101868] LNet: Removed LNI 192.168.202.51@tcp [ 8541.986377] Key type .llcrypt unregistered [ 8541.988467] Key type ._llcrypt unregistered [ 8558.556484] Key type ._llcrypt registered [ 8558.558269] Key type .llcrypt registered [ 8560.087834] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 04:13:02 (1787127182) [ 8563.143989] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8563.221710] alg: No test for adler32 (adler32-zlib) [ 8564.593479] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [ 8565.026437] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [ 8566.754165] Key type lgssc registered [ 8568.381201] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8630.562526] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8637.800777] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8662.344348] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8674.361149] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 8695.129957] Lustre: Unmounted lustre-client [ 8722.384885] Key type lgssc unregistered [ 8722.779557] LNet: 177846:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8722.789695] LNetError: 177846:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8722.821467] LNet: Removed LNI 192.168.202.51@tcp [ 8723.525406] Key type .llcrypt unregistered [ 8723.529095] Key type ._llcrypt unregistered [ 8737.662698] Key type ._llcrypt registered [ 8737.665482] Key type .llcrypt registered [ 8738.218534] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8738.241113] alg: No test for adler32 (adler32-zlib) [ 8739.401496] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [ 8739.701975] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [ 8741.368256] Key type lgssc registered [ 8742.731937] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8794.693813] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8801.239095] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8825.660156] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8827.561458] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 8835.283895] Lustre: Unmounted lustre-client [ 8871.897504] Key type lgssc unregistered [ 8872.126170] LNet: 180913:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8872.131369] LNetError: 180913:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8872.161959] LNet: Removed LNI 192.168.202.51@tcp [ 8872.947248] Key type .llcrypt unregistered [ 8872.950566] Key type ._llcrypt unregistered [ 8893.795398] Key type ._llcrypt registered [ 8893.799694] Key type .llcrypt registered [ 8894.868306] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 04:18:37 (1787127517) [ 8918.853198] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8918.873621] alg: No test for adler32 (adler32-zlib) [ 8920.074902] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [ 8920.301656] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [ 8921.984216] Key type lgssc registered [ 8923.269469] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9008.800284] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9014.173674] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9037.528181] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9081.598873] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 04:21:44 (1787127704) [ 9114.941627] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9120.433386] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9139.727879] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9142.368188] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 9146.803923] Lustre: Unmounted lustre-client [ 9183.936282] Key type lgssc unregistered [ 9184.298938] LNet: 188016:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9184.317516] LNetError: 188016:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9184.340397] LNet: Removed LNI 192.168.202.51@tcp [ 9185.164491] Key type .llcrypt unregistered [ 9185.167160] Key type ._llcrypt unregistered [ 9206.273868] Key type ._llcrypt registered [ 9206.278121] Key type .llcrypt registered [ 9207.792136] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 9209.526401] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 04:23:52 (1787127832) [ 9228.512438] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9228.529225] alg: No test for adler32 (adler32-zlib) [ 9229.794552] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [ 9230.082381] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [ 9231.851410] Key type lgssc registered [ 9233.300490] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9332.716758] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9338.423339] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9361.057752] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9381.303319] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9383.921661] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [ 9410.241793] Lustre: Unmounted lustre-client [ 9541.697751] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9547.693487] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9571.236393] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9620.050649] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 04:30:42 (1787128242) [ 9621.654584] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 9623.527772] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 04:30:45 (1787128245) [ 9723.414313] Key type lgssc unregistered [ 9723.755427] LNet: 199460:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9723.767710] LNetError: 199460:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9723.786634] LNet: Removed LNI 192.168.202.51@tcp [ 9724.817340] Key type .llcrypt unregistered [ 9724.823687] Key type ._llcrypt unregistered [ 9745.401091] Key type ._llcrypt registered [ 9745.407397] Key type .llcrypt registered [ 9746.991472] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 04:32:49 (1787128369) [ 9794.474826] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9794.487040] alg: No test for adler32 (adler32-zlib) [ 9795.646645] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [ 9795.963748] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [ 9797.712191] Key type lgssc registered [ 9799.149410] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9853.878754] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9860.074968] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9900.032168] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9931.045521] Key type lgssc unregistered [ 9931.319921] LNet: 203203:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9931.325675] LNetError: 203203:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9931.355179] LNet: Removed LNI 192.168.202.51@tcp [ 9932.500683] Key type .llcrypt unregistered [ 9932.508155] Key type ._llcrypt unregistered [ 9954.045501] Key type ._llcrypt registered [ 9954.049742] Key type .llcrypt registered [ 9955.446537] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 04:36:17 (1787128577) [ 9958.092558] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9958.224513] alg: No test for adler32 (adler32-zlib) [ 9959.362211] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [ 9959.628417] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [ 9961.368214] Key type lgssc registered [ 9963.115774] Lustre: Echo OBD driver; http://www.lustre.org/ [10031.813620] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10038.655854] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10062.237575] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10069.077559] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [10079.231128] Lustre: lustre-MDT0000-mdc-ffff9a4f20644000: Connection to lustre-MDT0000 (at 192.168.202.151@tcp) was lost; in progress operations using this service will wait for recovery to complete [10089.457719] Lustre: lustre-MDT0001-mdc-ffff9a4f20644000: Connection to lustre-MDT0001 (at 192.168.202.151@tcp) was lost; in progress operations using this service will wait for recovery to complete [10100.708309] Lustre: 204197:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787128708/real 1787128708] req@000000004064e94e x1873940141130368/t0(0) o400->MGC192.168.202.151@tcp@192.168.202.151@tcp:26/25 lens 224/224 e 0 to 1 dl 1787128724 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10100.764465] LustreError: MGC192.168.202.151@tcp: Connection to MGS (at 192.168.202.151@tcp) was lost; in progress operations using this service will fail [10125.288064] Lustre: Evicted from MGS (at 192.168.202.151@tcp) after server handle changed from 0x6133ac142ded1273 to 0x6133ac142ded2f5a [10125.298222] Lustre: MGC192.168.202.151@tcp: Connection restored to 192.168.202.151@tcp (at 192.168.202.151@tcp) [10134.063148] LustreError: lustre-MDT0000-mdc-ffff9a4f20644000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [10134.175474] Lustre: lustre-MDT0000-mdc-ffff9a4f20644000: Connection restored to 192.168.202.151@tcp (at 192.168.202.151@tcp) [10141.815920] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10143.789964] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10150.316584] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10151.784991] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [10154.816427] Lustre: Unmounted lustre-client [10192.704724] Key type lgssc unregistered [10193.057795] LNet: 208156:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10193.070383] LNetError: 208156:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10193.116051] LNet: Removed LNI 192.168.202.51@tcp [10193.823345] Key type .llcrypt unregistered [10193.825181] Key type ._llcrypt unregistered [10212.209522] Key type ._llcrypt registered [10212.215590] Key type .llcrypt registered [10213.641662] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 04:40:36 (1787128836) [10216.984603] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10217.017220] alg: No test for adler32 (adler32-zlib) [10218.093581] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [10218.315793] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [10220.000196] Key type lgssc registered [10221.232432] Lustre: Echo OBD driver; http://www.lustre.org/ [10278.900707] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10285.238333] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10357.101903] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10363.623613] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10403.457933] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10427.832308] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10478.470765] Key type lgssc unregistered [10478.730938] LNet: 213458:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10478.738602] LNetError: 213458:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10478.753927] LNet: Removed LNI 192.168.202.51@tcp [10479.384841] Key type .llcrypt unregistered [10479.387437] Key type ._llcrypt unregistered [10533.911777] Key type ._llcrypt registered [10533.915630] Key type .llcrypt registered [10535.487412] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 04:45:57 (1787129157) [10580.822193] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10580.838378] alg: No test for adler32 (adler32-zlib) [10581.872677] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [10582.041367] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [10583.728197] Key type lgssc registered [10585.775295] Lustre: Echo OBD driver; http://www.lustre.org/ [10680.409899] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10685.890642] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10715.976161] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10761.226115] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 04:49:43 (1787129383) [10769.373809] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 04:49:51 (1787129391) [10811.557859] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10819.041258] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10845.368068] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10851.389460] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [10858.101204] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [10861.467577] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [10863.417341] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [10865.722098] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [10871.814264] Lustre: lustre-MDT0000-mdc-ffff9a4f13750000: Connection to lustre-MDT0000 (at 192.168.202.151@tcp) was lost; in progress operations using this service will wait for recovery to complete [10887.136393] Lustre: 215356:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787129495/real 1787129495] req@00000000b52bed1d x1873940794321152/t0(0) o400->MGC192.168.202.151@tcp@192.168.202.151@tcp:26/25 lens 224/224 e 0 to 1 dl 1787129511 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10887.187539] LustreError: MGC192.168.202.151@tcp: Connection to MGS (at 192.168.202.151@tcp) was lost; in progress operations using this service will fail [10898.493476] Lustre: Evicted from MGS (at 192.168.202.151@tcp) after server handle changed from 0xa019497260974cc2 to 0xa019497260974f3f [10898.511649] Lustre: MGC192.168.202.151@tcp: Connection restored to 192.168.202.151@tcp (at 192.168.202.151@tcp) [10902.346902] Lustre: lustre-MDT0000-mdc-ffff9a4f13750000: Connection restored to 192.168.202.151@tcp (at 192.168.202.151@tcp) [10914.188806] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10916.524225] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10920.732322] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [10923.040623] Lustre: lustre-MDT0000-mdc-ffff9a4f13750000: Connection to lustre-MDT0000 (at 192.168.202.151@tcp) was lost; in progress operations using this service will wait for recovery to complete [10944.480305] Lustre: 215357:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787129552/real 1787129552] req@00000000a61d17ae x1873940794330880/t0(0) o400->MGC192.168.202.151@tcp@192.168.202.151@tcp:26/25 lens 224/224 e 0 to 1 dl 1787129568 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10944.509141] LustreError: MGC192.168.202.151@tcp: Connection to MGS (at 192.168.202.151@tcp) was lost; in progress operations using this service will fail [10944.582819] Lustre: Evicted from MGS (at 192.168.202.151@tcp) after server handle changed from 0xa019497260974f3f to 0xa01949726097522c [10944.596835] Lustre: MGC192.168.202.151@tcp: Connection restored to 192.168.202.151@tcp (at 192.168.202.151@tcp) [10949.952846] Lustre: lustre-MDT0000-mdc-ffff9a4f13750000: Connection restored to 192.168.202.151@tcp (at 192.168.202.151@tcp) [10961.141037] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10963.143855] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10968.026714] Lustre: Unmounted lustre-client [11052.229402] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11059.467212] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11088.364689] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11094.633885] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [11098.920810] Lustre: Unmounted lustre-client [11128.865732] Key type lgssc unregistered [11129.299585] LNet: 225953:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11129.308642] LNetError: 225953:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11129.342297] LNet: Removed LNI 192.168.202.51@tcp [11130.209476] Key type .llcrypt unregistered [11130.216274] Key type ._llcrypt unregistered [11149.797787] Key type ._llcrypt registered [11149.800743] Key type .llcrypt registered [11151.494949] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 04:56:13 (1787129773) [11156.756656] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11156.777931] alg: No test for adler32 (adler32-zlib) [11157.993621] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [11158.336929] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [11160.056190] Key type lgssc registered [11161.238496] Lustre: Echo OBD driver; http://www.lustre.org/ [11222.298165] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11228.997342] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11255.927704] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11262.620819] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [11270.250534] Lustre: Unmounted lustre-client [11348.812467] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11354.842239] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11378.395206] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11389.473603] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [11400.122139] Lustre: Unmounted lustre-client [11480.970492] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11487.949769] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11513.304515] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11519.553733] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [11522.784642] Lustre: Unmounted lustre-client [11553.393829] Key type lgssc unregistered [11553.780833] LNet: 233029:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11553.787121] LNetError: 233029:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11553.809143] LNet: Removed LNI 192.168.202.51@tcp [11554.790934] Key type .llcrypt unregistered [11554.795034] Key type ._llcrypt unregistered [11576.907917] Key type ._llcrypt registered [11576.912183] Key type .llcrypt registered [11578.661914] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 05:03:20 (1787130200) [11582.913839] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11582.927596] alg: No test for adler32 (adler32-zlib) [11584.181777] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [11584.553988] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [11586.312503] Key type lgssc registered [11587.838418] Lustre: Echo OBD driver; http://www.lustre.org/ [11638.257992] Key type lgssc unregistered [11638.588606] LNet: 235357:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11638.594699] LNetError: 235357:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11638.614660] LNet: Removed LNI 192.168.202.51@tcp [11639.700442] Key type .llcrypt unregistered [11639.703688] Key type ._llcrypt unregistered [11676.991261] Key type ._llcrypt registered [11676.994710] Key type .llcrypt registered [11677.870805] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11677.878585] alg: No test for adler32 (adler32-zlib) [11678.872585] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [11679.358558] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [11681.240567] Key type lgssc registered [11683.450679] Lustre: Echo OBD driver; http://www.lustre.org/ [11781.258718] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11788.243446] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11816.004273] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11859.929118] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [11861.663449] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 05:08:04 (1787130484) [11899.908187] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11906.121785] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11929.031692] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11957.226397] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11958.843414] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [11963.909934] Lustre: lustre-OST0001-osc-ffff9a4f10262000: Connection to lustre-OST0001 (at 192.168.202.151@tcp) was lost; in progress operations using this service will wait for recovery to complete [11973.062305] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11983.423986] Lustre: Unmounted lustre-client [12029.481145] Key type lgssc unregistered [12029.871395] LNet: 242485:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12029.884531] LNetError: 242485:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12029.908519] LNet: Removed LNI 192.168.202.51@tcp [12030.908885] Key type .llcrypt unregistered [12030.916912] Key type ._llcrypt unregistered [12066.269498] Key type ._llcrypt registered [12066.271220] Key type .llcrypt registered [12067.855453] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 05:11:30 (1787130690) [12082.909135] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12082.993675] alg: No test for adler32 (adler32-zlib) [12084.298898] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [12084.700847] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [12086.569580] Key type lgssc registered [12087.898388] Lustre: Echo OBD driver; http://www.lustre.org/ [12149.206587] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12154.504497] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12179.529348] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 05:13:21 (1787130801) [12217.542975] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12224.531365] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12248.742520] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12250.360756] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [12259.228550] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [12270.633566] Lustre: Unmounted lustre-client [12382.786263] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12390.304423] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12447.471772] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12448.009952] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [12451.355625] Lustre: Unmounted lustre-client [12480.476829] Key type lgssc unregistered [12480.881451] LNet: 250191:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12480.889836] LNetError: 250191:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12481.968254] LNet: Removed LNI 192.168.202.51@tcp [12482.745457] Key type .llcrypt unregistered [12482.754953] Key type ._llcrypt unregistered [12519.568516] Key type ._llcrypt registered [12519.570062] Key type .llcrypt registered [12520.349495] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12520.372443] alg: No test for adler32 (adler32-zlib) [12521.524721] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [12521.877706] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [12523.592291] Key type lgssc registered [12525.470582] Lustre: Echo OBD driver; http://www.lustre.org/ [12620.476813] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12627.475446] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12654.866388] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12701.381663] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [12703.090283] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 05:22:05 (1787131325) [12740.080388] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12746.643767] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12771.818264] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12777.814626] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [12781.569484] Lustre: Unmounted lustre-client [12817.308704] Key type lgssc unregistered [12817.588165] LNet: 256842:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12817.593346] LNetError: 256842:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12817.609791] LNet: Removed LNI 192.168.202.51@tcp [12818.069882] Key type .llcrypt unregistered [12818.071389] Key type ._llcrypt unregistered [12835.798084] Key type ._llcrypt registered [12835.802505] Key type .llcrypt registered [12837.283245] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [12839.410828] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 05:24:21 (1787131461) [12847.998615] Key type .llcrypt unregistered [12848.000079] Key type ._llcrypt unregistered [12858.069777] Key type ._llcrypt registered [12858.078720] Key type .llcrypt registered [12858.345114] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12858.356237] alg: No test for adler32 (adler32-zlib) [12859.432786] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [12859.669459] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [12861.329854] Key type lgssc registered [12862.294549] Lustre: Echo OBD driver; http://www.lustre.org/ [12926.321292] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12943.742854] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [12946.544211] Lustre: Unmounted lustre-client [12966.841370] Key type lgssc unregistered [12967.149039] LNet: 260301:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12967.158090] LNetError: 260301:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12967.177759] LNet: Removed LNI 192.168.202.51@tcp [12967.890411] Key type .llcrypt unregistered [12967.902196] Key type ._llcrypt unregistered [12983.991570] Key type ._llcrypt registered [12983.995668] Key type .llcrypt registered [12985.572328] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 05:26:47 (1787131607) [12987.994593] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12988.166569] alg: No test for adler32 (adler32-zlib) [12989.594996] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [12989.971752] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [12991.776288] Key type lgssc registered [12993.366247] Lustre: Echo OBD driver; http://www.lustre.org/ [13084.275244] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13127.748828] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13135.125316] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13136.842856] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [13139.125560] Lustre: Unmounted lustre-client [13159.988785] Key type lgssc unregistered [13160.400726] LNet: 263845:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13160.430284] LNetError: 263845:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13160.456887] LNet: Removed LNI 192.168.202.51@tcp [13161.686901] Key type .llcrypt unregistered [13161.693076] Key type ._llcrypt unregistered [13185.589688] Key type ._llcrypt registered [13185.591716] Key type .llcrypt registered [13187.240307] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 05:30:09 (1787131809) [13190.207639] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13190.324733] alg: No test for adler32 (adler32-zlib) [13191.757428] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [13192.004182] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [13193.856207] Key type lgssc registered [13195.394261] Lustre: Echo OBD driver; http://www.lustre.org/ [13247.244617] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13253.739700] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13276.099494] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13278.040444] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [13283.310626] Lustre: lustre-MDT0000-mdc-ffff9a4f04684800: Connection to lustre-MDT0000 (at 192.168.202.151@tcp) was lost; in progress operations using this service will wait for recovery to complete [13288.782379] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [13288.814732] LustreError: 267002:0:(file.c:6156:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [13289.245668] LustreError: MGC192.168.202.151@tcp: Connection to MGS (at 192.168.202.151@tcp) was lost; in progress operations using this service will fail [13289.335856] Lustre: Unmounted lustre-client [13311.039709] Key type lgssc unregistered [13311.389752] LNet: 267419:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13311.399611] LNetError: 267419:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13311.415335] LNet: Removed LNI 192.168.202.51@tcp [13312.339783] Key type .llcrypt unregistered [13312.341400] Key type ._llcrypt unregistered [13331.122422] Key type ._llcrypt registered [13331.129627] Key type .llcrypt registered [13332.583026] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 05:32:34 (1787131954) [13335.219625] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13335.294974] alg: No test for adler32 (adler32-zlib) [13336.464794] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [13336.725609] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [13338.464172] Key type lgssc registered [13339.908469] Lustre: Echo OBD driver; http://www.lustre.org/ [13397.116616] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13402.889373] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13429.887144] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13436.993469] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [13442.028322] Lustre: lustre-MDT0001-mdc-ffff9a4f049c0000: Connection to lustre-MDT0001 (at 192.168.202.151@tcp) was lost; in progress operations using this service will wait for recovery to complete [13448.507969] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [13452.341810] Lustre: Unmounted lustre-client [13476.613413] Key type lgssc unregistered [13476.892973] LNet: 271005:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13476.910787] LNetError: 271005:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13476.938874] LNet: Removed LNI 192.168.202.51@tcp [13477.674491] Key type .llcrypt unregistered [13477.678701] Key type ._llcrypt unregistered [13494.470201] Key type ._llcrypt registered [13494.475987] Key type .llcrypt registered [13496.393657] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 05:35:18 (1787132118) [13517.622434] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13517.640040] alg: No test for adler32 (adler32-zlib) [13518.734232] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [13519.032126] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [13520.792946] Key type lgssc registered [13522.668464] Lustre: Echo OBD driver; http://www.lustre.org/ [13645.635568] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13647.362173] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [13693.946230] Lustre: lustre-MDT0001-mdc-ffff9a4f08fa8000: Connection to lustre-MDT0001 (at 192.168.202.151@tcp) was lost; in progress operations using this service will wait for recovery to complete [13704.326082] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [13714.681114] Lustre: Unmounted lustre-client [13741.977617] Key type lgssc unregistered [13742.197468] LNet: 276795:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13742.209169] LNetError: 276795:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13742.229823] LNet: Removed LNI 192.168.202.51@tcp [13742.831285] Key type .llcrypt unregistered [13742.833116] Key type ._llcrypt unregistered [13757.274964] Key type ._llcrypt registered [13757.280133] Key type .llcrypt registered [13758.569982] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 05:39:41 (1787132381) [13760.020783] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [13762.001629] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 05:39:44 (1787132384) [13763.668880] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [13765.292703] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 05:39:48 (1787132388) [13767.021608] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [13769.022942] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 05:39:51 (1787132391) [13770.378750] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [13772.172492] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 05:39:54 (1787132394) [13774.139947] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [13775.966737] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 05:39:58 (1787132398) [13784.827463] Key type .llcrypt unregistered [13784.828639] Key type ._llcrypt unregistered [13793.710581] Key type ._llcrypt registered [13793.723682] Key type .llcrypt registered [13794.102690] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13794.122462] alg: No test for adler32 (adler32-zlib) [13795.270449] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [13795.535977] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [13797.264263] Key type lgssc registered [13798.415393] Lustre: Echo OBD driver; http://www.lustre.org/ [13885.003907] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13890.959777] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13917.975808] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13919.546312] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [13923.428488] Lustre: Unmounted lustre-client [13962.393216] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 05:43:04 (1787132584) [13999.273777] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14005.537987] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14040.594523] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14046.767240] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [14048.342187] Lustre: Unmounted lustre-client [14091.850911] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 05:45:14 (1787132714) [14097.632850] LNet: 1 local NIs in recovery (showing 1): 192.168.202.51@tcp [14750.251931] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14756.877795] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14795.638195] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14910.677832] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 05:58:53 (1787133533) [14924.466492] Key type lgssc unregistered [14924.833504] LNet: 290333:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14924.855128] LNetError: 290333:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14924.869095] LNet: Removed LNI 192.168.202.51@tcp [14925.684654] Key type .llcrypt unregistered [14925.687511] Key type ._llcrypt unregistered [15046.096876] Key type ._llcrypt registered [15046.103528] Key type .llcrypt registered [15046.763887] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15046.774759] alg: No test for adler32 (adler32-zlib) [15047.756689] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [15047.912868] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [15049.520227] Key type lgssc registered [15050.843501] Lustre: Echo OBD driver; http://www.lustre.org/ [15158.538731] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15163.756405] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15192.205395] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15226.636571] Key type lgssc unregistered [15226.912757] LNet: 294047:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15226.917351] LNetError: 294047:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15226.933413] LNet: Removed LNI 192.168.202.51@tcp [15227.459872] Key type .llcrypt unregistered [15227.469976] Key type ._llcrypt unregistered [15262.800874] Key type ._llcrypt registered [15262.802123] Key type .llcrypt registered [15264.427604] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 06:04:46 (1787133886) [15287.626699] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15287.637662] alg: No test for adler32 (adler32-zlib) [15288.607386] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [15288.856557] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [15290.650347] Key type lgssc registered [15292.355424] Lustre: Echo OBD driver; http://www.lustre.org/ [15346.309863] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 06:06:08 (1787133968) [15360.390582] Key type lgssc unregistered [15360.690811] LNet: 297608:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15360.697864] LNetError: 297608:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15360.717885] LNet: Removed LNI 192.168.202.51@tcp [15361.315508] Key type .llcrypt unregistered [15361.317398] Key type ._llcrypt unregistered [15373.640957] Key type ._llcrypt registered [15373.644044] Key type .llcrypt registered [15374.167574] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15374.191185] alg: No test for adler32 (adler32-zlib) [15375.334804] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [15375.555504] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [15377.264326] Key type lgssc registered [15378.474887] Lustre: Echo OBD driver; http://www.lustre.org/ [15440.709954] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15447.467517] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15478.109690] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15803.020363] LNet: Added LNI 192.168.202.51@tcp50 [8/256/0/180] [15805.280395] LNet: Removed LNI 192.168.202.51@tcp [15807.533660] Lustre: 302024:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff9a4f07b16800: config has no valid NIDs, force dynamic NIDs [15808.812849] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [15809.001559] Lustre: Unmounted lustre-client [15811.708899] Key type lgssc unregistered [15812.190567] LNet: 302285:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15812.212645] LNetError: 302285:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15812.232287] LNet: Removed LNI 192.168.202.51@tcp50 [15813.005730] Key type .llcrypt unregistered [15813.007451] Key type ._llcrypt unregistered [15833.962174] Key type ._llcrypt registered [15833.973206] Key type .llcrypt registered [15834.828218] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15834.836922] alg: No test for adler32 (adler32-zlib) [15836.231993] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [15836.792330] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [15838.662805] Key type lgssc registered [15840.380977] Lustre: Echo OBD driver; http://www.lustre.org/ [15909.259863] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15914.875344] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15924.085532] LNet: Added LNI 192.168.202.51@tcp50 [8/256/0/180] [15924.989830] LNet: Removed LNI 192.168.202.51@tcp [15926.279155] Lustre: 304981:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff9a4f1361f800: config has no valid NIDs, force dynamic NIDs [15927.490274] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [15928.318438] Lustre: Unmounted lustre-client [15964.744893] Key type lgssc unregistered [15965.193802] LNet: 305854:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15965.208672] LNetError: 305854:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15965.245950] LNet: Removed LNI 192.168.202.51@tcp50 [15966.439799] Key type .llcrypt unregistered [15966.442109] Key type ._llcrypt unregistered [15981.177521] Key type ._llcrypt registered [15981.183405] Key type .llcrypt registered [15982.881729] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 06:16:45 (1787134605) [16003.143782] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16003.158750] alg: No test for adler32 (adler32-zlib) [16004.329835] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [16004.660296] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [16006.416353] Key type lgssc registered [16008.431111] Lustre: Echo OBD driver; http://www.lustre.org/ [16110.323519] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16116.257592] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16140.200121] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16180.008490] Key type lgssc unregistered [16180.224308] LNet: 310606:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16180.235571] LNetError: 310606:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16180.262790] LNet: Removed LNI 192.168.202.51@tcp [16181.482079] Key type .llcrypt unregistered [16181.483890] Key type ._llcrypt unregistered [16194.523468] Key type ._llcrypt registered [16194.526463] Key type .llcrypt registered [16196.580074] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 06:20:18 (1787134818) [16200.038252] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16200.063310] alg: No test for adler32 (adler32-zlib) [16201.323909] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [16201.580370] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [16203.280239] Key type lgssc registered [16205.182514] Lustre: Echo OBD driver; http://www.lustre.org/ [16261.783881] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16267.291663] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16292.018776] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16295.082677] Lustre: Mounted lustre-client - version 2.17.57_3_g9f8445d [16363.995986] Lustre: Mounted fs15246-client - version 2.17.57_3_g9f8445d [16381.024047] Lustre: Unmounted fs15246-client [16410.597439] Lustre: Unmounted lustre-client [16450.397379] Key type lgssc unregistered [16450.579082] LNet: 315472:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16450.598052] LNetError: 315472:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16450.640289] LNet: Removed LNI 192.168.202.51@tcp [16451.231404] Key type .llcrypt unregistered [16451.236559] Key type ._llcrypt unregistered [16466.167050] Key type ._llcrypt registered [16466.168742] Key type .llcrypt registered [16467.641879] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 06:24:49 (1787135089) [16470.350410] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16470.383314] alg: No test for adler32 (adler32-zlib) [16471.583658] Lustre: Lustre: Build Version: 2.17.57_3_g9f8445d [16471.989833] LNet: Added LNI 192.168.202.51@tcp [8/256/0/180] [16473.712219] Key type lgssc registered [16475.061579] Lustre: Echo OBD driver; http://www.lustre.org/ [16619.137750] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16625.709514] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16652.374106] Lustre: DEBUG MARKER: oleg251-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16773.230312] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 16436 sec ========= 06:29:54 (1787135394) [16775.799189] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 06:29:57 (1787135397) === [16780.509705] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 06:30:02 (1787135402) === [16824.321963] Key type lgssc unregistered [16824.701260] LNet: 324750:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16824.712514] LNetError: 324750:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16824.758173] LNet: Removed LNI 192.168.202.51@tcp [16825.888287] Key type .llcrypt unregistered [16825.890396] Key type ._llcrypt unregistered