[ 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 932151356 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: 2887096K/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.001011] APIC: Switch to symmetric I/O mode setup [ 0.003066] x2apic enabled [ 0.004007] Switched APIC routing to physical x2apic. [ 0.005014] kvm-guest: setup PV IPIs [ 0.008000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008019] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009009] pid_max: default: 32768 minimum: 301 [ 0.010112] LSM: Security Framework initializing [ 0.012034] Yama: becoming mindful. [ 0.012846] SELinux: Initializing. [ 0.013052] *** VALIDATE selinux *** [ 0.020672] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.025338] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027137] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028108] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030047] *** VALIDATE tmpfs *** [ 0.031463] *** VALIDATE proc *** [ 0.033231] *** VALIDATE cgroup *** [ 0.034008] *** VALIDATE cgroup2 *** [ 0.035287] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.037133] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.038008] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.039043] Spectre V2 : User space: Vulnerable [ 0.040010] Speculative Store Bypass: Vulnerable [ 0.042775] debug: unmapping init [mem 0xffffffffa9c59000-0xffffffffa9c60fff] [ 0.044913] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.045850] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.046035] ... version: 2 [ 0.047013] ... bit width: 48 [ 0.048013] ... generic registers: 4 [ 0.049012] ... value mask: 0000ffffffffffff [ 0.050018] ... max period: 00007fffffffffff [ 0.051018] ... fixed-purpose events: 3 [ 0.052010] ... event mask: 000000070000000f [ 0.053336] rcu: Hierarchical SRCU implementation. [ 0.055713] smp: Bringing up secondary CPUs ... [ 0.056669] x86: Booting SMP configuration: [ 0.057032] .... node #0, CPUs: #1 #2 #3 [ 0.147185] smp: Brought up 1 node, 4 CPUs [ 0.149024] smpboot: Max logical packages: 1 [ 0.150015] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.227026] node 0 deferred pages initialised in 75ms [ 0.235027] devtmpfs: initialized [ 0.237035] x86/mm: Memory block size: 128MB [ 0.241929] gcov: version magic: 0x41383552 [ 0.246716] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.255071] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.258572] pinctrl core: initialized pinctrl subsystem [ 0.261939] [ 0.262007] ************************************************************* [ 0.264011] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.266011] ** ** [ 0.270012] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.273017] ** ** [ 0.277417] ** This means that this kernel is built to expose internal ** [ 0.282012] ** IOMMU data structures, which may compromise security on ** [ 0.285013] ** your system. ** [ 0.288017] ** ** [ 0.291013] ** If you see this message and you are not debugging the ** [ 0.295011] ** kernel, report this immediately to your vendor! ** [ 0.298145] ** ** [ 0.302013] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.305013] ************************************************************* [ 0.310204] NET: Registered protocol family 16 [ 0.314956] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.511096] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.513063] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.516895] cpuidle: using governor menu [ 0.519008] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.521008] PCI: Using configuration type 1 for base access [ 0.522678] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.536902] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.537013] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.541018] cryptd: max_cpu_qlen set to 1000 [ 0.544300] ACPI: Added _OSI(Module Device) [ 0.546013] ACPI: Added _OSI(Processor Device) [ 0.547000] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.548014] ACPI: Added _OSI(Processor Aggregator Device) [ 0.555000] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.563818] ACPI: Interpreter enabled [ 0.565174] ACPI: PM: (supports S0 S3 S4 S5) [ 0.568017] ACPI: Using IOAPIC for interrupt routing [ 0.570522] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.578435] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.592327] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.595040] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.598019] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.601132] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.606581] acpiphp: Slot [2] registered [ 0.608174] acpiphp: Slot [5] registered [ 0.610113] acpiphp: Slot [6] registered [ 0.611081] acpiphp: Slot [3] registered [ 0.612082] acpiphp: Slot [4] registered [ 0.614111] acpiphp: Slot [7] registered [ 0.615092] acpiphp: Slot [8] registered [ 0.617094] acpiphp: Slot [9] registered [ 0.618121] acpiphp: Slot [10] registered [ 0.620247] acpiphp: Slot [11] registered [ 0.622139] acpiphp: Slot [12] registered [ 0.624115] acpiphp: Slot [13] registered [ 0.625162] acpiphp: Slot [14] registered [ 0.627106] acpiphp: Slot [15] registered [ 0.629103] acpiphp: Slot [16] registered [ 0.631191] acpiphp: Slot [17] registered [ 0.633100] acpiphp: Slot [18] registered [ 0.634198] acpiphp: Slot [19] registered [ 0.635090] acpiphp: Slot [20] registered [ 0.637078] acpiphp: Slot [21] registered [ 0.638095] acpiphp: Slot [22] registered [ 0.640162] acpiphp: Slot [23] registered [ 0.641075] acpiphp: Slot [24] registered [ 0.642093] acpiphp: Slot [25] registered [ 0.644115] acpiphp: Slot [26] registered [ 0.645092] acpiphp: Slot [27] registered [ 0.647095] acpiphp: Slot [28] registered [ 0.648147] acpiphp: Slot [29] registered [ 0.649110] acpiphp: Slot [30] registered [ 0.651196] acpiphp: Slot [31] registered [ 0.653146] PCI host bridge to bus 0000:00 [ 0.655082] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.658022] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.661076] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.665023] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.668025] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.671027] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.675186] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.680777] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.686218] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.698014] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.705057] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.710017] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.714017] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.719018] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.724674] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.730120] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.734053] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.739143] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.746017] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.769014] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.780016] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.796028] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.845033] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.853033] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.868018] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.881688] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.889025] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.895024] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.903017] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.917116] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.919380] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.920328] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.922410] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.926206] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.931729] iommu: Default domain type: Passthrough [ 0.935375] SCSI subsystem initialized [ 0.938159] ACPI: bus type USB registered [ 0.939148] usbcore: registered new interface driver usbfs [ 0.942106] usbcore: registered new interface driver hub [ 0.944118] usbcore: registered new device driver usb [ 0.946234] pps_core: LinuxPPS API ver. 1 registered [ 0.948012] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.951080] PTP clock support registered [ 0.954060] EDAC MC: Ver: 3.0.0 [ 0.955156] PCI: Using ACPI for IRQ routing [ 0.958012] NetLabel: Initializing [ 0.959010] NetLabel: domain hash size = 128 [ 0.961013] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.963084] NetLabel: unlabeled traffic allowed by default [ 0.965283] vgaarb: loaded [ 0.984311] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.986023] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.992388] clocksource: Switched to clocksource kvm-clock [ 1.141472] VFS: Disk quotas dquot_6.6.0 [ 1.144487] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 1.150517] *** VALIDATE ramfs *** [ 1.154176] *** VALIDATE hugetlbfs *** [ 1.174855] pnp: PnP ACPI init [ 1.179057] pnp: PnP ACPI: found 6 devices [ 1.221568] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 1.229199] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 1.231928] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 1.236911] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 1.241256] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 1.244278] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 1.419511] NET: Registered protocol family 2 [ 1.424468] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 1.439142] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 1.452373] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 1.588869] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 1.623497] TCP: Hash tables configured (established 65536 bind 65536) [ 1.630764] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 1.637797] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 1.643993] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 1.651514] NET: Registered protocol family 1 [ 1.657042] RPC: Registered named UNIX socket transport module. [ 1.660814] RPC: Registered udp transport module. [ 1.664747] RPC: Registered tcp transport module. [ 1.667832] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 1.672797] NET: Registered protocol family 44 [ 1.675990] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 1.680244] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 1.684448] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 1.688958] PCI: CLS 0 bytes, default 64 [ 1.693470] Unpacking initramfs... [ 3.754768] debug: unmapping init [mem 0xffff9bd07cc64000-0xffff9bd07ffcffff] [ 3.759482] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 3.762179] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 3.765213] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 4.431933] Initialise system trusted keyrings [ 4.434048] Key type blacklist registered [ 4.436531] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 4.446972] zbud: loaded [ 4.450249] *** VALIDATE nfs *** [ 4.451582] *** VALIDATE nfs4 *** [ 4.453272] pstore: using deflate compression [ 4.457795] Platform Keyring initialized [ 4.565684] NET: Registered protocol family 38 [ 4.567145] Key type asymmetric registered [ 4.568447] Asymmetric key parser 'x509' registered [ 4.569956] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 4.572393] io scheduler mq-deadline registered [ 4.573780] io scheduler kyber registered [ 4.575324] io scheduler bfq registered [ 4.576694] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 4.579604] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 4.581935] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 4.584322] ACPI: Power Button [PWRF] [ 4.589412] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 4.595354] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 4.611503] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 4.642105] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 4.672738] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 4.677806] Non-volatile memory driver v1.3 [ 4.679661] Linux agpgart interface v0.103 [ 4.709557] virtio_blk virtio1: [vda] 146648 512-byte logical blocks (75.1 MB/71.6 MiB) [ 4.712803] vda: detected capacity change from 0 to 75083776 [ 4.728823] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 4.732038] vdb: detected capacity change from 0 to 1073741824 [ 4.738524] libphy: Fixed MDIO Bus: probed [ 4.743975] usbcore: registered new interface driver usbserial_generic [ 4.746797] usbserial: USB Serial support registered for generic [ 4.749472] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 4.754672] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 4.756692] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 4.759544] mousedev: PS/2 mouse device common for all mice [ 4.763166] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 4.766674] rtc_cmos 00:05: RTC can wake from S4 [ 4.771024] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 4.773170] rtc_cmos 00:05: registered as rtc0 [ 4.778863] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 4.781437] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 4.787647] intel_pstate: CPU model not supported [ 4.790960] hid: raw HID events driver (C) Jiri Kosina [ 4.793185] usbcore: registered new interface driver usbhid [ 4.795337] usbhid: USB HID core driver [ 4.796866] drop_monitor: Initializing network drop monitor service [ 4.799066] Initializing XFRM netlink socket [ 4.801986] NET: Registered protocol family 10 [ 4.807518] Segment Routing with IPv6 [ 4.809214] NET: Registered protocol family 17 [ 4.811616] mpls_gso: MPLS GSO support [ 4.817663] RAS: Correctable Errors collector initialized. [ 4.819927] AVX version of gcm_enc/dec engaged. [ 4.821892] AES CTR mode by8 optimization enabled [ 4.934559] sched_clock: Marking stable (4934466162, 0)->(6069620318, -1135154156) [ 4.939981] registered taskstats version 1 [ 4.942797] Loading compiled-in X.509 certificates [ 4.945743] zswap: loaded using pool lzo/zbud [ 5.044163] Key type big_key registered [ 5.058466] Key type encrypted registered [ 5.060407] ima: No TPM chip found, activating TPM-bypass! [ 5.062811] ima: Allocated hash algorithm: sha1 [ 5.064825] ima: No architecture policies found [ 5.066921] evm: Initialising EVM extended attributes: [ 5.069290] evm: security.selinux [ 5.070812] evm: security.ima [ 5.072170] evm: security.capability [ 5.073669] evm: HMAC attrs: 0x1 [ 5.076441] rtc_cmos 00:05: setting system clock to 2026-09-06 16:30:33 UTC (1788712233) [ 5.083982] debug: unmapping init [mem 0xffffffffaac03000-0xffffffffaadfffff] [ 5.087671] debug: unmapping init [mem 0xffffffffa9982000-0xffffffffa9c58fff] [ 5.096250] Write protecting the kernel read-only data: 28672k [ 5.100045] debug: unmapping init [mem 0xffffffffa8003000-0xffffffffa81fffff] [ 5.103351] debug: unmapping init [mem 0xffffffffa8914000-0xffffffffa89fffff] [ 5.136939] 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) [ 5.145567] systemd[1]: Detected virtualization kvm. [ 5.147649] systemd[1]: Detected architecture x86-64. [ 5.149188] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 5.182630] systemd[1]: No hostname configured. [ 5.184612] systemd[1]: Set hostname to . [ 5.186863] random: systemd: uninitialized urandom read (16 bytes read) [ 5.189752] systemd[1]: Initializing machine ID from random generator. [ 5.313193] random: ln: uninitialized urandom read (6 bytes read) [ 5.621148] random: systemd: uninitialized urandom read (16 bytes read) [ 5.624184] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 5.629368] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 5.634498] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Timers. [ OK ] Reached target Paths. [ OK ] Reached target Initrd Root Device. [ OK ] Listening on Journal Socket. Starting Create list of required st…ce nodes for the current kernel... Starting Setup Virtual Console... [ OK ] Started Memstrack Anylazing Service. Starting Apply Kernel Variables... [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. Starting Create Volatile Files and Directories... [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 7.151654] device-mapper: uevent: version 1.0.3 [ 7.154282] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started Journal Service. [ 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. [ 8.211966] virtio_net virtio0 ens2: renamed from eth0 [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 8.957070] scsi host0: ata_piix [ 8.984707] scsi host1: ata_piix [ 8.995342] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 9.004755] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 16.870483] random: crng init done [ 16.873095] random: 7 urandom warning(s) missed due to ratelimiting [ 20.320984] dracut-initqueue[589]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 22.038756] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ 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... [ 24.728414] printk: systemd: 28 output lines suppressed due to ratelimiting [ 26.062471] SELinux: Disabled at runtime. [ 26.215533] 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) [ 26.232540] systemd[1]: Detected virtualization kvm. [ 26.235961] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 28.102800] systemd[1]: initrd-switch-root.service: Succeeded. [ 28.116973] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 28.137354] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 28.143320] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 28.147262] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 28.163274] systemd[1]: Starting Journal Service... Starting Journal Service... [ 28.180681] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ OK ] Started Forward Password Requests to Wall Directory Watch. Mounting POSIX Message Queue File System... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice User and Session Slice. Starting Apply Kernel Variables... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Reached target Slices. Mounting Huge Pages File System... Activating swap /dev/disk/by-label/SWAP... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on udev Kernel Socket. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ 28.598598] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Listening on Process Core Dump Socket. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. Starting Remount Root and Kernel File Systems... [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target rpc_pipefs.target. [ OK ] Stopped target Initrd Root File System. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Created slice system-getty.slice. Mounting Kernel Debug File System... [ OK ] Started Journal Service. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Huge Pages File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Kernel Debug File System. Starting Configure read-only root support... [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 29.846809] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 31.279333] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 31.405577] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 31.795692] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 31.913827] EDAC sbridge: Ver: 1.1.2 [ 33.177215] hrtimer: interrupt took 8425962 ns [ 35.156890] Key type dns_resolver registered [ 35.832748] NFS: Registering the id_resolver key type [ 35.835096] Key type id_resolver registered [ 35.837163] Key type id_legacy registered [* ] A start job is running for Configur…-only root support (7s / no limit) [** ] A start job is running for Configur…-only root support (8s / no limit) [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Login Service... [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started irqbalance daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Started D-Bus System Message Bus. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Reached target sshd-keygen.target. Starting Network Manager... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg221-client login: [ 99.962066] libcfs: loading out-of-tree module taints kernel. [ 100.190685] Key type ._llcrypt registered [ 100.205614] Key type .llcrypt registered [ 100.675410] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 100.689679] alg: No test for adler32 (adler32-zlib) [ 101.948414] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 102.569847] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 104.359454] Key type lgssc registered [ 106.365709] Lustre: Echo OBD driver; http://www.lustre.org/ [ 254.299617] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 258.888626] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 274.714709] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing check_logdir /tmp/testlogs/ [ 278.240558] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing yml_node [ 280.031553] Lustre: lustre-OST0000-osc-ffff9bd0c64a6800: disconnect after 24s idle [ 281.488031] Lustre: DEBUG MARKER: Client: 2.17.57.103 [ 283.595929] Lustre: DEBUG MARKER: MDS: 2.17.57.103 [ 285.380405] Lustre: DEBUG MARKER: OSS: 2.17.57.103 [ 286.477856] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Sun Sep 6 12:35:13 EDT 2026 [ 302.128745] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 303.500494] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 305.628863] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 315.927523] Lustre: Unmounted lustre-client [ 414.220727] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 418.487203] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 434.635495] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 461.131718] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 12:38:08 (1788712688) [ 462.692415] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 463.862394] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 12:38:11 (1788712691) [ 477.831291] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 479.874212] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 487.569694] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 498.168750] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 499.994795] Lustre: Unmounted lustre-client [ 525.743340] Key type lgssc unregistered [ 525.862893] LNet: 15045:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 525.866452] LNetError: 15045:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 525.879367] LNet: Removed LNI 192.168.202.21@tcp [ 526.163131] Key type .llcrypt unregistered [ 526.164877] Key type ._llcrypt unregistered [ 531.197198] Key type ._llcrypt registered [ 531.198349] Key type .llcrypt registered [ 531.656913] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 12:39:19 (1788712759) [ 532.069472] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 532.103057] alg: No test for adler32 (adler32-zlib) [ 532.955194] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 533.039435] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 534.615245] Key type lgssc registered [ 534.997578] Lustre: Echo OBD driver; http://www.lustre.org/ [ 567.256894] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 642.205396] Lustre: Unmounted lustre-client [ 657.409503] Key type lgssc unregistered [ 657.534572] LNet: 18818:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 657.537228] LNetError: 18818:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 657.544930] LNet: Removed LNI 192.168.202.21@tcp [ 657.800856] Key type .llcrypt unregistered [ 657.802550] Key type ._llcrypt unregistered [ 661.665926] Key type ._llcrypt registered [ 661.667416] Key type .llcrypt registered [ 662.250372] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 12:41:30 (1788712890) [ 663.322475] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 663.362569] alg: No test for adler32 (adler32-zlib) [ 664.243959] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 664.358514] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 665.959133] Key type lgssc registered [ 666.382150] Lustre: Echo OBD driver; http://www.lustre.org/ [ 686.536982] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 688.959424] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 696.834631] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 702.451440] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 706.243138] Lustre: Unmounted lustre-client [ 723.748953] Key type lgssc unregistered [ 723.881337] LNet: 22401:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 723.884179] LNetError: 22401:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 723.891676] LNet: Removed LNI 192.168.202.21@tcp [ 724.166455] Key type .llcrypt unregistered [ 724.167883] Key type ._llcrypt unregistered [ 727.862588] Key type ._llcrypt registered [ 727.863933] Key type .llcrypt registered [ 728.378246] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 12:42:36 (1788712956) [ 729.172583] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 729.187415] alg: No test for adler32 (adler32-zlib) [ 730.055147] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 730.151555] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 731.735161] Key type lgssc registered [ 732.157364] Lustre: Echo OBD driver; http://www.lustre.org/ [ 751.060484] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 752.911557] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 759.880920] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 770.544484] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 773.469147] Lustre: Unmounted lustre-client [ 790.712847] Key type lgssc unregistered [ 790.832514] LNet: 25578:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 790.834533] LNetError: 25578:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 790.842773] LNet: Removed LNI 192.168.202.21@tcp [ 791.065776] Key type .llcrypt unregistered [ 791.067352] Key type ._llcrypt unregistered [ 795.901434] Key type ._llcrypt registered [ 795.902539] Key type .llcrypt registered [ 796.331515] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 12:43:44 (1788713024) [ 796.996794] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 797.050644] alg: No test for adler32 (adler32-zlib) [ 797.914244] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 798.010266] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 799.591231] Key type lgssc registered [ 799.967539] Lustre: Echo OBD driver; http://www.lustre.org/ [ 817.471421] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 819.279782] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 825.916676] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 836.591437] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 841.697381] Lustre: lustre-MDT0000-mdc-ffff9bd0d09b8800: Connection to lustre-MDT0000 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 846.817926] Lustre: lustre-MDT0001-mdc-ffff9bd0d09b8800: Connection to lustre-MDT0001 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 861.230057] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 863.007068] Lustre: 26531:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788713075/real 1788713075] req@0000000054cb91ec x1875601549956992/t0(0) o400->MGC192.168.202.121@tcp@192.168.202.121@tcp:26/25 lens 224/224 e 0 to 1 dl 1788713091 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 863.014970] LustreError: MGC192.168.202.121@tcp: Connection to MGS (at 192.168.202.121@tcp) was lost; in progress operations using this service will fail [ 871.479898] Lustre: Unmounted lustre-client [ 890.242823] Key type lgssc unregistered [ 890.354264] LNet: 29217:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 890.356629] LNetError: 29217:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 890.364303] LNet: Removed LNI 192.168.202.21@tcp [ 890.589821] Key type .llcrypt unregistered [ 890.590942] Key type ._llcrypt unregistered [ 895.404239] Key type ._llcrypt registered [ 895.405126] Key type .llcrypt registered [ 895.820222] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 12:45:23 (1788713123) [ 896.503982] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 896.541921] alg: No test for adler32 (adler32-zlib) [ 897.401679] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 897.498659] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 899.087159] Key type lgssc registered [ 899.412259] Lustre: Echo OBD driver; http://www.lustre.org/ [ 916.053814] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 917.665488] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 923.806212] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 934.383532] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 939.491375] Lustre: lustre-OST0000-osc-ffff9bd0d07cf000: Connection to lustre-OST0000 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 943.170056] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 953.466175] Lustre: Unmounted lustre-client [ 978.834736] Key type lgssc unregistered [ 978.960378] LNet: 32815:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 978.963146] LNetError: 32815:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 978.974325] LNet: Removed LNI 192.168.202.21@tcp [ 979.198459] Key type .llcrypt unregistered [ 979.199853] Key type ._llcrypt unregistered [ 984.291909] Key type ._llcrypt registered [ 984.293155] Key type .llcrypt registered [ 984.762963] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 12:46:52 (1788713212) [ 985.445968] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 985.497370] alg: No test for adler32 (adler32-zlib) [ 986.354045] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 986.447215] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 988.023123] Key type lgssc registered [ 988.394864] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1006.567618] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1008.408808] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1015.292389] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1026.034297] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 1027.846762] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1029.124404] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1039.667346] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-09-0612h47m47s [ 1040.400892] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1041.377741] Lustre: lustre-MDT0000-mdc-ffff9bd0d0a8c000: Connection to lustre-MDT0000 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1047.943342] LustreError: 36595:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff9bd0d0a8c000: can't stat MDS #0: rc = -19 [ 1048.426730] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1052.576908] LustreError: MGC192.168.202.121@tcp: Connection to MGS (at 192.168.202.121@tcp) was lost; in progress operations using this service will fail [ 1052.582882] Lustre: Evicted from MGS (at 192.168.202.121@tcp) after server handle changed from 0xf7914bbc8ce0611a to 0xf7914bbc8ce064bd [ 1052.586463] Lustre: MGC192.168.202.121@tcp: Connection restored to 192.168.202.121@tcp (at 192.168.202.121@tcp) [ 1053.351175] Lustre: DEBUG MARKER: Wait for df (36595) ... [ 1053.603780] LustreError: lustre-MDT0000-mdc-ffff9bd0d0a8c000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1053.609324] Lustre: lustre-MDT0000-mdc-ffff9bd0d0a8c000: Connection restored to 192.168.202.121@tcp (at 192.168.202.121@tcp) [ 1053.863207] Lustre: DEBUG MARKER: done [ 1054.193442] Lustre: Unmounted lustre-client [ 1069.470123] Key type lgssc unregistered [ 1069.597429] LNet: 37605:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1069.599762] LNetError: 37605:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1069.613155] LNet: Removed LNI 192.168.202.21@tcp [ 1069.890789] Key type .llcrypt unregistered [ 1069.891929] Key type ._llcrypt unregistered [ 1080.282456] Key type ._llcrypt registered [ 1080.283863] Key type .llcrypt registered [ 1080.775377] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 12:48:28 (1788713308) [ 1081.540826] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1081.579885] alg: No test for adler32 (adler32-zlib) [ 1082.446543] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 1082.537789] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 1084.119309] Key type lgssc registered [ 1084.523649] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1102.401926] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1104.333438] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1111.261854] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1112.449489] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 1114.607214] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-09-0612h49m02s [ 1115.124423] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1117.424923] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1118.276330] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1138.655179] Lustre: 41728:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788713346/real 1788713346] req@00000000ccae821e x1875601847751296/t0(0) o101->lustre-MDT0000-mdc-ffff9bd0d815e800@192.168.202.121@tcp:12/10 lens 576/1152 e 0 to 1 dl 1788713366 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1138.667574] Lustre: lustre-MDT0000-mdc-ffff9bd0d815e800: Connection to lustre-MDT0000 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1138.678689] Lustre: lustre-MDT0000-mdc-ffff9bd0d815e800: Connection restored to 192.168.202.121@tcp (at 192.168.202.121@tcp) [ 1158.860891] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1159.885328] Lustre: DEBUG MARKER: done [ 1160.889285] Lustre: Unmounted lustre-client [ 1180.139318] Key type lgssc unregistered [ 1180.256532] LNet: 42612:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1180.258748] LNetError: 42612:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1180.265534] LNet: Removed LNI 192.168.202.21@tcp [ 1180.535349] Key type .llcrypt unregistered [ 1180.536599] Key type ._llcrypt unregistered [ 1191.373187] Key type ._llcrypt registered [ 1191.374547] Key type .llcrypt registered [ 1191.957497] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 12:50:19 (1788713419) [ 1192.556205] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1193.127058] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 12:50:21 (1788713421) [ 1194.491899] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1194.500481] alg: No test for adler32 (adler32-zlib) [ 1195.371072] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 1195.463877] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 1197.047194] Key type lgssc registered [ 1197.508331] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1208.264353] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 12:50:36 (1788713436) [ 1218.950902] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1220.830249] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1227.529792] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1233.883878] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 1235.743815] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1236.462127] Lustre: Unmounted lustre-client [ 1245.962871] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 1262.339286] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1264.169718] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1264.325712] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 1265.178541] Lustre: Unmounted lustre-client [ 1291.821658] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1293.607691] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1293.774604] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 1294.601125] Lustre: Unmounted lustre-client [ 1305.825616] Lustre: DEBUG MARKER: files compared the same [ 1320.335400] Key type lgssc unregistered [ 1320.472566] LNet: 50200:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1320.474830] LNetError: 50200:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1320.484609] LNet: Removed LNI 192.168.202.21@tcp [ 1320.764274] Key type .llcrypt unregistered [ 1320.765347] Key type ._llcrypt unregistered [ 1325.873616] Key type ._llcrypt registered [ 1325.875291] Key type .llcrypt registered [ 1326.368023] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 12:52:34 (1788713554) [ 1327.198273] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1327.205026] alg: No test for adler32 (adler32-zlib) [ 1328.060497] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 1328.147980] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 1329.735131] Key type lgssc registered [ 1330.104625] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1347.972183] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1349.816267] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1356.677104] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1367.018988] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 1368.624403] Lustre: Unmounted lustre-client [ 1378.111848] Key type lgssc unregistered [ 1378.234390] LNet: 53765:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1378.238070] LNetError: 53765:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1378.249825] LNet: Removed LNI 192.168.202.21@tcp [ 1378.520750] Key type .llcrypt unregistered [ 1378.522026] Key type ._llcrypt unregistered [ 1383.657234] Key type ._llcrypt registered [ 1383.658309] Key type .llcrypt registered [ 1384.162560] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 12:53:32 (1788713612) [ 1385.032974] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1385.073577] alg: No test for adler32 (adler32-zlib) [ 1385.947704] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 1386.051361] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 1387.647185] Key type lgssc registered [ 1388.072167] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1448.498513] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1459.300427] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1461.120353] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1478.186583] Key type lgssc unregistered [ 1478.295337] LNet: 57237:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1478.297574] LNetError: 57237:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1478.309309] LNet: Removed LNI 192.168.202.21@tcp [ 1478.561764] Key type .llcrypt unregistered [ 1478.562958] Key type ._llcrypt unregistered [ 1483.560481] Key type ._llcrypt registered [ 1483.561502] Key type .llcrypt registered [ 1484.010539] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 12:55:11 (1788713711) [ 1484.970722] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1484.977478] alg: No test for adler32 (adler32-zlib) [ 1485.831883] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 1485.921179] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 1487.503200] Key type lgssc registered [ 1487.878859] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1515.137161] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 1520.484732] Lustre: Unmounted lustre-client [ 1540.872380] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 12:56:08 (1788713768) [ 1582.658626] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1593.841242] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 1599.200845] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1599.203494] LustreError: 63678:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff9bd0c6eeb000: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 1599.210800] Lustre: 58217:0:(llite_lib.c:4342:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.202.121@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 1609.465148] Lustre: Unmounted lustre-client [ 1644.827701] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 12:57:52 (1788713872) [ 1648.884222] Key type lgssc unregistered [ 1649.012761] LNet: 64886:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1649.016632] LNetError: 64886:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1649.025687] LNet: Removed LNI 192.168.202.21@tcp [ 1649.301550] Key type .llcrypt unregistered [ 1649.303274] Key type ._llcrypt unregistered [ 1653.325515] Key type ._llcrypt registered [ 1653.328815] Key type .llcrypt registered [ 1653.515937] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1653.521937] alg: No test for adler32 (adler32-zlib) [ 1654.393910] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 1654.493668] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 1656.079139] Key type lgssc registered [ 1656.450703] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1674.164731] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1675.870733] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1682.639434] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1693.172110] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 1694.780581] Lustre: Unmounted lustre-client [ 1712.229638] Key type lgssc unregistered [ 1712.356860] LNet: 67886:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1712.359499] LNetError: 67886:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1712.367424] LNet: Removed LNI 192.168.202.21@tcp [ 1712.651573] Key type .llcrypt unregistered [ 1712.652712] Key type ._llcrypt unregistered [ 1716.447335] Key type ._llcrypt registered [ 1716.448448] Key type .llcrypt registered [ 1716.630976] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1716.636722] alg: No test for adler32 (adler32-zlib) [ 1717.489602] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 1717.585065] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 1719.167203] Key type lgssc registered [ 1719.531280] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1767.993046] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1769.811438] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1776.916534] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1787.379954] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 1787.840135] Lustre: Unmounted lustre-client [ 1797.142387] Key type lgssc unregistered [ 1797.254536] LNet: 71300:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1797.257088] LNetError: 71300:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1797.263647] LNet: Removed LNI 192.168.202.21@tcp [ 1797.505710] Key type .llcrypt unregistered [ 1797.506850] Key type ._llcrypt unregistered [ 1802.434688] Key type ._llcrypt registered [ 1802.436738] Key type .llcrypt registered [ 1802.914676] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 13:00:30 (1788714030) [ 1803.701205] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1803.711518] alg: No test for adler32 (adler32-zlib) [ 1804.582098] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 1804.683567] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 1806.271153] Key type lgssc registered [ 1806.643227] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1824.422335] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1826.265582] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1833.128995] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1843.700977] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 1846.012808] Lustre: Unmounted lustre-client [ 1846.157947] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 1846.891833] Lustre: Unmounted lustre-client [ 1864.335897] Key type lgssc unregistered [ 1864.467494] LNet: 74981:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1864.471236] LNetError: 74981:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1864.481523] LNet: Removed LNI 192.168.202.21@tcp [ 1864.753569] Key type .llcrypt unregistered [ 1864.754648] Key type ._llcrypt unregistered [ 1868.979650] Key type ._llcrypt registered [ 1868.982355] Key type .llcrypt registered [ 1869.254567] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1869.273814] alg: No test for adler32 (adler32-zlib) [ 1870.127622] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 1870.218586] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 1871.799200] Key type lgssc registered [ 1872.176681] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1889.841833] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1891.780891] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1898.661289] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1909.232802] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 1910.949958] Lustre: Unmounted lustre-client [ 1928.291823] Key type lgssc unregistered [ 1928.411565] LNet: 78000:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1928.415430] LNetError: 78000:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1928.423449] LNet: Removed LNI 192.168.202.21@tcp [ 1928.688286] Key type .llcrypt unregistered [ 1928.690034] Key type ._llcrypt unregistered [ 1933.775222] Key type ._llcrypt registered [ 1933.776176] Key type .llcrypt registered [ 1934.233207] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 13:02:42 (1788714162) [ 1934.913222] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1934.955757] alg: No test for adler32 (adler32-zlib) [ 1935.817665] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 1935.916380] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 1937.495123] Key type lgssc registered [ 1937.840588] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1955.336464] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1957.326544] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1964.753823] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1975.282766] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 1980.849197] Lustre: lustre: nosquash_nids is cleared [ 1980.858222] Lustre: lustre: root_squash is set to 500:500 [ 1990.317644] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.202.21@tcp 192.168.0.[2,10]@tcp [ 2000.368516] Lustre: lustre: nosquash_nids set to 192.168.202.21/24@tcp [ 2001.282066] Lustre: Unmounted lustre-client [ 2022.959292] Key type lgssc unregistered [ 2023.076680] LNet: 81980:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2023.081191] LNetError: 81980:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2023.088732] LNet: Removed LNI 192.168.202.21@tcp [ 2023.366970] Key type .llcrypt unregistered [ 2023.368485] Key type ._llcrypt unregistered [ 2028.519539] Key type ._llcrypt registered [ 2028.520869] Key type .llcrypt registered [ 2029.019104] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 13:04:16 (1788714256) [ 2029.845934] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 2030.423104] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 13:04:18 (1788714258) [ 2031.325825] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2031.341580] alg: No test for adler32 (adler32-zlib) [ 2032.195513] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 2032.278199] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 2033.855135] Key type lgssc registered [ 2034.237168] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2052.415754] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2054.292804] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2061.032201] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2061.206806] Lustre: lustre: root_squash is set to 500:500 [ 2061.215552] Lustre: lustre: nosquash_nids set to 192.168.202.21/24@tcp [ 2071.539527] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 2073.901145] Lustre: Unmounted lustre-client [ 2099.375453] Key type lgssc unregistered [ 2099.481510] LNet: 85924:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2099.484093] LNetError: 85924:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2099.493164] LNet: Removed LNI 192.168.202.21@tcp [ 2099.765995] Key type .llcrypt unregistered [ 2099.767170] Key type ._llcrypt unregistered [ 2104.747992] Key type ._llcrypt registered [ 2104.749210] Key type .llcrypt registered [ 2105.228286] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 2105.797765] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 13:05:33 (1788714333) [ 2111.718119] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2111.749867] alg: No test for adler32 (adler32-zlib) [ 2112.614622] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 2112.713726] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 2114.303167] Key type lgssc registered [ 2114.707177] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2143.705895] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2145.471336] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2153.411977] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2183.347614] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2185.139331] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2191.779329] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2205.151730] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 2218.990355] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9bd0d15e6800.ost_server_uuid 50 [ 2219.519237] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9bd0d15e6800.ost_server_uuid in FULL state after 0 sec [ 2219.656498] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 2220.423229] Lustre: Unmounted lustre-client [ 2248.032340] Key type lgssc unregistered [ 2248.157604] LNet: 93557:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2248.160110] LNetError: 93557:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2248.169833] LNet: Removed LNI 192.168.202.21@tcp [ 2248.430229] Key type .llcrypt unregistered [ 2248.431318] Key type ._llcrypt unregistered [ 2258.775206] Key type ._llcrypt registered [ 2258.776402] Key type .llcrypt registered [ 2259.269791] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 13:08:07 (1788714487) [ 2265.146527] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2265.153662] alg: No test for adler32 (adler32-zlib) [ 2266.017830] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 2266.109392] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 2267.695137] Key type lgssc registered [ 2268.098424] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2297.349703] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2299.308851] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2307.350986] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2313.690811] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 2329.059244] Lustre: lustre-OST0000-osc-ffff9bd0ccd92800: Connection to lustre-OST0000 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2334.177655] Lustre: lustre-MDT0000-mdc-ffff9bd0ccd92800: Connection to lustre-MDT0000 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2335.199221] Lustre: 94915:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788714547/real 1788714547] req@0000000017932d49 x1875603089266176/t0(0) o400->lustre-OST0000-osc-ffff9bd0ccd92800@192.168.202.121@tcp:28/4 lens 224/224 e 0 to 1 dl 1788714563 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2340.319150] Lustre: 94914:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788714552/real 1788714552] req@0000000021f5fcda x1875603089266688/t0(0) o400->lustre-OST0000-osc-ffff9bd0ccd92800@192.168.202.121@tcp:28/4 lens 224/224 e 0 to 1 dl 1788714568 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2344.417616] LustreError: MGC192.168.202.121@tcp: Connection to MGS (at 192.168.202.121@tcp) was lost; in progress operations using this service will fail [ 2344.424364] Lustre: 98074:0:(mgc_request.c:1900:mgc_process_log()) MGC192.168.202.121@tcp: IR log lustre-cliir failed, not fatal: rc = -5 [ 2344.425989] Lustre: Evicted from MGS (at 192.168.202.121@tcp) after server handle changed from 0x928f7f16ee4909ca to 0x928f7f16ee490d0b [ 2344.432184] Lustre: MGC192.168.202.121@tcp: Connection restored to 192.168.202.121@tcp (at 192.168.202.121@tcp) [ 2349.429325] Lustre: lustre-MDT0000-mdc-ffff9bd0ccd92800: Connection restored to 192.168.202.121@tcp (at 192.168.202.121@tcp) [ 2349.817846] Lustre: Unmounted lustre-client [ 2367.146288] Key type lgssc unregistered [ 2367.271628] LNet: 99192:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2367.274638] LNetError: 99192:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2367.283508] LNet: Removed LNI 192.168.202.21@tcp [ 2367.544461] Key type .llcrypt unregistered [ 2367.546325] Key type ._llcrypt unregistered [ 2372.970917] Key type ._llcrypt registered [ 2372.971991] Key type .llcrypt registered [ 2373.461583] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 13:10:01 (1788714601) [ 2385.095362] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2385.103667] alg: No test for adler32 (adler32-zlib) [ 2385.966755] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 2386.065292] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 2387.655178] Key type lgssc registered [ 2388.079148] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2403.527911] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2436.589042] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2438.426648] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2446.509645] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2447.687262] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 2448.406256] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 2449.017094] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 13:11:16 (1788714676) [ 2500.970723] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2511.220074] Lustre: Unmounted lustre-client [ 2561.762947] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2563.647172] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2571.538814] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2593.311712] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 13:13:41 (1788714821) [ 2624.992657] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2626.768342] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2634.580867] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2634.763710] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 2635.838729] Lustre: Unmounted lustre-client [ 2654.036573] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 13:14:41 (1788714881) [ 2685.029849] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2686.901628] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2694.776514] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2700.273939] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 2701.310589] Lustre: Unmounted lustre-client [ 2726.850655] Key type lgssc unregistered [ 2726.968628] LNet: 122109:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2726.971932] LNetError: 122109:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2726.982862] LNet: Removed LNI 192.168.202.21@tcp [ 2727.259608] Key type .llcrypt unregistered [ 2727.261237] Key type ._llcrypt unregistered [ 2732.446612] Key type ._llcrypt registered [ 2732.447650] Key type .llcrypt registered [ 2732.938214] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 13:16:00 (1788714960) [ 2733.749148] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2733.757250] alg: No test for adler32 (adler32-zlib) [ 2734.624758] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 2734.715397] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 2736.303215] Key type lgssc registered [ 2736.738538] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2754.749464] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2756.812240] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2763.928546] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2774.514433] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 2785.238976] Lustre: Unmounted lustre-client [ 2802.893514] Key type lgssc unregistered [ 2803.014703] LNet: 125695:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2803.017531] LNetError: 125695:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2803.027899] LNet: Removed LNI 192.168.202.21@tcp [ 2803.296198] Key type .llcrypt unregistered [ 2803.297414] Key type ._llcrypt unregistered [ 2808.955248] Key type ._llcrypt registered [ 2808.956320] Key type .llcrypt registered [ 2809.436468] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 13:17:17 (1788715037) [ 2810.137372] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2810.148279] alg: No test for adler32 (adler32-zlib) [ 2811.014519] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 2811.109138] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 2812.695180] Key type lgssc registered [ 2813.063242] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2831.129299] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2832.923334] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2839.454500] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2849.794640] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 2854.882684] Lustre: lustre-OST0000-osc-ffff9bd0c3e5c800: Connection to lustre-OST0000 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2860.393041] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff9bd0c3e5c800.ost_server_uuid 50 [ 2860.962592] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 2870.303459] Lustre: Unmounted lustre-client [ 2881.000972] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 13:18:28 (1788715108) [ 2891.196457] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2892.959432] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2900.024942] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2907.955179] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2908.126918] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 2913.250768] Lustre: lustre-OST0000-osc-ffff9bd0c6d24000: Connection to lustre-OST0000 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2927.570508] Lustre: Unmounted lustre-client [ 2950.499716] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 13:19:38 (1788715178) [ 2960.755728] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2962.518791] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2969.042076] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2975.597982] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2975.758967] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 2980.835427] Lustre: lustre-OST0000-osc-ffff9bd0c3333800: Connection to lustre-OST0000 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2992.462145] Lustre: Unmounted lustre-client [ 3015.834939] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 13:20:43 (1788715243) [ 3046.459302] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3048.235992] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3056.176115] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3081.232795] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3083.135499] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3089.934600] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3111.301484] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 3139.121553] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3139.626082] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3139.967297] Lustre: Unmounted lustre-client [ 3150.019373] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 13:22:57 (1788715377) [ 3160.292150] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3162.185243] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3168.851321] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3187.878034] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3201.498938] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 3229.373844] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3229.892774] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3232.227627] Lustre: lustre-OST0001-osc-ffff9bd0d15e7800: Connection to lustre-OST0001 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3237.514713] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3247.799497] Lustre: Unmounted lustre-client [ 3277.520622] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 13:25:05 (1788715505) [ 3287.870988] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3289.623748] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3295.792223] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3295.948481] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 3303.382109] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3303.854522] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3308.219110] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9bd0d815b800.ost_server_uuid 50 [ 3308.706207] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9bd0d815b800.ost_server_uuid in FULL state after 0 sec [ 3309.350068] Lustre: Unmounted lustre-client [ 3309.437978] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 3309.440377] Lustre: Skipped 3 previous similar messages [ 3309.824750] LustreError: 148998:0:(sec.c:403:import_sec_validate_get()) import 00000000468c7158 (NEW) with no sec [ 3347.984746] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 13:26:15 (1788715575) [ 3358.359469] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3360.176975] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3366.081829] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3371.932047] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3372.042177] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 3372.074350] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 3372.075628] Lustre: Skipped 1 previous similar message [ 3380.716159] Lustre: Unmounted lustre-client [ 3380.718405] Lustre: Skipped 1 previous similar message [ 3405.260552] Key type lgssc unregistered [ 3405.368741] LNet: 152405:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3405.371447] LNetError: 152405:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3405.380979] LNet: Removed LNI 192.168.202.21@tcp [ 3405.618052] Key type .llcrypt unregistered [ 3405.619539] Key type ._llcrypt unregistered [ 3410.818621] Key type ._llcrypt registered [ 3410.819681] Key type .llcrypt registered [ 3411.266347] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 13:27:19 (1788715639) [ 3411.658397] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3411.675323] alg: No test for adler32 (adler32-zlib) [ 3412.530873] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 3412.621588] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 3414.207194] Key type lgssc registered [ 3414.605924] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3433.173025] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3435.074474] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3441.501494] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3448.044741] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3448.177900] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 3453.423767] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 3455.020323] LustreError: lustre-MDT0001-mdc-ffff9bd0ce622800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 3455.026280] Lustre: lustre-MDT0001-mdc-ffff9bd0ce622800: Connection restored to 192.168.202.121@tcp (at 192.168.202.121@tcp) [ 3457.611468] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3458.088167] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 3464.165772] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 3464.867136] Lustre: Unmounted lustre-client [ 3484.360545] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 13:28:32 (1788715712) [ 3515.621654] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3517.421936] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3525.360793] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3525.519110] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 3535.945476] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3536.481336] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3548.643495] Lustre: lustre-OST0001-osc-ffff9bd0c557a000: Connection to lustre-OST0001 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3553.810161] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3564.093912] Lustre: Unmounted lustre-client [ 3587.744639] Key type lgssc unregistered [ 3587.877481] LNet: 161925:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3587.882214] LNetError: 161925:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3587.891314] LNet: Removed LNI 192.168.202.21@tcp [ 3588.177153] Key type .llcrypt unregistered [ 3588.178452] Key type ._llcrypt unregistered [ 3598.614853] Key type ._llcrypt registered [ 3598.615986] Key type .llcrypt registered [ 3599.063471] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 13:30:26 (1788715826) [ 3599.801948] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3599.817942] alg: No test for adler32 (adler32-zlib) [ 3600.671677] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 3600.757530] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 3602.343155] Key type lgssc registered [ 3602.711104] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3620.401250] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3622.205793] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3629.077616] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3630.267340] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 3667.791652] Lustre: Unmounted lustre-client [ 3686.962558] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3687.182476] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 3719.999130] Lustre: Unmounted lustre-client [ 3737.443861] Key type lgssc unregistered [ 3737.583503] LNet: 166348:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3737.587159] LNetError: 166348:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3737.595687] LNet: Removed LNI 192.168.202.21@tcp [ 3737.853676] Key type .llcrypt unregistered [ 3737.855059] Key type ._llcrypt unregistered [ 3742.992226] Key type ._llcrypt registered [ 3742.993599] Key type .llcrypt registered [ 3743.452521] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 13:32:51 (1788715971) [ 3744.145723] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3744.197117] alg: No test for adler32 (adler32-zlib) [ 3745.066960] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 3745.157400] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 3746.743197] Key type lgssc registered [ 3747.127192] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3764.996705] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3766.736087] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3773.063444] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3783.663788] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 3790.219138] Lustre: Unmounted lustre-client [ 3815.754020] Key type lgssc unregistered [ 3815.869575] LNet: 170085:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3815.871916] LNetError: 170085:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3815.880781] LNet: Removed LNI 192.168.202.21@tcp [ 3816.155050] Key type .llcrypt unregistered [ 3816.156379] Key type ._llcrypt unregistered [ 3820.164630] Key type ._llcrypt registered [ 3820.166054] Key type .llcrypt registered [ 3820.319830] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3820.327637] alg: No test for adler32 (adler32-zlib) [ 3821.195121] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 3821.286573] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 3822.871174] Key type lgssc registered [ 3823.263443] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3841.418847] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3843.326730] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3850.215501] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3860.980596] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 3863.934669] Lustre: Unmounted lustre-client [ 3880.798987] Key type lgssc unregistered [ 3880.912415] LNet: 173133:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3880.914502] LNetError: 173133:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3880.920597] LNet: Removed LNI 192.168.202.21@tcp [ 3881.147651] Key type .llcrypt unregistered [ 3881.148554] Key type ._llcrypt unregistered [ 3885.777652] Key type ._llcrypt registered [ 3885.778559] Key type .llcrypt registered [ 3886.202338] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 13:35:14 (1788716114) [ 3886.908330] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3886.914102] alg: No test for adler32 (adler32-zlib) [ 3887.770050] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 3887.852801] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 3889.431211] Key type lgssc registered [ 3889.804363] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3907.098091] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3908.934797] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3915.442684] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3926.000502] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 3932.410040] Lustre: Unmounted lustre-client [ 3957.769376] Key type lgssc unregistered [ 3957.880474] LNet: 176872:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3957.882807] LNetError: 176872:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3957.890713] LNet: Removed LNI 192.168.202.21@tcp [ 3958.178562] Key type .llcrypt unregistered [ 3958.180209] Key type ._llcrypt unregistered [ 3962.448221] Key type ._llcrypt registered [ 3962.449309] Key type .llcrypt registered [ 3962.686413] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3962.692239] alg: No test for adler32 (adler32-zlib) [ 3963.576405] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 3963.683222] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 3965.279167] Key type lgssc registered [ 3965.666430] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3983.708569] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3985.587263] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3992.232202] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4002.797875] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 4005.560145] Lustre: Unmounted lustre-client [ 4030.754816] Key type lgssc unregistered [ 4030.866375] LNet: 179923:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4030.868529] LNetError: 179923:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4030.877595] LNet: Removed LNI 192.168.202.21@tcp [ 4031.093093] Key type .llcrypt unregistered [ 4031.094676] Key type ._llcrypt unregistered [ 4035.762754] Key type ._llcrypt registered [ 4035.764298] Key type .llcrypt registered [ 4036.186276] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 13:37:44 (1788716264) [ 4042.723269] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4042.732962] alg: No test for adler32 (adler32-zlib) [ 4043.585504] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 4043.672208] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 4045.255185] Key type lgssc registered [ 4045.610561] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4073.975693] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4075.718905] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4083.565602] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4112.725656] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 13:39:00 (1788716340) [ 4122.432672] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4124.089328] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4130.497342] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4141.040950] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 4143.016150] Lustre: Unmounted lustre-client [ 4160.364237] Key type lgssc unregistered [ 4160.481658] LNet: 187006:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4160.484525] LNetError: 187006:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4160.495069] LNet: Removed LNI 192.168.202.21@tcp [ 4160.743244] Key type .llcrypt unregistered [ 4160.744356] Key type ._llcrypt unregistered [ 4165.768520] Key type ._llcrypt registered [ 4165.769399] Key type .llcrypt registered [ 4166.229632] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 4166.762266] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 13:39:54 (1788716394) [ 4172.329322] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4172.357048] alg: No test for adler32 (adler32-zlib) [ 4173.208484] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 4173.293392] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 4174.879150] Key type lgssc registered [ 4175.298626] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4233.932696] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4237.581186] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4259.492887] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4273.805221] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4275.076925] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 4284.130404] Lustre: Unmounted lustre-client [ 4361.590540] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4364.544653] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4377.280713] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4403.252983] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 13:43:50 (1788716630) [ 4404.172583] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 4405.072347] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 13:43:52 (1788716632) [ 4459.917514] Key type lgssc unregistered [ 4460.078223] LNet: 198401:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4460.088414] LNetError: 198401:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4460.102630] LNet: Removed LNI 192.168.202.21@tcp [ 4460.528097] Key type .llcrypt unregistered [ 4460.529130] Key type ._llcrypt unregistered [ 4468.303757] Key type ._llcrypt registered [ 4468.305451] Key type .llcrypt registered [ 4468.896176] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 13:44:56 (1788716696) [ 4497.149426] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4497.174397] alg: No test for adler32 (adler32-zlib) [ 4498.171255] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 4498.361769] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 4500.023466] Key type lgssc registered [ 4501.167316] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4553.001708] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4562.248154] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4599.969192] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4634.851619] Key type lgssc unregistered [ 4635.095801] LNet: 202126:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4635.105983] LNetError: 202126:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4635.136915] LNet: Removed LNI 192.168.202.21@tcp [ 4635.761633] Key type .llcrypt unregistered [ 4635.764591] Key type ._llcrypt unregistered [ 4652.791473] Key type ._llcrypt registered [ 4652.798242] Key type .llcrypt registered [ 4654.281790] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 13:48:01 (1788716881) [ 4656.927911] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4657.114917] alg: No test for adler32 (adler32-zlib) [ 4658.270310] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 4658.546917] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 4660.279961] Key type lgssc registered [ 4661.624888] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4712.841360] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4718.765376] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4741.622110] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4744.309068] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 4759.536961] Lustre: lustre-MDT0000-mdc-ffff9bd0d15b9800: Connection to lustre-MDT0000 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4769.776216] Lustre: lustre-MDT0001-mdc-ffff9bd0d15b9800: Connection to lustre-MDT0001 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4780.897409] Lustre: 203114:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788716993/real 1788716993] req@000000006b740873 x1875605597537024/t0(0) o400->MGC192.168.202.121@tcp@192.168.202.121@tcp:26/25 lens 224/224 e 0 to 1 dl 1788717009 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4780.920783] LustreError: MGC192.168.202.121@tcp: Connection to MGS (at 192.168.202.121@tcp) was lost; in progress operations using this service will fail [ 4791.211101] Lustre: Evicted from MGS (at 192.168.202.121@tcp) after server handle changed from 0xaf68e27a947917bb to 0xaf68e27a94793305 [ 4791.221340] Lustre: MGC192.168.202.121@tcp: Connection restored to 192.168.202.121@tcp (at 192.168.202.121@tcp) [ 4810.749744] LustreError: lustre-MDT0000-mdc-ffff9bd0d15b9800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 4811.004349] Lustre: lustre-MDT0000-mdc-ffff9bd0d15b9800: Connection restored to 192.168.202.121@tcp (at 192.168.202.121@tcp) [ 4824.526839] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4828.261974] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 4836.893969] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4838.973319] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 4843.253495] Lustre: Unmounted lustre-client [ 4872.859217] Key type lgssc unregistered [ 4873.134344] LNet: 207066:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4873.149637] LNetError: 207066:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4874.219893] LNet: Removed LNI 192.168.202.21@tcp [ 4875.068648] Key type .llcrypt unregistered [ 4875.074095] Key type ._llcrypt unregistered [ 4894.083528] Key type ._llcrypt registered [ 4894.088357] Key type .llcrypt registered [ 4895.464509] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 13:52:02 (1788717122) [ 4897.714625] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4897.842147] alg: No test for adler32 (adler32-zlib) [ 4898.992669] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 4899.293238] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 4900.999208] Key type lgssc registered [ 4902.370381] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4957.179739] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4963.664115] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5019.044309] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5024.086809] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5061.047665] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5082.954534] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5123.634618] Key type lgssc unregistered [ 5123.912979] LNet: 212355:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5123.920180] LNetError: 212355:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5123.955064] LNet: Removed LNI 192.168.202.21@tcp [ 5124.670254] Key type .llcrypt unregistered [ 5124.676326] Key type ._llcrypt unregistered [ 5173.845452] Key type ._llcrypt registered [ 5173.847970] Key type .llcrypt registered [ 5175.853480] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 13:56:42 (1788717402) [ 5219.626243] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5219.665060] alg: No test for adler32 (adler32-zlib) [ 5221.048885] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 5221.473484] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 5223.295205] Key type lgssc registered [ 5225.745653] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5331.280501] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5339.093507] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5370.809328] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5427.601162] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 14:00:53 (1788717653) [ 5437.389562] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 14:01:04 (1788717664) [ 5478.649699] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5485.556755] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5510.432822] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5522.492782] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 5528.791480] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [ 5531.201512] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 5533.131567] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 5535.002250] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [ 5537.764307] Lustre: lustre-MDT0000-mdc-ffff9bd0d02ee800: Connection to lustre-MDT0000 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5554.143122] Lustre: 214251:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788717766/real 1788717766] req@00000000802b91ae x1875606187812864/t0(0) o400->MGC192.168.202.121@tcp@192.168.202.121@tcp:26/25 lens 224/224 e 0 to 1 dl 1788717782 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5554.189343] LustreError: MGC192.168.202.121@tcp: Connection to MGS (at 192.168.202.121@tcp) was lost; in progress operations using this service will fail [ 5564.403704] Lustre: Evicted from MGS (at 192.168.202.121@tcp) after server handle changed from 0x92f04168f086a4e0 to 0x92f04168f086a65a [ 5564.413689] Lustre: MGC192.168.202.121@tcp: Connection restored to 192.168.202.121@tcp (at 192.168.202.121@tcp) [ 5571.510165] Lustre: lustre-MDT0000-mdc-ffff9bd0d02ee800: Connection restored to 192.168.202.121@tcp (at 192.168.202.121@tcp) [ 5582.300200] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5584.348295] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 5588.973322] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [ 5595.122168] Lustre: lustre-MDT0000-mdc-ffff9bd0d02ee800: Connection to lustre-MDT0000 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5611.489775] Lustre: 214250:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788717823/real 1788717823] req@00000000909e2b3b x1875606187822592/t0(0) o400->MGC192.168.202.121@tcp@192.168.202.121@tcp:26/25 lens 224/224 e 0 to 1 dl 1788717839 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5611.523185] LustreError: MGC192.168.202.121@tcp: Connection to MGS (at 192.168.202.121@tcp) was lost; in progress operations using this service will fail [ 5620.715454] Lustre: Evicted from MGS (at 192.168.202.121@tcp) after server handle changed from 0x92f04168f086a65a to 0x92f04168f086a971 [ 5620.747057] Lustre: MGC192.168.202.121@tcp: Connection restored to 192.168.202.121@tcp (at 192.168.202.121@tcp) [ 5628.853277] Lustre: lustre-MDT0000-mdc-ffff9bd0d02ee800: Connection restored to 192.168.202.121@tcp (at 192.168.202.121@tcp) [ 5637.277631] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5639.063583] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 5643.569028] Lustre: Unmounted lustre-client [ 5715.900979] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5722.410831] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5746.805808] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5747.604368] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 5750.173479] Lustre: Unmounted lustre-client [ 5781.316893] Key type lgssc unregistered [ 5781.493821] LNet: 224851:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5781.503508] LNetError: 224851:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5781.524330] LNet: Removed LNI 192.168.202.21@tcp [ 5782.122704] Key type .llcrypt unregistered [ 5782.125686] Key type ._llcrypt unregistered [ 5802.128408] Key type ._llcrypt registered [ 5802.131538] Key type .llcrypt registered [ 5804.203303] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 14:07:10 (1788718030) [ 5808.918767] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5809.050232] alg: No test for adler32 (adler32-zlib) [ 5810.195705] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 5810.551599] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 5812.271253] Key type lgssc registered [ 5813.643410] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5867.405121] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5873.448112] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5893.552444] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5896.592213] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 5906.811615] Lustre: Unmounted lustre-client [ 5973.997718] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5980.167951] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6003.191710] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6003.973478] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 6012.887611] Lustre: Unmounted lustre-client [ 6085.422161] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6093.946281] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6119.357468] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6130.760382] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 6134.218049] Lustre: Unmounted lustre-client [ 6164.193322] Key type lgssc unregistered [ 6164.640761] LNet: 231891:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6164.648191] LNetError: 231891:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6164.699475] LNet: Removed LNI 192.168.202.21@tcp [ 6165.579174] Key type .llcrypt unregistered [ 6165.582790] Key type ._llcrypt unregistered [ 6181.945797] Key type ._llcrypt registered [ 6181.953925] Key type .llcrypt registered [ 6183.350897] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 14:13:30 (1788718410) [ 6186.610915] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6186.740230] alg: No test for adler32 (adler32-zlib) [ 6187.948421] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 6188.278650] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 6189.927233] Key type lgssc registered [ 6191.862950] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6237.272685] Key type lgssc unregistered [ 6237.495489] LNet: 234209:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6237.500364] LNetError: 234209:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6237.521301] LNet: Removed LNI 192.168.202.21@tcp [ 6238.186795] Key type .llcrypt unregistered [ 6238.190305] Key type ._llcrypt unregistered [ 6266.780853] Key type ._llcrypt registered [ 6266.800060] Key type .llcrypt registered [ 6267.123507] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6267.140238] alg: No test for adler32 (adler32-zlib) [ 6268.240487] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 6268.414199] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 6270.160377] Key type lgssc registered [ 6271.268460] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6359.082956] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6365.467227] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6392.431292] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6434.596600] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [ 6436.257831] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 14:17:43 (1788718663) [ 6475.339959] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6481.521638] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6504.540394] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6527.989097] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6529.672810] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 6534.657527] Lustre: lustre-OST0001-osc-ffff9bd0c56c5800: Connection to lustre-OST0001 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6542.221646] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6552.554109] Lustre: Unmounted lustre-client [ 6594.533359] Key type lgssc unregistered [ 6594.747449] LNet: 241317:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6594.753393] LNetError: 241317:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6594.765981] LNet: Removed LNI 192.168.202.21@tcp [ 6595.583473] Key type .llcrypt unregistered [ 6595.585776] Key type ._llcrypt unregistered [ 6629.315449] Key type ._llcrypt registered [ 6629.319117] Key type .llcrypt registered [ 6631.273506] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 14:20:57 (1788718857) [ 6646.503374] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6646.519476] alg: No test for adler32 (adler32-zlib) [ 6647.726132] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 6648.030985] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 6649.743274] Key type lgssc registered [ 6651.185082] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6701.303391] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6706.981219] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6730.925455] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 14:22:37 (1788718957) [ 6766.375457] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6772.695319] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6796.528534] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6799.390287] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 6813.748581] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 6817.347074] Lustre: Unmounted lustre-client [ 6919.165537] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6925.067709] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6975.972961] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6976.676873] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 6979.776209] Lustre: Unmounted lustre-client [ 7007.388666] Key type lgssc unregistered [ 7007.698821] LNet: 249003:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7007.713726] LNetError: 249003:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7007.755633] LNet: Removed LNI 192.168.202.21@tcp [ 7008.595439] Key type .llcrypt unregistered [ 7008.598742] Key type ._llcrypt unregistered [ 7036.880616] Key type ._llcrypt registered [ 7036.898820] Key type .llcrypt registered [ 7037.488645] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7037.501694] alg: No test for adler32 (adler32-zlib) [ 7038.567814] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 7038.923872] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 7040.655187] Key type lgssc registered [ 7041.644663] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7128.618956] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7134.155956] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7157.808709] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7207.158897] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [ 7209.128650] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 14:30:35 (1788719435) [ 7241.392155] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7246.329424] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7266.337479] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7270.157490] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 7273.120549] Lustre: Unmounted lustre-client [ 7304.482769] Key type lgssc unregistered [ 7304.768892] LNet: 255631:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7304.786922] LNetError: 255631:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7304.810055] LNet: Removed LNI 192.168.202.21@tcp [ 7305.656681] Key type .llcrypt unregistered [ 7305.659830] Key type ._llcrypt unregistered [ 7318.591219] Key type ._llcrypt registered [ 7318.593929] Key type .llcrypt registered [ 7319.595511] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [ 7320.734611] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 14:32:28 (1788719548) [ 7329.701496] Key type .llcrypt unregistered [ 7329.703383] Key type ._llcrypt unregistered [ 7338.984147] Key type ._llcrypt registered [ 7338.987473] Key type .llcrypt registered [ 7339.455856] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7339.472935] alg: No test for adler32 (adler32-zlib) [ 7340.596669] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 7340.914368] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 7342.671456] Key type lgssc registered [ 7343.730312] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7393.724403] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7408.989244] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 7411.260102] Lustre: Unmounted lustre-client [ 7429.751985] Key type lgssc unregistered [ 7429.932658] LNet: 259076:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7429.941683] LNetError: 259076:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7429.958377] LNet: Removed LNI 192.168.202.21@tcp [ 7430.645814] Key type .llcrypt unregistered [ 7430.648219] Key type ._llcrypt unregistered [ 7443.035509] Key type ._llcrypt registered [ 7443.037602] Key type .llcrypt registered [ 7444.096281] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 14:34:31 (1788719671) [ 7445.870491] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7445.893094] alg: No test for adler32 (adler32-zlib) [ 7447.044862] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 7447.373693] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 7449.047252] Key type lgssc registered [ 7450.194502] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7535.439816] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7570.149687] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7575.716146] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7578.672895] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 7581.274308] Lustre: Unmounted lustre-client [ 7600.710647] Key type lgssc unregistered [ 7601.081936] LNet: 262610:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7601.097200] LNetError: 262610:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7601.124279] LNet: Removed LNI 192.168.202.21@tcp [ 7601.988964] Key type .llcrypt unregistered [ 7601.992564] Key type ._llcrypt unregistered [ 7618.864693] Key type ._llcrypt registered [ 7618.868429] Key type .llcrypt registered [ 7620.285335] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 14:37:27 (1788719847) [ 7622.961318] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7623.000276] alg: No test for adler32 (adler32-zlib) [ 7624.178649] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 7624.528450] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 7626.263231] Key type lgssc registered [ 7627.333944] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7678.613550] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7684.903872] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7707.943519] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7709.653293] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 7714.810672] Lustre: lustre-MDT0000-mdc-ffff9bd0d15e0000: Connection to lustre-MDT0000 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7719.865667] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7719.895438] LustreError: 265766:0:(file.c:6167:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [ 7720.340118] LustreError: MGC192.168.202.121@tcp: Connection to MGS (at 192.168.202.121@tcp) was lost; in progress operations using this service will fail [ 7720.398438] Lustre: Unmounted lustre-client [ 7740.908197] Key type lgssc unregistered [ 7741.348693] LNet: 266178:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7741.363605] LNetError: 266178:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7741.415339] LNet: Removed LNI 192.168.202.21@tcp [ 7742.242882] Key type .llcrypt unregistered [ 7742.247452] Key type ._llcrypt unregistered [ 7758.694235] Key type ._llcrypt registered [ 7758.696797] Key type .llcrypt registered [ 7760.057147] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 14:39:46 (1788719986) [ 7762.274206] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7762.291650] alg: No test for adler32 (adler32-zlib) [ 7763.477439] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 7763.596253] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 7765.240633] Key type lgssc registered [ 7765.985545] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7819.373438] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7825.645356] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7849.891246] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7852.055904] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 7857.132665] Lustre: lustre-MDT0001-mdc-ffff9bd0c2af6000: Connection to lustre-MDT0001 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7862.934798] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7865.218710] Lustre: Unmounted lustre-client [ 7888.798229] Key type lgssc unregistered [ 7889.153153] LNet: 269754:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7889.163334] LNetError: 269754:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7889.190759] LNet: Removed LNI 192.168.202.21@tcp [ 7890.290209] Key type .llcrypt unregistered [ 7890.294146] Key type ._llcrypt unregistered [ 7908.138450] Key type ._llcrypt registered [ 7908.140588] Key type .llcrypt registered [ 7909.845307] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 14:42:16 (1788720136) [ 7931.083241] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7931.230668] alg: No test for adler32 (adler32-zlib) [ 7932.398137] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 7932.752692] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 7934.535819] Key type lgssc registered [ 7935.921514] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8046.325957] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8048.188924] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 8093.672077] Lustre: lustre-MDT0001-mdc-ffff9bd0c6298800: Connection to lustre-MDT0001 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8101.710732] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 8112.055236] Lustre: Unmounted lustre-client [ 8140.117205] Key type lgssc unregistered [ 8140.474597] LNet: 275538:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8140.489716] LNetError: 275538:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8140.516475] LNet: Removed LNI 192.168.202.21@tcp [ 8141.528104] Key type .llcrypt unregistered [ 8141.544637] Key type ._llcrypt unregistered [ 8159.595627] Key type ._llcrypt registered [ 8159.601324] Key type .llcrypt registered [ 8160.852309] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 14:46:27 (1788720387) [ 8163.083701] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [ 8165.145665] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 14:46:31 (1788720391) [ 8166.900247] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [ 8168.748164] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 14:46:35 (1788720395) [ 8170.675403] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [ 8172.297884] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 14:46:39 (1788720399) [ 8173.944928] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [ 8175.924702] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 14:46:42 (1788720402) [ 8178.180207] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [ 8180.312031] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 14:46:47 (1788720407) [ 8190.567294] Key type .llcrypt unregistered [ 8190.571591] Key type ._llcrypt unregistered [ 8199.519349] Key type ._llcrypt registered [ 8199.521493] Key type .llcrypt registered [ 8200.175859] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8200.200675] alg: No test for adler32 (adler32-zlib) [ 8201.431122] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 8201.767424] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 8203.479211] Key type lgssc registered [ 8205.134199] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8281.152751] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8287.202096] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8310.904199] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8312.753600] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 8315.549726] Lustre: Unmounted lustre-client [ 8353.029540] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 14:49:39 (1788720579) [ 8388.068516] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8393.482884] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8430.305784] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8436.281536] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [ 8437.408799] Lustre: Unmounted lustre-client [ 8478.323722] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 14:51:44 (1788720704) [ 8489.503892] LNet: 1 local NIs in recovery (showing 1): 192.168.202.21@tcp [ 9129.013437] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9135.740889] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9171.314780] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9279.935560] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 15:05:06 (1788721506) [ 9295.264920] Key type lgssc unregistered [ 9295.561986] LNet: 289073:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9295.567566] LNetError: 289073:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9296.632430] LNet: Removed LNI 192.168.202.21@tcp [ 9297.798060] Key type .llcrypt unregistered [ 9297.800662] Key type ._llcrypt unregistered [ 9418.781984] Key type ._llcrypt registered [ 9418.805660] Key type .llcrypt registered [ 9419.272233] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9419.293820] alg: No test for adler32 (adler32-zlib) [ 9420.513564] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 9420.859219] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 9422.567231] Key type lgssc registered [ 9424.175530] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9520.644522] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9528.340235] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9551.220823] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9581.788817] Key type lgssc unregistered [ 9582.018805] LNet: 292762:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9582.029689] LNetError: 292762:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9582.041419] LNet: Removed LNI 192.168.202.21@tcp [ 9582.695696] Key type .llcrypt unregistered [ 9582.699705] Key type ._llcrypt unregistered [ 9620.517618] Key type ._llcrypt registered [ 9620.521071] Key type .llcrypt registered [ 9621.611762] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 15:10:48 (1788721848) [ 9643.271987] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9643.282541] alg: No test for adler32 (adler32-zlib) [ 9644.536490] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 9644.799211] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 9646.495238] Key type lgssc registered [ 9647.988623] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9704.341311] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 15:12:11 (1788721931) [ 9718.004513] Key type lgssc unregistered [ 9718.374377] LNet: 296315:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9718.386172] LNetError: 296315:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9718.406158] LNet: Removed LNI 192.168.202.21@tcp [ 9719.313705] Key type .llcrypt unregistered [ 9719.316191] Key type ._llcrypt unregistered [ 9732.285768] Key type ._llcrypt registered [ 9732.308582] Key type .llcrypt registered [ 9732.694578] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9732.707512] alg: No test for adler32 (adler32-zlib) [ 9733.924219] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [ 9734.204305] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 9735.887199] Key type lgssc registered [ 9736.848590] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9798.684932] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9806.211982] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9832.306278] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10162.518891] LNet: Added LNI 192.168.202.21@tcp50 [8/256/0/180] [10164.882223] LNet: Removed LNI 192.168.202.21@tcp [10167.419955] Lustre: 300726:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff9bd0c77f6800: config has no valid NIDs, force dynamic NIDs [10168.647576] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [10168.872185] Lustre: Unmounted lustre-client [10172.032615] Key type lgssc unregistered [10172.468539] LNet: 300983:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10172.473351] LNetError: 300983:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10172.492194] LNet: Removed LNI 192.168.202.21@tcp50 [10173.141691] Key type .llcrypt unregistered [10173.148448] Key type ._llcrypt unregistered [10192.096215] Key type ._llcrypt registered [10192.098336] Key type .llcrypt registered [10192.565907] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10192.583387] alg: No test for adler32 (adler32-zlib) [10193.615732] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [10193.857341] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [10195.599210] Key type lgssc registered [10197.175455] Lustre: Echo OBD driver; http://www.lustre.org/ [10260.347696] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10266.642837] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10274.309313] LNet: Added LNI 192.168.202.21@tcp50 [8/256/0/180] [10274.801575] LNet: Removed LNI 192.168.202.21@tcp [10275.939805] Lustre: 303681:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff9bd0c2af6800: config has no valid NIDs, force dynamic NIDs [10277.150616] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [10277.816823] Lustre: Unmounted lustre-client [10312.916488] Key type lgssc unregistered [10313.380531] LNet: 304551:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10313.392250] LNetError: 304551:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10313.429800] LNet: Removed LNI 192.168.202.21@tcp50 [10314.432254] Key type .llcrypt unregistered [10314.435497] Key type ._llcrypt unregistered [10325.383543] Key type ._llcrypt registered [10325.387047] Key type .llcrypt registered [10327.335944] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 15:22:33 (1788722553) [10345.453424] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10345.481041] alg: No test for adler32 (adler32-zlib) [10346.683278] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [10347.043801] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [10348.719412] Key type lgssc registered [10350.184609] Lustre: Echo OBD driver; http://www.lustre.org/ [10436.778110] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10442.587952] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10467.123541] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10499.624684] Key type lgssc unregistered [10499.842801] LNet: 309292:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10499.847206] LNetError: 309292:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10499.857912] LNet: Removed LNI 192.168.202.21@tcp [10500.500123] Key type .llcrypt unregistered [10500.502970] Key type ._llcrypt unregistered [10513.632874] Key type ._llcrypt registered [10513.634716] Key type .llcrypt registered [10515.183737] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 15:25:41 (1788722741) [10517.872344] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10517.987431] alg: No test for adler32 (adler32-zlib) [10519.167735] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [10519.591656] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [10521.367199] Key type lgssc registered [10522.863775] Lustre: Echo OBD driver; http://www.lustre.org/ [10579.497791] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10585.957424] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10608.908942] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10611.822082] Lustre: Mounted lustre-client - version 2.17.57_103_g794a134 [10675.328370] Lustre: Mounted fs15246-client - version 2.17.57_103_g794a134 [10691.253391] Lustre: Unmounted fs15246-client [10721.355605] Lustre: Unmounted lustre-client [10749.449443] Key type lgssc unregistered [10749.643846] LNet: 314150:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10749.648646] LNetError: 314150:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10749.671757] LNet: Removed LNI 192.168.202.21@tcp [10750.360840] Key type .llcrypt unregistered [10750.366849] Key type ._llcrypt unregistered [10762.081468] Key type ._llcrypt registered [10762.086059] Key type .llcrypt registered [10763.440941] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 15:29:50 (1788722990) [10766.112986] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10766.171525] alg: No test for adler32 (adler32-zlib) [10767.599697] Lustre: Lustre: Build Version: 2.17.57_103_g794a134 [10768.012152] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [10769.775233] Key type lgssc registered [10771.239438] Lustre: Echo OBD driver; http://www.lustre.org/ [10909.267269] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10915.779290] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10943.795176] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11058.164652] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 10769 sec ========= 15:34:45 (1788723285) [11060.018297] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 15:34:46 (1788723286) === [11064.523348] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 15:34:51 (1788723291) === [11102.337723] Key type lgssc unregistered [11102.701822] LNet: 323413:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11102.715280] LNetError: 323413:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11102.739343] LNet: Removed LNI 192.168.202.21@tcp [11103.737915] Key type .llcrypt unregistered [11103.740956] Key type ._llcrypt unregistered