[ 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 446494147 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001010] APIC: Switch to symmetric I/O mode setup [ 0.003103] x2apic enabled [ 0.004008] Switched APIC routing to physical x2apic. [ 0.005012] kvm-guest: setup PV IPIs [ 0.008000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.009021] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010011] pid_max: default: 32768 minimum: 301 [ 0.011139] LSM: Security Framework initializing [ 0.013001] Yama: becoming mindful. [ 0.013957] SELinux: Initializing. [ 0.014070] *** VALIDATE selinux *** [ 0.021722] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.025787] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.026144] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027101] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029028] *** VALIDATE tmpfs *** [ 0.030417] *** VALIDATE proc *** [ 0.031210] *** VALIDATE cgroup *** [ 0.032009] *** VALIDATE cgroup2 *** [ 0.034003] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035130] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036006] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037024] Spectre V2 : User space: Vulnerable [ 0.038007] Speculative Store Bypass: Vulnerable [ 0.040793] debug: unmapping init [mem 0xffffffff98e59000-0xffffffff98e60fff] [ 0.042138] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.043551] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.044023] ... version: 2 [ 0.045013] ... bit width: 48 [ 0.046013] ... generic registers: 4 [ 0.047014] ... value mask: 0000ffffffffffff [ 0.048016] ... max period: 00007fffffffffff [ 0.049016] ... fixed-purpose events: 3 [ 0.050013] ... event mask: 000000070000000f [ 0.051334] rcu: Hierarchical SRCU implementation. [ 0.054564] smp: Bringing up secondary CPUs ... [ 0.055675] x86: Booting SMP configuration: [ 0.056029] .... node #0, CPUs: #1 #2 #3 [ 0.059456] smp: Brought up 1 node, 4 CPUs [ 0.061025] smpboot: Max logical packages: 1 [ 0.062019] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.133851] node 0 deferred pages initialised in 69ms [ 0.137089] devtmpfs: initialized [ 0.138289] x86/mm: Memory block size: 128MB [ 0.143887] gcov: version magic: 0x41383552 [ 0.147336] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.151093] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.154267] pinctrl core: initialized pinctrl subsystem [ 0.156175] [ 0.156861] ************************************************************* [ 0.159014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.162013] ** ** [ 0.164011] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.167014] ** ** [ 0.170013] ** This means that this kernel is built to expose internal ** [ 0.173015] ** IOMMU data structures, which may compromise security on ** [ 0.175012] ** your system. ** [ 0.178014] ** ** [ 0.181014] ** If you see this message and you are not debugging the ** [ 0.184013] ** kernel, report this immediately to your vendor! ** [ 0.186013] ** ** [ 0.189013] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.192014] ************************************************************* [ 0.195507] NET: Registered protocol family 16 [ 0.198559] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.201060] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.204063] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.208018] cpuidle: using governor menu [ 0.209846] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.210569] PCI: Using configuration type 1 for base access [ 0.212179] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.221131] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.224074] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.228146] cryptd: max_cpu_qlen set to 1000 [ 0.231904] ACPI: Added _OSI(Module Device) [ 0.234013] ACPI: Added _OSI(Processor Device) [ 0.236013] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.237014] ACPI: Added _OSI(Processor Aggregator Device) [ 0.242126] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.250465] ACPI: Interpreter enabled [ 0.252054] ACPI: PM: (supports S0 S3 S4 S5) [ 0.254012] ACPI: Using IOAPIC for interrupt routing [ 0.256106] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.259423] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.268628] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.270036] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.272016] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.274068] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.280351] acpiphp: Slot [2] registered [ 0.281125] acpiphp: Slot [5] registered [ 0.283127] acpiphp: Slot [6] registered [ 0.285130] acpiphp: Slot [3] registered [ 0.286096] acpiphp: Slot [4] registered [ 0.288134] acpiphp: Slot [7] registered [ 0.290094] acpiphp: Slot [8] registered [ 0.292116] acpiphp: Slot [9] registered [ 0.293000] acpiphp: Slot [10] registered [ 0.294049] acpiphp: Slot [11] registered [ 0.295194] acpiphp: Slot [12] registered [ 0.297176] acpiphp: Slot [13] registered [ 0.299104] acpiphp: Slot [14] registered [ 0.301199] acpiphp: Slot [15] registered [ 0.303111] acpiphp: Slot [16] registered [ 0.304730] acpiphp: Slot [17] registered [ 0.306116] acpiphp: Slot [18] registered [ 0.308083] acpiphp: Slot [19] registered [ 0.310108] acpiphp: Slot [20] registered [ 0.312117] acpiphp: Slot [21] registered [ 0.314111] acpiphp: Slot [22] registered [ 0.315104] acpiphp: Slot [23] registered [ 0.317132] acpiphp: Slot [24] registered [ 0.319106] acpiphp: Slot [25] registered [ 0.321114] acpiphp: Slot [26] registered [ 0.322189] acpiphp: Slot [27] registered [ 0.324108] acpiphp: Slot [28] registered [ 0.326114] acpiphp: Slot [29] registered [ 0.327119] acpiphp: Slot [30] registered [ 0.329146] acpiphp: Slot [31] registered [ 0.331130] PCI host bridge to bus 0000:00 [ 0.333023] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.335022] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.338024] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.341033] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.344026] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.346032] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.348150] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.352072] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.355409] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.364014] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.367000] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.369078] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.372021] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.374015] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.376470] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.380382] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.383046] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.385766] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.390013] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.399757] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.403013] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.408622] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.414013] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.421021] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.433021] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.444029] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.449014] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.460020] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.480024] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.492197] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.494730] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.497447] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.500431] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.503288] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.508280] iommu: Default domain type: Passthrough [ 0.509453] SCSI subsystem initialized [ 0.510151] ACPI: bus type USB registered [ 0.511122] usbcore: registered new interface driver usbfs [ 0.513118] usbcore: registered new interface driver hub [ 0.515111] usbcore: registered new device driver usb [ 0.517160] pps_core: LinuxPPS API ver. 1 registered [ 0.519013] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.522076] PTP clock support registered [ 0.524133] EDAC MC: Ver: 3.0.0 [ 0.526369] PCI: Using ACPI for IRQ routing [ 0.528925] NetLabel: Initializing [ 0.530012] NetLabel: domain hash size = 128 [ 0.532067] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.534088] NetLabel: unlabeled traffic allowed by default [ 0.537195] vgaarb: loaded [ 0.538314] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.540013] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.546578] clocksource: Switched to clocksource kvm-clock [ 0.658282] VFS: Disk quotas dquot_6.6.0 [ 0.660739] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.663551] *** VALIDATE ramfs *** [ 0.664865] *** VALIDATE hugetlbfs *** [ 0.666743] pnp: PnP ACPI init [ 0.669093] pnp: PnP ACPI: found 6 devices [ 0.704388] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.707857] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.710471] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.712892] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.715580] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.718366] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.721606] NET: Registered protocol family 2 [ 0.724738] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.730126] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.734029] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.739990] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.743804] TCP: Hash tables configured (established 65536 bind 65536) [ 0.746922] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.750343] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.752652] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.754916] NET: Registered protocol family 1 [ 0.757390] RPC: Registered named UNIX socket transport module. [ 0.759694] RPC: Registered udp transport module. [ 0.761603] RPC: Registered tcp transport module. [ 0.763556] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.766085] NET: Registered protocol family 44 [ 0.768233] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.770287] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.772386] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.774891] PCI: CLS 0 bytes, default 64 [ 0.776633] Unpacking initramfs... [ 2.198828] debug: unmapping init [mem 0xffff9aa07cc64000-0xffff9aa07ffcffff] [ 2.203099] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.205675] software IO TLB: mapped [mem 0x00000000a1600000-0x00000000a5600000] (64MB) [ 2.208887] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.723242] Initialise system trusted keyrings [ 2.727389] Key type blacklist registered [ 2.729366] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.738592] zbud: loaded [ 2.741609] *** VALIDATE nfs *** [ 2.743050] *** VALIDATE nfs4 *** [ 2.744885] pstore: using deflate compression [ 2.748858] Platform Keyring initialized [ 2.861363] NET: Registered protocol family 38 [ 2.863534] Key type asymmetric registered [ 2.865465] Asymmetric key parser 'x509' registered [ 2.867701] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.872091] io scheduler mq-deadline registered [ 2.874128] io scheduler kyber registered [ 2.876466] io scheduler bfq registered [ 2.879097] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.883076] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.886504] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.889624] ACPI: Power Button [PWRF] [ 2.895402] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.902927] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.918452] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.949126] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.979575] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.991877] Non-volatile memory driver v1.3 [ 2.993795] Linux agpgart interface v0.103 [ 3.041336] virtio_blk virtio1: [vda] 145888 512-byte logical blocks (74.7 MB/71.2 MiB) [ 3.046203] vda: detected capacity change from 0 to 74694656 [ 3.066245] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.069649] vdb: detected capacity change from 0 to 1073741824 [ 3.086803] libphy: Fixed MDIO Bus: probed [ 3.092465] usbcore: registered new interface driver usbserial_generic [ 3.095209] usbserial: USB Serial support registered for generic [ 3.098368] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.102579] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.104780] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.107651] mousedev: PS/2 mouse device common for all mice [ 3.110554] rtc_cmos 00:05: RTC can wake from S4 [ 3.113286] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.115102] rtc_cmos 00:05: registered as rtc0 [ 3.118081] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.121774] intel_pstate: CPU model not supported [ 3.125867] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.128945] hid: raw HID events driver (C) Jiri Kosina [ 3.132515] usbcore: registered new interface driver usbhid [ 3.134827] usbhid: USB HID core driver [ 3.135108] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.136566] drop_monitor: Initializing network drop monitor service [ 3.142230] Initializing XFRM netlink socket [ 3.144290] NET: Registered protocol family 10 [ 3.147571] Segment Routing with IPv6 [ 3.149110] NET: Registered protocol family 17 [ 3.151142] mpls_gso: MPLS GSO support [ 3.156585] RAS: Correctable Errors collector initialized. [ 3.159439] AVX version of gcm_enc/dec engaged. [ 3.161242] AES CTR mode by8 optimization enabled [ 3.243100] sched_clock: Marking stable (3243040770, 0)->(4118739974, -875699204) [ 3.246430] registered taskstats version 1 [ 3.249135] Loading compiled-in X.509 certificates [ 3.251180] zswap: loaded using pool lzo/zbud [ 3.277544] Key type big_key registered [ 3.289698] Key type encrypted registered [ 3.291574] ima: No TPM chip found, activating TPM-bypass! [ 3.293630] ima: Allocated hash algorithm: sha1 [ 3.295179] ima: No architecture policies found [ 3.296807] evm: Initialising EVM extended attributes: [ 3.298612] evm: security.selinux [ 3.299793] evm: security.ima [ 3.301011] evm: security.capability [ 3.302493] evm: HMAC attrs: 0x1 [ 3.304897] rtc_cmos 00:05: setting system clock to 2026-08-06 23:28:26 UTC (1786058906) [ 3.311890] debug: unmapping init [mem 0xffffffff99e03000-0xffffffff99ffffff] [ 3.315786] debug: unmapping init [mem 0xffffffff98b82000-0xffffffff98e58fff] [ 3.325327] Write protecting the kernel read-only data: 28672k [ 3.328917] debug: unmapping init [mem 0xffffffff97203000-0xffffffff973fffff] [ 3.332093] debug: unmapping init [mem 0xffffffff97b14000-0xffffffff97bfffff] [ 3.363863] 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.371166] systemd[1]: Detected virtualization kvm. [ 3.373732] systemd[1]: Detected architecture x86-64. [ 3.375820] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.404557] systemd[1]: No hostname configured. [ 3.406816] systemd[1]: Set hostname to . [ 3.409251] random: systemd: uninitialized urandom read (16 bytes read) [ 3.412227] systemd[1]: Initializing machine ID from random generator. [ 3.461663] random: ln: uninitialized urandom read (6 bytes read) [ 3.561322] random: systemd: uninitialized urandom read (16 bytes read) [ 3.565144] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 3.570050] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 3.576410] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Reached target Local Encrypted Volumes. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Swap. [ OK ] Reached target Slices. Starting Apply Kernel Variables... [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Paths. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Initrd Root Device. Starting Setup Virtual Console... [ OK ] Started Memstrack Anylazing Service. Starting Journal Service... [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.230094] device-mapper: uevent: version 1.0.3 [ 4.232434] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 4.977796] virtio_net virtio0 ens2: renamed from eth0 [ 4.987047] random: fast init done [ 5.036256] scsi host0: ata_piix [ 5.082689] scsi host1: ata_piix [ 5.084135] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.086546] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.936134] dracut-initqueue[581]: RTNETLINK answers: File exists [ 9.807852] random: crng init done [ 9.809772] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 10.403225] 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 Initrd Default Target. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Swap. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Apply Kernel Variables. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.620357] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.876798] SELinux: Disabled at runtime. [ 11.939742] 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.948474] systemd[1]: Detected virtualization kvm. [ 11.950304] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.449924] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.455307] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.460200] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.467429] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.470676] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.479835] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.488529] systemd[1]: Mounting Kernel Debug File System... Mounting Kernel Debug File System... Mounting POSIX Message Queue File System... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Reached target rpc_pipefs.target. [ OK ] Created slice User and Session Slice. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on Process Core Dump Socket. Mounting Huge Pages File System... [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target Slices. Activating swap /dev/disk/by-label/SWAP... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Started Forward Password Requests to Wall Directory Watch. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK [[ 12.606310] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS 0m] Stopped target Initrd File Systems. Starting Apply Kernel Variables... Starting Remount Root and Kernel File Systems... [ OK ] Created slice system-getty.slice. [ OK ] Reached target RPC Port Mapper. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Started Journal Service. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Huge Pages File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. 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 /home/green/git/lustre-release... Mounting /mnt... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 12.911210] 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. [ 13.327869] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.383489] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.492703] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.508220] EDAC sbridge: Ver: 1.1.2 [ 14.757490] Key type dns_resolver registered [ 15.075400] NFS: Registering the id_resolver key type [ 15.077756] Key type id_resolver registered [ 15.079634] 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 RPC Bind. [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started dnf makecache --timer. [ 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. [ OK ] Reached target sshd-keygen.target. Starting Restore /run/initramfs on shutdown... [ OK ] Started irqbalance daemon. Starting Login Service... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ 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 ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting System Logging Service... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg603-client login: [ 66.468401] libcfs: loading out-of-tree module taints kernel. [ 67.041926] Key type ._llcrypt registered [ 67.048618] Key type .llcrypt registered [ 67.469299] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 67.492784] alg: No test for adler32 (adler32-zlib) [ 68.935961] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 69.794894] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 71.560190] Key type lgssc registered [ 73.493372] Lustre: Echo OBD driver; http://www.lustre.org/ [ 219.991472] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 225.210412] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 242.187394] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing check_logdir /tmp/testlogs/ [ 244.519028] hrtimer: interrupt took 4570925 ns [ 245.729279] Lustre: lustre-OST0000-osc-ffff9aa0c6c54000: disconnect after 23s idle [ 247.665547] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing yml_node [ 252.179461] Lustre: DEBUG MARKER: Client: 2.17.56.50 [ 254.727942] Lustre: DEBUG MARKER: MDS: 2.17.56.50 [ 257.633393] Lustre: DEBUG MARKER: OSS: 2.17.56.50 [ 259.625919] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Thu Aug 6 19:32:41 EDT 2026 [ 275.460093] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 276.822588] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 279.088381] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 289.445263] Lustre: Unmounted lustre-client [ 387.956053] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 392.808511] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 414.298315] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 448.276111] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 19:35:50 (1786059350) [ 450.824578] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 452.180073] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 19:35:54 (1786059354) [ 482.156884] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 487.015571] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 505.427788] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 505.846285] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 510.321874] Lustre: Unmounted lustre-client [ 537.226248] Key type lgssc unregistered [ 537.520272] LNet: 15038:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 537.533398] LNetError: 15038:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 537.556587] LNet: Removed LNI 192.168.206.3@tcp [ 538.477147] Key type .llcrypt unregistered [ 538.480986] Key type ._llcrypt unregistered [ 554.880625] Key type ._llcrypt registered [ 554.884865] Key type .llcrypt registered [ 556.211967] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 19:37:37 (1786059457) [ 557.846257] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 557.876704] alg: No test for adler32 (adler32-zlib) [ 558.887170] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 559.260585] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 560.984386] Key type lgssc registered [ 562.333759] Lustre: Echo OBD driver; http://www.lustre.org/ [ 630.762075] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 708.537527] Lustre: Unmounted lustre-client [ 735.649211] Key type lgssc unregistered [ 735.863303] LNet: 18819:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 735.869960] LNetError: 18819:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 735.890139] LNet: Removed LNI 192.168.206.3@tcp [ 736.524958] Key type .llcrypt unregistered [ 736.531894] Key type ._llcrypt unregistered [ 745.904139] Key type ._llcrypt registered [ 745.907047] Key type .llcrypt registered [ 747.136375] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 19:40:48 (1786059648) [ 749.122233] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 749.178633] alg: No test for adler32 (adler32-zlib) [ 750.240345] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 750.460854] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 752.120161] Key type lgssc registered [ 753.240662] Lustre: Echo OBD driver; http://www.lustre.org/ [ 796.107144] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 801.242473] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 818.909622] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 821.545750] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 831.249868] Lustre: Unmounted lustre-client [ 857.771598] Key type lgssc unregistered [ 858.063737] LNet: 22417:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 858.071563] LNetError: 22417:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 858.098346] LNet: Removed LNI 192.168.206.3@tcp [ 858.720104] Key type .llcrypt unregistered [ 858.728253] Key type ._llcrypt unregistered [ 867.867496] Key type ._llcrypt registered [ 867.869527] Key type .llcrypt registered [ 869.195796] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 19:42:50 (1786059770) [ 871.633909] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 871.672918] alg: No test for adler32 (adler32-zlib) [ 872.805449] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 873.047077] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 874.760275] Key type lgssc registered [ 875.829942] Lustre: Echo OBD driver; http://www.lustre.org/ [ 914.992242] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 918.838119] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 935.330197] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 946.204794] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 950.827286] Lustre: Unmounted lustre-client [ 975.114570] Key type lgssc unregistered [ 975.331623] LNet: 25608:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 975.337556] LNetError: 25608:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 975.354070] LNet: Removed LNI 192.168.206.3@tcp [ 975.956507] Key type .llcrypt unregistered [ 975.961202] Key type ._llcrypt unregistered [ 988.248769] Key type ._llcrypt registered [ 988.251255] Key type .llcrypt registered [ 989.454885] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 19:44:51 (1786059891) [ 991.308788] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 991.325329] alg: No test for adler32 (adler32-zlib) [ 992.337751] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 992.535631] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 994.184885] Key type lgssc registered [ 995.191296] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1038.140285] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1043.645186] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1064.135138] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1066.085872] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 1071.079465] Lustre: lustre-MDT0000-mdc-ffff9aa0c6c55000: Connection to lustre-MDT0000 (at 192.168.206.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1081.317888] Lustre: lustre-MDT0001-mdc-ffff9aa0c6c55000: Connection to lustre-MDT0001 (at 192.168.206.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1088.662485] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1092.576194] Lustre: 26565:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786059979/real 1786059979] req@00000000ed68493e x1872819540465024/t0(0) o400->MGC192.168.206.103@tcp@192.168.206.103@tcp:26/25 lens 224/224 e 0 to 1 dl 1786059995 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1092.596413] LustreError: MGC192.168.206.103@tcp: Connection to MGS (at 192.168.206.103@tcp) was lost; in progress operations using this service will fail [ 1099.002789] Lustre: Unmounted lustre-client [ 1116.679342] Key type lgssc unregistered [ 1116.934754] LNet: 29259:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1116.944723] LNetError: 29259:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1116.962816] LNet: Removed LNI 192.168.206.3@tcp [ 1117.550836] Key type .llcrypt unregistered [ 1117.558251] Key type ._llcrypt unregistered [ 1130.670269] Key type ._llcrypt registered [ 1130.673809] Key type .llcrypt registered [ 1131.621359] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 19:47:13 (1786060033) [ 1134.057217] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1134.083738] alg: No test for adler32 (adler32-zlib) [ 1135.281798] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 1135.526208] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 1137.184212] Key type lgssc registered [ 1138.780035] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1180.933684] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1185.744967] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1202.754371] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1208.365052] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 1213.431292] Lustre: lustre-OST0000-osc-ffff9aa0ca237800: Connection to lustre-OST0000 (at 192.168.206.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1227.666821] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1237.996759] Lustre: Unmounted lustre-client [ 1271.753778] Key type lgssc unregistered [ 1271.968528] LNet: 32874:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1271.975073] LNetError: 32874:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1271.992763] LNet: Removed LNI 192.168.206.3@tcp [ 1272.802473] Key type .llcrypt unregistered [ 1272.814202] Key type ._llcrypt unregistered [ 1287.517738] Key type ._llcrypt registered [ 1287.519770] Key type .llcrypt registered [ 1288.693494] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 19:49:50 (1786060190) [ 1290.559536] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1290.693978] alg: No test for adler32 (adler32-zlib) [ 1291.760855] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 1292.095547] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 1293.800323] Key type lgssc registered [ 1294.841118] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1340.450797] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1345.435469] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1364.438466] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1367.114786] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 1370.356724] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1373.344921] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1384.839566] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-08-0619h51m26s [ 1386.486111] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1392.616616] Lustre: lustre-MDT0000-mdc-ffff9aa0c54bf000: Connection to lustre-MDT0000 (at 192.168.206.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1396.170570] LustreError: 36658:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff9aa0c54bf000: can't stat MDS #0: rc = -19 [ 1397.300516] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1403.887092] LustreError: MGC192.168.206.103@tcp: Connection to MGS (at 192.168.206.103@tcp) was lost; in progress operations using this service will fail [ 1403.896676] Lustre: Evicted from MGS (at 192.168.206.103@tcp) after server handle changed from 0x8accffc32f372725 to 0x8accffc32f3729e8 [ 1403.902604] Lustre: MGC192.168.206.103@tcp: Connection restored to 192.168.206.103@tcp (at 192.168.206.103@tcp) [ 1404.975193] LustreError: lustre-MDT0000-mdc-ffff9aa0c54bf000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1404.998684] Lustre: lustre-MDT0000-mdc-ffff9aa0c54bf000: Connection restored to 192.168.206.103@tcp (at 192.168.206.103@tcp) [ 1409.626493] Lustre: DEBUG MARKER: Wait for df (36658) ... [ 1410.686148] Lustre: DEBUG MARKER: done [ 1412.189156] Lustre: Unmounted lustre-client [ 1446.404159] Key type lgssc unregistered [ 1446.632773] LNet: 37675:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1446.638169] LNetError: 37675:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1446.672862] LNet: Removed LNI 192.168.206.3@tcp [ 1447.569076] Key type .llcrypt unregistered [ 1447.578790] Key type ._llcrypt unregistered [ 1476.855517] Key type ._llcrypt registered [ 1476.857287] Key type .llcrypt registered [ 1477.898809] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 19:52:59 (1786060379) [ 1479.567541] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1479.695222] alg: No test for adler32 (adler32-zlib) [ 1480.760867] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 1480.992537] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 1482.648209] Key type lgssc registered [ 1484.078520] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1525.145587] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1529.419568] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1544.253724] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1546.705039] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 1550.280532] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-08-0619h54m12s [ 1551.518361] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1557.106897] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1559.135104] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1579.488184] Lustre: 41815:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786060462/real 1786060462] req@000000005b256e56 x1872820053219584/t0(0) o101->lustre-MDT0000-mdc-ffff9aa0c74a1800@192.168.206.103@tcp:12/10 lens 576/1152 e 0 to 1 dl 1786060482 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1579.526433] Lustre: lustre-MDT0000-mdc-ffff9aa0c74a1800: Connection to lustre-MDT0000 (at 192.168.206.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1579.563976] Lustre: lustre-MDT0000-mdc-ffff9aa0c74a1800: Connection restored to 192.168.206.103@tcp (at 192.168.206.103@tcp) [ 1600.781162] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1603.723951] Lustre: DEBUG MARKER: done [ 1607.803827] Lustre: Unmounted lustre-client [ 1629.367681] Key type lgssc unregistered [ 1629.622426] LNet: 42699:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1629.628929] LNetError: 42699:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1629.649449] LNet: Removed LNI 192.168.206.3@tcp [ 1630.356276] Key type .llcrypt unregistered [ 1630.359074] Key type ._llcrypt unregistered [ 1658.634880] Key type ._llcrypt registered [ 1658.642510] Key type .llcrypt registered [ 1659.879857] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 19:56:01 (1786060561) [ 1661.368509] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1662.998878] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 19:56:04 (1786060564) [ 1666.593578] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1666.610619] alg: No test for adler32 (adler32-zlib) [ 1667.648410] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 1667.888646] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 1669.561139] Key type lgssc registered [ 1670.724532] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1692.638831] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 19:56:34 (1786060594) [ 1719.638439] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1724.725650] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1746.257861] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1748.915574] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 1752.108894] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1755.430676] Lustre: Unmounted lustre-client [ 1771.054401] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 1817.004259] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1821.401692] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1821.819444] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 1825.214385] Lustre: Unmounted lustre-client [ 1887.899546] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1893.263245] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1893.812638] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 1897.278307] Lustre: Unmounted lustre-client [ 1913.644229] Lustre: DEBUG MARKER: files compared the same [ 1934.646687] Key type lgssc unregistered [ 1934.905752] LNet: 50317:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1934.913159] LNetError: 50317:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1934.925486] LNet: Removed LNI 192.168.206.3@tcp [ 1935.621759] Key type .llcrypt unregistered [ 1935.623588] Key type ._llcrypt unregistered [ 1949.008850] Key type ._llcrypt registered [ 1949.011033] Key type .llcrypt registered [ 1950.205745] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 20:00:52 (1786060852) [ 1952.377985] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1952.404846] alg: No test for adler32 (adler32-zlib) [ 1953.621592] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 1953.953206] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 1955.632199] Key type lgssc registered [ 1956.760847] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2006.370937] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2012.450454] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2034.582609] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2037.243904] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 2041.341673] Lustre: Unmounted lustre-client [ 2078.974556] Key type lgssc unregistered [ 2079.279030] LNet: 53914:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2079.292094] LNetError: 53914:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2079.319319] LNet: Removed LNI 192.168.206.3@tcp [ 2080.134460] Key type .llcrypt unregistered [ 2080.141848] Key type ._llcrypt unregistered [ 2097.581813] Key type ._llcrypt registered [ 2097.584700] Key type .llcrypt registered [ 2098.692824] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 20:03:20 (1786061000) [ 2100.752031] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2100.786704] alg: No test for adler32 (adler32-zlib) [ 2101.862209] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 2102.101942] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 2103.800235] Key type lgssc registered [ 2104.851784] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2188.253353] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2218.686815] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2224.232563] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2250.717831] Key type lgssc unregistered [ 2250.966513] LNet: 57404:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2250.981916] LNetError: 57404:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2251.008271] LNet: Removed LNI 192.168.206.3@tcp [ 2251.704193] Key type .llcrypt unregistered [ 2251.706587] Key type ._llcrypt unregistered [ 2267.416899] Key type ._llcrypt registered [ 2267.419482] Key type .llcrypt registered [ 2268.920481] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 20:06:10 (1786061170) [ 2272.484404] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2272.616770] alg: No test for adler32 (adler32-zlib) [ 2273.811185] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 2274.121746] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 2275.810110] Key type lgssc registered [ 2276.890344] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2351.124664] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 2358.410600] Lustre: Unmounted lustre-client [ 2394.052605] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 20:08:15 (1786061295) [ 2503.387188] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2515.654213] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 2523.284151] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2523.305561] LustreError: 63881:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff9aa0c7308000: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2523.386168] Lustre: 58391:0:(llite_lib.c:4340:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.206.103@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2533.779227] Lustre: Unmounted lustre-client [ 2579.006838] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 20:11:20 (1786061480) [ 2591.345729] Key type lgssc unregistered [ 2591.576070] LNet: 65098:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2591.580363] LNetError: 65098:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2591.594910] LNet: Removed LNI 192.168.206.3@tcp [ 2592.416744] Key type .llcrypt unregistered [ 2592.421182] Key type ._llcrypt unregistered [ 2606.125630] Key type ._llcrypt registered [ 2606.134807] Key type .llcrypt registered [ 2606.512525] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2606.523409] alg: No test for adler32 (adler32-zlib) [ 2607.702533] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 2608.099140] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 2609.841073] Key type lgssc registered [ 2611.694602] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2668.111827] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2674.875277] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2696.787298] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2698.284228] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 2705.474512] Lustre: Unmounted lustre-client [ 2732.901589] Key type lgssc unregistered [ 2733.141417] LNet: 68119:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2733.148688] LNetError: 68119:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2733.175340] LNet: Removed LNI 192.168.206.3@tcp [ 2733.801887] Key type .llcrypt unregistered [ 2733.812802] Key type ._llcrypt unregistered [ 2745.192084] Key type ._llcrypt registered [ 2745.196600] Key type .llcrypt registered [ 2745.442919] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2745.456142] alg: No test for adler32 (adler32-zlib) [ 2746.601424] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 2746.860520] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 2748.600419] Key type lgssc registered [ 2750.179571] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2857.294256] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2862.900335] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2882.280836] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2883.853536] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 2886.420027] Lustre: Unmounted lustre-client [ 2920.628815] Key type lgssc unregistered [ 2920.895238] LNet: 71560:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2920.906548] LNetError: 71560:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2920.930845] LNet: Removed LNI 192.168.206.3@tcp [ 2921.722311] Key type .llcrypt unregistered [ 2921.728460] Key type ._llcrypt unregistered [ 2935.313838] Key type ._llcrypt registered [ 2935.318200] Key type .llcrypt registered [ 2936.490758] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 20:17:18 (1786061838) [ 2938.262780] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2938.288802] alg: No test for adler32 (adler32-zlib) [ 2939.386258] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 2939.619240] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 2941.280209] Key type lgssc registered [ 2942.137121] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2980.709581] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2985.132257] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3001.693413] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3008.538520] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 3012.520477] Lustre: Unmounted lustre-client [ 3012.829109] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 3014.763355] Lustre: Unmounted lustre-client [ 3035.389306] Key type lgssc unregistered [ 3035.674620] LNet: 75245:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3035.688990] LNetError: 75245:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3036.721124] LNet: Removed LNI 192.168.206.3@tcp [ 3037.473304] Key type .llcrypt unregistered [ 3037.476093] Key type ._llcrypt unregistered [ 3048.428320] Key type ._llcrypt registered [ 3048.434399] Key type .llcrypt registered [ 3048.883759] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3048.914772] alg: No test for adler32 (adler32-zlib) [ 3049.940990] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 3050.176291] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 3051.840180] Key type lgssc registered [ 3052.866889] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3092.960564] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3097.306945] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3118.902040] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3120.798592] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 3125.803596] Lustre: Unmounted lustre-client [ 3155.486170] Key type lgssc unregistered [ 3155.703932] LNet: 78282:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3155.716396] LNetError: 78282:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3155.736815] LNet: Removed LNI 192.168.206.3@tcp [ 3156.283429] Key type .llcrypt unregistered [ 3156.294954] Key type ._llcrypt unregistered [ 3172.239020] Key type ._llcrypt registered [ 3172.243642] Key type .llcrypt registered [ 3174.199494] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 20:21:15 (1786062075) [ 3176.803438] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3176.870574] alg: No test for adler32 (adler32-zlib) [ 3177.852359] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 3178.086166] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 3179.762946] Key type lgssc registered [ 3181.181275] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3228.400568] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3234.820324] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3251.772375] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3253.270217] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 3258.295624] Lustre: lustre: root_squash is set to 0:0 [ 3264.882708] Lustre: lustre: nosquash_nids is cleared [ 3273.270313] Lustre: lustre: root_squash is set to 500:500 [ 3280.484759] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.206.3@tcp 192.168.0.[2,10]@tcp [ 3295.834262] Lustre: lustre: nosquash_nids set to 192.168.206.3/24@tcp [ 3299.438795] Lustre: Unmounted lustre-client [ 3328.274430] Key type lgssc unregistered [ 3328.465837] LNet: 82265:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3328.471032] LNetError: 82265:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3328.484453] LNet: Removed LNI 192.168.206.3@tcp [ 3328.965578] Key type .llcrypt unregistered [ 3328.969983] Key type ._llcrypt unregistered [ 3341.316963] Key type ._llcrypt registered [ 3341.319613] Key type .llcrypt registered [ 3342.304456] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 20:24:04 (1786062244) [ 3344.223278] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3345.756306] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 20:24:07 (1786062247) [ 3347.797558] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3347.818775] alg: No test for adler32 (adler32-zlib) [ 3348.858541] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 3349.058771] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 3350.704170] Key type lgssc registered [ 3351.673533] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3399.822127] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3404.900242] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3425.491206] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3426.002619] Lustre: lustre: root_squash is set to 500:500 [ 3426.023370] Lustre: lustre: nosquash_nids set to 192.168.206.3/24@tcp [ 3426.851431] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 3432.314458] Lustre: Unmounted lustre-client [ 3459.976682] Key type lgssc unregistered [ 3460.132064] LNet: 86226:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3460.138300] LNetError: 86226:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3460.161263] LNet: Removed LNI 192.168.206.3@tcp [ 3460.974820] Key type .llcrypt unregistered [ 3460.976609] Key type ._llcrypt unregistered [ 3477.441581] Key type ._llcrypt registered [ 3477.443537] Key type .llcrypt registered [ 3478.812928] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 3480.278392] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 20:26:22 (1786062382) [ 3500.295100] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3500.332278] alg: No test for adler32 (adler32-zlib) [ 3501.345463] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 3501.631421] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 3503.312287] Key type lgssc registered [ 3505.161481] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3585.837636] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3591.437836] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3612.443221] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3670.494067] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3674.968445] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3697.005412] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3708.369947] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 3737.048401] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9aa0c4de4800.ost_server_uuid 50 [ 3738.488800] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9aa0c4de4800.ost_server_uuid in FULL state after 0 sec [ 3738.850325] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 3742.226026] Lustre: Unmounted lustre-client [ 3744.249977] Lustre: Unmounted lustre-client [ 3785.633091] Key type lgssc unregistered [ 3785.892333] LNet: 93895:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3785.901744] LNetError: 93895:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3785.920641] LNet: Removed LNI 192.168.206.3@tcp [ 3786.535063] Key type .llcrypt unregistered [ 3786.537636] Key type ._llcrypt unregistered [ 3812.736300] Key type ._llcrypt registered [ 3812.738316] Key type .llcrypt registered [ 3813.695330] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 20:31:55 (1786062715) [ 3826.800641] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3826.814134] alg: No test for adler32 (adler32-zlib) [ 3827.815039] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 3828.005672] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 3829.656266] Key type lgssc registered [ 3830.408519] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3896.872285] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3901.417593] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3919.694571] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3921.255907] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 3941.858268] Lustre: 95259:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786062829/real 1786062829] req@00000000b0456358 x1872822514226304/t0(0) o400->lustre-OST0000-osc-ffff9aa0c82ac800@192.168.206.103@tcp:28/4 lens 224/224 e 0 to 1 dl 1786062845 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3941.882436] Lustre: lustre-OST0000-osc-ffff9aa0c82ac800: Connection to lustre-OST0000 (at 192.168.206.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3945.189323] Lustre: lustre-OST0000-osc-ffff9aa0c82ac800: Connection restored to 192.168.206.103@tcp (at 192.168.206.103@tcp) [ 3948.000156] Lustre: 95259:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786062834/real 1786062834] req@000000000d8d46c8 x1872822514226816/t0(0) o400->lustre-OST0000-osc-ffff9aa0c82ac800@192.168.206.103@tcp:28/4 lens 224/224 e 0 to 1 dl 1786062850 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3952.109286] Lustre: lustre-MDT0000-mdc-ffff9aa0c82ac800: Connection to lustre-MDT0000 (at 192.168.206.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3953.120191] Lustre: 95259:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786062839/real 1786062839] req@0000000012b5553e x1872822514227328/t0(0) o400->lustre-OST0000-osc-ffff9aa0c82ac800@192.168.206.103@tcp:28/4 lens 224/224 e 0 to 1 dl 1786062855 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3964.384119] Lustre: 98431:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786062851/real 1786062851] req@0000000011bf9f6c x1872822514229120/t0(0) o101->MGC192.168.206.103@tcp@192.168.206.103@tcp:26/25 lens 328/344 e 0 to 1 dl 1786062867 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3964.423735] LustreError: MGC192.168.206.103@tcp: Connection to MGS (at 192.168.206.103@tcp) was lost; in progress operations using this service will fail [ 3964.434327] Lustre: 98431:0:(mgc_request.c:1899:mgc_process_log()) MGC192.168.206.103@tcp: IR log lustre-cliir failed, not fatal: rc = -5 [ 3970.258224] Lustre: lustre-MDT0000-mdc-ffff9aa0c82ac800: Connection restored to 192.168.206.103@tcp (at 192.168.206.103@tcp) [ 3974.327549] Lustre: Evicted from MGS (at 192.168.206.103@tcp) after server handle changed from 0x2706a45f3e132db6 to 0x2706a45f3e1331de [ 3974.339898] Lustre: MGC192.168.206.103@tcp: Connection restored to 192.168.206.103@tcp (at 192.168.206.103@tcp) [ 3975.145497] Lustre: Unmounted lustre-client [ 4008.469627] Key type lgssc unregistered [ 4008.743625] LNet: 99559:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4008.763156] LNetError: 99559:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4008.792651] LNet: Removed LNI 192.168.206.3@tcp [ 4009.678154] Key type .llcrypt unregistered [ 4009.681476] Key type ._llcrypt unregistered [ 4021.326902] Key type ._llcrypt registered [ 4021.328553] Key type .llcrypt registered [ 4022.416991] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 20:35:24 (1786062924) [ 4036.893430] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4036.908731] alg: No test for adler32 (adler32-zlib) [ 4037.967238] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 4038.192925] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 4039.834948] Key type lgssc registered [ 4041.279956] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4087.959080] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4180.200588] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4185.715088] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4209.354721] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4211.096031] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 4213.096798] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 4214.779906] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 20:38:36 (1786063116) [ 4430.035367] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4440.338275] Lustre: Unmounted lustre-client [ 4547.904407] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4552.951813] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4572.576272] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4621.405469] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 20:45:23 (1786063523) [ 4700.524884] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4705.297715] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4725.007781] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4725.613978] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 4730.024921] Lustre: Unmounted lustre-client [ 4767.704981] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 20:47:49 (1786063669) [ 4849.048156] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4855.985089] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4881.229737] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4881.731444] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 4885.935700] Lustre: Unmounted lustre-client [ 4928.280770] Key type lgssc unregistered [ 4928.533612] LNet: 122594:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4928.553277] LNetError: 122594:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4928.573825] LNet: Removed LNI 192.168.206.3@tcp [ 4929.206359] Key type .llcrypt unregistered [ 4929.208321] Key type ._llcrypt unregistered [ 4942.161299] Key type ._llcrypt registered [ 4942.162676] Key type .llcrypt registered [ 4943.296518] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 20:50:45 (1786063845) [ 4945.162059] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4945.258638] alg: No test for adler32 (adler32-zlib) [ 4946.330664] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 4946.574363] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 4948.226509] Key type lgssc registered [ 4949.354562] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4995.446279] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5000.532336] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5019.794692] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5021.548730] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 5034.133247] Lustre: Unmounted lustre-client [ 5058.169735] Key type lgssc unregistered [ 5058.350069] LNet: 126188:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5058.355329] LNetError: 126188:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5058.370054] LNet: Removed LNI 192.168.206.3@tcp [ 5058.936377] Key type .llcrypt unregistered [ 5058.939789] Key type ._llcrypt unregistered [ 5071.967313] Key type ._llcrypt registered [ 5071.973364] Key type .llcrypt registered [ 5073.099805] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 20:52:55 (1786063975) [ 5074.795625] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5074.813574] alg: No test for adler32 (adler32-zlib) [ 5075.878498] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 5076.073851] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 5077.728180] Key type lgssc registered [ 5078.749479] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5123.398784] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5128.327433] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5145.351791] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5151.268944] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 5156.326508] Lustre: lustre-OST0000-osc-ffff9aa0c7766800: Connection to lustre-OST0000 (at 192.168.206.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5167.454845] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff9aa0c7766800.ost_server_uuid 50 [ 5168.809687] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 5179.458604] Lustre: Unmounted lustre-client [ 5199.691954] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 20:55:01 (1786064101) [ 5228.527314] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5233.363099] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5252.645940] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5274.283731] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5274.844439] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 5280.244405] Lustre: lustre-OST0000-osc-ffff9aa0d2270000: Connection to lustre-OST0000 (at 192.168.206.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5304.205542] Lustre: Unmounted lustre-client [ 5354.859653] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 20:57:36 (1786064256) [ 5385.188992] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5390.639717] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5407.109486] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5426.180311] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5426.883252] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 5432.295059] Lustre: lustre-OST0000-osc-ffff9aa0c74a7800: Connection to lustre-OST0000 (at 192.168.206.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5448.234491] Lustre: Unmounted lustre-client [ 5498.532363] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 21:00:00 (1786064400) [ 5581.476694] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5586.530446] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5607.291364] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5670.655629] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5676.499696] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5696.165247] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5724.149882] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 5766.826153] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5771.580847] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 3 sec [ 5773.420995] Lustre: Unmounted lustre-client [ 5805.536770] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 21:05:07 (1786064707) [ 5839.519644] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5845.526650] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5867.400752] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5904.108484] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5934.577480] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 5979.112235] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5981.548874] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 5994.124850] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6004.475662] Lustre: Unmounted lustre-client [ 6064.119794] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 21:09:26 (1786064966) [ 6094.893239] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6100.633539] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6121.068745] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6121.714596] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 6143.476343] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6145.424970] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6160.320111] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9aa0d2273000.ost_server_uuid 50 [ 6161.685811] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9aa0d2273000.ost_server_uuid in IDLE state after 0 sec [ 6165.223099] Lustre: Unmounted lustre-client [ 6165.474787] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6165.481963] Lustre: Skipped 3 previous similar messages [ 6165.593925] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 6166.707514] LustreError: 149646:0:(sec.c:403:import_sec_validate_get()) import 0000000028accc8f (NEW) with no sec [ 6218.562117] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 21:12:00 (1786065120) [ 6249.195595] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6254.952158] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6272.685153] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6291.030164] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6291.394388] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 6291.538896] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 6304.357540] Lustre: Unmounted lustre-client [ 6304.359353] Lustre: Skipped 1 previous similar message [ 6337.383097] Key type lgssc unregistered [ 6337.585704] LNet: 153064:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6337.592671] LNetError: 153064:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6338.663395] LNet: Removed LNI 192.168.206.3@tcp [ 6339.347972] Key type .llcrypt unregistered [ 6339.350784] Key type ._llcrypt unregistered [ 6353.186926] Key type ._llcrypt registered [ 6353.189222] Key type .llcrypt registered [ 6354.432316] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 21:14:16 (1786065256) [ 6355.451209] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6355.477884] alg: No test for adler32 (adler32-zlib) [ 6356.467630] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 6356.652814] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 6358.328176] Key type lgssc registered [ 6359.641620] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6409.703257] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6414.557440] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6434.892375] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6454.483996] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6456.044683] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 6457.216995] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 6465.028388] LustreError: lustre-MDT0001-mdc-ffff9aa0c7765000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 6465.065538] Lustre: lustre-MDT0001-mdc-ffff9aa0c7765000: Connection restored to 192.168.206.103@tcp (at 192.168.206.103@tcp) [ 6469.986827] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6471.241397] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6490.119738] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 6493.255301] Lustre: Unmounted lustre-client [ 6521.191749] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 21:17:03 (1786065423) [ 6608.951640] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6613.762985] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6637.277930] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6637.758105] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 6663.744783] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6665.110811] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6677.678339] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6688.070367] Lustre: Unmounted lustre-client [ 6720.636912] Key type lgssc unregistered [ 6721.032795] LNet: 162673:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6721.042393] LNetError: 162673:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6721.056827] LNet: Removed LNI 192.168.206.3@tcp [ 6721.606182] Key type .llcrypt unregistered [ 6721.614986] Key type ._llcrypt unregistered [ 6754.746243] Key type ._llcrypt registered [ 6754.749403] Key type .llcrypt registered [ 6756.042982] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 21:20:57 (1786065657) [ 6758.096042] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6758.233883] alg: No test for adler32 (adler32-zlib) [ 6759.314439] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 6759.541700] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 6761.185506] Key type lgssc registered [ 6762.492498] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6808.421485] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6813.265760] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6831.862954] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6833.420900] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 6879.436097] Lustre: Unmounted lustre-client [ 6919.020461] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6919.720767] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 6954.542593] Lustre: Unmounted lustre-client [ 6981.166696] Key type lgssc unregistered [ 6981.388153] LNet: 167116:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6981.414189] LNetError: 167116:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6981.479241] LNet: Removed LNI 192.168.206.3@tcp [ 6982.414110] Key type .llcrypt unregistered [ 6982.416773] Key type ._llcrypt unregistered [ 6998.288351] Key type ._llcrypt registered [ 6998.294174] Key type .llcrypt registered [ 6999.698652] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 21:25:01 (1786065901) [ 7002.026095] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7002.042536] alg: No test for adler32 (adler32-zlib) [ 7003.169170] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 7003.427938] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 7005.153516] Key type lgssc registered [ 7006.504718] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7058.654401] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7065.016721] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7084.406898] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7087.097189] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 7111.214233] Lustre: Unmounted lustre-client [ 7147.690608] Key type lgssc unregistered [ 7148.000695] LNet: 170874:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7148.039693] LNetError: 170874:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7148.068138] LNet: Removed LNI 192.168.206.3@tcp [ 7149.052210] Key type .llcrypt unregistered [ 7149.055077] Key type ._llcrypt unregistered [ 7162.571294] Key type ._llcrypt registered [ 7162.574241] Key type .llcrypt registered [ 7163.074770] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7163.102654] alg: No test for adler32 (adler32-zlib) [ 7164.158994] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 7164.461816] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 7166.152342] Key type lgssc registered [ 7167.382736] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7218.404741] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7224.007400] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7244.571136] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7247.319504] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 7257.355699] Lustre: Unmounted lustre-client [ 7286.418598] Key type lgssc unregistered [ 7286.704148] LNet: 173938:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7286.716527] LNetError: 173938:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7286.750212] LNet: Removed LNI 192.168.206.3@tcp [ 7287.612099] Key type .llcrypt unregistered [ 7287.615247] Key type ._llcrypt unregistered [ 7301.983516] Key type ._llcrypt registered [ 7301.985489] Key type .llcrypt registered [ 7303.056504] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 21:30:05 (1786066205) [ 7305.531387] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7305.550571] alg: No test for adler32 (adler32-zlib) [ 7306.656965] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 7306.930167] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 7308.616191] Key type lgssc registered [ 7309.804967] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7353.549153] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7357.889880] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7376.448916] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7379.305857] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 7397.929514] Lustre: Unmounted lustre-client [ 7425.135524] Key type lgssc unregistered [ 7425.350604] LNet: 177690:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7425.355493] LNetError: 177690:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7425.380889] LNet: Removed LNI 192.168.206.3@tcp [ 7426.004384] Key type .llcrypt unregistered [ 7426.013863] Key type ._llcrypt unregistered [ 7437.304422] Key type ._llcrypt registered [ 7437.309322] Key type .llcrypt registered [ 7437.705816] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7437.723554] alg: No test for adler32 (adler32-zlib) [ 7438.736827] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 7438.960212] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 7440.656327] Key type lgssc registered [ 7442.089356] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7488.284588] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7493.266727] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7514.786819] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7516.474985] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 7522.897194] Lustre: Unmounted lustre-client [ 7557.857206] Key type lgssc unregistered [ 7558.108544] LNet: 180755:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7558.125752] LNetError: 180755:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7558.147970] LNet: Removed LNI 192.168.206.3@tcp [ 7559.286386] Key type .llcrypt unregistered [ 7559.304945] Key type ._llcrypt unregistered [ 7579.038119] Key type ._llcrypt registered [ 7579.040094] Key type .llcrypt registered [ 7579.865945] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 21:34:42 (1786066482) [ 7598.346195] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7598.360981] alg: No test for adler32 (adler32-zlib) [ 7599.434862] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 7599.657611] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 7601.416167] Key type lgssc registered [ 7602.778371] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7681.793661] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7687.504114] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7708.383898] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7749.757676] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 21:37:31 (1786066651) [ 7783.054786] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7788.964506] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7807.315901] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7809.904653] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 7814.643465] Lustre: Unmounted lustre-client [ 7844.826893] Key type lgssc unregistered [ 7845.337066] LNet: 187856:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7845.358903] LNetError: 187856:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7845.377971] LNet: Removed LNI 192.168.206.3@tcp [ 7846.004278] Key type .llcrypt unregistered [ 7846.006701] Key type ._llcrypt unregistered [ 7865.212659] Key type ._llcrypt registered [ 7865.215742] Key type .llcrypt registered [ 7866.473504] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 7868.316133] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 21:39:29 (1786066769) [ 7888.639391] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7888.664592] alg: No test for adler32 (adler32-zlib) [ 7889.695632] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 7889.953283] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 7891.664172] Key type lgssc registered [ 7893.539415] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7984.767199] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7990.136935] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8012.369385] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8032.730843] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8035.505123] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 8062.079706] Lustre: Unmounted lustre-client [ 8187.347561] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8193.731514] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8213.702602] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8250.380314] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 21:45:52 (1786067152) [ 8251.507705] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 8252.971967] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 21:45:55 (1786067155) [ 8339.544375] Key type lgssc unregistered [ 8339.890451] LNet: 199296:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8339.904691] LNetError: 199296:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8339.921704] LNet: Removed LNI 192.168.206.3@tcp [ 8340.778763] Key type .llcrypt unregistered [ 8340.782104] Key type ._llcrypt unregistered [ 8357.402833] Key type ._llcrypt registered [ 8357.405984] Key type .llcrypt registered [ 8358.419284] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 21:47:40 (1786067260) [ 8398.033685] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8398.044750] alg: No test for adler32 (adler32-zlib) [ 8399.316421] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 8399.681769] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 8401.354481] Key type lgssc registered [ 8402.631129] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8441.979987] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8447.117624] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8480.452893] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8505.876214] Key type lgssc unregistered [ 8506.168446] LNet: 203036:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8506.177989] LNetError: 203036:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8506.201420] LNet: Removed LNI 192.168.206.3@tcp [ 8506.888187] Key type .llcrypt unregistered [ 8506.891108] Key type ._llcrypt unregistered [ 8523.868454] Key type ._llcrypt registered [ 8523.872305] Key type .llcrypt registered [ 8525.293591] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 21:50:26 (1786067426) [ 8528.186152] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8528.237222] alg: No test for adler32 (adler32-zlib) [ 8529.489414] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 8529.965405] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 8531.768699] Key type lgssc registered [ 8533.712024] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8584.963588] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8590.020388] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8613.054595] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8614.744451] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 8620.014874] Lustre: lustre-MDT0000-mdc-ffff9aa0c946b000: Connection to lustre-MDT0000 (at 192.168.206.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8630.251657] Lustre: lustre-MDT0001-mdc-ffff9aa0c946b000: Connection to lustre-MDT0001 (at 192.168.206.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8646.496508] Lustre: 204026:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786067533/real 1786067533] req@00000000675a0275 x1872827444707328/t0(0) o400->MGC192.168.206.103@tcp@192.168.206.103@tcp:26/25 lens 224/224 e 0 to 1 dl 1786067549 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 8646.526046] LustreError: MGC192.168.206.103@tcp: Connection to MGS (at 192.168.206.103@tcp) was lost; in progress operations using this service will fail [ 8656.811847] Lustre: Evicted from MGS (at 192.168.206.103@tcp) after server handle changed from 0xb30814de1efbc2cd to 0xb30814de1efbdded [ 8656.821845] Lustre: MGC192.168.206.103@tcp: Connection restored to 192.168.206.103@tcp (at 192.168.206.103@tcp) [ 8675.306843] LustreError: lustre-MDT0000-mdc-ffff9aa0c946b000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 8675.339793] Lustre: lustre-MDT0000-mdc-ffff9aa0c946b000: Connection restored to 192.168.206.103@tcp (at 192.168.206.103@tcp) [ 8678.169851] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8679.488953] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 8684.250278] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8685.432557] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 8688.589483] Lustre: Unmounted lustre-client [ 8724.067591] Key type lgssc unregistered [ 8724.345186] LNet: 207986:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8724.352279] LNetError: 207986:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8724.370137] LNet: Removed LNI 192.168.206.3@tcp [ 8725.097451] Key type .llcrypt unregistered [ 8725.100950] Key type ._llcrypt unregistered [ 8739.606359] Key type ._llcrypt registered [ 8739.608738] Key type .llcrypt registered [ 8740.762586] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 21:54:02 (1786067642) [ 8743.209379] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8743.227220] alg: No test for adler32 (adler32-zlib) [ 8744.320360] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 8744.536527] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 8746.248165] Key type lgssc registered [ 8747.177161] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8791.615343] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8797.035400] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8842.599661] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8849.269456] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8887.293378] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8913.041233] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8949.168947] Key type lgssc unregistered [ 8949.458521] LNet: 213281:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8949.466078] LNetError: 213281:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8949.485084] LNet: Removed LNI 192.168.206.3@tcp [ 8950.183546] Key type .llcrypt unregistered [ 8950.205173] Key type ._llcrypt unregistered [ 8993.595314] Key type ._llcrypt registered [ 8993.597561] Key type .llcrypt registered [ 8994.847283] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 21:58:16 (1786067896) [ 9037.904552] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9037.940611] alg: No test for adler32 (adler32-zlib) [ 9038.989617] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 9039.458946] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 9041.216951] Key type lgssc registered [ 9042.532292] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9125.785077] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9131.007318] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9151.913336] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9194.803000] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 22:01:36 (1786068096) [ 9204.927040] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 22:01:45 (1786068105) [ 9241.513759] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9247.141078] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9269.428577] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9271.268582] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 9275.755848] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [ 9277.322441] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 9278.695857] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 9280.045650] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [ 9286.634294] Lustre: lustre-MDT0000-mdc-ffff9aa0c98b6800: Connection to lustre-MDT0000 (at 192.168.206.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9301.920191] Lustre: 215176:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786068189/real 1786068189] req@00000000966c61e5 x1872827978360064/t0(0) o400->MGC192.168.206.103@tcp@192.168.206.103@tcp:26/25 lens 224/224 e 0 to 1 dl 1786068205 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9301.944194] LustreError: MGC192.168.206.103@tcp: Connection to MGS (at 192.168.206.103@tcp) was lost; in progress operations using this service will fail [ 9301.964566] Lustre: Evicted from MGS (at 192.168.206.103@tcp) after server handle changed from 0x297487cfb98dbd4c to 0x297487cfb98dbfbb [ 9301.981982] Lustre: MGC192.168.206.103@tcp: Connection restored to 192.168.206.103@tcp (at 192.168.206.103@tcp) [ 9304.281345] Lustre: lustre-MDT0000-mdc-ffff9aa0c98b6800: Connection restored to 192.168.206.103@tcp (at 192.168.206.103@tcp) [ 9308.866162] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9310.063935] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9313.135818] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [ 9317.357456] Lustre: lustre-MDT0000-mdc-ffff9aa0c98b6800: Connection to lustre-MDT0000 (at 192.168.206.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9332.713586] LustreError: MGC192.168.206.103@tcp: Connection to MGS (at 192.168.206.103@tcp) was lost; in progress operations using this service will fail [ 9332.735423] Lustre: Evicted from MGS (at 192.168.206.103@tcp) after server handle changed from 0x297487cfb98dbfbb to 0x297487cfb98dc27e [ 9332.748572] Lustre: MGC192.168.206.103@tcp: Connection restored to 192.168.206.103@tcp (at 192.168.206.103@tcp) [ 9338.076048] Lustre: lustre-MDT0000-mdc-ffff9aa0c98b6800: Connection restored to 192.168.206.103@tcp (at 192.168.206.103@tcp) [ 9345.406608] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9346.771118] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9350.344127] Lustre: Unmounted lustre-client [ 9409.808876] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9416.930833] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9438.166236] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9438.686774] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 9444.987090] Lustre: Unmounted lustre-client [ 9478.817563] Key type lgssc unregistered [ 9479.026251] LNet: 225764:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9479.036384] LNetError: 225764:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9479.064681] LNet: Removed LNI 192.168.206.3@tcp [ 9479.675912] Key type .llcrypt unregistered [ 9479.677787] Key type ._llcrypt unregistered [ 9496.253397] Key type ._llcrypt registered [ 9496.255942] Key type .llcrypt registered [ 9497.842713] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 22:06:39 (1786068399) [ 9501.275641] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9501.286792] alg: No test for adler32 (adler32-zlib) [ 9502.325717] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 9502.528244] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 9504.169940] Key type lgssc registered [ 9505.060326] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9549.527861] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9556.350328] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9585.257860] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9587.093140] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 9593.252064] Lustre: Unmounted lustre-client [ 9648.543647] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9653.590955] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9672.955851] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9673.642373] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 9683.060957] Lustre: Unmounted lustre-client [ 9752.128623] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9758.740598] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9779.451854] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9780.183765] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [ 9782.758410] Lustre: Unmounted lustre-client [ 9809.280846] Key type lgssc unregistered [ 9809.578967] LNet: 232814:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9809.587202] LNetError: 232814:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9809.610747] LNet: Removed LNI 192.168.206.3@tcp [ 9810.329289] Key type .llcrypt unregistered [ 9810.331391] Key type ._llcrypt unregistered [ 9826.629496] Key type ._llcrypt registered [ 9826.631984] Key type .llcrypt registered [ 9827.906694] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 22:12:09 (1786068729) [ 9831.075066] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9831.187260] alg: No test for adler32 (adler32-zlib) [ 9832.273914] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 9832.496442] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 9834.232552] Key type lgssc registered [ 9835.281239] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9878.727297] Key type lgssc unregistered [ 9879.093705] LNet: 235137:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9879.103718] LNetError: 235137:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9879.132228] LNet: Removed LNI 192.168.206.3@tcp [ 9879.902774] Key type .llcrypt unregistered [ 9879.906652] Key type ._llcrypt unregistered [ 9904.246243] Key type ._llcrypt registered [ 9904.266422] Key type .llcrypt registered [ 9904.883588] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9904.912492] alg: No test for adler32 (adler32-zlib) [ 9906.000621] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [ 9906.323779] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [ 9908.056215] Key type lgssc registered [ 9909.480713] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9978.188165] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9983.877836] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10003.657493] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10041.783309] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [10043.357700] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 22:15:45 (1786068945) [10074.733391] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10080.379855] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10102.304944] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10123.565916] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10125.350400] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [10130.414941] Lustre: lustre-OST0001-osc-ffff9aa0c946c000: Connection to lustre-OST0001 (at 192.168.206.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [10136.468598] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [10146.809449] Lustre: Unmounted lustre-client [10188.779966] Key type lgssc unregistered [10189.042584] LNet: 242243:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10189.047115] LNetError: 242243:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10189.061748] LNet: Removed LNI 192.168.206.3@tcp [10189.849678] Key type .llcrypt unregistered [10189.857091] Key type ._llcrypt unregistered [10220.418364] Key type ._llcrypt registered [10220.421165] Key type .llcrypt registered [10221.693693] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 22:18:43 (1786069123) [10233.466067] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10233.496708] alg: No test for adler32 (adler32-zlib) [10234.624861] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [10234.958074] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [10236.624262] Key type lgssc registered [10238.153048] Lustre: Echo OBD driver; http://www.lustre.org/ [10281.294962] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10285.679335] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10304.103222] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 22:20:06 (1786069206) [10334.604645] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10339.829111] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10359.903644] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10361.346201] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [10373.132448] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [10377.231389] Lustre: Unmounted lustre-client [10476.699683] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10482.841669] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10533.750156] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10534.442367] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [10536.878782] Lustre: Unmounted lustre-client [10564.112736] Key type lgssc unregistered [10564.361539] LNet: 249955:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10564.368384] LNetError: 249955:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10565.415980] LNet: Removed LNI 192.168.206.3@tcp [10566.191280] Key type .llcrypt unregistered [10566.195538] Key type ._llcrypt unregistered [10594.628399] Key type ._llcrypt registered [10594.637015] Key type .llcrypt registered [10595.000681] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10595.012452] alg: No test for adler32 (adler32-zlib) [10596.039965] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [10596.267578] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [10597.945800] Key type lgssc registered [10599.113438] Lustre: Echo OBD driver; http://www.lustre.org/ [10670.978218] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10675.854218] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10698.461169] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10735.094701] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [10736.784869] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 22:27:18 (1786069638) [10768.486953] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10774.081156] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10790.487320] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10795.271962] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [10797.828170] Lustre: Unmounted lustre-client [10823.566881] Key type lgssc unregistered [10823.888724] LNet: 256586:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10823.903765] LNetError: 256586:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10823.927605] LNet: Removed LNI 192.168.206.3@tcp [10825.003197] Key type .llcrypt unregistered [10825.005711] Key type ._llcrypt unregistered [10840.313433] Key type ._llcrypt registered [10840.322918] Key type .llcrypt registered [10841.765134] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [10843.047373] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 22:29:05 (1786069745) [10850.891984] Key type .llcrypt unregistered [10850.898326] Key type ._llcrypt unregistered [10859.158939] Key type ._llcrypt registered [10859.166583] Key type .llcrypt registered [10859.570438] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10859.580185] alg: No test for adler32 (adler32-zlib) [10860.649807] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [10860.893263] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [10862.584164] Key type lgssc registered [10863.563227] Lustre: Echo OBD driver; http://www.lustre.org/ [10911.110556] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10923.316253] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [10925.640100] Lustre: Unmounted lustre-client [10959.825253] Key type lgssc unregistered [10960.016580] LNet: 260043:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10960.021796] LNetError: 260043:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10960.039273] LNet: Removed LNI 192.168.206.3@tcp [10960.588405] Key type .llcrypt unregistered [10960.591319] Key type ._llcrypt unregistered [10974.004459] Key type ._llcrypt registered [10974.006084] Key type .llcrypt registered [10975.048889] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 22:31:17 (1786069877) [10976.645635] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10976.665809] alg: No test for adler32 (adler32-zlib) [10977.676576] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [10977.857859] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [10979.488171] Key type lgssc registered [10980.440278] Lustre: Echo OBD driver; http://www.lustre.org/ [11059.045211] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11090.007291] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11096.134374] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11098.820204] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [11101.858321] Lustre: Unmounted lustre-client [11127.483971] Key type lgssc unregistered [11127.754917] LNet: 263584:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11127.763745] LNetError: 263584:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11127.794561] LNet: Removed LNI 192.168.206.3@tcp [11128.523766] Key type .llcrypt unregistered [11128.531542] Key type ._llcrypt unregistered [11142.731814] Key type ._llcrypt registered [11142.734400] Key type .llcrypt registered [11143.776674] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 22:34:05 (1786070045) [11145.771176] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11145.816970] alg: No test for adler32 (adler32-zlib) [11146.831045] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [11147.033896] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [11148.744183] Key type lgssc registered [11150.014765] Lustre: Echo OBD driver; http://www.lustre.org/ [11200.314283] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11206.371115] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11228.827126] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11231.784441] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [11236.850765] Lustre: lustre-MDT0000-mdc-ffff9aa0d2274800: Connection to lustre-MDT0000 (at 192.168.206.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [11242.266761] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11242.298228] LustreError: 266738:0:(file.c:6154:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [11242.804425] LustreError: MGC192.168.206.103@tcp: Connection to MGS (at 192.168.206.103@tcp) was lost; in progress operations using this service will fail [11242.847071] Lustre: Unmounted lustre-client [11272.801701] Key type lgssc unregistered [11273.148678] LNet: 267155:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11273.156922] LNetError: 267155:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11273.180695] LNet: Removed LNI 192.168.206.3@tcp [11274.017743] Key type .llcrypt unregistered [11274.028639] Key type ._llcrypt unregistered [11289.966314] Key type ._llcrypt registered [11289.967768] Key type .llcrypt registered [11291.193604] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 22:36:33 (1786070193) [11293.082830] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11293.120468] alg: No test for adler32 (adler32-zlib) [11294.140050] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [11294.317219] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [11295.960490] Key type lgssc registered [11297.344204] Lustre: Echo OBD driver; http://www.lustre.org/ [11348.645987] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11354.224465] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11375.632368] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11378.465421] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [11383.791977] Lustre: lustre-MDT0001-mdc-ffff9aa0c54bf800: Connection to lustre-MDT0001 (at 192.168.206.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [11389.200297] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [11391.522188] Lustre: Unmounted lustre-client [11409.628623] Key type lgssc unregistered [11409.908975] LNet: 270733:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11409.918365] LNetError: 270733:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11409.943914] LNet: Removed LNI 192.168.206.3@tcp [11410.665717] Key type .llcrypt unregistered [11410.673471] Key type ._llcrypt unregistered [11425.553980] Key type ._llcrypt registered [11425.558968] Key type .llcrypt registered [11426.725346] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 22:38:48 (1786070328) [11443.334336] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11443.415465] alg: No test for adler32 (adler32-zlib) [11444.523275] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [11444.779153] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [11446.488604] Key type lgssc registered [11447.783744] Lustre: Echo OBD driver; http://www.lustre.org/ [11540.685902] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11543.395992] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [11584.492238] Lustre: lustre-MDT0001-mdc-ffff9aa0ca235800: Connection to lustre-MDT0001 (at 192.168.206.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [11593.390545] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11603.759880] Lustre: Unmounted lustre-client [11640.092604] Key type lgssc unregistered [11640.455249] LNet: 276519:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11640.467589] LNetError: 276519:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11640.486857] LNet: Removed LNI 192.168.206.3@tcp [11641.215159] Key type .llcrypt unregistered [11641.220565] Key type ._llcrypt unregistered [11658.370723] Key type ._llcrypt registered [11658.372973] Key type .llcrypt registered [11659.476462] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 22:42:41 (1786070561) [11660.907666] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [11662.521666] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 22:42:44 (1786070564) [11664.096562] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [11665.914125] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 22:42:47 (1786070567) [11667.854994] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [11669.876472] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 22:42:51 (1786070571) [11671.684958] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [11673.827397] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 22:42:55 (1786070575) [11675.386284] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [11677.500326] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 22:42:59 (1786070579) [11687.872330] Key type .llcrypt unregistered [11687.878623] Key type ._llcrypt unregistered [11695.165452] Key type ._llcrypt registered [11695.189017] Key type .llcrypt registered [11695.725833] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11695.740096] alg: No test for adler32 (adler32-zlib) [11696.785641] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [11697.130177] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [11698.857294] Key type lgssc registered [11700.495839] Lustre: Echo OBD driver; http://www.lustre.org/ [11777.831330] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11783.136921] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11806.063427] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11807.598325] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [11810.687275] Lustre: Unmounted lustre-client [11848.228602] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 22:45:49 (1786070749) [11883.677857] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11889.844590] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11923.831990] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11930.143392] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [11932.681973] Lustre: Unmounted lustre-client [11976.021796] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 22:47:58 (1786070878) [11986.912341] LNet: 1 local NIs in recovery (showing 1): 192.168.206.3@tcp [12617.731391] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12623.166188] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12659.837531] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12757.141667] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 23:00:58 (1786071658) [12768.543948] Key type lgssc unregistered [12768.765843] LNet: 290077:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12768.777082] LNetError: 290077:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12768.813956] LNet: Removed LNI 192.168.206.3@tcp [12769.435992] Key type .llcrypt unregistered [12769.437596] Key type ._llcrypt unregistered [12908.854946] Key type ._llcrypt registered [12908.862140] Key type .llcrypt registered [12909.380146] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12909.391899] alg: No test for adler32 (adler32-zlib) [12910.409401] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [12910.628637] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [12912.289589] Key type lgssc registered [12913.390377] Lustre: Echo OBD driver; http://www.lustre.org/ [12986.945614] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12991.359579] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13014.330580] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13043.172076] Key type lgssc unregistered [13043.444350] LNet: 293775:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13043.451972] LNetError: 293775:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13043.467829] LNet: Removed LNI 192.168.206.3@tcp [13044.065641] Key type .llcrypt unregistered [13044.067957] Key type ._llcrypt unregistered [13083.733646] Key type ._llcrypt registered [13083.736572] Key type .llcrypt registered [13084.906455] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 23:06:26 (1786071986) [13104.067436] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13104.100500] alg: No test for adler32 (adler32-zlib) [13105.205112] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [13105.655518] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [13107.312179] Key type lgssc registered [13108.288240] Lustre: Echo OBD driver; http://www.lustre.org/ [13156.095161] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 23:07:38 (1786072058) [13165.741886] Key type lgssc unregistered [13165.936050] LNet: 297334:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13165.950227] LNetError: 297334:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13165.966917] LNet: Removed LNI 192.168.206.3@tcp [13166.645610] Key type .llcrypt unregistered [13166.649246] Key type ._llcrypt unregistered [13175.215266] Key type ._llcrypt registered [13175.217051] Key type .llcrypt registered [13175.551062] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13175.564934] alg: No test for adler32 (adler32-zlib) [13176.526548] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [13176.728040] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [13178.376174] Key type lgssc registered [13179.335982] Lustre: Echo OBD driver; http://www.lustre.org/ [13228.971858] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13235.783742] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13261.981947] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13505.270599] LNet: Added LNI 192.168.206.3@tcp50 [8/256/0/180] [13507.565644] LNet: Removed LNI 192.168.206.3@tcp [13509.472957] Lustre: 301724:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff9aa0d2270000: config has no valid NIDs, force dynamic NIDs [13510.653176] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [13510.790116] Lustre: Unmounted lustre-client [13513.117516] Key type lgssc unregistered [13513.423068] LNet: 301988:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13513.435418] LNetError: 301988:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13513.450677] LNet: Removed LNI 192.168.206.3@tcp50 [13513.967677] Key type .llcrypt unregistered [13513.970936] Key type ._llcrypt unregistered [13523.049320] Key type ._llcrypt registered [13523.051689] Key type .llcrypt registered [13523.301297] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13523.311234] alg: No test for adler32 (adler32-zlib) [13524.285331] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [13524.458929] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [13526.104202] Key type lgssc registered [13527.079502] Lustre: Echo OBD driver; http://www.lustre.org/ [13569.990693] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13574.972691] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13581.605298] LNet: Added LNI 192.168.206.3@tcp50 [8/256/0/180] [13582.528493] LNet: Removed LNI 192.168.206.3@tcp [13583.790159] Lustre: 304674:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff9aa0c4256800: config has no valid NIDs, force dynamic NIDs [13584.973544] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [13585.495915] Lustre: Unmounted lustre-client [13615.642776] Key type lgssc unregistered [13615.907803] LNet: 305547:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13615.917267] LNetError: 305547:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13615.950289] LNet: Removed LNI 192.168.206.3@tcp50 [13616.597213] Key type .llcrypt unregistered [13616.600490] Key type ._llcrypt unregistered [13626.932251] Key type ._llcrypt registered [13626.938334] Key type .llcrypt registered [13628.329647] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 23:15:29 (1786072529) [13643.319480] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13643.330159] alg: No test for adler32 (adler32-zlib) [13644.401973] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [13644.700486] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [13646.376154] Key type lgssc registered [13647.664459] Lustre: Echo OBD driver; http://www.lustre.org/ [13725.481133] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13731.523492] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13753.224131] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13781.019018] Key type lgssc unregistered [13781.172835] LNet: 310291:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13781.178437] LNetError: 310291:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13781.194355] LNet: Removed LNI 192.168.206.3@tcp [13781.610991] Key type .llcrypt unregistered [13781.613241] Key type ._llcrypt unregistered [13788.791311] Key type ._llcrypt registered [13788.793479] Key type .llcrypt registered [13789.893485] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 23:18:11 (1786072691) [13791.596156] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13791.613937] alg: No test for adler32 (adler32-zlib) [13792.602126] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [13792.789725] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [13794.424320] Key type lgssc registered [13795.426097] Lustre: Echo OBD driver; http://www.lustre.org/ [13847.417844] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13851.805696] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13868.608815] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13870.201244] Lustre: Mounted lustre-client - version 2.17.56_50_gf66e916 [13922.375831] Lustre: Mounted fs15246-client - version 2.17.56_50_gf66e916 [13937.414260] Lustre: Unmounted fs15246-client [13961.362778] Lustre: Unmounted lustre-client [13986.220427] Key type lgssc unregistered [13986.443194] LNet: 315149:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13986.451062] LNetError: 315149:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13986.471584] LNet: Removed LNI 192.168.206.3@tcp [13987.152677] Key type .llcrypt unregistered [13987.154828] Key type ._llcrypt unregistered [13996.557733] Key type ._llcrypt registered [13996.559756] Key type .llcrypt registered [13997.848841] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 23:21:39 (1786072899) [13999.463470] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13999.472650] alg: No test for adler32 (adler32-zlib) [14000.480977] Lustre: Lustre: Build Version: 2.17.56_50_gf66e916 [14000.634494] LNet: Added LNI 192.168.206.3@tcp [8/256/0/180] [14002.368184] Key type lgssc registered [14003.196917] Lustre: Echo OBD driver; http://www.lustre.org/ [14152.501241] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14158.764867] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14182.392281] Lustre: DEBUG MARKER: oleg603-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14279.446083] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 14018 sec ========= 23:26:21 (1786073181) [14281.596803] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 23:26:22 (1786073182) === [14285.139062] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 23:26:26 (1786073186) === [14314.130810] Key type lgssc unregistered [14314.396556] LNet: 324415:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14314.409905] LNetError: 324415:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14314.421934] LNet: Removed LNI 192.168.206.3@tcp [14315.266820] Key type .llcrypt unregistered [14315.268472] Key type ._llcrypt unregistered