[ 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 470681880 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, 524576K 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.001012] APIC: Switch to symmetric I/O mode setup [ 0.002317] x2apic enabled [ 0.003009] Switched APIC routing to physical x2apic. [ 0.004013] kvm-guest: setup PV IPIs [ 0.007411] ..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.009018] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010010] pid_max: default: 32768 minimum: 301 [ 0.011126] LSM: Security Framework initializing [ 0.012054] Yama: becoming mindful. [ 0.013036] SELinux: Initializing. [ 0.014079] *** VALIDATE selinux *** [ 0.022364] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026663] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027194] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028104] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029148] *** VALIDATE tmpfs *** [ 0.031190] *** VALIDATE proc *** [ 0.032327] *** VALIDATE cgroup *** [ 0.033011] *** VALIDATE cgroup2 *** [ 0.034338] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.036035] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.037008] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.038025] Spectre V2 : User space: Vulnerable [ 0.039008] Speculative Store Bypass: Vulnerable [ 0.042356] debug: unmapping init [mem 0xffffffff90459000-0xffffffff90460fff] [ 0.044158] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.045770] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.046023] ... version: 2 [ 0.047014] ... bit width: 48 [ 0.048016] ... generic registers: 4 [ 0.049015] ... value mask: 0000ffffffffffff [ 0.050021] ... max period: 00007fffffffffff [ 0.051016] ... fixed-purpose events: 3 [ 0.052018] ... event mask: 000000070000000f [ 0.053341] rcu: Hierarchical SRCU implementation. [ 0.055543] smp: Bringing up secondary CPUs ... [ 0.056656] x86: Booting SMP configuration: [ 0.057027] .... node #0, CPUs: #1 #2 #3 [ 0.061748] smp: Brought up 1 node, 4 CPUs [ 0.063015] smpboot: Max logical packages: 1 [ 0.064016] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.139024] node 0 deferred pages initialised in 72ms [ 0.141128] devtmpfs: initialized [ 0.142233] x86/mm: Memory block size: 128MB [ 0.144850] gcov: version magic: 0x41383552 [ 0.146312] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.147122] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.148273] pinctrl core: initialized pinctrl subsystem [ 0.149237] [ 0.149727] ************************************************************* [ 0.150014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.151020] ** ** [ 0.152013] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.153020] ** ** [ 0.154016] ** This means that this kernel is built to expose internal ** [ 0.155018] ** IOMMU data structures, which may compromise security on ** [ 0.156014] ** your system. ** [ 0.157018] ** ** [ 0.158015] ** If you see this message and you are not debugging the ** [ 0.159024] ** kernel, report this immediately to your vendor! ** [ 0.160020] ** ** [ 0.161014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.162015] ************************************************************* [ 0.163746] NET: Registered protocol family 16 [ 0.164467] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.165080] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.166068] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.167512] cpuidle: using governor menu [ 0.169681] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.171587] PCI: Using configuration type 1 for base access [ 0.174134] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.184054] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.185020] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.187036] cryptd: max_cpu_qlen set to 1000 [ 0.189416] ACPI: Added _OSI(Module Device) [ 0.191017] ACPI: Added _OSI(Processor Device) [ 0.192014] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.194015] ACPI: Added _OSI(Processor Aggregator Device) [ 0.199689] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.205545] ACPI: Interpreter enabled [ 0.206069] ACPI: PM: (supports S0 S3 S4 S5) [ 0.207013] ACPI: Using IOAPIC for interrupt routing [ 0.208110] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.209624] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.217683] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.220049] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.223018] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.226088] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.232388] acpiphp: Slot [2] registered [ 0.233147] acpiphp: Slot [5] registered [ 0.235114] acpiphp: Slot [6] registered [ 0.236112] acpiphp: Slot [3] registered [ 0.238104] acpiphp: Slot [4] registered [ 0.240177] acpiphp: Slot [7] registered [ 0.241114] acpiphp: Slot [8] registered [ 0.243139] acpiphp: Slot [9] registered [ 0.244100] acpiphp: Slot [10] registered [ 0.246114] acpiphp: Slot [11] registered [ 0.247122] acpiphp: Slot [12] registered [ 0.249104] acpiphp: Slot [13] registered [ 0.250111] acpiphp: Slot [14] registered [ 0.252105] acpiphp: Slot [15] registered [ 0.253112] acpiphp: Slot [16] registered [ 0.255108] acpiphp: Slot [17] registered [ 0.256166] acpiphp: Slot [18] registered [ 0.258097] acpiphp: Slot [19] registered [ 0.259154] acpiphp: Slot [20] registered [ 0.260099] acpiphp: Slot [21] registered [ 0.262184] acpiphp: Slot [22] registered [ 0.263085] acpiphp: Slot [23] registered [ 0.265088] acpiphp: Slot [24] registered [ 0.266087] acpiphp: Slot [25] registered [ 0.267131] acpiphp: Slot [26] registered [ 0.269103] acpiphp: Slot [27] registered [ 0.271120] acpiphp: Slot [28] registered [ 0.272172] acpiphp: Slot [29] registered [ 0.274129] acpiphp: Slot [30] registered [ 0.275095] acpiphp: Slot [31] registered [ 0.277099] PCI host bridge to bus 0000:00 [ 0.279022] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.281024] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.283077] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.285018] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.287021] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.288018] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.289227] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.291844] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.294103] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.299419] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.302551] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.304013] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.305010] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.308018] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.310512] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.312925] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.315031] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.317680] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.320616] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.329020] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.334012] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.338997] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.342016] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.348015] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.356017] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.363611] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.366997] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.371011] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.378013] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.385625] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.388303] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.389316] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.391305] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.393179] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.398036] iommu: Default domain type: Passthrough [ 0.399365] SCSI subsystem initialized [ 0.400122] ACPI: bus type USB registered [ 0.401031] usbcore: registered new interface driver usbfs [ 0.402064] usbcore: registered new interface driver hub [ 0.403073] usbcore: registered new device driver usb [ 0.404135] pps_core: LinuxPPS API ver. 1 registered [ 0.405007] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.407051] PTP clock support registered [ 0.408121] EDAC MC: Ver: 3.0.0 [ 0.409272] PCI: Using ACPI for IRQ routing [ 0.410590] NetLabel: Initializing [ 0.412011] NetLabel: domain hash size = 128 [ 0.412938] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.414087] NetLabel: unlabeled traffic allowed by default [ 0.415305] vgaarb: loaded [ 0.416206] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.417007] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.424000] clocksource: Switched to clocksource kvm-clock [ 0.510535] VFS: Disk quotas dquot_6.6.0 [ 0.511482] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.513018] *** VALIDATE ramfs *** [ 0.513744] *** VALIDATE hugetlbfs *** [ 0.515167] pnp: PnP ACPI init [ 0.516821] pnp: PnP ACPI: found 6 devices [ 0.529490] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.531634] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.532779] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.533892] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.535378] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.537413] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.539406] NET: Registered protocol family 2 [ 0.541300] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.546469] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.549056] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.555099] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.558804] TCP: Hash tables configured (established 65536 bind 65536) [ 0.562038] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.565411] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.568212] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.571426] NET: Registered protocol family 1 [ 0.574353] RPC: Registered named UNIX socket transport module. [ 0.576782] RPC: Registered udp transport module. [ 0.578619] RPC: Registered tcp transport module. [ 0.580328] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.583060] NET: Registered protocol family 44 [ 0.584856] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.586905] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.589443] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.591942] PCI: CLS 0 bytes, default 64 [ 0.593163] Unpacking initramfs... [ 2.007822] debug: unmapping init [mem 0xffff953cfcc64000-0xffff953cfffcffff] [ 2.011702] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.013891] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.016554] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.511306] Initialise system trusted keyrings [ 2.512866] Key type blacklist registered [ 2.515287] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.524539] zbud: loaded [ 2.527434] *** VALIDATE nfs *** [ 2.528654] *** VALIDATE nfs4 *** [ 2.530110] pstore: using deflate compression [ 2.533784] Platform Keyring initialized [ 2.638539] NET: Registered protocol family 38 [ 2.639943] Key type asymmetric registered [ 2.641405] Asymmetric key parser 'x509' registered [ 2.643064] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.645237] io scheduler mq-deadline registered [ 2.646197] io scheduler kyber registered [ 2.647219] io scheduler bfq registered [ 2.648726] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.651313] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.653946] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.657080] ACPI: Power Button [PWRF] [ 2.661971] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.668883] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.676931] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.705502] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.733747] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.738028] Non-volatile memory driver v1.3 [ 2.739130] Linux agpgart interface v0.103 [ 2.767281] virtio_blk virtio1: [vda] 149760 512-byte logical blocks (76.7 MB/73.1 MiB) [ 2.769227] vda: detected capacity change from 0 to 76677120 [ 2.782361] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.785493] vdb: detected capacity change from 0 to 1073741824 [ 2.791423] libphy: Fixed MDIO Bus: probed [ 2.814959] usbcore: registered new interface driver usbserial_generic [ 2.817567] usbserial: USB Serial support registered for generic [ 2.819816] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.823854] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.825724] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.828814] mousedev: PS/2 mouse device common for all mice [ 2.831635] rtc_cmos 00:05: RTC can wake from S4 [ 2.834452] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.835548] rtc_cmos 00:05: registered as rtc0 [ 2.839336] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.842663] intel_pstate: CPU model not supported [ 2.845457] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.849814] hid: raw HID events driver (C) Jiri Kosina [ 2.852275] usbcore: registered new interface driver usbhid [ 2.854512] usbhid: USB HID core driver [ 2.856147] drop_monitor: Initializing network drop monitor service [ 2.856199] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.858661] Initializing XFRM netlink socket [ 2.863738] NET: Registered protocol family 10 [ 2.867079] Segment Routing with IPv6 [ 2.868869] NET: Registered protocol family 17 [ 2.871351] mpls_gso: MPLS GSO support [ 2.878259] RAS: Correctable Errors collector initialized. [ 2.880265] AVX version of gcm_enc/dec engaged. [ 2.881388] AES CTR mode by8 optimization enabled [ 2.958525] sched_clock: Marking stable (2958440589, 0)->(3892740896, -934300307) [ 2.962279] registered taskstats version 1 [ 2.964357] Loading compiled-in X.509 certificates [ 2.967242] zswap: loaded using pool lzo/zbud [ 2.992786] Key type big_key registered [ 3.007703] Key type encrypted registered [ 3.009314] ima: No TPM chip found, activating TPM-bypass! [ 3.011530] ima: Allocated hash algorithm: sha1 [ 3.013962] ima: No architecture policies found [ 3.015971] evm: Initialising EVM extended attributes: [ 3.017790] evm: security.selinux [ 3.018994] evm: security.ima [ 3.020096] evm: security.capability [ 3.021599] evm: HMAC attrs: 0x1 [ 3.024180] rtc_cmos 00:05: setting system clock to 2026-09-03 12:50:39 UTC (1788439839) [ 3.030746] debug: unmapping init [mem 0xffffffff91403000-0xffffffff915fffff] [ 3.034167] debug: unmapping init [mem 0xffffffff90182000-0xffffffff90458fff] [ 3.043360] Write protecting the kernel read-only data: 28672k [ 3.047154] debug: unmapping init [mem 0xffffffff8e803000-0xffffffff8e9fffff] [ 3.050019] debug: unmapping init [mem 0xffffffff8f114000-0xffffffff8f1fffff] [ 3.084957] 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.093089] systemd[1]: Detected virtualization kvm. [ 3.094139] systemd[1]: Detected architecture x86-64. [ 3.095192] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.117355] systemd[1]: No hostname configured. [ 3.118480] systemd[1]: Set hostname to . [ 3.120131] random: systemd: uninitialized urandom read (16 bytes read) [ 3.123287] systemd[1]: Initializing machine ID from random generator. [ 3.248277] random: systemd: uninitialized urandom read (16 bytes read) [ 3.251559] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 3.256223] random: systemd: uninitialized urandom read (16 bytes read) [ 3.259046] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 3.263398] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ OK ] Reached target Initrd Root Device. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Listening on Journal Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... Starting Apply Kernel Variables... [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Timers. [ OK ] Started Memstrack Anylazing Service. Starting Setup Virtual Console... [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. Starting Create Static Device Nodes in /dev... [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 3.896804] device-mapper: uevent: version 1.0.3 [ 3.899357] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 4.710864] virtio_net virtio0 ens2: renamed from eth0 [ 4.725249] scsi host0: ata_piix [ 4.748127] scsi host1: ata_piix [ 4.749412] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.751388] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 6.189637] random: fast init done [ 8.675828] dracut-initqueue[584]: RTNETLINK answers: File exists [ 9.526717] random: crng init done [ 9.527914] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 10.055827] 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 Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Timers. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. Stopping udev Kernel Device Manager... [ OK ] Stopped target Swap. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev 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.250192] printk: systemd: 25 output lines suppressed due to ratelimiting [ 11.483294] SELinux: Disabled at runtime. [ 11.549289] 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.556249] systemd[1]: Detected virtualization kvm. [ 11.557879] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.126182] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.129909] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.134629] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.138823] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.142156] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.153508] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.169669] systemd[1]: Starting Remount Root and Kernel File Systems... Starting Remount Root and Kernel File Systems... [ OK ] Created slice User and Session Slice. [ OK ] Started Dispatch Password Requests to Console Directory Watch. Mounting POSIX Message Queue File System... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on initctl Compatibility Named Pipe. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Reached target rpc_pipefs.target. [ OK ] Listening on udev Kernel Socket. Starting Create list of required st…ce nodes for the current kernel... Starting Apply Kernel Variables... Mounting Huge Pages File System... [ OK ] Reached target Slices. [ OK ] Listening on Process Core Dump Socket. [ OK ] Created slice system-getty.slice. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. [ OK ] Stopped target Initrd Root File System. Activating swap /dev/disk/by-label/SWAP... Mounting Kernel Debug File System... [ OK ] Reached target Local Encrypted Volumes. [ 12.363903] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Started Journal Service. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Huge Pages File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Kernel Debug File System. [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Configure read-only root support... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 12.631890] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 12.975120] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 12.980844] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.107417] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.119715] EDAC sbridge: Ver: 1.1.2 [ 14.327787] Key type dns_resolver registered [ 14.632215] NFS: Registering the id_resolver key type [ 14.634213] Key type id_resolver registered [ 14.635951] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... Starting Rebuild Dynamic Linker Cache... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started D-Bus System Message Bus. Starting Restore /run/initramfs on shutdown... Starting Login Service... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started irqbalance daemon. Starting Network Manager... [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. 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 oleg127-client login: [ 71.499501] libcfs: loading out-of-tree module taints kernel. [ 71.923077] Key type ._llcrypt registered [ 71.926244] Key type .llcrypt registered [ 72.507624] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 72.563755] alg: No test for adler32 (adler32-zlib) [ 74.302760] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [ 75.277726] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [ 76.984229] Key type lgssc registered [ 77.997177] hrtimer: interrupt took 4168197 ns [ 78.869479] Lustre: Echo OBD driver; http://www.lustre.org/ [ 264.575428] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 270.406487] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 288.634672] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing check_logdir /tmp/testlogs/ [ 290.285240] Lustre: lustre-OST0000-osc-ffff953d44fff800: disconnect after 22s idle [ 294.526895] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing yml_node [ 299.388214] Lustre: DEBUG MARKER: Client: 2.17.58.2 [ 302.131673] Lustre: DEBUG MARKER: MDS: 2.17.58.2 [ 305.281587] Lustre: DEBUG MARKER: OSS: 2.17.58.2 [ 307.227474] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Thu Sep 3 08:55:42 EDT 2026 [ 326.385607] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 327.893731] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 330.766964] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 341.132155] Lustre: Unmounted lustre-client [ 463.002405] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 470.753927] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 498.284550] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 536.090912] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 08:59:30 (1788440370) [ 538.672906] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 540.695636] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 08:59:35 (1788440375) [ 575.685516] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 582.599540] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 609.169680] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 610.259095] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 615.236495] Lustre: Unmounted lustre-client [ 648.745369] Key type lgssc unregistered [ 649.087960] LNet: 15078:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 649.108605] LNetError: 15078:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 649.126359] LNet: Removed LNI 192.168.201.27@tcp [ 650.085444] Key type .llcrypt unregistered [ 650.087828] Key type ._llcrypt unregistered [ 667.564524] Key type ._llcrypt registered [ 667.567131] Key type .llcrypt registered [ 668.860240] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 09:01:43 (1788440503) [ 670.609750] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 670.650146] alg: No test for adler32 (adler32-zlib) [ 671.786339] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [ 672.196354] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [ 673.960072] Key type lgssc registered [ 675.405129] Lustre: Echo OBD driver; http://www.lustre.org/ [ 751.073915] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 841.454615] Lustre: Unmounted lustre-client [ 871.072794] Key type lgssc unregistered [ 871.450628] LNet: 18884:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 871.457995] LNetError: 18884:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 871.481520] LNet: Removed LNI 192.168.201.27@tcp [ 872.309091] Key type .llcrypt unregistered [ 872.312311] Key type ._llcrypt unregistered [ 882.004263] Key type ._llcrypt registered [ 882.006436] Key type .llcrypt registered [ 883.707416] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 09:05:18 (1788440718) [ 886.294400] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 886.483589] alg: No test for adler32 (adler32-zlib) [ 887.480753] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [ 887.680875] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [ 889.351321] Key type lgssc registered [ 890.853512] Lustre: Echo OBD driver; http://www.lustre.org/ [ 943.159262] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 948.499966] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 970.980857] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 974.025907] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 985.093394] Lustre: Unmounted lustre-client [ 1012.652498] Key type lgssc unregistered [ 1012.955123] LNet: 22479:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1012.964427] LNetError: 22479:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1012.987379] LNet: Removed LNI 192.168.201.27@tcp [ 1014.018268] Key type .llcrypt unregistered [ 1014.021471] Key type ._llcrypt unregistered [ 1024.541380] Key type ._llcrypt registered [ 1024.543350] Key type .llcrypt registered [ 1025.648343] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 09:07:40 (1788440860) [ 1028.478336] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1028.504867] alg: No test for adler32 (adler32-zlib) [ 1029.669777] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [ 1029.973912] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [ 1031.695276] Key type lgssc registered [ 1033.118645] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1088.108761] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1096.491557] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1120.565934] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1133.127057] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 1139.638728] Lustre: Unmounted lustre-client [ 1168.693376] Key type lgssc unregistered [ 1168.999818] LNet: 25666:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1169.026552] LNetError: 25666:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1170.099295] LNet: Removed LNI 192.168.201.27@tcp [ 1170.748784] Key type .llcrypt unregistered [ 1170.751302] Key type ._llcrypt unregistered [ 1186.745214] Key type ._llcrypt registered [ 1186.748553] Key type .llcrypt registered [ 1188.292087] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 09:10:22 (1788441022) [ 1191.314554] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1191.452633] alg: No test for adler32 (adler32-zlib) [ 1192.580429] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [ 1192.887765] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [ 1194.679521] Key type lgssc registered [ 1196.086548] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1253.381317] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1258.896889] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1280.018348] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1281.837790] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 1292.261481] Lustre: lustre-MDT0000-mdc-ffff953d4749e800: Connection to lustre-MDT0000 (at 192.168.201.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1302.508479] Lustre: lustre-MDT0001-mdc-ffff953d4749e800: Connection to lustre-MDT0001 (at 192.168.201.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1307.374900] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1312.735179] Lustre: 26621:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788441133/real 1788441133] req@00000000dd9d4797 x1875316340430848/t0(0) o400->MGC192.168.201.127@tcp@192.168.201.127@tcp:26/25 lens 224/224 e 0 to 1 dl 1788441149 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1312.766750] LustreError: MGC192.168.201.127@tcp: Connection to MGS (at 192.168.201.127@tcp) was lost; in progress operations using this service will fail [ 1317.740146] Lustre: Unmounted lustre-client [ 1338.865900] Key type lgssc unregistered [ 1339.218925] LNet: 29315:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1339.243904] LNetError: 29315:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1339.271478] LNet: Removed LNI 192.168.201.27@tcp [ 1340.560872] Key type .llcrypt unregistered [ 1340.564872] Key type ._llcrypt unregistered [ 1360.714181] Key type ._llcrypt registered [ 1360.716098] Key type .llcrypt registered [ 1362.311514] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 09:13:17 (1788441197) [ 1364.797451] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1364.901539] alg: No test for adler32 (adler32-zlib) [ 1366.152610] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [ 1366.526929] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [ 1368.319291] Key type lgssc registered [ 1370.213671] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1430.041800] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1437.315259] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1458.614430] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1461.357885] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 1471.474192] Lustre: lustre-OST0000-osc-ffff953d5817d800: Connection to lustre-OST0000 (at 192.168.201.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1476.996357] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1487.350789] Lustre: Unmounted lustre-client [ 1516.134386] Key type lgssc unregistered [ 1516.520811] LNet: 32922:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1516.530746] LNetError: 32922:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1517.549315] LNet: Removed LNI 192.168.201.27@tcp [ 1518.663804] Key type .llcrypt unregistered [ 1518.676061] Key type ._llcrypt unregistered [ 1536.030238] Key type ._llcrypt registered [ 1536.032703] Key type .llcrypt registered [ 1537.548301] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 09:16:12 (1788441372) [ 1540.432878] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1540.464536] alg: No test for adler32 (adler32-zlib) [ 1541.614806] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [ 1541.931304] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [ 1543.655210] Key type lgssc registered [ 1545.247721] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1596.125727] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1603.120340] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1626.597342] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1629.419373] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 1633.866786] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1638.140419] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1650.792822] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-09-03 9h18m05s [ 1654.201268] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1660.389436] Lustre: lustre-MDT0000-mdc-ffff953d60bd1800: Connection to lustre-MDT0000 (at 192.168.201.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1666.624917] LustreError: 36709:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff953d60bd1800: can't stat MDS #0: rc = -19 [ 1668.169716] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1678.838069] LustreError: MGC192.168.201.127@tcp: Connection to MGS (at 192.168.201.127@tcp) was lost; in progress operations using this service will fail [ 1678.862975] Lustre: Evicted from MGS (at 192.168.201.127@tcp) after server handle changed from 0x8ff4018f76ecea49 to 0x8ff4018f76eced13 [ 1678.886190] Lustre: MGC192.168.201.127@tcp: Connection restored to 192.168.201.127@tcp (at 192.168.201.127@tcp) [ 1679.925945] LustreError: lustre-MDT0000-mdc-ffff953d60bd1800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1679.946826] Lustre: lustre-MDT0000-mdc-ffff953d60bd1800: Connection restored to 192.168.201.127@tcp (at 192.168.201.127@tcp) [ 1686.231812] Lustre: DEBUG MARKER: Wait for df (36709) ... [ 1688.151281] Lustre: DEBUG MARKER: done [ 1691.040128] Lustre: Unmounted lustre-client [ 1720.884349] Key type lgssc unregistered [ 1721.197838] LNet: 37725:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1721.206777] LNetError: 37725:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1721.240736] LNet: Removed LNI 192.168.201.27@tcp [ 1722.329949] Key type .llcrypt unregistered [ 1722.338464] Key type ._llcrypt unregistered [ 1758.113820] Key type ._llcrypt registered [ 1758.116496] Key type .llcrypt registered [ 1759.936619] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 09:19:54 (1788441594) [ 1762.608104] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1762.723721] alg: No test for adler32 (adler32-zlib) [ 1763.849758] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [ 1764.149921] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [ 1765.920331] Key type lgssc registered [ 1767.513879] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1820.001415] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1826.491916] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1849.388258] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1852.138450] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 1857.338714] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-09-03 9h21m32s [ 1859.175426] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1868.794917] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1872.156985] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1892.322681] Lustre: 41866:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788441708/real 1788441708] req@00000000d522b2f5 x1875316939168512/t0(0) o101->lustre-MDT0000-mdc-ffff953d58b17000@192.168.201.127@tcp:12/10 lens 576/1152 e 0 to 1 dl 1788441728 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1892.336171] Lustre: lustre-MDT0000-mdc-ffff953d58b17000: Connection to lustre-MDT0000 (at 192.168.201.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1892.358871] Lustre: lustre-MDT0000-mdc-ffff953d58b17000: Connection restored to 192.168.201.127@tcp (at 192.168.201.127@tcp) [ 1914.032665] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1918.004277] Lustre: DEBUG MARKER: done [ 1922.901552] Lustre: Unmounted lustre-client [ 1952.122776] Key type lgssc unregistered [ 1952.333917] LNet: 42751:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1952.338435] LNetError: 42751:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1952.353443] LNet: Removed LNI 192.168.201.27@tcp [ 1953.270610] Key type .llcrypt unregistered [ 1953.273440] Key type ._llcrypt unregistered [ 1989.112751] Key type ._llcrypt registered [ 1989.114932] Key type .llcrypt registered [ 1991.221251] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 09:23:45 (1788441825) [ 1993.273577] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1995.776849] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 09:23:50 (1788441830) [ 2000.694073] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2001.049695] alg: No test for adler32 (adler32-zlib) [ 2002.419849] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [ 2002.791409] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [ 2004.647272] Key type lgssc registered [ 2006.341725] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2036.977410] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 09:24:31 (1788441871) [ 2071.714831] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2077.870451] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2100.312392] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2102.057492] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 2106.421985] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 2111.287831] Lustre: Unmounted lustre-client [ 2129.253966] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 2177.753580] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2183.604812] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2184.296730] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 2189.045916] Lustre: Unmounted lustre-client [ 2258.443258] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2265.663127] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2266.684089] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 2271.391495] Lustre: Unmounted lustre-client [ 2296.081643] Lustre: DEBUG MARKER: files compared the same [ 2323.310272] Key type lgssc unregistered [ 2323.679365] LNet: 50388:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2323.696271] LNetError: 50388:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2323.748986] LNet: Removed LNI 192.168.201.27@tcp [ 2324.914274] Key type .llcrypt unregistered [ 2324.916658] Key type ._llcrypt unregistered [ 2341.791924] Key type ._llcrypt registered [ 2341.793724] Key type .llcrypt registered [ 2343.325749] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 09:29:38 (1788442178) [ 2346.009485] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2346.024983] alg: No test for adler32 (adler32-zlib) [ 2347.101446] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [ 2347.374169] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [ 2349.111245] Key type lgssc registered [ 2350.640517] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2402.537528] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2409.187280] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2430.750260] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2433.544790] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 2440.836370] Lustre: Unmounted lustre-client [ 2468.656534] Key type lgssc unregistered [ 2468.980279] LNet: 53974:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2468.988601] LNetError: 53974:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2469.002820] LNet: Removed LNI 192.168.201.27@tcp [ 2469.736560] Key type .llcrypt unregistered [ 2469.739088] Key type ._llcrypt unregistered [ 2486.916425] Key type ._llcrypt registered [ 2486.918585] Key type .llcrypt registered [ 2488.575145] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 09:32:03 (1788442323) [ 2491.630121] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2491.736619] alg: No test for adler32 (adler32-zlib) [ 2492.820212] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [ 2493.130738] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [ 2494.815168] Key type lgssc registered [ 2496.519808] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2584.583935] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2622.465541] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2629.163650] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2673.219969] Key type lgssc unregistered [ 2673.626578] LNet: 57464:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2673.634202] LNetError: 57464:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2673.657858] LNet: Removed LNI 192.168.201.27@tcp [ 2674.363945] Key type .llcrypt unregistered [ 2674.365704] Key type ._llcrypt unregistered [ 2690.591626] Key type ._llcrypt registered [ 2690.593235] Key type .llcrypt registered [ 2691.903417] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 09:35:26 (1788442526) [ 2695.804874] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2695.819144] alg: No test for adler32 (adler32-zlib) [ 2697.045332] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [ 2697.396986] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [ 2699.167191] Key type lgssc registered [ 2700.461955] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2773.471169] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 2781.271059] Lustre: Unmounted lustre-client [ 2817.567592] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 09:37:32 (1788442652) [ 2963.328539] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2985.530794] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 2993.438624] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2993.465311] Lustre: 58446:0:(llite_lib.c:4342:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.201.127@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 3003.862087] Lustre: Unmounted lustre-client [ 3052.294551] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 09:41:27 (1788442887) [ 3067.942775] Key type lgssc unregistered [ 3068.242679] LNet: 65152:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3068.256965] LNetError: 65152:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3068.287450] LNet: Removed LNI 192.168.201.27@tcp [ 3069.107638] Key type .llcrypt unregistered [ 3069.110505] Key type ._llcrypt unregistered [ 3083.139811] Key type ._llcrypt registered [ 3083.159896] Key type .llcrypt registered [ 3083.767931] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3083.784119] alg: No test for adler32 (adler32-zlib) [ 3085.068978] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [ 3085.404202] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [ 3087.087859] Key type lgssc registered [ 3088.392870] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3145.337725] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3152.994656] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3182.897401] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3186.009896] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 3189.962756] Lustre: Unmounted lustre-client [ 3218.877718] Key type lgssc unregistered [ 3219.465930] LNet: 68167:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3219.478847] LNetError: 68167:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3219.512212] LNet: Removed LNI 192.168.201.27@tcp [ 3220.336401] Key type .llcrypt unregistered [ 3220.343619] Key type ._llcrypt unregistered [ 3233.753432] Key type ._llcrypt registered [ 3233.755279] Key type .llcrypt registered [ 3234.558177] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3234.586095] alg: No test for adler32 (adler32-zlib) [ 3235.779413] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [ 3236.018566] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [ 3237.735239] Key type lgssc registered [ 3239.409678] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3359.026571] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3365.983914] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3392.181247] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3394.163528] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 3398.352883] Lustre: Unmounted lustre-client [ 3425.894701] Key type lgssc unregistered [ 3426.184591] LNet: 71597:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3426.197088] LNetError: 71597:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3426.224171] LNet: Removed LNI 192.168.201.27@tcp [ 3426.926267] Key type .llcrypt unregistered [ 3426.930948] Key type ._llcrypt unregistered [ 3445.114367] Key type ._llcrypt registered [ 3445.118350] Key type .llcrypt registered [ 3446.950492] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 09:48:01 (1788443281) [ 3449.159297] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3449.290357] alg: No test for adler32 (adler32-zlib) [ 3450.389431] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [ 3450.645866] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [ 3452.327180] Key type lgssc registered [ 3453.737310] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3508.057287] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3515.042631] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3537.227961] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3540.069966] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 3547.651472] Lustre: Unmounted lustre-client [ 3548.245961] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 3551.640507] Lustre: Unmounted lustre-client [ 3584.660573] Key type lgssc unregistered [ 3585.123243] LNet: 75279:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3585.140115] LNetError: 75279:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3585.171801] LNet: Removed LNI 192.168.201.27@tcp [ 3586.902771] Key type .llcrypt unregistered [ 3586.914750] Key type ._llcrypt unregistered [ 3603.784231] Key type ._llcrypt registered [ 3603.791279] Key type .llcrypt registered [ 3604.651717] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3604.672310] alg: No test for adler32 (adler32-zlib) [ 3605.842416] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [ 3606.218201] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [ 3607.930150] Key type lgssc registered [ 3609.720945] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3668.782198] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3675.549174] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3699.568655] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3710.584650] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 3716.913182] Lustre: Unmounted lustre-client [ 3750.857405] Key type lgssc unregistered [ 3751.224936] LNet: 78312:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3751.242412] LNetError: 78312:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3751.273949] LNet: Removed LNI 192.168.201.27@tcp [ 3752.106789] Key type .llcrypt unregistered [ 3752.109215] Key type ._llcrypt unregistered [ 3772.548599] Key type ._llcrypt registered [ 3772.552822] Key type .llcrypt registered [ 3774.458658] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 09:53:28 (1788443608) [ 3776.927064] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3777.179701] alg: No test for adler32 (adler32-zlib) [ 3778.421649] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [ 3778.823389] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [ 3780.554879] Key type lgssc registered [ 3782.427920] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3840.448623] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3847.575926] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3871.608461] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3873.664951] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 3880.958260] Lustre: lustre: root_squash is set to 0:0 [ 3889.292667] Lustre: lustre: nosquash_nids is cleared [ 3889.393298] Lustre: lustre: root_squash is set to 500:500 [ 3909.176975] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.201.27@tcp 192.168.0.[2,10]@tcp [ 3919.447544] Lustre: lustre: nosquash_nids set to 192.168.201.27/24@tcp [ 3927.624591] Lustre: Unmounted lustre-client [ 3962.832650] Key type lgssc unregistered [ 3963.192610] LNet: 82303:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3963.199795] LNetError: 82303:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3963.219203] LNet: Removed LNI 192.168.201.27@tcp [ 3964.235454] Key type .llcrypt unregistered [ 3964.238155] Key type ._llcrypt unregistered [ 3980.452413] Key type ._llcrypt registered [ 3980.454096] Key type .llcrypt registered [ 3981.907699] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 09:56:56 (1788443816) [ 3984.317593] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3986.155529] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 09:57:01 (1788443821) [ 3988.903652] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3988.953927] alg: No test for adler32 (adler32-zlib) [ 3990.134257] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [ 3990.408528] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [ 3992.127247] Key type lgssc registered [ 3993.426595] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4043.955986] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4050.422500] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4070.848380] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4072.617957] Lustre: lustre: root_squash is set to 500:500 [ 4072.638588] Lustre: lustre: nosquash_nids set to 192.168.201.27/24@tcp [ 4073.782570] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 4082.480332] Lustre: Unmounted lustre-client [ 4110.489506] Key type lgssc unregistered [ 4110.876822] LNet: 86256:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4110.885424] LNetError: 86256:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4110.906042] LNet: Removed LNI 192.168.201.27@tcp [ 4111.993038] Key type .llcrypt unregistered [ 4111.997926] Key type ._llcrypt unregistered [ 4134.781547] Key type ._llcrypt registered [ 4134.783785] Key type .llcrypt registered [ 4136.478858] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 4138.010099] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 09:59:33 (1788443973) [ 4162.318403] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4162.364988] alg: No test for adler32 (adler32-zlib) [ 4163.914414] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [ 4164.310833] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [ 4165.995860] Key type lgssc registered [ 4167.454709] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4268.539171] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4275.515739] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4303.495874] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4379.430527] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4386.468843] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4413.078608] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4428.445825] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 4466.055796] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff953d448ed800.ost_server_uuid 50 [ 4468.524599] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff953d448ed800.ost_server_uuid in IDLE state after 0 sec [ 4469.244441] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 4474.358395] Lustre: Unmounted lustre-client [ 4477.500469] Lustre: Unmounted lustre-client [ 4528.140966] Key type lgssc unregistered [ 4528.361688] LNet: 93931:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4528.370797] LNetError: 93931:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4529.451252] LNet: Removed LNI 192.168.201.27@tcp [ 4530.492133] Key type .llcrypt unregistered [ 4530.495338] Key type ._llcrypt unregistered [ 4566.173826] Key type ._llcrypt registered [ 4566.180277] Key type .llcrypt registered [ 4567.688766] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 10:06:42 (1788444402) [ 4587.146769] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4587.268914] alg: No test for adler32 (adler32-zlib) [ 4588.567376] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [ 4588.983960] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [ 4590.815382] Key type lgssc registered [ 4592.738660] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4688.372980] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4695.230783] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4722.226123] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4725.301716] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 4745.695735] Lustre: 95290:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788444566/real 1788444566] req@0000000058f13132 x1875319901394176/t0(0) o400->lustre-OST0000-osc-ffff953d600c5000@192.168.201.127@tcp:28/4 lens 224/224 e 0 to 1 dl 1788444582 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4745.802933] Lustre: lustre-OST0000-osc-ffff953d600c5000: Connection to lustre-OST0000 (at 192.168.201.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4750.815194] Lustre: 95291:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788444571/real 1788444571] req@0000000071059bfd x1875319901394688/t0(0) o400->lustre-OST0000-osc-ffff953d600c5000@192.168.201.127@tcp:28/4 lens 224/224 e 0 to 1 dl 1788444587 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4756.611722] Lustre: lustre-OST0000-osc-ffff953d600c5000: Connection restored to 192.168.201.127@tcp (at 192.168.201.127@tcp) [ 4757.471654] Lustre: 95291:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788444577/real 1788444577] req@00000000868c690c x1875319901395200/t0(0) o400->lustre-OST0000-osc-ffff953d600c5000@192.168.201.127@tcp:28/4 lens 224/224 e 0 to 1 dl 1788444593 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4761.604060] Lustre: lustre-MDT0000-mdc-ffff953d600c5000: Connection to lustre-MDT0000 (at 192.168.201.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4762.591500] Lustre: 95291:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788444582/real 1788444582] req@0000000040441af1 x1875319901395712/t0(0) o400->lustre-OST0000-osc-ffff953d600c5000@192.168.201.127@tcp:28/4 lens 224/224 e 0 to 1 dl 1788444598 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4777.887730] Lustre: 95293:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788444598/real 1788444598] req@000000009345c68d x1875319901397632/t0(0) o400->MGC192.168.201.127@tcp@192.168.201.127@tcp:26/25 lens 224/224 e 0 to 1 dl 1788444614 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4777.947153] LustreError: MGC192.168.201.127@tcp: Connection to MGS (at 192.168.201.127@tcp) was lost; in progress operations using this service will fail [ 4788.136275] Lustre: Evicted from MGS (at 192.168.201.127@tcp) after server handle changed from 0xa6e686bfb4585a5f to 0xa6e686bfb4585bf5 [ 4788.165327] Lustre: MGC192.168.201.127@tcp: Connection restored to 192.168.201.127@tcp (at 192.168.201.127@tcp) [ 4796.426378] Lustre: lustre-MDT0000-mdc-ffff953d600c5000: Connection restored to 192.168.201.127@tcp (at 192.168.201.127@tcp) [ 4802.215356] Lustre: Unmounted lustre-client [ 4832.658565] Key type lgssc unregistered [ 4833.065275] LNet: 99601:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4833.083609] LNetError: 99601:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4833.102745] LNet: Removed LNI 192.168.201.27@tcp [ 4834.370978] Key type .llcrypt unregistered [ 4834.374302] Key type ._llcrypt unregistered [ 4856.389431] Key type ._llcrypt registered [ 4856.394810] Key type .llcrypt registered [ 4858.610357] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 10:11:32 (1788444692) [ 4874.413099] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4874.520391] alg: No test for adler32 (adler32-zlib) [ 4875.716747] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [ 4876.224373] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [ 4878.063313] Key type lgssc registered [ 4879.884272] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4929.479215] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5040.676663] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5046.546831] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5073.538845] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5075.352947] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 5077.915083] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 5080.223590] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 10:15:14 (1788444914) [ 5343.689926] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5354.036166] Lustre: Unmounted lustre-client [ 5496.006787] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5503.264089] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5530.434856] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5588.950431] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 10:23:43 (1788445423) [ 5691.764885] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5697.405678] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5722.350747] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5723.280914] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 5727.931742] Lustre: Unmounted lustre-client [ 5769.480087] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 10:26:44 (1788445604) [ 5870.219760] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5878.442718] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5906.343756] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5907.321621] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 5912.542961] Lustre: Unmounted lustre-client [ 5940.885629] Key type lgssc unregistered [ 5941.099375] LNet: 122630:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5941.107337] LNetError: 122630:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5941.121516] LNet: Removed LNI 192.168.201.27@tcp [ 5941.775982] Key type .llcrypt unregistered [ 5941.777599] Key type ._llcrypt unregistered [ 5960.052580] Key type ._llcrypt registered [ 5960.055361] Key type .llcrypt registered [ 5961.578703] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 10:29:56 (1788445796) [ 5963.610897] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5963.637983] alg: No test for adler32 (adler32-zlib) [ 5964.693477] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [ 5965.035482] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [ 5966.783212] Key type lgssc registered [ 5968.310242] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6022.942720] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6029.286879] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6054.863663] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6058.134985] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 6072.300319] Lustre: Unmounted lustre-client [ 6103.913770] Key type lgssc unregistered [ 6104.219772] LNet: 126227:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6104.228782] LNetError: 126227:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6104.251155] LNet: Removed LNI 192.168.201.27@tcp [ 6104.966941] Key type .llcrypt unregistered [ 6104.969255] Key type ._llcrypt unregistered [ 6122.171502] Key type ._llcrypt registered [ 6122.173606] Key type .llcrypt registered [ 6123.706403] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 10:32:38 (1788445958) [ 6126.178220] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6126.297581] alg: No test for adler32 (adler32-zlib) [ 6127.524289] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [ 6127.919449] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [ 6129.616095] Key type lgssc registered [ 6131.111180] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6192.227338] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6199.321911] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6226.012909] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6232.724356] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 6242.799485] Lustre: lustre-OST0000-osc-ffff953d44c64800: Connection to lustre-OST0000 (at 192.168.201.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6254.765339] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff953d44c64800.ost_server_uuid 50 [ 6257.191151] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 6268.856635] Lustre: Unmounted lustre-client [ 6290.189360] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 10:35:25 (1788446125) [ 6322.584772] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6329.863268] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6355.000373] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6382.738076] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6383.388810] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 6388.710956] Lustre: lustre-OST0000-osc-ffff953d50625000: Connection to lustre-OST0000 (at 192.168.201.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6413.878764] Lustre: Unmounted lustre-client [ 6468.850888] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 10:38:23 (1788446303) [ 6503.734307] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6510.009416] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6532.658627] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6553.979757] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6554.576694] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 6559.729128] Lustre: lustre-OST0000-osc-ffff953d5939b000: Connection to lustre-OST0000 (at 192.168.201.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6576.684154] Lustre: Unmounted lustre-client [ 6633.138793] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 10:41:08 (1788446468) [ 6725.623223] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6731.624877] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6756.644350] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6824.676456] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6832.641438] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6856.766905] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6895.220242] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 6936.961686] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6941.978481] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 3 sec [ 6944.147589] Lustre: Unmounted lustre-client [ 6974.566700] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 10:46:49 (1788446809) [ 7009.412711] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7015.708359] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7035.490660] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7066.388274] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7096.115405] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 7137.672294] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7138.966294] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7142.380668] Lustre: lustre-OST0001-osc-ffff953d476b4800: Connection to lustre-OST0001 (at 192.168.201.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7150.454088] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7160.828866] Lustre: Unmounted lustre-client [ 7219.026786] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 10:50:53 (1788447053) [ 7253.871678] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7261.191996] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7284.216065] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7284.872409] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 7309.648826] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7311.253551] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7331.173937] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff953d46e84000.ost_server_uuid 50 [ 7332.887235] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff953d46e84000.ost_server_uuid in IDLE state after 0 sec [ 7335.729424] Lustre: Unmounted lustre-client [ 7336.165472] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 7336.168824] Lustre: Skipped 3 previous similar messages [ 7336.240299] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 7337.073395] LustreError: 149662:0:(sec.c:403:import_sec_validate_get()) import 000000006266cfe2 (NEW) with no sec [ 7396.856221] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 10:53:51 (1788447231) [ 7434.519841] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7442.438274] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7464.604445] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7485.460523] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7486.098496] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 7486.264384] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 7500.702351] Lustre: Unmounted lustre-client [ 7500.709715] Lustre: Skipped 1 previous similar message [ 7546.763503] Key type lgssc unregistered [ 7547.056635] LNet: 153085:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7547.080651] LNetError: 153085:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7547.108106] LNet: Removed LNI 192.168.201.27@tcp [ 7547.858701] Key type .llcrypt unregistered [ 7547.861597] Key type ._llcrypt unregistered [ 7565.945854] Key type ._llcrypt registered [ 7565.949478] Key type .llcrypt registered [ 7567.556395] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 10:56:42 (1788447402) [ 7568.760738] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7568.782294] alg: No test for adler32 (adler32-zlib) [ 7569.952472] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [ 7570.272330] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [ 7571.968877] Key type lgssc registered [ 7573.289729] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7627.670277] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7634.407328] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7656.706526] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7676.495915] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7677.081195] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7678.290798] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 7683.164315] LustreError: lustre-MDT0001-mdc-ffff953d476b2800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 7683.187715] Lustre: lustre-MDT0001-mdc-ffff953d476b2800: Connection restored to 192.168.201.127@tcp (at 192.168.201.127@tcp) [ 7691.583126] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7693.581469] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7717.382953] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7721.569158] Lustre: Unmounted lustre-client [ 7745.067083] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 10:59:39 (1788447579) [ 7845.264184] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7851.606626] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7873.428479] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7874.105637] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 7899.703115] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7901.549087] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7905.769150] Lustre: lustre-OST0001-osc-ffff953d486e2800: Connection to lustre-OST0001 (at 192.168.201.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7913.628531] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7923.947923] Lustre: Unmounted lustre-client [ 7957.125378] Key type lgssc unregistered [ 7957.334075] LNet: 162700:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7957.349672] LNetError: 162700:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7957.366189] LNet: Removed LNI 192.168.201.27@tcp [ 7958.013291] Key type .llcrypt unregistered [ 7958.020119] Key type ._llcrypt unregistered [ 7992.274033] Key type ._llcrypt registered [ 7992.276142] Key type .llcrypt registered [ 7993.731863] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 11:03:48 (1788447828) [ 7996.514611] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7996.544058] alg: No test for adler32 (adler32-zlib) [ 7997.552485] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [ 7997.826748] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [ 7999.496544] Key type lgssc registered [ 8000.701243] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8050.687238] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8056.360269] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8078.627275] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8080.435348] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 8129.449678] Lustre: Unmounted lustre-client [ 8170.201995] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8170.805514] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 8207.515142] Lustre: Unmounted lustre-client [ 8244.209642] Key type lgssc unregistered [ 8244.410730] LNet: 167142:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8244.414083] LNetError: 167142:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8244.429732] LNet: Removed LNI 192.168.201.27@tcp [ 8245.159643] Key type .llcrypt unregistered [ 8245.162208] Key type ._llcrypt unregistered [ 8262.048493] Key type ._llcrypt registered [ 8262.054662] Key type .llcrypt registered [ 8263.473270] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 11:08:18 (1788448098) [ 8265.438169] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8265.516765] alg: No test for adler32 (adler32-zlib) [ 8266.716621] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [ 8266.995386] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [ 8268.680924] Key type lgssc registered [ 8270.499124] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8316.473853] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8323.064558] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8345.993548] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8347.509138] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 8369.182334] Lustre: Unmounted lustre-client [ 8394.969361] Key type lgssc unregistered [ 8395.230192] LNet: 170891:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8395.236591] LNetError: 170891:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8395.250200] LNet: Removed LNI 192.168.201.27@tcp [ 8395.861175] Key type .llcrypt unregistered [ 8395.863508] Key type ._llcrypt unregistered [ 8407.832284] Key type ._llcrypt registered [ 8407.845741] Key type .llcrypt registered [ 8408.333435] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8408.358399] alg: No test for adler32 (adler32-zlib) [ 8409.475268] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [ 8409.800822] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [ 8411.487523] Key type lgssc registered [ 8412.757166] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8466.431373] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8472.874871] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8493.993843] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8495.643827] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 8505.333895] Lustre: Unmounted lustre-client [ 8530.536949] Key type lgssc unregistered [ 8530.818265] LNet: 173946:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8530.822513] LNetError: 173946:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8530.834988] LNet: Removed LNI 192.168.201.27@tcp [ 8531.619117] Key type .llcrypt unregistered [ 8531.621248] Key type ._llcrypt unregistered [ 8552.836060] Key type ._llcrypt registered [ 8552.837961] Key type .llcrypt registered [ 8554.378368] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 11:13:09 (1788448389) [ 8557.068037] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8557.089676] alg: No test for adler32 (adler32-zlib) [ 8558.156228] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [ 8558.426957] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [ 8560.151280] Key type lgssc registered [ 8561.665996] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8618.726303] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8624.923921] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8646.870577] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8648.626404] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 8667.768801] Lustre: Unmounted lustre-client [ 8695.060837] Key type lgssc unregistered [ 8695.359306] LNet: 177694:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8695.370946] LNetError: 177694:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8695.392481] LNet: Removed LNI 192.168.201.27@tcp [ 8696.102455] Key type .llcrypt unregistered [ 8696.105918] Key type ._llcrypt unregistered [ 8708.743849] Key type ._llcrypt registered [ 8708.780153] Key type .llcrypt registered [ 8709.101454] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8709.119686] alg: No test for adler32 (adler32-zlib) [ 8710.134433] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [ 8710.347528] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [ 8712.015214] Key type lgssc registered [ 8713.031258] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8760.893824] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8766.089952] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8784.972412] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8787.713372] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 8795.063227] Lustre: Unmounted lustre-client [ 8822.690391] Key type lgssc unregistered [ 8822.958975] LNet: 180749:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8822.975887] LNetError: 180749:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8823.004502] LNet: Removed LNI 192.168.201.27@tcp [ 8823.879330] Key type .llcrypt unregistered [ 8823.882166] Key type ._llcrypt unregistered [ 8840.224862] Key type ._llcrypt registered [ 8840.226819] Key type .llcrypt registered [ 8841.855217] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 11:17:56 (1788448676) [ 8864.645341] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8864.685633] alg: No test for adler32 (adler32-zlib) [ 8865.918253] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [ 8866.204187] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [ 8867.929629] Key type lgssc registered [ 8869.063874] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8949.209164] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8955.314945] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8980.311240] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9020.664752] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 11:20:55 (1788448855) [ 9053.620251] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9059.537583] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9082.873461] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9085.873820] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 9094.339758] Lustre: Unmounted lustre-client [ 9121.845689] Key type lgssc unregistered [ 9122.144570] LNet: 187840:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9122.155068] LNetError: 187840:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9122.177462] LNet: Removed LNI 192.168.201.27@tcp [ 9122.974608] Key type .llcrypt unregistered [ 9122.981451] Key type ._llcrypt unregistered [ 9139.031512] Key type ._llcrypt registered [ 9139.034465] Key type .llcrypt registered [ 9140.620306] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 9142.432676] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 11:22:57 (1788448977) [ 9161.646590] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9161.692850] alg: No test for adler32 (adler32-zlib) [ 9162.897366] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [ 9163.127863] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [ 9164.759235] Key type lgssc registered [ 9165.910917] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9264.627687] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9271.460125] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9295.725732] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9318.314925] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9321.067516] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 9343.720248] Lustre: Unmounted lustre-client [ 9489.844401] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9495.587426] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9518.055616] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9570.562213] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 11:30:05 (1788449405) [ 9572.461559] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 9574.738361] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 11:30:09 (1788449409) [ 9667.163267] Key type lgssc unregistered [ 9667.444694] LNet: 199292:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9667.449798] LNetError: 199292:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9667.465578] LNet: Removed LNI 192.168.201.27@tcp [ 9668.135780] Key type .llcrypt unregistered [ 9668.138693] Key type ._llcrypt unregistered [ 9681.595881] Key type ._llcrypt registered [ 9681.597806] Key type .llcrypt registered [ 9683.103924] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 11:31:57 (1788449517) [ 9730.500836] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9730.517079] alg: No test for adler32 (adler32-zlib) [ 9731.779867] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [ 9732.266407] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [ 9734.063418] Key type lgssc registered [ 9735.165484] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9785.413354] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9790.234369] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9821.599576] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9850.086618] Key type lgssc unregistered [ 9850.323858] LNet: 203023:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9850.330615] LNetError: 203023:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9850.350639] LNet: Removed LNI 192.168.201.27@tcp [ 9851.213626] Key type .llcrypt unregistered [ 9851.216429] Key type ._llcrypt unregistered [ 9869.885052] Key type ._llcrypt registered [ 9869.887770] Key type .llcrypt registered [ 9871.704478] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 11:35:06 (1788449706) [ 9876.261983] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9876.380408] alg: No test for adler32 (adler32-zlib) [ 9877.737953] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [ 9878.116827] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [ 9879.983581] Key type lgssc registered [ 9882.367950] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9943.464680] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9950.157939] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9979.239966] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9985.669554] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [ 9995.768368] Lustre: lustre-MDT0000-mdc-ffff953d510f4000: Connection to lustre-MDT0000 (at 192.168.201.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [10006.009159] Lustre: lustre-MDT0001-mdc-ffff953d510f4000: Connection to lustre-MDT0001 (at 192.168.201.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [10017.247774] Lustre: 204007:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788449837/real 1788449837] req@000000006bcdf6e2 x1875325447389440/t0(0) o400->MGC192.168.201.127@tcp@192.168.201.127@tcp:26/25 lens 224/224 e 0 to 1 dl 1788449853 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10017.288276] LustreError: MGC192.168.201.127@tcp: Connection to MGS (at 192.168.201.127@tcp) was lost; in progress operations using this service will fail [10027.492093] Lustre: Evicted from MGS (at 192.168.201.127@tcp) after server handle changed from 0x9e1cd9c276e9fdc2 to 0x9e1cd9c276ea18a3 [10027.505657] Lustre: MGC192.168.201.127@tcp: Connection restored to 192.168.201.127@tcp (at 192.168.201.127@tcp) [10050.023517] LustreError: lustre-MDT0000-mdc-ffff953d510f4000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [10050.042670] Lustre: lustre-MDT0000-mdc-ffff953d510f4000: Connection restored to 192.168.201.127@tcp (at 192.168.201.127@tcp) [10059.369089] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10062.095899] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10069.330857] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10071.411864] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [10074.812303] Lustre: Unmounted lustre-client [10110.429150] Key type lgssc unregistered [10110.774835] LNet: 207966:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10110.781444] LNetError: 207966:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10110.808008] LNet: Removed LNI 192.168.201.27@tcp [10111.654356] Key type .llcrypt unregistered [10111.656151] Key type ._llcrypt unregistered [10129.861209] Key type ._llcrypt registered [10129.868424] Key type .llcrypt registered [10131.624140] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 11:39:26 (1788449966) [10134.696439] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10134.705614] alg: No test for adler32 (adler32-zlib) [10135.962842] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [10136.216327] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [10137.911668] Key type lgssc registered [10139.871141] Lustre: Echo OBD driver; http://www.lustre.org/ [10195.547310] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10203.008522] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10258.915607] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10265.905807] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10302.609283] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10324.833374] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10378.136898] Key type lgssc unregistered [10378.397559] LNet: 213260:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10378.411519] LNetError: 213260:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10378.425449] LNet: Removed LNI 192.168.201.27@tcp [10379.135669] Key type .llcrypt unregistered [10379.140775] Key type ._llcrypt unregistered [10422.950587] Key type ._llcrypt registered [10422.959944] Key type .llcrypt registered [10424.450459] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 11:44:19 (1788450259) [10465.025134] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10465.073216] alg: No test for adler32 (adler32-zlib) [10466.378511] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [10466.831263] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [10468.616642] Key type lgssc registered [10470.236953] Lustre: Echo OBD driver; http://www.lustre.org/ [10555.580460] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10562.089324] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10586.544275] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10625.126980] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 11:47:40 (1788450460) [10632.018678] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 11:47:47 (1788450467) [10667.329124] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10672.939387] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10691.883441] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10694.676300] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [10700.451628] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [10703.175293] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [10705.763040] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [10708.114413] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [10715.133270] Lustre: lustre-MDT0000-mdc-ffff953d50625000: Connection to lustre-MDT0000 (at 192.168.201.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [10731.487979] Lustre: 215151:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788450551/real 1788450551] req@000000002ba20c56 x1875326064929024/t0(0) o400->MGC192.168.201.127@tcp@192.168.201.127@tcp:26/25 lens 224/224 e 0 to 1 dl 1788450567 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10731.507100] LustreError: MGC192.168.201.127@tcp: Connection to MGS (at 192.168.201.127@tcp) was lost; in progress operations using this service will fail [10740.725870] Lustre: Evicted from MGS (at 192.168.201.127@tcp) after server handle changed from 0xf05e5b6a8ef1687e to 0xf05e5b6a8ef169f1 [10740.749314] Lustre: MGC192.168.201.127@tcp: Connection restored to 192.168.201.127@tcp (at 192.168.201.127@tcp) [10747.383415] Lustre: lustre-MDT0000-mdc-ffff953d50625000: Connection restored to 192.168.201.127@tcp (at 192.168.201.127@tcp) [10755.579033] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10757.348325] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10760.754342] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [10766.311617] Lustre: lustre-MDT0000-mdc-ffff953d50625000: Connection to lustre-MDT0000 (at 192.168.201.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [10781.668125] Lustre: 215150:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788450602/real 1788450602] req@000000001aad3621 x1875326064938112/t0(0) o400->MGC192.168.201.127@tcp@192.168.201.127@tcp:26/25 lens 224/224 e 0 to 1 dl 1788450618 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10781.721407] LustreError: MGC192.168.201.127@tcp: Connection to MGS (at 192.168.201.127@tcp) was lost; in progress operations using this service will fail [10787.354583] Lustre: lustre-MDT0000-mdc-ffff953d50625000: Connection restored to 192.168.201.127@tcp (at 192.168.201.127@tcp) [10792.495583] Lustre: Evicted from MGS (at 192.168.201.127@tcp) after server handle changed from 0xf05e5b6a8ef169f1 to 0xf05e5b6a8ef16f9a [10792.510255] Lustre: MGC192.168.201.127@tcp: Connection restored to 192.168.201.127@tcp (at 192.168.201.127@tcp) [10795.587223] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10797.642984] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10801.735712] Lustre: Unmounted lustre-client [10870.837929] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10877.099640] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10900.207698] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10901.052920] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [10909.168530] Lustre: Unmounted lustre-client [10936.644635] Key type lgssc unregistered [10936.896559] LNet: 225727:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10936.904113] LNetError: 225727:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10936.919783] LNet: Removed LNI 192.168.201.27@tcp [10937.578824] Key type .llcrypt unregistered [10937.582927] Key type ._llcrypt unregistered [10952.208407] Key type ._llcrypt registered [10952.209858] Key type .llcrypt registered [10953.710495] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 11:53:08 (1788450788) [10957.769487] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10957.804935] alg: No test for adler32 (adler32-zlib) [10959.042624] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [10959.389908] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [10961.143838] Key type lgssc registered [10962.654559] Lustre: Echo OBD driver; http://www.lustre.org/ [11014.624388] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11019.531559] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11041.282804] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11042.927609] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [11050.421825] Lustre: Unmounted lustre-client [11109.050521] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11115.443208] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11135.528664] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11136.282113] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [11145.723859] Lustre: Unmounted lustre-client [11209.467640] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11215.167966] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11236.593929] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11237.597527] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [11240.700134] Lustre: Unmounted lustre-client [11272.030448] Key type lgssc unregistered [11272.269985] LNet: 232759:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11272.278481] LNetError: 232759:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11272.292470] LNet: Removed LNI 192.168.201.27@tcp [11273.016350] Key type .llcrypt unregistered [11273.019049] Key type ._llcrypt unregistered [11288.912567] Key type ._llcrypt registered [11288.917626] Key type .llcrypt registered [11290.334607] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 11:58:45 (1788451125) [11294.037555] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11294.080405] alg: No test for adler32 (adler32-zlib) [11295.277981] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [11295.680648] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [11297.423630] Key type lgssc registered [11298.621665] Lustre: Echo OBD driver; http://www.lustre.org/ [11346.736816] Key type lgssc unregistered [11347.023180] LNet: 235078:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11347.028686] LNetError: 235078:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11347.045388] LNet: Removed LNI 192.168.201.27@tcp [11347.968459] Key type .llcrypt unregistered [11347.974564] Key type ._llcrypt unregistered [11374.991112] Key type ._llcrypt registered [11374.993346] Key type .llcrypt registered [11375.497097] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11375.517289] alg: No test for adler32 (adler32-zlib) [11376.575236] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [11376.840167] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [11378.504927] Key type lgssc registered [11379.763774] Lustre: Echo OBD driver; http://www.lustre.org/ [11465.977780] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11472.016439] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11494.339278] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11537.004378] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [11538.943715] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 12:02:53 (1788451373) [11568.822042] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11574.828640] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11594.675924] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11616.458410] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11618.196641] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [11623.419286] Lustre: lustre-OST0001-osc-ffff953d44c66800: Connection to lustre-OST0001 (at 192.168.201.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [11629.334512] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11639.667376] Lustre: Unmounted lustre-client [11674.393460] Key type lgssc unregistered [11674.784126] LNet: 242192:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11674.790802] LNetError: 242192:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11674.812435] LNet: Removed LNI 192.168.201.27@tcp [11675.780918] Key type .llcrypt unregistered [11675.784128] Key type ._llcrypt unregistered [11710.191758] Key type ._llcrypt registered [11710.195282] Key type .llcrypt registered [11712.050841] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 12:05:46 (1788451546) [11727.221214] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11727.268817] alg: No test for adler32 (adler32-zlib) [11728.471450] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [11728.760509] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [11730.487550] Key type lgssc registered [11731.920794] Lustre: Echo OBD driver; http://www.lustre.org/ [11782.844979] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11789.120262] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11812.898569] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 12:07:27 (1788451647) [11849.601718] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11856.151398] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11879.561146] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11882.323413] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [11897.883304] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [11902.948757] Lustre: Unmounted lustre-client [12004.013100] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12010.118071] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12066.679548] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12067.429780] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [12070.794293] Lustre: Unmounted lustre-client [12108.887934] Key type lgssc unregistered [12109.251245] LNet: 249898:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12109.267757] LNetError: 249898:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12109.298039] LNet: Removed LNI 192.168.201.27@tcp [12110.108755] Key type .llcrypt unregistered [12110.110639] Key type ._llcrypt unregistered [12139.379505] Key type ._llcrypt registered [12139.388443] Key type .llcrypt registered [12140.186108] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12140.213393] alg: No test for adler32 (adler32-zlib) [12141.362795] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [12141.712862] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [12143.463689] Key type lgssc registered [12144.848038] Lustre: Echo OBD driver; http://www.lustre.org/ [12232.525756] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12239.202931] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12267.100671] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12319.821804] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [12322.297872] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 12:15:57 (1788452157) [12356.966141] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12362.748698] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12386.418386] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12390.682546] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [12395.475247] Lustre: Unmounted lustre-client [12425.225787] Key type lgssc unregistered [12425.592176] LNet: 256533:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12425.604681] LNetError: 256533:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12425.634643] LNet: Removed LNI 192.168.201.27@tcp [12426.545424] Key type .llcrypt unregistered [12426.547285] Key type ._llcrypt unregistered [12445.192969] Key type ._llcrypt registered [12445.196345] Key type .llcrypt registered [12446.706976] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [12448.457597] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 12:18:03 (1788452283) [12457.589362] Key type .llcrypt unregistered [12457.592193] Key type ._llcrypt unregistered [12467.719983] Key type ._llcrypt registered [12467.745050] Key type .llcrypt registered [12468.436876] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12468.465900] alg: No test for adler32 (adler32-zlib) [12469.669816] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [12469.923309] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [12471.671257] Key type lgssc registered [12472.910193] Lustre: Echo OBD driver; http://www.lustre.org/ [12537.079410] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12553.809791] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [12556.119481] Lustre: Unmounted lustre-client [12584.397847] Key type lgssc unregistered [12584.651924] LNet: 259979:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12584.668367] LNetError: 259979:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12584.685372] LNet: Removed LNI 192.168.201.27@tcp [12585.531577] Key type .llcrypt unregistered [12585.533653] Key type ._llcrypt unregistered [12603.019449] Key type ._llcrypt registered [12603.026697] Key type .llcrypt registered [12604.763555] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 12:20:39 (1788452439) [12607.474385] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12607.502975] alg: No test for adler32 (adler32-zlib) [12608.754214] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [12609.236325] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [12611.023619] Key type lgssc registered [12612.866452] Lustre: Echo OBD driver; http://www.lustre.org/ [12704.529989] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12742.232098] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12749.551262] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12752.323520] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [12754.946512] Lustre: Unmounted lustre-client [12785.839693] Key type lgssc unregistered [12786.273743] LNet: 263520:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12786.285704] LNetError: 263520:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12786.308295] LNet: Removed LNI 192.168.201.27@tcp [12787.033192] Key type .llcrypt unregistered [12787.038570] Key type ._llcrypt unregistered [12803.873429] Key type ._llcrypt registered [12803.879507] Key type .llcrypt registered [12805.435885] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 12:24:00 (1788452640) [12808.472526] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12808.607423] alg: No test for adler32 (adler32-zlib) [12809.855583] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [12810.156877] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [12811.895270] Key type lgssc registered [12813.933886] Lustre: Echo OBD driver; http://www.lustre.org/ [12875.442918] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12881.637798] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12904.587935] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12907.359165] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [12912.615405] Lustre: lustre-MDT0000-mdc-ffff953d50620800: Connection to lustre-MDT0000 (at 192.168.201.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [12917.595462] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12917.629382] LustreError: 266689:0:(file.c:6167:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [12918.138838] LustreError: MGC192.168.201.127@tcp: Connection to MGS (at 192.168.201.127@tcp) was lost; in progress operations using this service will fail [12918.193935] Lustre: Unmounted lustre-client [12939.226515] Key type lgssc unregistered [12939.488938] LNet: 267102:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12939.497814] LNetError: 267102:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12939.514507] LNet: Removed LNI 192.168.201.27@tcp [12940.420458] Key type .llcrypt unregistered [12940.423598] Key type ._llcrypt unregistered [12959.285960] Key type ._llcrypt registered [12959.293789] Key type .llcrypt registered [12960.939303] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 12:26:35 (1788452795) [12964.020364] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12964.075161] alg: No test for adler32 (adler32-zlib) [12965.187599] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [12965.432498] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [12967.167219] Key type lgssc registered [12968.760671] Lustre: Echo OBD driver; http://www.lustre.org/ [13028.229585] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13035.841711] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13065.240861] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13067.417246] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [13072.885825] Lustre: lustre-MDT0001-mdc-ffff953d444bd000: Connection to lustre-MDT0001 (at 192.168.201.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [13076.119466] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [13080.566144] Lustre: Unmounted lustre-client [13102.214908] Key type lgssc unregistered [13102.532898] LNet: 270684:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13102.554230] LNetError: 270684:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13102.575414] LNet: Removed LNI 192.168.201.27@tcp [13103.428718] Key type .llcrypt unregistered [13103.446082] Key type ._llcrypt unregistered [13122.412170] Key type ._llcrypt registered [13122.414543] Key type .llcrypt registered [13124.275879] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 12:29:18 (1788452958) [13147.547823] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13147.565836] alg: No test for adler32 (adler32-zlib) [13148.937965] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [13149.471294] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [13151.359400] Key type lgssc registered [13153.129623] Lustre: Echo OBD driver; http://www.lustre.org/ [13282.550472] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13285.318566] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [13338.609492] Lustre: lustre-MDT0001-mdc-ffff953d444bb000: Connection to lustre-MDT0001 (at 192.168.201.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [13348.273157] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [13358.643416] Lustre: Unmounted lustre-client [13389.221666] Key type lgssc unregistered [13389.671103] LNet: 276472:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13389.681733] LNetError: 276472:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13389.697554] LNet: Removed LNI 192.168.201.27@tcp [13390.559928] Key type .llcrypt unregistered [13390.561404] Key type ._llcrypt unregistered [13408.898994] Key type ._llcrypt registered [13408.900654] Key type .llcrypt registered [13410.891551] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 12:34:05 (1788453245) [13412.851851] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [13414.916126] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 12:34:09 (1788453249) [13417.299330] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [13419.689490] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 12:34:14 (1788453254) [13422.206966] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [13424.738545] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 12:34:19 (1788453259) [13427.056391] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [13429.004812] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 12:34:23 (1788453263) [13431.365654] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [13433.393257] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 12:34:28 (1788453268) [13443.578405] Key type .llcrypt unregistered [13443.581164] Key type ._llcrypt unregistered [13452.434919] Key type ._llcrypt registered [13452.451819] Key type .llcrypt registered [13452.998948] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13453.010269] alg: No test for adler32 (adler32-zlib) [13454.032464] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [13454.265991] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [13455.943540] Key type lgssc registered [13457.773485] Lustre: Echo OBD driver; http://www.lustre.org/ [13543.022586] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13549.214460] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13576.047482] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13577.852306] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [13582.019499] Lustre: Unmounted lustre-client [13625.264511] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 12:37:39 (1788453459) [13663.689078] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13670.150746] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13709.246275] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13710.069205] LustreError: 279245:0:(lmv_obd.c:211:lmv_notify()) activation of lustre-MDT0001_UUID failed: -22 [13710.127293] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [13713.688643] Lustre: Unmounted lustre-client [13762.368266] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 12:39:57 (1788453597) [13769.695414] LNet: 1 local NIs in recovery (showing 1): 192.168.201.27@tcp [14420.070823] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14427.180401] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14463.566641] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14578.067642] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 12:53:33 (1788454413) [14592.428462] Key type lgssc unregistered [14592.895131] LNet: 290011:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14592.920650] LNetError: 290011:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14593.961481] LNet: Removed LNI 192.168.201.27@tcp [14594.730546] Key type .llcrypt unregistered [14594.732180] Key type ._llcrypt unregistered [14715.397177] Key type ._llcrypt registered [14715.399490] Key type .llcrypt registered [14715.876434] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14715.897820] alg: No test for adler32 (adler32-zlib) [14716.982274] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [14717.258752] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [14719.000201] Key type lgssc registered [14721.032305] Lustre: Echo OBD driver; http://www.lustre.org/ [14813.874210] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14821.056250] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14845.991169] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14881.946769] Key type lgssc unregistered [14882.209872] LNet: 293707:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14882.217482] LNetError: 293707:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14882.228410] LNet: Removed LNI 192.168.201.27@tcp [14882.960737] Key type .llcrypt unregistered [14882.962963] Key type ._llcrypt unregistered [14908.932792] Key type ._llcrypt registered [14908.935983] Key type .llcrypt registered [14910.259433] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 12:59:05 (1788454745) [14933.814692] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14934.074760] alg: No test for adler32 (adler32-zlib) [14935.245962] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [14935.584807] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [14937.367628] Key type lgssc registered [14938.506465] Lustre: Echo OBD driver; http://www.lustre.org/ [14996.242880] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 13:00:30 (1788454830) [15009.606462] Key type lgssc unregistered [15009.891048] LNet: 297261:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15009.900879] LNetError: 297261:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15009.923662] LNet: Removed LNI 192.168.201.27@tcp [15010.611715] Key type .llcrypt unregistered [15010.614655] Key type ._llcrypt unregistered [15022.888693] Key type ._llcrypt registered [15022.894665] Key type .llcrypt registered [15023.430474] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15023.456475] alg: No test for adler32 (adler32-zlib) [15024.929760] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [15025.302335] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [15027.105766] Key type lgssc registered [15028.822171] Lustre: Echo OBD driver; http://www.lustre.org/ [15094.927972] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15103.726776] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15134.253101] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15475.966770] LNet: Added LNI 192.168.201.27@tcp50 [8/256/0/180] [15479.182797] LNet: Removed LNI 192.168.201.27@tcp [15481.505865] Lustre: 301697:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff953d6000a000: config has no valid NIDs, force dynamic NIDs [15482.783852] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [15482.951556] Lustre: Unmounted lustre-client [15486.320291] Key type lgssc unregistered [15486.768643] LNet: 301954:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15486.775577] LNetError: 301954:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15487.860893] LNet: Removed LNI 192.168.201.27@tcp50 [15488.869386] Key type .llcrypt unregistered [15488.874171] Key type ._llcrypt unregistered [15517.645056] Key type ._llcrypt registered [15517.647699] Key type .llcrypt registered [15518.082494] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15518.097421] alg: No test for adler32 (adler32-zlib) [15519.191300] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [15519.658368] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [15521.431405] Key type lgssc registered [15523.202511] Lustre: Echo OBD driver; http://www.lustre.org/ [15589.147529] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15597.827495] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15608.796193] LNet: Added LNI 192.168.201.27@tcp50 [8/256/0/180] [15610.565685] LNet: Removed LNI 192.168.201.27@tcp [15611.790250] Lustre: 304645:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff953d4a9ee800: config has no valid NIDs, force dynamic NIDs [15613.276416] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [15614.357304] Lustre: Unmounted lustre-client [15656.762664] Key type lgssc unregistered [15657.327796] LNet: 305515:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15657.345887] LNetError: 305515:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15658.406973] LNet: Removed LNI 192.168.201.27@tcp50 [15659.346254] Key type .llcrypt unregistered [15659.352375] Key type ._llcrypt unregistered [15674.241975] Key type ._llcrypt registered [15674.244454] Key type .llcrypt registered [15676.071449] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 13:11:50 (1788455510) [15697.594551] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15697.678043] alg: No test for adler32 (adler32-zlib) [15698.944976] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [15699.442337] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [15701.199672] Key type lgssc registered [15703.335083] Lustre: Echo OBD driver; http://www.lustre.org/ [15810.018690] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15816.892708] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15842.368302] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15876.985216] Key type lgssc unregistered [15877.347077] LNet: 310269:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15877.364669] LNetError: 310269:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15877.397273] LNet: Removed LNI 192.168.201.27@tcp [15878.324192] Key type .llcrypt unregistered [15878.326441] Key type ._llcrypt unregistered [15891.715448] Key type ._llcrypt registered [15891.716968] Key type .llcrypt registered [15893.488396] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 13:15:28 (1788455728) [15897.162919] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15897.207708] alg: No test for adler32 (adler32-zlib) [15898.592641] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [15899.061196] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [15900.833643] Key type lgssc registered [15903.164539] Lustre: Echo OBD driver; http://www.lustre.org/ [15965.555191] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15973.722976] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15997.164450] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16003.174870] Lustre: Mounted lustre-client - version 2.17.58_2_gee80f9b [16065.693690] Lustre: Mounted fs15246-client - version 2.17.58_2_gee80f9b [16082.424625] Lustre: Unmounted fs15246-client [16115.187982] Lustre: Unmounted lustre-client [16145.873971] Key type lgssc unregistered [16146.364799] LNet: 315130:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16146.388865] LNetError: 315130:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16146.422141] LNet: Removed LNI 192.168.201.27@tcp [16147.527850] Key type .llcrypt unregistered [16147.537882] Key type ._llcrypt unregistered [16163.264169] Key type ._llcrypt registered [16163.266950] Key type .llcrypt registered [16165.629618] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 13:19:59 (1788455999) [16168.569684] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16168.778994] alg: No test for adler32 (adler32-zlib) [16170.031886] Lustre: Lustre: Build Version: 2.17.58_2_gee80f9b [16170.288830] LNet: Added LNI 192.168.201.27@tcp [8/256/0/180] [16171.967212] Key type lgssc registered [16173.904825] Lustre: Echo OBD driver; http://www.lustre.org/ [16325.287929] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16335.449596] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16367.589449] Lustre: DEBUG MARKER: oleg127-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16494.087166] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 16185 sec ========= 13:25:28 (1788456328) [16495.892541] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 13:25:30 (1788456330) === [16499.880829] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 13:25:34 (1788456334) === [16540.633635] Key type lgssc unregistered [16540.863273] LNet: 324402:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16540.881566] LNetError: 324402:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16540.902184] LNet: Removed LNI 192.168.201.27@tcp [16541.753031] Key type .llcrypt unregistered [16541.755432] Key type ._llcrypt unregistered