[ 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 436798214 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524584K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001010] APIC: Switch to symmetric I/O mode setup [ 0.003068] x2apic enabled [ 0.004008] Switched APIC routing to physical x2apic. [ 0.005012] kvm-guest: setup PV IPIs [ 0.007772] ..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.008021] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009012] pid_max: default: 32768 minimum: 301 [ 0.010152] LSM: Security Framework initializing [ 0.011056] Yama: becoming mindful. [ 0.012036] SELinux: Initializing. [ 0.013060] *** VALIDATE selinux *** [ 0.021681] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.025467] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.026168] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027097] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028096] *** VALIDATE tmpfs *** [ 0.029383] *** VALIDATE proc *** [ 0.030199] *** VALIDATE cgroup *** [ 0.031008] *** VALIDATE cgroup2 *** [ 0.032233] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.033126] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.034005] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.035031] Spectre V2 : User space: Vulnerable [ 0.036013] Speculative Store Bypass: Vulnerable [ 0.038793] debug: unmapping init [mem 0xffffffffa1859000-0xffffffffa1860fff] [ 0.040160] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.041721] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.042020] ... version: 2 [ 0.043012] ... bit width: 48 [ 0.044012] ... generic registers: 4 [ 0.045012] ... value mask: 0000ffffffffffff [ 0.046013] ... max period: 00007fffffffffff [ 0.047012] ... fixed-purpose events: 3 [ 0.048012] ... event mask: 000000070000000f [ 0.049284] rcu: Hierarchical SRCU implementation. [ 0.051473] smp: Bringing up secondary CPUs ... [ 0.052561] x86: Booting SMP configuration: [ 0.053026] .... node #0, CPUs: #1 #2 #3 [ 0.058019] smp: Brought up 1 node, 4 CPUs [ 0.060013] smpboot: Max logical packages: 1 [ 0.061033] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.137400] node 0 deferred pages initialised in 73ms [ 0.142107] devtmpfs: initialized [ 0.143205] x86/mm: Memory block size: 128MB [ 0.145731] gcov: version magic: 0x41383552 [ 0.147449] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.150080] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.151251] pinctrl core: initialized pinctrl subsystem [ 0.152191] [ 0.152719] ************************************************************* [ 0.153012] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.154012] ** ** [ 0.155040] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.156015] ** ** [ 0.157014] ** This means that this kernel is built to expose internal ** [ 0.158009] ** IOMMU data structures, which may compromise security on ** [ 0.159015] ** your system. ** [ 0.160012] ** ** [ 0.161012] ** If you see this message and you are not debugging the ** [ 0.162012] ** kernel, report this immediately to your vendor! ** [ 0.163013] ** ** [ 0.164015] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.165012] ************************************************************* [ 0.166711] NET: Registered protocol family 16 [ 0.167426] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.168060] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.169077] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.170447] cpuidle: using governor menu [ 0.172297] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.175400] PCI: Using configuration type 1 for base access [ 0.177093] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.186105] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.187040] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.189032] cryptd: max_cpu_qlen set to 1000 [ 0.190240] ACPI: Added _OSI(Module Device) [ 0.191014] ACPI: Added _OSI(Processor Device) [ 0.192017] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.193027] ACPI: Added _OSI(Processor Aggregator Device) [ 0.197331] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.200061] ACPI: Interpreter enabled [ 0.201054] ACPI: PM: (supports S0 S3 S4 S5) [ 0.202018] ACPI: Using IOAPIC for interrupt routing [ 0.203096] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.204410] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.213129] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.214038] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.215020] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.216063] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.218259] acpiphp: Slot [2] registered [ 0.219099] acpiphp: Slot [5] registered [ 0.220198] acpiphp: Slot [6] registered [ 0.221111] acpiphp: Slot [3] registered [ 0.222088] acpiphp: Slot [4] registered [ 0.223104] acpiphp: Slot [7] registered [ 0.224085] acpiphp: Slot [8] registered [ 0.225119] acpiphp: Slot [9] registered [ 0.226097] acpiphp: Slot [10] registered [ 0.227105] acpiphp: Slot [11] registered [ 0.228104] acpiphp: Slot [12] registered [ 0.229087] acpiphp: Slot [13] registered [ 0.230089] acpiphp: Slot [14] registered [ 0.231086] acpiphp: Slot [15] registered [ 0.232085] acpiphp: Slot [16] registered [ 0.233101] acpiphp: Slot [17] registered [ 0.234116] acpiphp: Slot [18] registered [ 0.235089] acpiphp: Slot [19] registered [ 0.236089] acpiphp: Slot [20] registered [ 0.237088] acpiphp: Slot [21] registered [ 0.238071] acpiphp: Slot [22] registered [ 0.239094] acpiphp: Slot [23] registered [ 0.240092] acpiphp: Slot [24] registered [ 0.241109] acpiphp: Slot [25] registered [ 0.242093] acpiphp: Slot [26] registered [ 0.243098] acpiphp: Slot [27] registered [ 0.244127] acpiphp: Slot [28] registered [ 0.245102] acpiphp: Slot [29] registered [ 0.246100] acpiphp: Slot [30] registered [ 0.247129] acpiphp: Slot [31] registered [ 0.248055] PCI host bridge to bus 0000:00 [ 0.249018] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.250022] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.251030] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.252024] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.253034] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.254026] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.255167] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.257050] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.259232] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.264016] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.267060] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.268018] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.269023] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.270015] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.271510] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.274796] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.277033] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.279841] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.284018] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.295014] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.299015] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.303993] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.309015] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.314015] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.330014] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.339337] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.343873] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.348013] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.362896] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.371996] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.375396] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.378395] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.382398] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.384172] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.389032] iommu: Default domain type: Passthrough [ 0.390382] SCSI subsystem initialized [ 0.391028] ACPI: bus type USB registered [ 0.393117] usbcore: registered new interface driver usbfs [ 0.395080] usbcore: registered new interface driver hub [ 0.396082] usbcore: registered new device driver usb [ 0.398170] pps_core: LinuxPPS API ver. 1 registered [ 0.400011] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.403064] PTP clock support registered [ 0.405074] EDAC MC: Ver: 3.0.0 [ 0.406362] PCI: Using ACPI for IRQ routing [ 0.407680] NetLabel: Initializing [ 0.408010] NetLabel: domain hash size = 128 [ 0.409008] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.410109] NetLabel: unlabeled traffic allowed by default [ 0.412057] vgaarb: loaded [ 0.414278] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.415013] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.422055] clocksource: Switched to clocksource kvm-clock [ 0.528661] VFS: Disk quotas dquot_6.6.0 [ 0.529927] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.531682] *** VALIDATE ramfs *** [ 0.532698] *** VALIDATE hugetlbfs *** [ 0.533911] pnp: PnP ACPI init [ 0.535753] pnp: PnP ACPI: found 6 devices [ 0.553902] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.556328] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.557815] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.559556] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.561372] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.563085] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.565130] NET: Registered protocol family 2 [ 0.567142] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.571223] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.573948] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.578296] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.581260] TCP: Hash tables configured (established 65536 bind 65536) [ 0.583419] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.585760] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.587751] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.589963] NET: Registered protocol family 1 [ 0.591880] RPC: Registered named UNIX socket transport module. [ 0.593390] RPC: Registered udp transport module. [ 0.594546] RPC: Registered tcp transport module. [ 0.595723] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.597380] NET: Registered protocol family 44 [ 0.598574] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.600131] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.601548] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.603175] PCI: CLS 0 bytes, default 64 [ 0.604373] Unpacking initramfs... [ 2.062697] debug: unmapping init [mem 0xffff9e18bcc64000-0xffff9e18bffcffff] [ 2.066875] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.069166] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.072392] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.572078] Initialise system trusted keyrings [ 2.573811] Key type blacklist registered [ 2.575709] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.586229] zbud: loaded [ 2.589635] *** VALIDATE nfs *** [ 2.590985] *** VALIDATE nfs4 *** [ 2.592686] pstore: using deflate compression [ 2.596495] Platform Keyring initialized [ 2.693297] NET: Registered protocol family 38 [ 2.695752] Key type asymmetric registered [ 2.697259] Asymmetric key parser 'x509' registered [ 2.699097] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.702076] io scheduler mq-deadline registered [ 2.703359] io scheduler kyber registered [ 2.704883] io scheduler bfq registered [ 2.707095] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.709710] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.711970] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.714452] ACPI: Power Button [PWRF] [ 2.719548] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.728764] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.742970] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.768864] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.795843] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.800900] Non-volatile memory driver v1.3 [ 2.802221] Linux agpgart interface v0.103 [ 2.832894] virtio_blk virtio1: [vda] 146152 512-byte logical blocks (74.8 MB/71.4 MiB) [ 2.835042] vda: detected capacity change from 0 to 74829824 [ 2.852948] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.855232] vdb: detected capacity change from 0 to 1073741824 [ 2.862329] libphy: Fixed MDIO Bus: probed [ 2.867943] usbcore: registered new interface driver usbserial_generic [ 2.871306] usbserial: USB Serial support registered for generic [ 2.873270] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.876778] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.878731] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.881315] mousedev: PS/2 mouse device common for all mice [ 2.884467] rtc_cmos 00:05: RTC can wake from S4 [ 2.887845] rtc_cmos 00:05: registered as rtc0 [ 2.888648] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.889715] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.895762] intel_pstate: CPU model not supported [ 2.898657] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.903967] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.903968] hid: raw HID events driver (C) Jiri Kosina [ 2.909497] usbcore: registered new interface driver usbhid [ 2.911621] usbhid: USB HID core driver [ 2.912990] drop_monitor: Initializing network drop monitor service [ 2.915451] Initializing XFRM netlink socket [ 2.917878] NET: Registered protocol family 10 [ 2.920918] Segment Routing with IPv6 [ 2.922455] NET: Registered protocol family 17 [ 2.924440] mpls_gso: MPLS GSO support [ 2.929422] RAS: Correctable Errors collector initialized. [ 2.931463] AVX version of gcm_enc/dec engaged. [ 2.933074] AES CTR mode by8 optimization enabled [ 3.003287] sched_clock: Marking stable (3003257084, 0)->(3975530045, -972272961) [ 3.006892] registered taskstats version 1 [ 3.008766] Loading compiled-in X.509 certificates [ 3.010842] zswap: loaded using pool lzo/zbud [ 3.034700] Key type big_key registered [ 3.046059] Key type encrypted registered [ 3.047539] ima: No TPM chip found, activating TPM-bypass! [ 3.048945] ima: Allocated hash algorithm: sha1 [ 3.050071] ima: No architecture policies found [ 3.051229] evm: Initialising EVM extended attributes: [ 3.052424] evm: security.selinux [ 3.053467] evm: security.ima [ 3.054254] evm: security.capability [ 3.055073] evm: HMAC attrs: 0x1 [ 3.056992] rtc_cmos 00:05: setting system clock to 2026-08-27 08:08:15 UTC (1787818095) [ 3.062603] debug: unmapping init [mem 0xffffffffa2803000-0xffffffffa29fffff] [ 3.065176] debug: unmapping init [mem 0xffffffffa1582000-0xffffffffa1858fff] [ 3.075071] Write protecting the kernel read-only data: 28672k [ 3.078100] debug: unmapping init [mem 0xffffffff9fc03000-0xffffffff9fdfffff] [ 3.080471] debug: unmapping init [mem 0xffffffffa0514000-0xffffffffa05fffff] [ 3.109689] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 3.116869] systemd[1]: Detected virtualization kvm. [ 3.118848] systemd[1]: Detected architecture x86-64. [ 3.120777] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.146532] systemd[1]: No hostname configured. [ 3.148376] systemd[1]: Set hostname to . [ 3.150574] random: systemd: uninitialized urandom read (16 bytes read) [ 3.153192] systemd[1]: Initializing machine ID from random generator. [ 3.189723] random: ln: uninitialized urandom read (6 bytes read) [ 3.266579] random: systemd: uninitialized urandom read (16 bytes read) [ 3.269534] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 3.274344] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ 3.278757] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on udev Kernel Socket. [ OK ] Started Memstrack Anylazing Service. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Swap. Starting Apply Kernel Variables... [ OK ] Reached target Slices. Starting Journal Service... Starting Setup Virtual Console... [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 3.825420] device-mapper: uevent: version 1.0.3 [ 3.827690] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 4.459672] virtio_net virtio0 ens2: renamed from eth0 [ 4.470398] scsi host0: ata_piix [ 4.487884] scsi host1: ata_piix [ 4.489931] random: fast init done [ 4.490250] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.493472] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.110367] dracut-initqueue[589]: RTNETLINK answers: File exists [ 9.567826] random: crng init done [ 9.569157] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 9.825266] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Initrd Root Device. [ 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 Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ 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 Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.126447] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.395882] SELinux: Disabled at runtime. [ 11.465287] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 11.475621] systemd[1]: Detected virtualization kvm. [ 11.478547] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 11.990511] systemd[1]: initrd-switch-root.service: Succeeded. [ 11.994657] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.001289] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.005100] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.009247] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.016505] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.021166] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. Mounting Kernel Debug File System... [ OK ] Stopped target Switch Root. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on Process Core Dump Socket. Mounting POSIX Message Queue File System... [ OK ] Stopped target Initrd File Systems. [ 12.082852] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Mounting Huge Pages File System... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. Starting Create list of required st…ce nodes for the current kernel... Starting Remount Root and Kernel File Systems... Starting Apply Kernel Variables... [ OK ] Created slice User and Session Slice. [ OK ] Listening on udev Control Socket. [ OK ] Reached target rpc_pipefs.target. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Reached target Slices. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-sshd\x2dkeygen.slice. Starting udev Coldplug all Devices... [ OK ] Created slice system-getty.slice. [ OK ] Stopped target Initrd Root File System. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Started Journal Service. [ OK ] Mounted Kernel Debug File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Huge Pages File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Apply Kernel Variables. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 12.431530] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Coldplug all Devices. [ OK ] Started udev Kernel Device Manager. [ 12.772178] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 12.887167] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 12.918540] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 12.934375] EDAC sbridge: Ver: 1.1.2 [ 13.959418] Key type dns_resolver registered [ 14.256746] NFS: Registering the id_resolver key type [ 14.258710] Key type id_resolver registered [ 14.260144] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started dnf makecache --timer. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Reached target sshd-keygen.target. Starting Login Service... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Started OpenSSH server 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 Command Scheduler. [ OK ] Started Getty on tty1. [ 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 System Logging Service... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg648-client login: [ 45.993174] libcfs: loading out-of-tree module taints kernel. [ 46.024227] Key type ._llcrypt registered [ 46.025736] Key type .llcrypt registered [ 46.371438] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 46.378281] alg: No test for adler32 (adler32-zlib) [ 47.443240] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 47.829652] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 49.472220] Key type lgssc registered [ 50.284849] Lustre: Echo OBD driver; http://www.lustre.org/ [ 147.416010] hrtimer: interrupt took 11020428 ns [ 204.774722] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 209.412960] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 222.473662] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing check_logdir /tmp/testlogs/ [ 228.892731] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing yml_node [ 230.368286] Lustre: lustre-OST0000-osc-ffff9e1902fff000: disconnect after 23s idle [ 233.015513] Lustre: DEBUG MARKER: Client: 2.17.57.80 [ 235.399754] Lustre: DEBUG MARKER: MDS: 2.17.57.80 [ 238.110643] Lustre: DEBUG MARKER: OSS: 2.17.57.80 [ 239.809190] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Thu Aug 27 04:12:10 EDT 2026 [ 256.304431] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 257.781624] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 260.026718] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 270.389737] Lustre: Unmounted lustre-client [ 393.654717] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 401.128184] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 427.315474] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 467.224819] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 04:15:58 (1787818558) [ 469.532078] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 471.372674] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 04:16:02 (1787818562) [ 505.981922] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 512.208945] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 534.387719] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 535.088262] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 542.484348] Lustre: Unmounted lustre-client [ 570.246649] Key type lgssc unregistered [ 570.529371] LNet: 15043:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 570.536084] LNetError: 15043:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 570.575677] LNet: Removed LNI 192.168.206.48@tcp [ 571.317160] Key type .llcrypt unregistered [ 571.319233] Key type ._llcrypt unregistered [ 587.401430] Key type ._llcrypt registered [ 587.404104] Key type .llcrypt registered [ 588.559553] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 04:17:59 (1787818679) [ 589.892672] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 590.078901] alg: No test for adler32 (adler32-zlib) [ 591.185503] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 591.423706] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 593.113376] Key type lgssc registered [ 594.693110] Lustre: Echo OBD driver; http://www.lustre.org/ [ 671.216103] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 760.706937] Lustre: Unmounted lustre-client [ 790.380776] Key type lgssc unregistered [ 790.697869] LNet: 18900:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 790.714501] LNetError: 18900:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 790.737587] LNet: Removed LNI 192.168.206.48@tcp [ 791.541200] Key type .llcrypt unregistered [ 791.544465] Key type ._llcrypt unregistered [ 802.504438] Key type ._llcrypt registered [ 802.514535] Key type .llcrypt registered [ 803.973871] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 04:21:34 (1787818894) [ 806.226360] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 806.287712] alg: No test for adler32 (adler32-zlib) [ 807.350416] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 807.518685] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 809.194082] Key type lgssc registered [ 810.314704] Lustre: Echo OBD driver; http://www.lustre.org/ [ 854.988706] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 860.542040] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 878.664523] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 880.271948] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 889.141539] Lustre: Unmounted lustre-client [ 913.766755] Key type lgssc unregistered [ 914.045708] LNet: 22502:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 914.052348] LNetError: 22502:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 914.072521] LNet: Removed LNI 192.168.206.48@tcp [ 914.686237] Key type .llcrypt unregistered [ 914.690467] Key type ._llcrypt unregistered [ 923.490106] Key type ._llcrypt registered [ 923.496548] Key type .llcrypt registered [ 924.840590] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 04:23:35 (1787819015) [ 927.062450] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 927.208986] alg: No test for adler32 (adler32-zlib) [ 928.201388] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 928.541663] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 930.304168] Key type lgssc registered [ 931.424863] Lustre: Echo OBD driver; http://www.lustre.org/ [ 975.828631] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 980.945832] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 999.767223] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1002.476408] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 1007.684741] Lustre: Unmounted lustre-client [ 1032.444475] Key type lgssc unregistered [ 1032.665968] LNet: 25693:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1032.672345] LNetError: 25693:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1032.686603] LNet: Removed LNI 192.168.206.48@tcp [ 1033.254597] Key type .llcrypt unregistered [ 1033.256914] Key type ._llcrypt unregistered [ 1046.038133] Key type ._llcrypt registered [ 1046.040037] Key type .llcrypt registered [ 1047.168311] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 04:25:38 (1787819138) [ 1049.367183] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1049.379432] alg: No test for adler32 (adler32-zlib) [ 1050.445896] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 1050.734078] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 1052.376218] Key type lgssc registered [ 1053.488458] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1099.983497] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1104.892069] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1125.282476] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1128.074561] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 1133.031391] Lustre: lustre-MDT0000-mdc-ffff9e190395a000: Connection to lustre-MDT0000 (at 192.168.206.148@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1138.154778] Lustre: lustre-MDT0001-mdc-ffff9e190395a000: Connection to lustre-MDT0001 (at 192.168.206.148@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1146.181360] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1154.464152] Lustre: 26656:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787819230/real 1787819230] req@00000000b1ce568f x1874664245696128/t0(0) o400->MGC192.168.206.148@tcp@192.168.206.148@tcp:26/25 lens 224/224 e 0 to 1 dl 1787819246 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1154.500124] LustreError: MGC192.168.206.148@tcp: Connection to MGS (at 192.168.206.148@tcp) was lost; in progress operations using this service will fail [ 1156.545270] Lustre: Unmounted lustre-client [ 1174.965450] Key type lgssc unregistered [ 1175.237521] LNet: 29352:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1175.246123] LNetError: 29352:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1175.263689] LNet: Removed LNI 192.168.206.48@tcp [ 1176.019478] Key type .llcrypt unregistered [ 1176.022105] Key type ._llcrypt unregistered [ 1189.803291] Key type ._llcrypt registered [ 1189.809954] Key type .llcrypt registered [ 1190.993348] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 04:28:02 (1787819282) [ 1193.176370] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1193.337821] alg: No test for adler32 (adler32-zlib) [ 1194.376257] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 1194.600286] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 1196.288176] Key type lgssc registered [ 1197.435479] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1244.476511] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1250.274982] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1268.441447] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1270.013461] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 1275.378408] Lustre: lustre-OST0000-osc-ffff9e1912522000: Connection to lustre-OST0000 (at 192.168.206.148@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1281.404430] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1291.746584] Lustre: Unmounted lustre-client [ 1325.712848] Key type lgssc unregistered [ 1325.972713] LNet: 32965:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1325.977351] LNetError: 32965:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1325.998192] LNet: Removed LNI 192.168.206.48@tcp [ 1326.606972] Key type .llcrypt unregistered [ 1326.612767] Key type ._llcrypt unregistered [ 1340.810530] Key type ._llcrypt registered [ 1340.812291] Key type .llcrypt registered [ 1341.926993] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 04:30:33 (1787819433) [ 1344.228560] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1344.252718] alg: No test for adler32 (adler32-zlib) [ 1345.431513] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 1345.706059] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 1347.361176] Key type lgssc registered [ 1348.420460] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1390.966386] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1395.650925] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1414.127170] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1419.793275] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 1423.011399] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1425.361330] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1436.954820] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-08-27 4h32m07s [ 1438.688735] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1445.369435] Lustre: lustre-MDT0000-mdc-ffff9e19054c1000: Connection to lustre-MDT0000 (at 192.168.206.148@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1449.048515] LustreError: 36751:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff9e19054c1000: can't stat MDS #0: rc = -19 [ 1450.337867] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1456.615172] LustreError: MGC192.168.206.148@tcp: Connection to MGS (at 192.168.206.148@tcp) was lost; in progress operations using this service will fail [ 1456.634975] Lustre: Evicted from MGS (at 192.168.206.148@tcp) after server handle changed from 0x4c8580f40a940f6c to 0x4c8580f40a941187 [ 1456.644981] Lustre: MGC192.168.206.148@tcp: Connection restored to 192.168.206.148@tcp (at 192.168.206.148@tcp) [ 1457.728988] LustreError: lustre-MDT0000-mdc-ffff9e19054c1000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1457.758369] Lustre: lustre-MDT0000-mdc-ffff9e19054c1000: Connection restored to 192.168.206.148@tcp (at 192.168.206.148@tcp) [ 1464.343950] Lustre: DEBUG MARKER: Wait for df (36751) ... [ 1465.932847] Lustre: DEBUG MARKER: done [ 1468.357338] Lustre: Unmounted lustre-client [ 1494.212146] Key type lgssc unregistered [ 1494.519567] LNet: 37773:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1494.532114] LNetError: 37773:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1494.570273] LNet: Removed LNI 192.168.206.48@tcp [ 1495.320262] Key type .llcrypt unregistered [ 1495.325835] Key type ._llcrypt unregistered [ 1522.752750] Key type ._llcrypt registered [ 1522.756194] Key type .llcrypt registered [ 1523.783974] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 04:33:35 (1787819615) [ 1525.902192] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1525.955301] alg: No test for adler32 (adler32-zlib) [ 1527.016256] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 1527.337422] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 1529.032251] Key type lgssc registered [ 1529.873348] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1568.858054] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1573.186137] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1588.559236] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1589.971846] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 1593.134915] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-08-27 4h34m44s [ 1594.162360] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1599.164228] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1600.973434] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1621.472237] Lustre: 41916:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787819693/real 1787819693] req@000000000dba78c5 x1874664744818816/t0(0) o101->lustre-MDT0000-mdc-ffff9e1910553000@192.168.206.148@tcp:12/10 lens 576/1152 e 0 to 1 dl 1787819713 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1621.506784] Lustre: lustre-MDT0000-mdc-ffff9e1910553000: Connection to lustre-MDT0000 (at 192.168.206.148@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1621.546817] Lustre: lustre-MDT0000-mdc-ffff9e1910553000: Connection restored to 192.168.206.148@tcp (at 192.168.206.148@tcp) [ 1642.547879] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1644.901470] Lustre: DEBUG MARKER: done [ 1647.998179] Lustre: Unmounted lustre-client [ 1673.517800] Key type lgssc unregistered [ 1673.774737] LNet: 42799:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1673.786433] LNetError: 42799:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1673.826448] LNet: Removed LNI 192.168.206.48@tcp [ 1674.554354] Key type .llcrypt unregistered [ 1674.556616] Key type ._llcrypt unregistered [ 1702.880394] Key type ._llcrypt registered [ 1702.886929] Key type .llcrypt registered [ 1704.000920] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 04:36:35 (1787819795) [ 1705.141085] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1706.552173] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 04:36:37 (1787819797) [ 1709.930825] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1709.969947] alg: No test for adler32 (adler32-zlib) [ 1711.016678] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 1711.227069] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 1712.907598] Key type lgssc registered [ 1714.074366] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1740.002762] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 04:37:11 (1787819831) [ 1768.528971] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1774.013898] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1793.043185] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1795.604760] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 1798.660398] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1801.235753] Lustre: Unmounted lustre-client [ 1812.198309] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 1857.485911] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1862.034442] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1862.556706] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 1865.659142] Lustre: Unmounted lustre-client [ 1919.046867] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1926.067363] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1926.773845] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 1930.066745] Lustre: Unmounted lustre-client [ 1946.593524] Lustre: DEBUG MARKER: files compared the same [ 1968.437609] Key type lgssc unregistered [ 1968.658118] LNet: 50420:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1968.668318] LNetError: 50420:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1968.686252] LNet: Removed LNI 192.168.206.48@tcp [ 1969.287221] Key type .llcrypt unregistered [ 1969.289676] Key type ._llcrypt unregistered [ 1982.730805] Key type ._llcrypt registered [ 1982.733736] Key type .llcrypt registered [ 1983.942812] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 04:41:14 (1787820074) [ 1986.034883] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1986.045024] alg: No test for adler32 (adler32-zlib) [ 1987.047741] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 1987.215695] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 1988.848661] Key type lgssc registered [ 1990.047513] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2034.810886] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2039.204234] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2057.387207] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2063.385997] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 2066.678247] Lustre: Unmounted lustre-client [ 2093.913741] Key type lgssc unregistered [ 2094.182145] LNet: 54009:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2094.186608] LNetError: 54009:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2094.216540] LNet: Removed LNI 192.168.206.48@tcp [ 2095.168305] Key type .llcrypt unregistered [ 2095.178972] Key type ._llcrypt unregistered [ 2110.301832] Key type ._llcrypt registered [ 2110.303826] Key type .llcrypt registered [ 2111.371464] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 04:43:22 (1787820202) [ 2114.532617] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2114.547615] alg: No test for adler32 (adler32-zlib) [ 2115.584743] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 2115.878235] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 2117.552252] Key type lgssc registered [ 2119.069764] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2203.405101] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2234.475494] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2240.170152] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2265.879877] Key type lgssc unregistered [ 2266.038806] LNet: 57497:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2266.047924] LNetError: 57497:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2266.066815] LNet: Removed LNI 192.168.206.48@tcp [ 2266.670794] Key type .llcrypt unregistered [ 2266.674024] Key type ._llcrypt unregistered [ 2280.064839] Key type ._llcrypt registered [ 2280.067141] Key type .llcrypt registered [ 2281.532589] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 04:46:12 (1787820372) [ 2284.353656] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2284.378343] alg: No test for adler32 (adler32-zlib) [ 2285.481816] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 2285.822826] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 2287.680302] Key type lgssc registered [ 2288.760335] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2359.126127] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 2367.428103] Lustre: Unmounted lustre-client [ 2404.672713] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 04:48:15 (1787820495) [ 2524.671389] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2546.238675] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 2553.508337] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2553.519256] LustreError: 63965:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff9e1908368800: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2553.539775] Lustre: 58482:0:(llite_lib.c:4342:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.206.148@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2563.880154] Lustre: Unmounted lustre-client [ 2608.028549] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 04:51:38 (1787820698) [ 2619.549809] Key type lgssc unregistered [ 2619.848444] LNet: 65180:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2619.858849] LNetError: 65180:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2619.881700] LNet: Removed LNI 192.168.206.48@tcp [ 2620.694353] Key type .llcrypt unregistered [ 2620.695946] Key type ._llcrypt unregistered [ 2632.131382] Key type ._llcrypt registered [ 2632.136727] Key type .llcrypt registered [ 2632.511567] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2632.522815] alg: No test for adler32 (adler32-zlib) [ 2633.579722] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 2633.799353] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 2635.522303] Key type lgssc registered [ 2636.664469] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2682.758957] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2687.794370] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2707.309176] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2709.912142] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 2713.413805] Lustre: Unmounted lustre-client [ 2738.469454] Key type lgssc unregistered [ 2738.698685] LNet: 68191:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2738.703589] LNetError: 68191:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2738.718149] LNet: Removed LNI 192.168.206.48@tcp [ 2739.499632] Key type .llcrypt unregistered [ 2739.505075] Key type ._llcrypt unregistered [ 2748.873626] Key type ._llcrypt registered [ 2748.876687] Key type .llcrypt registered [ 2749.292148] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2749.303871] alg: No test for adler32 (adler32-zlib) [ 2750.349178] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 2750.611791] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 2752.304197] Key type lgssc registered [ 2753.240827] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2836.313532] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2840.745455] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2858.295412] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2865.213253] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 2867.566502] Lustre: Unmounted lustre-client [ 2894.754907] Key type lgssc unregistered [ 2894.972366] LNet: 71619:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2894.981205] LNetError: 71619:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2895.018848] LNet: Removed LNI 192.168.206.48@tcp [ 2895.652062] Key type .llcrypt unregistered [ 2895.657771] Key type ._llcrypt unregistered [ 2910.621495] Key type ._llcrypt registered [ 2910.623540] Key type .llcrypt registered [ 2911.923494] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 04:56:42 (1787821002) [ 2914.187431] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2914.219855] alg: No test for adler32 (adler32-zlib) [ 2915.363374] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 2915.606187] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 2917.240178] Key type lgssc registered [ 2918.336214] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2963.915799] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2968.925196] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2989.611098] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2991.215644] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 2996.099829] Lustre: Unmounted lustre-client [ 2996.525520] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 2999.689296] Lustre: Unmounted lustre-client [ 3020.596604] Key type lgssc unregistered [ 3020.850878] LNet: 75301:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3020.859823] LNetError: 75301:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3020.875582] LNet: Removed LNI 192.168.206.48@tcp [ 3021.581603] Key type .llcrypt unregistered [ 3021.583544] Key type ._llcrypt unregistered [ 3033.268031] Key type ._llcrypt registered [ 3033.275359] Key type .llcrypt registered [ 3033.927846] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3033.941791] alg: No test for adler32 (adler32-zlib) [ 3035.062340] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 3035.283955] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 3037.000183] Key type lgssc registered [ 3038.226927] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3082.398456] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3087.885485] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3107.423394] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3109.994540] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 3114.090311] Lustre: Unmounted lustre-client [ 3144.142824] Key type lgssc unregistered [ 3144.363777] LNet: 78335:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3144.372653] LNetError: 78335:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3144.399926] LNet: Removed LNI 192.168.206.48@tcp [ 3145.119769] Key type .llcrypt unregistered [ 3145.124937] Key type ._llcrypt unregistered [ 3161.144898] Key type ._llcrypt registered [ 3161.146613] Key type .llcrypt registered [ 3162.819749] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 05:00:53 (1787821253) [ 3165.293785] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3165.322750] alg: No test for adler32 (adler32-zlib) [ 3166.529292] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 3166.805022] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 3168.544717] Key type lgssc registered [ 3170.358493] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3212.827706] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3219.353802] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3240.383375] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3242.109941] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 3249.962758] Lustre: lustre: root_squash is set to 0:0 [ 3249.985753] Lustre: lustre: nosquash_nids is cleared [ 3255.832380] Lustre: lustre: root_squash is set to 500:500 [ 3263.972214] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.206.48@tcp 192.168.0.[2,10]@tcp [ 3271.224239] Lustre: lustre: nosquash_nids set to 192.168.206.48/24@tcp [ 3275.902656] Lustre: Unmounted lustre-client [ 3301.809184] Key type lgssc unregistered [ 3302.057739] LNet: 82282:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3302.072364] LNetError: 82282:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3302.088758] LNet: Removed LNI 192.168.206.48@tcp [ 3302.801720] Key type .llcrypt unregistered [ 3302.804598] Key type ._llcrypt unregistered [ 3318.417716] Key type ._llcrypt registered [ 3318.420953] Key type .llcrypt registered [ 3319.716174] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 05:03:30 (1787821410) [ 3321.793659] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3323.357925] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 05:03:34 (1787821414) [ 3325.843362] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3325.863970] alg: No test for adler32 (adler32-zlib) [ 3326.875148] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 3327.146239] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 3328.840180] Key type lgssc registered [ 3330.065347] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3379.807962] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3384.880793] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3404.389574] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3406.050736] Lustre: lustre: root_squash is set to 500:500 [ 3406.095444] Lustre: lustre: nosquash_nids set to 192.168.206.48/24@tcp [ 3407.136511] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 3412.444586] Lustre: Unmounted lustre-client [ 3437.989589] Key type lgssc unregistered [ 3438.196371] LNet: 86242:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3438.202190] LNetError: 86242:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3438.214514] LNet: Removed LNI 192.168.206.48@tcp [ 3438.754961] Key type .llcrypt unregistered [ 3438.757919] Key type ._llcrypt unregistered [ 3450.207154] Key type ._llcrypt registered [ 3450.208086] Key type .llcrypt registered [ 3451.386749] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 3452.986915] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 05:05:44 (1787821544) [ 3471.706395] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3471.728272] alg: No test for adler32 (adler32-zlib) [ 3472.945870] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 3473.543782] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 3475.441117] Key type lgssc registered [ 3476.938227] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3547.794346] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3552.984128] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3573.307801] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3638.729628] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3643.104276] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3661.016229] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3672.759390] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 3699.569478] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9e1904b89800.ost_server_uuid 50 [ 3700.795632] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9e1904b89800.ost_server_uuid in FULL state after 0 sec [ 3701.200524] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 3703.915638] Lustre: Unmounted lustre-client [ 3705.193854] Lustre: Unmounted lustre-client [ 3756.757726] Key type lgssc unregistered [ 3756.977912] LNet: 93899:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3756.987138] LNetError: 93899:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3756.997652] LNet: Removed LNI 192.168.206.48@tcp [ 3757.569299] Key type .llcrypt unregistered [ 3757.573826] Key type ._llcrypt unregistered [ 3785.383264] Key type ._llcrypt registered [ 3785.384716] Key type .llcrypt registered [ 3786.554541] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 05:11:17 (1787821877) [ 3801.583963] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3801.672355] alg: No test for adler32 (adler32-zlib) [ 3802.725458] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 3802.902107] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 3804.520601] Key type lgssc registered [ 3806.150352] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3882.230958] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3887.345111] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3911.682651] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3914.289129] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 3934.688100] Lustre: 95269:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787822011/real 1787822011] req@0000000048443626 x1874667131376896/t0(0) o400->lustre-OST0000-osc-ffff9e1902fff000@192.168.206.148@tcp:28/4 lens 224/224 e 0 to 1 dl 1787822027 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3934.728902] Lustre: lustre-OST0000-osc-ffff9e1902fff000: Connection to lustre-OST0000 (at 192.168.206.148@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3940.832156] Lustre: 95269:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787822016/real 1787822016] req@0000000079cb6391 x1874667131377408/t0(0) o400->lustre-OST0000-osc-ffff9e1902fff000@192.168.206.148@tcp:28/4 lens 224/224 e 0 to 1 dl 1787822032 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3944.979953] Lustre: lustre-OST0000-osc-ffff9e1902fff000: Connection restored to 192.168.206.148@tcp (at 192.168.206.148@tcp) [ 3945.888120] Lustre: 95270:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787822022/real 1787822022] req@0000000038f2b67a x1874667131377920/t0(0) o400->lustre-OST0000-osc-ffff9e1902fff000@192.168.206.148@tcp:28/4 lens 224/224 e 0 to 1 dl 1787822038 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3950.059442] Lustre: lustre-MDT0000-mdc-ffff9e1902fff000: Connection to lustre-MDT0000 (at 192.168.206.148@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3951.072378] Lustre: 95270:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787822027/real 1787822027] req@000000006b7c5c46 x1874667131378432/t0(0) o400->lustre-OST0000-osc-ffff9e1902fff000@192.168.206.148@tcp:28/4 lens 224/224 e 0 to 1 dl 1787822043 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3965.419398] LustreError: MGC192.168.206.148@tcp: Connection to MGS (at 192.168.206.148@tcp) was lost; in progress operations using this service will fail [ 3965.459682] Lustre: Evicted from MGS (at 192.168.206.148@tcp) after server handle changed from 0x7c5d374f7c14ef6b to 0x7c5d374f7c14f1fd [ 3965.493208] Lustre: MGC192.168.206.148@tcp: Connection restored to 192.168.206.148@tcp (at 192.168.206.148@tcp) [ 3970.047877] Lustre: lustre-MDT0000-mdc-ffff9e1902fff000: Connection restored to 192.168.206.148@tcp (at 192.168.206.148@tcp) [ 3975.235181] Lustre: Unmounted lustre-client [ 4002.383469] Key type lgssc unregistered [ 4002.682824] LNet: 99571:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4002.689044] LNetError: 99571:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4002.713407] LNet: Removed LNI 192.168.206.48@tcp [ 4003.596080] Key type .llcrypt unregistered [ 4003.598621] Key type ._llcrypt unregistered [ 4018.131275] Key type ._llcrypt registered [ 4018.133399] Key type .llcrypt registered [ 4019.677080] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 05:15:10 (1787822110) [ 4034.326795] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4034.361785] alg: No test for adler32 (adler32-zlib) [ 4035.317280] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 4035.546259] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 4037.256224] Key type lgssc registered [ 4038.366144] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4072.078606] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4161.102447] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4166.125848] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4185.017184] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4186.614238] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 4188.219627] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 4189.735760] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 05:18:01 (1787822281) [ 4395.989105] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4406.297310] Lustre: Unmounted lustre-client [ 4515.880271] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4521.854606] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4543.580981] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4579.417253] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 05:24:30 (1787822670) [ 4660.116726] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4665.608964] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4688.140797] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4688.883188] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 4692.640506] Lustre: Unmounted lustre-client [ 4721.204481] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 05:26:52 (1787822812) [ 4805.922721] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4811.172770] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4834.162311] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4834.651897] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 4838.841179] Lustre: Unmounted lustre-client [ 4863.438037] Key type lgssc unregistered [ 4863.734257] LNet: 122567:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4863.737940] LNetError: 122567:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4863.757678] LNet: Removed LNI 192.168.206.48@tcp [ 4864.387143] Key type .llcrypt unregistered [ 4864.393216] Key type ._llcrypt unregistered [ 4881.794270] Key type ._llcrypt registered [ 4881.799300] Key type .llcrypt registered [ 4882.876714] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 05:29:34 (1787822974) [ 4884.449767] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4884.466832] alg: No test for adler32 (adler32-zlib) [ 4885.536696] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 4885.788821] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 4887.480177] Key type lgssc registered [ 4888.514431] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4936.964098] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4943.148619] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4962.304798] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4963.784320] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 4985.676555] Lustre: Unmounted lustre-client [ 5010.596518] Key type lgssc unregistered [ 5010.806667] LNet: 126165:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5010.813361] LNetError: 126165:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5010.828437] LNet: Removed LNI 192.168.206.48@tcp [ 5011.343992] Key type .llcrypt unregistered [ 5011.349526] Key type ._llcrypt unregistered [ 5025.048936] Key type ._llcrypt registered [ 5025.053290] Key type .llcrypt registered [ 5026.299214] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 05:31:57 (1787823117) [ 5028.307464] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5028.327383] alg: No test for adler32 (adler32-zlib) [ 5029.348339] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 5029.578348] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 5031.248183] Key type lgssc registered [ 5032.223418] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5076.728720] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5082.456877] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5101.639286] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5103.294091] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 5108.712427] Lustre: lustre-OST0000-osc-ffff9e1904b8f800: Connection to lustre-OST0000 (at 192.168.206.148@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5119.972214] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff9e1904b8f800.ost_server_uuid 50 [ 5121.546081] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 5132.499672] Lustre: Unmounted lustre-client [ 5152.344880] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 05:34:03 (1787823243) [ 5183.906499] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5190.056690] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5210.832176] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5233.202860] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5233.765923] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 5238.759990] Lustre: lustre-OST0000-osc-ffff9e19109fe800: Connection to lustre-OST0000 (at 192.168.206.148@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5263.515776] Lustre: Unmounted lustre-client [ 5306.971253] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 05:36:38 (1787823398) [ 5334.421917] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5338.737841] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5356.081477] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5373.109802] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5373.586938] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 5379.067145] Lustre: lustre-OST0000-osc-ffff9e190395c800: Connection to lustre-OST0000 (at 192.168.206.148@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5395.441425] Lustre: Unmounted lustre-client [ 5440.007624] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 05:38:51 (1787823531) [ 5516.752744] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5521.640941] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5540.393878] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5609.604227] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5614.473497] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5632.648477] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5660.000066] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 5700.737333] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5707.510614] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 5 sec [ 5709.722851] Lustre: Unmounted lustre-client [ 5747.841682] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 05:43:58 (1787823838) [ 5776.555479] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5781.012749] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5798.346666] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5826.112299] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5855.161902] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 5896.613342] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5897.921786] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 5901.295129] Lustre: lustre-OST0001-osc-ffff9e1911325800: Connection to lustre-OST0001 (at 192.168.206.148@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5910.293594] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5920.612764] Lustre: Unmounted lustre-client [ 5971.367516] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 05:47:42 (1787824062) [ 6000.753037] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6005.857548] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6028.083968] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6028.669965] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 6049.558231] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6050.963038] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6064.977689] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9e190496c800.ost_server_uuid 50 [ 6066.159859] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9e190496c800.ost_server_uuid in IDLE state after 0 sec [ 6068.982297] Lustre: Unmounted lustre-client [ 6069.258286] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6069.261559] Lustre: Skipped 3 previous similar messages [ 6069.343075] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 6070.352508] LustreError: 149617:0:(sec.c:403:import_sec_validate_get()) import 000000004e3fa43f (NEW) with no sec [ 6122.089335] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 05:50:13 (1787824213) [ 6149.830285] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6154.777242] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6173.611446] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6193.370697] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6193.913453] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 6194.010594] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 6207.898286] Lustre: Unmounted lustre-client [ 6207.901861] Lustre: Skipped 1 previous similar message [ 6240.631521] Key type lgssc unregistered [ 6240.838804] LNet: 153033:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6240.851490] LNetError: 153033:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6240.876121] LNet: Removed LNI 192.168.206.48@tcp [ 6241.507597] Key type .llcrypt unregistered [ 6241.509543] Key type ._llcrypt unregistered [ 6254.912993] Key type ._llcrypt registered [ 6254.921703] Key type .llcrypt registered [ 6255.993755] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 05:52:27 (1787824347) [ 6257.043678] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6257.078631] alg: No test for adler32 (adler32-zlib) [ 6258.153598] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 6258.357608] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 6260.024148] Key type lgssc registered [ 6261.241112] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6307.419206] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6312.227220] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6330.681858] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6348.328032] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6349.914937] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 6351.038284] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 6358.040086] LustreError: lustre-MDT0001-mdc-ffff9e190d84d000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 6358.055515] Lustre: lustre-MDT0001-mdc-ffff9e190d84d000: Connection restored to 192.168.206.148@tcp (at 192.168.206.148@tcp) [ 6363.939247] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6365.393264] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6387.759752] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 6392.161630] Lustre: Unmounted lustre-client [ 6422.055514] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 05:55:13 (1787824513) [ 6515.212760] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6520.770544] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6545.297927] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6546.011500] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 6571.007787] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6572.270984] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6576.626174] Lustre: lustre-OST0001-osc-ffff9e191240f800: Connection to lustre-OST0001 (at 192.168.206.148@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6584.272310] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6594.614511] Lustre: Unmounted lustre-client [ 6635.740856] Key type lgssc unregistered [ 6635.976663] LNet: 162665:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6635.984796] LNetError: 162665:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6636.001669] LNet: Removed LNI 192.168.206.48@tcp [ 6636.709550] Key type .llcrypt unregistered [ 6636.711405] Key type ._llcrypt unregistered [ 6664.500747] Key type ._llcrypt registered [ 6664.505474] Key type .llcrypt registered [ 6665.718592] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 05:59:16 (1787824756) [ 6668.337943] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6668.357085] alg: No test for adler32 (adler32-zlib) [ 6669.452580] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 6669.698717] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 6671.400770] Key type lgssc registered [ 6672.505496] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6716.441848] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6721.078566] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6741.704263] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6744.439947] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 6789.020905] Lustre: Unmounted lustre-client [ 6827.839613] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6828.349847] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 6864.150680] Lustre: Unmounted lustre-client [ 6891.037695] Key type lgssc unregistered [ 6891.330380] LNet: 167130:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6891.338279] LNetError: 167130:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6891.351991] LNet: Removed LNI 192.168.206.48@tcp [ 6892.014051] Key type .llcrypt unregistered [ 6892.016458] Key type ._llcrypt unregistered [ 6905.599469] Key type ._llcrypt registered [ 6905.601878] Key type .llcrypt registered [ 6906.755869] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 06:03:17 (1787824997) [ 6908.811473] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6908.875484] alg: No test for adler32 (adler32-zlib) [ 6910.077226] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 6910.316285] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 6912.040295] Key type lgssc registered [ 6913.381911] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6962.157138] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6967.334806] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6987.219848] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6988.849225] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 7006.183881] Lustre: Unmounted lustre-client [ 7032.282521] Key type lgssc unregistered [ 7032.510274] LNet: 170887:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7032.514349] LNetError: 170887:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7032.528567] LNet: Removed LNI 192.168.206.48@tcp [ 7033.212936] Key type .llcrypt unregistered [ 7033.222335] Key type ._llcrypt unregistered [ 7045.977069] Key type ._llcrypt registered [ 7045.979821] Key type .llcrypt registered [ 7046.550896] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7046.571719] alg: No test for adler32 (adler32-zlib) [ 7047.700376] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 7048.036279] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 7049.816128] Key type lgssc registered [ 7051.183560] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7098.862310] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7104.046947] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7123.851958] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7125.380067] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 7132.323192] Lustre: Unmounted lustre-client [ 7154.376798] Key type lgssc unregistered [ 7154.647671] LNet: 173954:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7154.661871] LNetError: 173954:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7154.718179] LNet: Removed LNI 192.168.206.48@tcp [ 7155.365175] Key type .llcrypt unregistered [ 7155.367745] Key type ._llcrypt unregistered [ 7174.276941] Key type ._llcrypt registered [ 7174.283201] Key type .llcrypt registered [ 7176.194465] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 06:07:46 (1787825266) [ 7178.690119] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7178.895113] alg: No test for adler32 (adler32-zlib) [ 7180.099911] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 7180.413760] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 7182.152261] Key type lgssc registered [ 7183.570529] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7231.922408] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7237.659810] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7258.096432] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7260.914684] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 7279.502336] Lustre: Unmounted lustre-client [ 7306.185906] Key type lgssc unregistered [ 7306.476932] LNet: 177712:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7306.491062] LNetError: 177712:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7306.514124] LNet: Removed LNI 192.168.206.48@tcp [ 7307.243387] Key type .llcrypt unregistered [ 7307.246128] Key type ._llcrypt unregistered [ 7319.689987] Key type ._llcrypt registered [ 7319.692414] Key type .llcrypt registered [ 7320.051564] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7320.075470] alg: No test for adler32 (adler32-zlib) [ 7321.187734] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 7321.437437] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 7323.112784] Key type lgssc registered [ 7324.151231] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7369.847588] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7375.190306] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7394.281115] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7395.929910] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 7402.695372] Lustre: Unmounted lustre-client [ 7428.552842] Key type lgssc unregistered [ 7428.863798] LNet: 180774:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7428.875414] LNetError: 180774:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7428.893163] LNet: Removed LNI 192.168.206.48@tcp [ 7429.668764] Key type .llcrypt unregistered [ 7429.671598] Key type ._llcrypt unregistered [ 7446.482602] Key type ._llcrypt registered [ 7446.487254] Key type .llcrypt registered [ 7447.820942] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 06:12:18 (1787825538) [ 7468.881645] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7468.893593] alg: No test for adler32 (adler32-zlib) [ 7469.978619] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 7470.241491] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 7471.913331] Key type lgssc registered [ 7473.126168] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7546.487977] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7551.724355] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7574.976215] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7613.659033] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 06:15:04 (1787825704) [ 7642.951404] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7647.179624] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7665.054157] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7667.849033] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 7672.787116] Lustre: Unmounted lustre-client [ 7699.722239] Key type lgssc unregistered [ 7699.987421] LNet: 187866:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7700.003117] LNetError: 187866:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7700.020907] LNet: Removed LNI 192.168.206.48@tcp [ 7700.601290] Key type .llcrypt unregistered [ 7700.606231] Key type ._llcrypt unregistered [ 7715.219660] Key type ._llcrypt registered [ 7715.221142] Key type .llcrypt registered [ 7716.804574] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 7718.438877] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 06:16:49 (1787825809) [ 7738.587945] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7738.639597] alg: No test for adler32 (adler32-zlib) [ 7739.760808] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 7740.000379] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 7741.720166] Key type lgssc registered [ 7742.823635] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7831.015940] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7834.990794] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7858.288650] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7878.339379] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7879.952920] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 7901.763966] Lustre: Unmounted lustre-client [ 8017.959756] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8023.424032] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8045.116783] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8086.450615] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 06:22:57 (1787826177) [ 8087.866840] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 8089.638930] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 06:23:00 (1787826180) [ 8184.426765] Key type lgssc unregistered [ 8184.698685] LNet: 199303:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8184.709188] LNetError: 199303:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8184.724254] LNet: Removed LNI 192.168.206.48@tcp [ 8185.475852] Key type .llcrypt unregistered [ 8185.479161] Key type ._llcrypt unregistered [ 8200.140219] Key type ._llcrypt registered [ 8200.143567] Key type .llcrypt registered [ 8201.426544] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 06:24:52 (1787826292) [ 8243.637751] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8243.679069] alg: No test for adler32 (adler32-zlib) [ 8244.882928] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 8245.193644] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 8246.904516] Key type lgssc registered [ 8248.457092] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8295.299690] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8301.262837] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8336.277904] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8363.744816] Key type lgssc unregistered [ 8364.041198] LNet: 203040:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8364.048824] LNetError: 203040:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8364.069328] LNet: Removed LNI 192.168.206.48@tcp [ 8364.720233] Key type .llcrypt unregistered [ 8364.724613] Key type ._llcrypt unregistered [ 8381.213466] Key type ._llcrypt registered [ 8381.215966] Key type .llcrypt registered [ 8382.544622] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 06:27:53 (1787826473) [ 8385.164343] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8385.314397] alg: No test for adler32 (adler32-zlib) [ 8386.503684] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 8386.805876] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 8388.520763] Key type lgssc registered [ 8390.180402] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8445.432746] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8451.470877] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8475.362662] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8478.065167] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 8493.557689] Lustre: lustre-MDT0000-mdc-ffff9e1906153000: Connection to lustre-MDT0000 (at 192.168.206.148@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8503.782599] Lustre: lustre-MDT0001-mdc-ffff9e1906153000: Connection to lustre-MDT0001 (at 192.168.206.148@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8515.040489] Lustre: 204032:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787826591/real 1787826591] req@000000001faeb8fe x1874671938126592/t0(0) o400->MGC192.168.206.148@tcp@192.168.206.148@tcp:26/25 lens 224/224 e 0 to 1 dl 1787826607 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 8515.067826] LustreError: MGC192.168.206.148@tcp: Connection to MGS (at 192.168.206.148@tcp) was lost; in progress operations using this service will fail [ 8525.349537] Lustre: Evicted from MGS (at 192.168.206.148@tcp) after server handle changed from 0x79602dee7a1aec13 to 0x79602dee7a1b0764 [ 8525.362567] Lustre: MGC192.168.206.148@tcp: Connection restored to 192.168.206.148@tcp (at 192.168.206.148@tcp) [ 8548.847048] LustreError: lustre-MDT0000-mdc-ffff9e1906153000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 8548.935767] Lustre: lustre-MDT0000-mdc-ffff9e1906153000: Connection restored to 192.168.206.148@tcp (at 192.168.206.148@tcp) [ 8553.035722] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8554.755547] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 8559.113653] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8560.390258] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 8562.607509] Lustre: Unmounted lustre-client [ 8589.315709] Key type lgssc unregistered [ 8589.561383] LNet: 207993:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8589.574161] LNetError: 207993:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8589.600665] LNet: Removed LNI 192.168.206.48@tcp [ 8590.257845] Key type .llcrypt unregistered [ 8590.260850] Key type ._llcrypt unregistered [ 8605.590099] Key type ._llcrypt registered [ 8605.594147] Key type .llcrypt registered [ 8606.862209] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 06:31:37 (1787826697) [ 8609.133855] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8609.143219] alg: No test for adler32 (adler32-zlib) [ 8610.222291] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 8610.437769] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 8612.120715] Key type lgssc registered [ 8613.349446] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8657.696222] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8662.719886] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8720.726684] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8726.472324] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8757.979778] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8776.362847] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8813.609597] Key type lgssc unregistered [ 8813.870487] LNet: 213289:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8813.881239] LNetError: 213289:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8813.916441] LNet: Removed LNI 192.168.206.48@tcp [ 8814.738087] Key type .llcrypt unregistered [ 8814.744205] Key type ._llcrypt unregistered [ 8854.025175] Key type ._llcrypt registered [ 8854.028240] Key type .llcrypt registered [ 8855.093490] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 06:35:46 (1787826946) [ 8889.600223] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8889.791379] alg: No test for adler32 (adler32-zlib) [ 8890.931379] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 8891.232080] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 8893.016155] Key type lgssc registered [ 8893.992458] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8969.595380] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8976.421791] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8998.139882] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9043.916683] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 06:38:55 (1787827135) [ 9050.319522] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 06:39:01 (1787827141) [ 9083.861031] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9089.998965] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9112.541742] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9115.212154] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 9123.648623] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [ 9126.021748] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 9127.660638] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 9129.399047] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [ 9135.595951] Lustre: lustre-MDT0000-mdc-ffff9e19054c1000: Connection to lustre-MDT0000 (at 192.168.206.148@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9150.958468] LustreError: MGC192.168.206.148@tcp: Connection to MGS (at 192.168.206.148@tcp) was lost; in progress operations using this service will fail [ 9150.979396] Lustre: Evicted from MGS (at 192.168.206.148@tcp) after server handle changed from 0x72653c513c05e212 to 0x72653c513c05e481 [ 9151.008400] Lustre: MGC192.168.206.148@tcp: Connection restored to 192.168.206.148@tcp (at 192.168.206.148@tcp) [ 9155.076096] Lustre: lustre-MDT0000-mdc-ffff9e19054c1000: Connection restored to 192.168.206.148@tcp (at 192.168.206.148@tcp) [ 9162.756613] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9164.442700] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9167.914626] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [ 9171.431836] Lustre: lustre-MDT0000-mdc-ffff9e19054c1000: Connection to lustre-MDT0000 (at 192.168.206.148@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9186.784303] Lustre: 215199:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787827263/real 1787827263] req@0000000038e71eb3 x1874672466544000/t0(0) o400->MGC192.168.206.148@tcp@192.168.206.148@tcp:26/25 lens 224/224 e 0 to 1 dl 1787827279 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9186.837445] LustreError: MGC192.168.206.148@tcp: Connection to MGS (at 192.168.206.148@tcp) was lost; in progress operations using this service will fail [ 9197.053149] Lustre: Evicted from MGS (at 192.168.206.148@tcp) after server handle changed from 0x72653c513c05e481 to 0x72653c513c05e791 [ 9197.070506] Lustre: MGC192.168.206.148@tcp: Connection restored to 192.168.206.148@tcp (at 192.168.206.148@tcp) [ 9201.719721] Lustre: lustre-MDT0000-mdc-ffff9e19054c1000: Connection restored to 192.168.206.148@tcp (at 192.168.206.148@tcp) [ 9210.831178] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9212.936154] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9217.060198] Lustre: Unmounted lustre-client [ 9285.140955] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9290.571338] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9309.529818] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9309.990174] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 9311.864566] Lustre: Unmounted lustre-client [ 9337.868466] Key type lgssc unregistered [ 9338.095415] LNet: 225779:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9338.105748] LNetError: 225779:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9338.135703] LNet: Removed LNI 192.168.206.48@tcp [ 9339.001488] Key type .llcrypt unregistered [ 9339.004616] Key type ._llcrypt unregistered [ 9353.357991] Key type ._llcrypt registered [ 9353.361465] Key type .llcrypt registered [ 9354.530423] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 06:44:05 (1787827445) [ 9358.191162] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9358.214784] alg: No test for adler32 (adler32-zlib) [ 9359.326559] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 9359.596807] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 9361.288185] Key type lgssc registered [ 9362.471099] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9405.773547] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9411.269419] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9430.707551] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9433.391133] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 9439.824383] Lustre: Unmounted lustre-client [ 9500.578467] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9506.112672] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9526.842712] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9527.589937] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 9536.748188] Lustre: Unmounted lustre-client [ 9598.701514] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9604.315908] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9625.594973] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9626.106381] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 9628.427051] Lustre: Unmounted lustre-client [ 9656.344285] Key type lgssc unregistered [ 9656.639413] LNet: 232822:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9656.653276] LNetError: 232822:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9656.680474] LNet: Removed LNI 192.168.206.48@tcp [ 9657.420127] Key type .llcrypt unregistered [ 9657.421919] Key type ._llcrypt unregistered [ 9673.362419] Key type ._llcrypt registered [ 9673.365264] Key type .llcrypt registered [ 9674.580963] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 06:49:25 (1787827765) [ 9677.107588] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9677.224806] alg: No test for adler32 (adler32-zlib) [ 9678.418587] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 9678.666289] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 9680.304178] Key type lgssc registered [ 9681.358543] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9730.097555] Key type lgssc unregistered [ 9730.348559] LNet: 235148:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9730.361676] LNetError: 235148:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9730.381377] LNet: Removed LNI 192.168.206.48@tcp [ 9731.053881] Key type .llcrypt unregistered [ 9731.057791] Key type ._llcrypt unregistered [ 9755.083324] Key type ._llcrypt registered [ 9755.092805] Key type .llcrypt registered [ 9755.541516] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9755.576018] alg: No test for adler32 (adler32-zlib) [ 9756.802658] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [ 9757.151916] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [ 9758.784407] Key type lgssc registered [ 9760.163273] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9842.294551] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9847.633206] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9868.915851] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9909.873992] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [ 9911.406811] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 06:53:22 (1787828002) [ 9944.292339] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9949.516598] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9970.792905] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9993.122426] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9994.676423] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [ 9999.847926] Lustre: lustre-OST0001-osc-ffff9e19054c5800: Connection to lustre-OST0001 (at 192.168.206.148@tcp) was lost; in progress operations using this service will wait for recovery to complete [10007.185996] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [10017.512454] Lustre: Unmounted lustre-client [10052.255658] Key type lgssc unregistered [10052.555924] LNet: 242284:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10052.565482] LNetError: 242284:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10052.587995] LNet: Removed LNI 192.168.206.48@tcp [10053.395707] Key type .llcrypt unregistered [10053.398380] Key type ._llcrypt unregistered [10084.600650] Key type ._llcrypt registered [10084.602554] Key type .llcrypt registered [10085.710431] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 06:56:16 (1787828176) [10096.519761] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10096.541353] alg: No test for adler32 (adler32-zlib) [10097.627542] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [10097.873601] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [10099.600173] Key type lgssc registered [10100.713431] Lustre: Echo OBD driver; http://www.lustre.org/ [10150.604976] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10156.419575] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10176.418277] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 06:57:47 (1787828267) [10204.323653] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10209.782212] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10227.849370] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10230.397349] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [10236.135822] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [10246.309781] Lustre: Unmounted lustre-client [10334.936801] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10339.668186] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10392.512391] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10393.350933] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [10395.700585] Lustre: Unmounted lustre-client [10421.893790] Key type lgssc unregistered [10422.215799] LNet: 250011:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10422.227374] LNetError: 250011:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10422.252355] LNet: Removed LNI 192.168.206.48@tcp [10422.941792] Key type .llcrypt unregistered [10422.946517] Key type ._llcrypt unregistered [10449.102729] Key type ._llcrypt registered [10449.104531] Key type .llcrypt registered [10449.568076] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10449.590951] alg: No test for adler32 (adler32-zlib) [10450.630388] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [10450.830401] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [10452.512187] Key type lgssc registered [10453.820662] Lustre: Echo OBD driver; http://www.lustre.org/ [10531.858651] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10537.047255] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10557.326620] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10596.461162] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [10598.132727] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 07:04:49 (1787828689) [10630.224034] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10635.860316] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10656.165302] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10660.416893] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [10669.214696] Lustre: Unmounted lustre-client [10694.753738] Key type lgssc unregistered [10695.008646] LNet: 256649:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10695.017448] LNetError: 256649:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10695.032650] LNet: Removed LNI 192.168.206.48@tcp [10695.732504] Key type .llcrypt unregistered [10695.737485] Key type ._llcrypt unregistered [10710.022387] Key type ._llcrypt registered [10710.026204] Key type .llcrypt registered [10711.038902] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [10712.412904] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 07:06:43 (1787828803) [10720.096483] Key type .llcrypt unregistered [10720.101044] Key type ._llcrypt unregistered [10726.219260] Key type ._llcrypt registered [10726.222950] Key type .llcrypt registered [10726.491142] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10726.511912] alg: No test for adler32 (adler32-zlib) [10727.524728] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [10727.759123] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [10729.440325] Key type lgssc registered [10730.265652] Lustre: Echo OBD driver; http://www.lustre.org/ [10777.806619] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10793.257709] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [10796.112954] Lustre: Unmounted lustre-client [10823.188033] Key type lgssc unregistered [10823.440940] LNet: 260115:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10823.451544] LNetError: 260115:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10823.486551] LNet: Removed LNI 192.168.206.48@tcp [10824.197703] Key type .llcrypt unregistered [10824.199359] Key type ._llcrypt unregistered [10838.828951] Key type ._llcrypt registered [10838.830726] Key type .llcrypt registered [10840.187554] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 07:08:51 (1787828931) [10842.679829] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10842.693592] alg: No test for adler32 (adler32-zlib) [10843.713710] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [10844.076120] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [10845.801179] Key type lgssc registered [10847.008605] Lustre: Echo OBD driver; http://www.lustre.org/ [10932.778614] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10968.275744] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10974.996534] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10976.842483] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [10979.660590] Lustre: Unmounted lustre-client [11007.895237] Key type lgssc unregistered [11008.102412] LNet: 263664:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11008.112381] LNetError: 263664:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11008.126515] LNet: Removed LNI 192.168.206.48@tcp [11008.703421] Key type .llcrypt unregistered [11008.707132] Key type ._llcrypt unregistered [11024.263174] Key type ._llcrypt registered [11024.265483] Key type .llcrypt registered [11026.036330] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 07:11:56 (1787829116) [11028.572921] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11028.592069] alg: No test for adler32 (adler32-zlib) [11030.072563] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [11030.333150] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [11032.016293] Key type lgssc registered [11033.318560] Lustre: Echo OBD driver; http://www.lustre.org/ [11085.197863] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11090.502187] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11112.050846] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11113.883966] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [11119.099861] Lustre: lustre-MDT0000-mdc-ffff9e190317b000: Connection to lustre-MDT0000 (at 192.168.206.148@tcp) was lost; in progress operations using this service will wait for recovery to complete [11124.553798] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11124.583892] LustreError: 266823:0:(file.c:6167:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [11125.145151] LustreError: MGC192.168.206.148@tcp: Connection to MGS (at 192.168.206.148@tcp) was lost; in progress operations using this service will fail [11125.169429] Lustre: Unmounted lustre-client [11145.116502] Key type lgssc unregistered [11145.430375] LNet: 267240:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11145.445713] LNetError: 267240:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11145.468149] LNet: Removed LNI 192.168.206.48@tcp [11146.130748] Key type .llcrypt unregistered [11146.133372] Key type ._llcrypt unregistered [11162.261581] Key type ._llcrypt registered [11162.263789] Key type .llcrypt registered [11163.625909] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 07:14:14 (1787829254) [11166.066627] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11166.128334] alg: No test for adler32 (adler32-zlib) [11167.252751] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [11167.465503] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [11169.160178] Key type lgssc registered [11170.202125] Lustre: Echo OBD driver; http://www.lustre.org/ [11220.026260] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11225.870881] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11248.903996] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11250.563188] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [11261.434830] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [11263.748787] Lustre: Unmounted lustre-client [11283.947878] Key type lgssc unregistered [11284.197143] LNet: 270822:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11284.218640] LNetError: 270822:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11284.241919] LNet: Removed LNI 192.168.206.48@tcp [11284.950372] Key type .llcrypt unregistered [11284.953974] Key type ._llcrypt unregistered [11300.332084] Key type ._llcrypt registered [11300.334795] Key type .llcrypt registered [11301.557458] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 07:16:32 (1787829392) [11321.587650] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11321.614729] alg: No test for adler32 (adler32-zlib) [11322.760576] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [11323.120596] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [11324.824306] Key type lgssc registered [11326.457280] Lustre: Echo OBD driver; http://www.lustre.org/ [11440.581842] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11442.291045] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [11482.611071] Lustre: lustre-MDT0001-mdc-ffff9e190317b000: Connection to lustre-MDT0001 (at 192.168.206.148@tcp) was lost; in progress operations using this service will wait for recovery to complete [11491.626266] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11502.024359] Lustre: Unmounted lustre-client [11538.085852] Key type lgssc unregistered [11538.341905] LNet: 276616:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11538.354163] LNetError: 276616:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11538.386319] LNet: Removed LNI 192.168.206.48@tcp [11539.120063] Key type .llcrypt unregistered [11539.122717] Key type ._llcrypt unregistered [11557.310709] Key type ._llcrypt registered [11557.313273] Key type .llcrypt registered [11558.955967] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 07:20:49 (1787829649) [11560.983483] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [11563.111738] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 07:20:53 (1787829653) [11565.070633] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [11566.959073] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 07:20:58 (1787829658) [11568.370468] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [11570.252723] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 07:21:01 (1787829661) [11572.157359] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [11574.272657] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 07:21:04 (1787829664) [11576.329974] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [11578.277550] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 07:21:09 (1787829669) [11588.063319] Key type .llcrypt unregistered [11588.065906] Key type ._llcrypt unregistered [11596.552098] Key type ._llcrypt registered [11596.568594] Key type .llcrypt registered [11597.088853] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11597.099406] alg: No test for adler32 (adler32-zlib) [11598.279828] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [11598.574176] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [11600.240190] Key type lgssc registered [11601.733567] Lustre: Echo OBD driver; http://www.lustre.org/ [11674.584720] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11681.201590] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11703.281844] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11704.925054] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [11707.311490] Lustre: Unmounted lustre-client [11747.637676] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 07:23:58 (1787829838) [11780.828242] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11786.551459] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11819.976628] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11830.823432] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [11832.862290] Lustre: Unmounted lustre-client [11874.023426] LNet: 1 local NIs in recovery (showing 1): 192.168.206.48@tcp [11876.011579] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 07:26:07 (1787829967) [12519.337525] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12524.647855] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12557.297288] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12652.901382] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 07:39:03 (1787830743) [12666.204638] Key type lgssc unregistered [12666.475201] LNet: 290158:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12666.492206] LNetError: 290158:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12667.581985] LNet: Removed LNI 192.168.206.48@tcp [12668.374515] Key type .llcrypt unregistered [12668.376891] Key type ._llcrypt unregistered [12808.656843] Key type ._llcrypt registered [12808.662946] Key type .llcrypt registered [12808.860347] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12808.875775] alg: No test for adler32 (adler32-zlib) [12809.872905] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [12810.008699] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [12811.632193] Key type lgssc registered [12812.571483] Lustre: Echo OBD driver; http://www.lustre.org/ [12897.361383] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12903.775919] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12926.657205] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12969.033942] Key type lgssc unregistered [12969.305697] LNet: 293862:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12969.327109] LNetError: 293862:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12969.363537] LNet: Removed LNI 192.168.206.48@tcp [12970.115121] Key type .llcrypt unregistered [12970.120890] Key type ._llcrypt unregistered [13007.527486] Key type ._llcrypt registered [13007.530943] Key type .llcrypt registered [13008.751362] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 07:44:59 (1787831099) [13032.549130] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13032.580177] alg: No test for adler32 (adler32-zlib) [13033.675422] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [13033.947870] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [13035.712375] Key type lgssc registered [13036.992156] Lustre: Echo OBD driver; http://www.lustre.org/ [13085.938944] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 07:46:16 (1787831176) [13096.621622] Key type lgssc unregistered [13096.890247] LNet: 297416:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13096.897480] LNetError: 297416:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13096.917931] LNet: Removed LNI 192.168.206.48@tcp [13097.582724] Key type .llcrypt unregistered [13097.584388] Key type ._llcrypt unregistered [13107.775590] Key type ._llcrypt registered [13107.794909] Key type .llcrypt registered [13108.034275] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13108.046688] alg: No test for adler32 (adler32-zlib) [13109.094090] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [13109.372153] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [13111.136142] Key type lgssc registered [13112.394402] Lustre: Echo OBD driver; http://www.lustre.org/ [13160.704729] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13167.016645] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13189.823475] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13437.639464] LNet: Added LNI 192.168.206.48@tcp50 [8/256/0/180] [13439.627557] LNet: Removed LNI 192.168.206.48@tcp [13441.628559] Lustre: 301803:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff9e1905452800: config has no valid NIDs, force dynamic NIDs [13442.810637] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [13442.984277] Lustre: Unmounted lustre-client [13445.592299] Key type lgssc unregistered [13445.956302] LNet: 302065:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13445.964962] LNetError: 302065:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13445.984252] LNet: Removed LNI 192.168.206.48@tcp50 [13446.577148] Key type .llcrypt unregistered [13446.581235] Key type ._llcrypt unregistered [13456.324969] Key type ._llcrypt registered [13456.330094] Key type .llcrypt registered [13456.776681] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13456.809081] alg: No test for adler32 (adler32-zlib) [13457.853626] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [13458.066629] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [13459.745730] Key type lgssc registered [13460.888107] Lustre: Echo OBD driver; http://www.lustre.org/ [13523.654528] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13528.973641] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13536.440542] LNet: Added LNI 192.168.206.48@tcp50 [8/256/0/180] [13537.413552] LNet: Removed LNI 192.168.206.48@tcp [13538.645212] Lustre: 304756:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff9e1907804800: config has no valid NIDs, force dynamic NIDs [13539.803622] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [13540.384282] Lustre: Unmounted lustre-client [13579.180844] Key type lgssc unregistered [13579.464177] LNet: 305632:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13579.468923] LNetError: 305632:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13579.483543] LNet: Removed LNI 192.168.206.48@tcp50 [13579.958974] Key type .llcrypt unregistered [13579.965183] Key type ._llcrypt unregistered [13591.024057] Key type ._llcrypt registered [13591.028185] Key type .llcrypt registered [13592.049969] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 07:54:43 (1787831683) [13607.599787] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13607.608168] alg: No test for adler32 (adler32-zlib) [13608.664304] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [13608.913729] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [13610.577571] Key type lgssc registered [13611.510671] Lustre: Echo OBD driver; http://www.lustre.org/ [13681.323772] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13686.609795] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13708.560788] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13746.713346] Key type lgssc unregistered [13747.030996] LNet: 310389:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13747.045380] LNetError: 310389:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13747.067200] LNet: Removed LNI 192.168.206.48@tcp [13747.814070] Key type .llcrypt unregistered [13747.818705] Key type ._llcrypt unregistered [13758.332812] Key type ._llcrypt registered [13758.338728] Key type .llcrypt registered [13760.116683] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 07:57:30 (1787831850) [13762.972395] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13763.002978] alg: No test for adler32 (adler32-zlib) [13764.233895] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [13764.468652] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [13766.128193] Key type lgssc registered [13767.482655] Lustre: Echo OBD driver; http://www.lustre.org/ [13812.345938] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13819.097150] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13839.260923] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13841.208466] Lustre: Mounted lustre-client - version 2.17.57_80_gdb9f866 [13888.932282] Lustre: Mounted fs15246-client - version 2.17.57_80_gdb9f866 [13901.082166] Lustre: Unmounted fs15246-client [13927.003306] Lustre: Unmounted lustre-client [13952.708554] Key type lgssc unregistered [13952.926074] LNet: 315262:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13952.932554] LNetError: 315262:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13953.968396] LNet: Removed LNI 192.168.206.48@tcp [13954.536727] Key type .llcrypt unregistered [13954.539120] Key type ._llcrypt unregistered [13963.635127] Key type ._llcrypt registered [13963.638460] Key type .llcrypt registered [13964.795519] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 08:00:55 (1787832055) [13966.208245] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13966.219285] alg: No test for adler32 (adler32-zlib) [13967.268204] Lustre: Lustre: Build Version: 2.17.57_80_gdb9f866 [13967.448416] LNet: Added LNI 192.168.206.48@tcp [8/256/0/180] [13969.112198] Key type lgssc registered [13970.297254] Lustre: Echo OBD driver; http://www.lustre.org/ [14084.838421] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14089.535855] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14109.296733] Lustre: DEBUG MARKER: oleg648-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14196.403707] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 13955 sec ========= 08:04:47 (1787832287) [14197.912862] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 08:04:49 (1787832289) === [14200.512850] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 08:04:51 (1787832291) === [14232.797940] Key type lgssc unregistered [14232.962200] LNet: 324536:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14232.968091] LNetError: 324536:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14232.981341] LNet: Removed LNI 192.168.206.48@tcp [14233.796519] Key type .llcrypt unregistered [14233.803691] Key type ._llcrypt unregistered