[ 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 452349234 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 2464MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001013] APIC: Switch to symmetric I/O mode setup [ 0.003342] x2apic enabled [ 0.004010] Switched APIC routing to physical x2apic. [ 0.005016] kvm-guest: setup PV IPIs [ 0.008000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008024] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009014] pid_max: default: 32768 minimum: 301 [ 0.010150] LSM: Security Framework initializing [ 0.011071] Yama: becoming mindful. [ 0.013030] SELinux: Initializing. [ 0.014079] *** VALIDATE selinux *** [ 0.023220] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.028812] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.029174] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031129] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.032124] *** VALIDATE tmpfs *** [ 0.034358] *** VALIDATE proc *** [ 0.035263] *** VALIDATE cgroup *** [ 0.036013] *** VALIDATE cgroup2 *** [ 0.038187] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.040131] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.041010] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.042027] Spectre V2 : User space: Vulnerable [ 0.043011] Speculative Store Bypass: Vulnerable [ 0.046219] debug: unmapping init [mem 0xffffffffa3c59000-0xffffffffa3c60fff] [ 0.049000] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.049738] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.050025] ... version: 2 [ 0.051018] ... bit width: 48 [ 0.052018] ... generic registers: 4 [ 0.053015] ... value mask: 0000ffffffffffff [ 0.054022] ... max period: 00007fffffffffff [ 0.055015] ... fixed-purpose events: 3 [ 0.056012] ... event mask: 000000070000000f [ 0.057329] rcu: Hierarchical SRCU implementation. [ 0.059481] smp: Bringing up secondary CPUs ... [ 0.060601] x86: Booting SMP configuration: [ 0.061030] .... node #0, CPUs: #1 #2 #3 [ 0.064369] smp: Brought up 1 node, 4 CPUs [ 0.066019] smpboot: Max logical packages: 1 [ 0.067027] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.145019] node 0 deferred pages initialised in 75ms [ 0.149516] devtmpfs: initialized [ 0.151303] x86/mm: Memory block size: 128MB [ 0.154947] gcov: version magic: 0x41383552 [ 0.158122] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.159129] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.162377] pinctrl core: initialized pinctrl subsystem [ 0.164220] [ 0.164863] ************************************************************* [ 0.167016] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.169011] ** ** [ 0.172014] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.174013] ** ** [ 0.176013] ** This means that this kernel is built to expose internal ** [ 0.179014] ** IOMMU data structures, which may compromise security on ** [ 0.181012] ** your system. ** [ 0.183014] ** ** [ 0.186013] ** If you see this message and you are not debugging the ** [ 0.188011] ** kernel, report this immediately to your vendor! ** [ 0.191018] ** ** [ 0.193010] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.195013] ************************************************************* [ 0.197508] NET: Registered protocol family 16 [ 0.200607] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.203062] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.205072] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.210047] cpuidle: using governor menu [ 0.211719] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.213341] PCI: Using configuration type 1 for base access [ 0.215093] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.224238] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.226019] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.230096] cryptd: max_cpu_qlen set to 1000 [ 0.233262] ACPI: Added _OSI(Module Device) [ 0.235026] ACPI: Added _OSI(Processor Device) [ 0.237017] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.238015] ACPI: Added _OSI(Processor Aggregator Device) [ 0.243085] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.248541] ACPI: Interpreter enabled [ 0.250070] ACPI: PM: (supports S0 S3 S4 S5) [ 0.252014] ACPI: Using IOAPIC for interrupt routing [ 0.254162] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.257363] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.267633] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.270053] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.273023] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.277102] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.282392] acpiphp: Slot [2] registered [ 0.284158] acpiphp: Slot [5] registered [ 0.285150] acpiphp: Slot [6] registered [ 0.287155] acpiphp: Slot [3] registered [ 0.288198] acpiphp: Slot [4] registered [ 0.290117] acpiphp: Slot [7] registered [ 0.291118] acpiphp: Slot [8] registered [ 0.293132] acpiphp: Slot [9] registered [ 0.294100] acpiphp: Slot [10] registered [ 0.295102] acpiphp: Slot [11] registered [ 0.297104] acpiphp: Slot [12] registered [ 0.298099] acpiphp: Slot [13] registered [ 0.300097] acpiphp: Slot [14] registered [ 0.301131] acpiphp: Slot [15] registered [ 0.302110] acpiphp: Slot [16] registered [ 0.304122] acpiphp: Slot [17] registered [ 0.305111] acpiphp: Slot [18] registered [ 0.307093] acpiphp: Slot [19] registered [ 0.308095] acpiphp: Slot [20] registered [ 0.309122] acpiphp: Slot [21] registered [ 0.311118] acpiphp: Slot [22] registered [ 0.313124] acpiphp: Slot [23] registered [ 0.315140] acpiphp: Slot [24] registered [ 0.316190] acpiphp: Slot [25] registered [ 0.317168] acpiphp: Slot [26] registered [ 0.318087] acpiphp: Slot [27] registered [ 0.320085] acpiphp: Slot [28] registered [ 0.321100] acpiphp: Slot [29] registered [ 0.322104] acpiphp: Slot [30] registered [ 0.324151] acpiphp: Slot [31] registered [ 0.325054] PCI host bridge to bus 0000:00 [ 0.326017] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.328023] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.331023] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.333020] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.335023] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.338027] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.340063] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.342235] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.346621] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.355014] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.360000] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.363018] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.365017] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.368019] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.371660] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.375083] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.377033] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.380801] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.385016] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.395747] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.400016] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.405958] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.413017] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.418026] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.430020] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.440218] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.447016] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.452016] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.470012] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.478895] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.482427] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.484382] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.487524] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.490230] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.494234] iommu: Default domain type: Passthrough [ 0.496457] SCSI subsystem initialized [ 0.498170] ACPI: bus type USB registered [ 0.499107] usbcore: registered new interface driver usbfs [ 0.501079] usbcore: registered new interface driver hub [ 0.503083] usbcore: registered new device driver usb [ 0.505177] pps_core: LinuxPPS API ver. 1 registered [ 0.507012] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.510071] PTP clock support registered [ 0.513109] EDAC MC: Ver: 3.0.0 [ 0.515134] PCI: Using ACPI for IRQ routing [ 0.517795] NetLabel: Initializing [ 0.519012] NetLabel: domain hash size = 128 [ 0.520012] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.522110] NetLabel: unlabeled traffic allowed by default [ 0.525076] vgaarb: loaded [ 0.527295] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.529016] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.537000] clocksource: Switched to clocksource kvm-clock [ 0.650370] VFS: Disk quotas dquot_6.6.0 [ 0.652112] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.654865] *** VALIDATE ramfs *** [ 0.656250] *** VALIDATE hugetlbfs *** [ 0.658052] pnp: PnP ACPI init [ 0.661054] pnp: PnP ACPI: found 6 devices [ 0.677211] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.680191] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.682226] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.684606] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.687241] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.689838] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.692449] NET: Registered protocol family 2 [ 0.695132] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.699857] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.703549] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.708996] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.712599] TCP: Hash tables configured (established 65536 bind 65536) [ 0.716031] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.719055] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.722171] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.724288] NET: Registered protocol family 1 [ 0.727202] RPC: Registered named UNIX socket transport module. [ 0.729312] RPC: Registered udp transport module. [ 0.730849] RPC: Registered tcp transport module. [ 0.732681] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.736629] NET: Registered protocol family 44 [ 0.738409] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.740158] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.742535] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.744959] PCI: CLS 0 bytes, default 64 [ 0.746458] Unpacking initramfs... [ 2.169177] debug: unmapping init [mem 0xffff9faafcc64000-0xffff9faafffcffff] [ 2.171842] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.173607] software IO TLB: mapped [mem 0x00000000b8c64000-0x00000000bcc64000] (64MB) [ 2.175757] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.678839] Initialise system trusted keyrings [ 2.680541] Key type blacklist registered [ 2.682481] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.691408] zbud: loaded [ 2.696127] *** VALIDATE nfs *** [ 2.697334] *** VALIDATE nfs4 *** [ 2.699390] pstore: using deflate compression [ 2.703454] Platform Keyring initialized [ 2.812506] NET: Registered protocol family 38 [ 2.814956] Key type asymmetric registered [ 2.817263] Asymmetric key parser 'x509' registered [ 2.820345] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.826950] io scheduler mq-deadline registered [ 2.828969] io scheduler kyber registered [ 2.831044] io scheduler bfq registered [ 2.833806] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.837143] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.840032] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.844294] ACPI: Power Button [PWRF] [ 2.849622] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.856455] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.870428] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.898738] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.927673] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.933138] Non-volatile memory driver v1.3 [ 2.935036] Linux agpgart interface v0.103 [ 2.976082] virtio_blk virtio1: [vda] 145904 512-byte logical blocks (74.7 MB/71.2 MiB) [ 2.979260] vda: detected capacity change from 0 to 74702848 [ 2.998237] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.001827] vdb: detected capacity change from 0 to 1073741824 [ 3.010386] libphy: Fixed MDIO Bus: probed [ 3.016779] usbcore: registered new interface driver usbserial_generic [ 3.019645] usbserial: USB Serial support registered for generic [ 3.022205] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.027175] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.029398] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.032449] mousedev: PS/2 mouse device common for all mice [ 3.036224] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.036684] rtc_cmos 00:05: RTC can wake from S4 [ 3.042567] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.045087] rtc_cmos 00:05: registered as rtc0 [ 3.048178] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.049405] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.055453] intel_pstate: CPU model not supported [ 3.058778] hid: raw HID events driver (C) Jiri Kosina [ 3.061475] usbcore: registered new interface driver usbhid [ 3.063776] usbhid: USB HID core driver [ 3.065631] drop_monitor: Initializing network drop monitor service [ 3.068464] Initializing XFRM netlink socket [ 3.070648] NET: Registered protocol family 10 [ 3.073768] Segment Routing with IPv6 [ 3.075230] NET: Registered protocol family 17 [ 3.077654] mpls_gso: MPLS GSO support [ 3.082642] RAS: Correctable Errors collector initialized. [ 3.084706] AVX version of gcm_enc/dec engaged. [ 3.086341] AES CTR mode by8 optimization enabled [ 3.169498] sched_clock: Marking stable (3169466332, 0)->(4079721039, -910254707) [ 3.190621] registered taskstats version 1 [ 3.192823] Loading compiled-in X.509 certificates [ 3.194882] zswap: loaded using pool lzo/zbud [ 3.225052] Key type big_key registered [ 3.237876] Key type encrypted registered [ 3.239034] ima: No TPM chip found, activating TPM-bypass! [ 3.241108] ima: Allocated hash algorithm: sha1 [ 3.243160] ima: No architecture policies found [ 3.245156] evm: Initialising EVM extended attributes: [ 3.247299] evm: security.selinux [ 3.248552] evm: security.ima [ 3.249689] evm: security.capability [ 3.251110] evm: HMAC attrs: 0x1 [ 3.255389] rtc_cmos 00:05: setting system clock to 2026-09-05 00:53:20 UTC (1788569600) [ 3.261656] debug: unmapping init [mem 0xffffffffa4c03000-0xffffffffa4dfffff] [ 3.266012] debug: unmapping init [mem 0xffffffffa3982000-0xffffffffa3c58fff] [ 3.278194] Write protecting the kernel read-only data: 28672k [ 3.281519] debug: unmapping init [mem 0xffffffffa2003000-0xffffffffa21fffff] [ 3.284444] debug: unmapping init [mem 0xffffffffa2914000-0xffffffffa29fffff] [ 3.323214] 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.329593] systemd[1]: Detected virtualization kvm. [ 3.331129] systemd[1]: Detected architecture x86-64. [ 3.332942] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.358667] systemd[1]: No hostname configured. [ 3.360613] systemd[1]: Set hostname to . [ 3.362957] random: systemd: uninitialized urandom read (16 bytes read) [ 3.365516] systemd[1]: Initializing machine ID from random generator. [ 3.515335] random: systemd: uninitialized urandom read (16 bytes read) [ 3.517683] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 3.522597] random: systemd: uninitialized urandom read (16 bytes read) [ 3.525849] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 3.531425] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ OK ] Listening on Journal Socket. Starting Setup Virtual Console... Starting Create list of required st…ce nodes for the current kernel... Starting Create Volatile Files and Directories... [ OK ] Listening on udev Control Socket. Starting Journal Service... [ OK ] Reached target Swap. [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Slices. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Sockets. Starting Apply Kernel Variables... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Timers. [ OK ] Reached target Paths. [ OK ] Started 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... Starting dracut cmdline hook... [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.239235] device-mapper: uevent: version 1.0.3 [ 4.241845] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 4.954296] virtio_net virtio0 ens2: renamed from eth0 [ 5.085170] scsi host0: ata_piix [ 5.157218] scsi host1: ata_piix [ 5.160263] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.162943] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.717296] dracut-initqueue[578]: RTNETLINK answers: File exists [ 9.758092] random: crng init done [ 9.760976] 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.199443] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. Stopping udev Kernel Device Manager... [ OK ] Stopped target Swap. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.310803] printk: systemd: 24 output lines suppressed due to ratelimiting [ 11.599983] SELinux: Disabled at runtime. [ 11.660755] 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.670981] systemd[1]: Detected virtualization kvm. [ 11.673192] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.293878] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.298356] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.304772] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.310271] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.314239] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.323441] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.332385] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Listening on udev Control Socket. [ OK ] Created slice User and Session Slice. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Created slice system-getty.slice. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on udev Kernel Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Stopped target Switch Root. [ OK ] Reached target Slices. Starting udev Coldplug all Devices... Mounting Kernel Debug File System... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Stopped target Initrd File Systems. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Stopped target Initrd Root File System. Mounting Huge Pages File System... [ OK ] Reached target RPC Port Mapper. [ OK ] Listening on Process Core Dump Socket. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. Starting Apply Kernel Variables... [ OK ] Reached target Local Encrypted Volumes. Mounting POSIX Message Queue File System... Starting Remount Root and Kernel File Systems... [ OK ] Reached target rpc_pipefs.target. Activating swap /dev/disk/by-label/SWAP... [ OK ] Started Journal Service. [[ 12.533560] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS  OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted Huge Pages File System. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted POSIX Message Queue File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started udev Coldplug all Devices. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 12.778725] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 13.139932] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.147365] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.247632] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.264907] EDAC sbridge: Ver: 1.1.2 [ 14.568785] Key type dns_resolver registered [ 14.884690] NFS: Registering the id_resolver key type [ 14.886916] Key type id_resolver registered [ 14.888810] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... Starting Mark the need to relabel after reboot... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ 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 dnf makecache --timer. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... [ OK ] Started D-Bus System Message Bus. [ OK ] Started irqbalance daemon. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Network Manager... Starting Login Service... [ OK ] Started daily update of the root trust anchor for DNSSEC. [ 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. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started 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 System Logging Service... Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg454-client login: [ 77.103513] libcfs: loading out-of-tree module taints kernel. [ 77.347519] Key type ._llcrypt registered [ 77.351081] Key type .llcrypt registered [ 77.876529] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 77.888906] alg: No test for adler32 (adler32-zlib) [ 79.560701] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [ 80.746438] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 82.553125] Key type lgssc registered [ 83.596060] hrtimer: interrupt took 4301262 ns [ 84.533341] Lustre: Echo OBD driver; http://www.lustre.org/ [ 259.654164] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 264.747269] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 276.811264] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing check_logdir /tmp/testlogs/ [ 282.207379] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing yml_node [ 285.153864] Lustre: lustre-OST0000-osc-ffff9fab50504800: disconnect after 23s idle [ 287.328702] Lustre: DEBUG MARKER: Client: 2.17.57.1 [ 289.851242] Lustre: DEBUG MARKER: MDS: 2.17.57.1 [ 292.372422] Lustre: DEBUG MARKER: OSS: 2.17.57.1 [ 294.155415] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Fri Sep 4 20:58:09 EDT 2026 [ 312.246761] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 314.012056] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 316.808358] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 327.218100] Lustre: Unmounted lustre-client [ 458.844662] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 465.201070] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 494.520789] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 537.936706] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 21:02:13 (1788570133) [ 541.522300] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 544.094848] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 21:02:19 (1788570139) [ 586.579831] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 593.121428] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 615.312996] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 616.215373] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 624.631198] Lustre: Unmounted lustre-client [ 661.378505] Key type lgssc unregistered [ 661.738070] LNet: 15045:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 661.745541] LNetError: 15045:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 661.762443] LNet: Removed LNI 192.168.204.54@tcp [ 662.505336] Key type .llcrypt unregistered [ 662.510191] Key type ._llcrypt unregistered [ 681.936084] Key type ._llcrypt registered [ 681.941176] Key type .llcrypt registered [ 683.696789] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 21:04:38 (1788570278) [ 685.346191] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 685.369079] alg: No test for adler32 (adler32-zlib) [ 686.524818] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [ 686.852764] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 688.529677] Key type lgssc registered [ 689.943970] Lustre: Echo OBD driver; http://www.lustre.org/ [ 774.704813] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 863.451400] Lustre: Unmounted lustre-client [ 892.118528] Key type lgssc unregistered [ 892.485678] LNet: 18840:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 892.496811] LNetError: 18840:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 892.519471] LNet: Removed LNI 192.168.204.54@tcp [ 893.470177] Key type .llcrypt unregistered [ 893.473348] Key type ._llcrypt unregistered [ 905.362477] Key type ._llcrypt registered [ 905.365578] Key type .llcrypt registered [ 906.748094] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 21:08:22 (1788570502) [ 909.478405] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 909.555134] alg: No test for adler32 (adler32-zlib) [ 910.673258] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [ 910.992373] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 912.728198] Key type lgssc registered [ 914.137385] Lustre: Echo OBD driver; http://www.lustre.org/ [ 972.367994] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 980.247114] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1004.398530] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1015.374955] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 1028.021699] Lustre: Unmounted lustre-client [ 1056.912891] Key type lgssc unregistered [ 1057.183701] LNet: 22445:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1057.194309] LNetError: 22445:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1057.216647] LNet: Removed LNI 192.168.204.54@tcp [ 1057.860843] Key type .llcrypt unregistered [ 1057.866305] Key type ._llcrypt unregistered [ 1070.890682] Key type ._llcrypt registered [ 1070.897188] Key type .llcrypt registered [ 1072.462577] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 21:11:07 (1788570667) [ 1075.460380] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1075.510945] alg: No test for adler32 (adler32-zlib) [ 1076.463557] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [ 1076.730211] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 1078.440358] Key type lgssc registered [ 1079.794375] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1136.932640] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1143.912866] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1173.189405] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1180.280688] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 1188.393613] Lustre: Unmounted lustre-client [ 1221.579314] Key type lgssc unregistered [ 1221.978407] LNet: 25655:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1221.998335] LNetError: 25655:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1222.031595] LNet: Removed LNI 192.168.204.54@tcp [ 1222.944337] Key type .llcrypt unregistered [ 1222.950221] Key type ._llcrypt unregistered [ 1241.106944] Key type ._llcrypt registered [ 1241.111919] Key type .llcrypt registered [ 1242.620274] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 21:13:58 (1788570838) [ 1245.619341] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1245.640524] alg: No test for adler32 (adler32-zlib) [ 1246.853516] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [ 1247.242122] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 1248.992168] Key type lgssc registered [ 1250.395472] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1308.173711] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1314.546447] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1339.115720] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1342.043312] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 1347.070353] Lustre: lustre-MDT0000-mdc-ffff9fab43c46800: Connection to lustre-MDT0000 (at 192.168.204.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1357.303375] Lustre: lustre-MDT0001-mdc-ffff9fab43c46800: Connection to lustre-MDT0001 (at 192.168.204.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1365.536231] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1373.536202] Lustre: 26614:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788570954/real 1788570954] req@000000007aa7dbaa x1875452461324288/t0(0) o400->MGC192.168.204.154@tcp@192.168.204.154@tcp:26/25 lens 224/224 e 0 to 1 dl 1788570970 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1373.588521] LustreError: MGC192.168.204.154@tcp: Connection to MGS (at 192.168.204.154@tcp) was lost; in progress operations using this service will fail [ 1375.833219] Lustre: Unmounted lustre-client [ 1397.486920] Key type lgssc unregistered [ 1397.823916] LNet: 29319:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1397.828806] LNetError: 29319:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1397.852750] LNet: Removed LNI 192.168.204.54@tcp [ 1398.633233] Key type .llcrypt unregistered [ 1398.636783] Key type ._llcrypt unregistered [ 1416.711870] Key type ._llcrypt registered [ 1416.714686] Key type .llcrypt registered [ 1418.607866] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 21:16:53 (1788571013) [ 1421.124685] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1421.167052] alg: No test for adler32 (adler32-zlib) [ 1422.268371] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [ 1422.618995] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 1424.296327] Key type lgssc registered [ 1425.776430] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1478.216560] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1485.132635] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1508.472471] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1511.288650] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 1516.540468] Lustre: lustre-OST0000-osc-ffff9fab47dc1800: Connection to lustre-OST0000 (at 192.168.204.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1532.003316] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1542.372279] Lustre: Unmounted lustre-client [ 1570.152935] Key type lgssc unregistered [ 1570.401981] LNet: 32934:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1570.410676] LNetError: 32934:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1570.430686] LNet: Removed LNI 192.168.204.54@tcp [ 1570.977089] Key type .llcrypt unregistered [ 1570.980964] Key type ._llcrypt unregistered [ 1585.507374] Key type ._llcrypt registered [ 1585.511937] Key type .llcrypt registered [ 1586.533255] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 21:19:42 (1788571182) [ 1589.159174] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1589.171233] alg: No test for adler32 (adler32-zlib) [ 1590.213859] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [ 1590.392336] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 1592.024158] Key type lgssc registered [ 1593.163828] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1648.594559] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1655.114819] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1679.132669] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1680.889895] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 1685.288492] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1689.217712] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1700.613632] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-09-0421h21m36s [ 1703.073329] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1706.485529] Lustre: lustre-MDT0000-mdc-ffff9fab45402000: Connection to lustre-MDT0000 (at 192.168.204.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1714.106851] LustreError: 36728:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff9fab45402000: can't stat MDS #0: rc = -19 [ 1715.477412] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1724.910274] LustreError: MGC192.168.204.154@tcp: Connection to MGS (at 192.168.204.154@tcp) was lost; in progress operations using this service will fail [ 1724.925900] LustreError: lustre-MDT0000-mdc-ffff9fab45402000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1724.952592] Lustre: Evicted from MGS (at 192.168.204.154@tcp) after server handle changed from 0x223666c97fb260ce to 0x223666c97fb2637c [ 1724.965834] Lustre: lustre-MDT0000-mdc-ffff9fab45402000: Connection restored to 192.168.204.154@tcp (at 192.168.204.154@tcp) [ 1724.978908] Lustre: Skipped 1 previous similar message [ 1734.037279] Lustre: DEBUG MARKER: Wait for df (36728) ... [ 1735.721347] Lustre: DEBUG MARKER: done [ 1738.120876] Lustre: Unmounted lustre-client [ 1766.421845] Key type lgssc unregistered [ 1766.749415] LNet: 37751:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1766.757401] LNetError: 37751:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1766.775277] LNet: Removed LNI 192.168.204.54@tcp [ 1767.645476] Key type .llcrypt unregistered [ 1767.652924] Key type ._llcrypt unregistered [ 1800.421789] Key type ._llcrypt registered [ 1800.424195] Key type .llcrypt registered [ 1801.707230] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 21:23:17 (1788571397) [ 1804.341423] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1804.524352] alg: No test for adler32 (adler32-zlib) [ 1805.564624] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [ 1805.813116] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 1807.496321] Key type lgssc registered [ 1808.641488] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1870.009164] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1877.551854] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1901.055265] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1904.021328] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 1909.070273] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-09-0421h25m04s [ 1911.174794] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1918.955431] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1921.463814] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1941.984285] Lustre: 41905:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788571518/real 1788571518] req@000000004bdc329a x1875453047478144/t0(0) o101->lustre-MDT0000-mdc-ffff9fab45e01000@192.168.204.154@tcp:12/10 lens 576/1152 e 0 to 1 dl 1788571538 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1942.013655] Lustre: lustre-MDT0000-mdc-ffff9fab45e01000: Connection to lustre-MDT0000 (at 192.168.204.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1942.059591] Lustre: lustre-MDT0000-mdc-ffff9fab45e01000: Connection restored to 192.168.204.154@tcp (at 192.168.204.154@tcp) [ 1963.505557] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1967.181275] Lustre: DEBUG MARKER: done [ 1973.232177] Lustre: Unmounted lustre-client [ 2006.543917] Key type lgssc unregistered [ 2006.873291] LNet: 42788:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2006.880370] LNetError: 42788:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2006.902887] LNet: Removed LNI 192.168.204.54@tcp [ 2007.715429] Key type .llcrypt unregistered [ 2007.717251] Key type ._llcrypt unregistered [ 2043.698782] Key type ._llcrypt registered [ 2043.701614] Key type .llcrypt registered [ 2045.098327] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 21:27:20 (1788571640) [ 2047.502238] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 2050.194945] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 21:27:25 (1788571645) [ 2055.257301] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2055.384103] alg: No test for adler32 (adler32-zlib) [ 2056.638490] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [ 2057.001323] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 2058.697862] Key type lgssc registered [ 2059.910555] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2094.378289] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 21:28:10 (1788571690) [ 2127.866385] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2133.813900] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2156.718675] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2159.451759] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 2162.815527] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 2167.313708] Lustre: Unmounted lustre-client [ 2185.216352] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 2234.873170] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2241.657401] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2242.416743] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 2247.290878] Lustre: Unmounted lustre-client [ 2314.082141] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2321.386384] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2322.053621] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 2326.207471] Lustre: Unmounted lustre-client [ 2348.341856] Lustre: DEBUG MARKER: files compared the same [ 2373.961791] Key type lgssc unregistered [ 2374.230852] LNet: 50419:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2374.243614] LNetError: 50419:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2374.268756] LNet: Removed LNI 192.168.204.54@tcp [ 2375.047946] Key type .llcrypt unregistered [ 2375.053297] Key type ._llcrypt unregistered [ 2392.368468] Key type ._llcrypt registered [ 2392.370950] Key type .llcrypt registered [ 2393.678317] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 21:33:09 (1788571989) [ 2396.420990] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2396.469333] alg: No test for adler32 (adler32-zlib) [ 2397.658326] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [ 2398.021875] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 2399.768168] Key type lgssc registered [ 2401.106400] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2458.375957] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2465.035188] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2488.418582] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2491.182359] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 2495.472844] Lustre: Unmounted lustre-client [ 2524.251616] Key type lgssc unregistered [ 2524.627103] LNet: 54002:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2524.635365] LNetError: 54002:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2524.670550] LNet: Removed LNI 192.168.204.54@tcp [ 2525.438664] Key type .llcrypt unregistered [ 2525.444470] Key type ._llcrypt unregistered [ 2543.552520] Key type ._llcrypt registered [ 2543.555371] Key type .llcrypt registered [ 2545.035923] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 21:35:40 (1788572140) [ 2548.332369] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2548.342507] alg: No test for adler32 (adler32-zlib) [ 2549.491890] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [ 2549.767594] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 2551.496237] Key type lgssc registered [ 2552.855802] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2638.692086] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2670.144197] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2675.821515] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2703.806980] Key type lgssc unregistered [ 2704.048877] LNet: 57490:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2704.064328] LNetError: 57490:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2704.094778] LNet: Removed LNI 192.168.204.54@tcp [ 2704.846735] Key type .llcrypt unregistered [ 2704.849929] Key type ._llcrypt unregistered [ 2720.043798] Key type ._llcrypt registered [ 2720.046040] Key type .llcrypt registered [ 2721.573347] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 21:38:37 (1788572317) [ 2725.554037] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2725.665330] alg: No test for adler32 (adler32-zlib) [ 2726.771869] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [ 2727.033162] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 2728.760378] Key type lgssc registered [ 2729.968480] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2803.096615] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 2810.376488] Lustre: Unmounted lustre-client [ 2851.764061] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 21:40:47 (1788572447) [ 2969.626602] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2991.186864] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 2998.949568] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2998.956901] LustreError: 63967:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff9fab43c42800: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2998.986223] Lustre: 58475:0:(llite_lib.c:4340:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.204.154@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 3009.330877] Lustre: Unmounted lustre-client [ 3070.506995] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 21:44:26 (1788572666) [ 3081.832939] Key type lgssc unregistered [ 3082.055890] LNet: 65184:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3082.066347] LNetError: 65184:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3082.090816] LNet: Removed LNI 192.168.204.54@tcp [ 3082.748106] Key type .llcrypt unregistered [ 3082.751434] Key type ._llcrypt unregistered [ 3095.236324] Key type ._llcrypt registered [ 3095.239016] Key type .llcrypt registered [ 3095.749160] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3095.770124] alg: No test for adler32 (adler32-zlib) [ 3096.856548] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [ 3097.056760] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 3098.744208] Key type lgssc registered [ 3099.869522] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3150.064462] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3155.797754] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3179.631875] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3181.393397] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 3189.368687] Lustre: Unmounted lustre-client [ 3219.667724] Key type lgssc unregistered [ 3220.113972] LNet: 68199:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3220.123712] LNetError: 68199:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3220.155274] LNet: Removed LNI 192.168.204.54@tcp [ 3221.445418] Key type .llcrypt unregistered [ 3221.454699] Key type ._llcrypt unregistered [ 3235.790210] Key type ._llcrypt registered [ 3235.794583] Key type .llcrypt registered [ 3236.434436] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3236.483062] alg: No test for adler32 (adler32-zlib) [ 3237.712799] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [ 3238.121747] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 3239.840409] Key type lgssc registered [ 3241.335557] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3356.919204] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3362.904845] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3388.295194] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3389.974944] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 3393.229293] Lustre: Unmounted lustre-client [ 3431.926909] Key type lgssc unregistered [ 3432.182971] LNet: 71635:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3432.190524] LNetError: 71635:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3432.219566] LNet: Removed LNI 192.168.204.54@tcp [ 3432.967516] Key type .llcrypt unregistered [ 3432.973834] Key type ._llcrypt unregistered [ 3449.509930] Key type ._llcrypt registered [ 3449.512947] Key type .llcrypt registered [ 3451.262612] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 21:50:46 (1788573046) [ 3454.235133] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3454.446139] alg: No test for adler32 (adler32-zlib) [ 3455.574709] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [ 3455.851118] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 3457.648981] Key type lgssc registered [ 3459.037144] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3512.563696] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3519.725485] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3545.456465] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3547.255987] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 3554.003480] Lustre: Unmounted lustre-client [ 3554.511428] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 3558.152408] Lustre: Unmounted lustre-client [ 3587.293909] Key type lgssc unregistered [ 3587.757445] LNet: 75327:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3587.767224] LNetError: 75327:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3587.804198] LNet: Removed LNI 192.168.204.54@tcp [ 3588.754880] Key type .llcrypt unregistered [ 3588.759757] Key type ._llcrypt unregistered [ 3604.723790] Key type ._llcrypt registered [ 3604.725309] Key type .llcrypt registered [ 3605.165991] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3605.188310] alg: No test for adler32 (adler32-zlib) [ 3606.374531] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [ 3606.728573] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 3608.512247] Key type lgssc registered [ 3610.188622] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3669.754192] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3677.158687] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3704.158982] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3715.685997] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 3722.739243] Lustre: Unmounted lustre-client [ 3754.429345] Key type lgssc unregistered [ 3754.776542] LNet: 78368:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3754.795692] LNetError: 78368:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3754.825786] LNet: Removed LNI 192.168.204.54@tcp [ 3755.672572] Key type .llcrypt unregistered [ 3755.679956] Key type ._llcrypt unregistered [ 3773.982878] Key type ._llcrypt registered [ 3773.987478] Key type .llcrypt registered [ 3775.377383] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 21:56:10 (1788573370) [ 3778.669919] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3778.685740] alg: No test for adler32 (adler32-zlib) [ 3779.738935] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [ 3780.017104] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 3781.768314] Key type lgssc registered [ 3783.397384] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3841.769822] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3848.881093] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3874.047140] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3885.155929] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 3899.953996] Lustre: lustre: nosquash_nids is cleared [ 3899.990842] Lustre: lustre: root_squash is set to 500:500 [ 3912.803956] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.204.54@tcp 192.168.0.[2,10]@tcp [ 3920.011990] Lustre: lustre: nosquash_nids set to 192.168.204.54/24@tcp [ 3924.174841] Lustre: Unmounted lustre-client [ 3969.090319] Key type lgssc unregistered [ 3969.329153] LNet: 82301:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3969.340511] LNetError: 82301:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3969.358438] LNet: Removed LNI 192.168.204.54@tcp [ 3970.084383] Key type .llcrypt unregistered [ 3970.086475] Key type ._llcrypt unregistered [ 3986.378522] Key type ._llcrypt registered [ 3986.384387] Key type .llcrypt registered [ 3987.756882] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 21:59:43 (1788573583) [ 3990.442655] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3992.425249] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 21:59:48 (1788573588) [ 3995.248743] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3995.312822] alg: No test for adler32 (adler32-zlib) [ 3996.518608] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [ 3996.825485] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 3998.529420] Key type lgssc registered [ 4000.118568] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4056.048524] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4062.341964] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4086.501794] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4087.289844] Lustre: lustre: root_squash is set to 500:500 [ 4087.321644] Lustre: lustre: nosquash_nids set to 192.168.204.54/24@tcp [ 4088.454884] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 4095.420057] Lustre: Unmounted lustre-client [ 4124.838847] Key type lgssc unregistered [ 4125.318816] LNet: 86273:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4125.332757] LNetError: 86273:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4125.384607] LNet: Removed LNI 192.168.204.54@tcp [ 4126.508661] Key type .llcrypt unregistered [ 4126.511085] Key type ._llcrypt unregistered [ 4144.161479] Key type ._llcrypt registered [ 4144.163502] Key type .llcrypt registered [ 4145.398421] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 4147.364959] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 22:02:22 (1788573742) [ 4166.036813] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4166.169338] alg: No test for adler32 (adler32-zlib) [ 4167.233991] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [ 4167.632412] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 4169.432766] Key type lgssc registered [ 4170.599505] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4258.143725] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4264.330938] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4289.217845] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4369.171816] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4376.303190] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4397.864958] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4410.097662] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 4444.658362] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9fab43237000.ost_server_uuid 50 [ 4446.501530] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9fab43237000.ost_server_uuid in FULL state after 0 sec [ 4446.983962] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 4451.695276] Lustre: Unmounted lustre-client [ 4453.572026] Lustre: Unmounted lustre-client [ 4499.963490] Key type lgssc unregistered [ 4500.290666] LNet: 93941:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4500.301756] LNetError: 93941:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4500.323295] LNet: Removed LNI 192.168.204.54@tcp [ 4501.426504] Key type .llcrypt unregistered [ 4501.436556] Key type ._llcrypt unregistered [ 4538.621747] Key type ._llcrypt registered [ 4538.631490] Key type .llcrypt registered [ 4540.971244] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 22:08:55 (1788574135) [ 4563.524256] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4563.545541] alg: No test for adler32 (adler32-zlib) [ 4564.670583] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [ 4564.952438] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 4566.616351] Key type lgssc registered [ 4567.895796] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4657.746036] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4663.933101] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4689.820282] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4692.603347] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 4712.931439] Lustre: 95305:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788574294/real 1788574294] req@000000003ada05f8 x1875455940498688/t0(0) o400->lustre-OST0000-osc-ffff9fab503be800@192.168.204.154@tcp:28/4 lens 224/224 e 0 to 1 dl 1788574310 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4712.976947] Lustre: lustre-OST0000-osc-ffff9fab503be800: Connection to lustre-OST0000 (at 192.168.204.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4719.072194] Lustre: 95305:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788574299/real 1788574299] req@00000000e5cc0048 x1875455940499200/t0(0) o400->lustre-OST0000-osc-ffff9fab503be800@192.168.204.154@tcp:28/4 lens 224/224 e 0 to 1 dl 1788574315 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4723.912603] Lustre: lustre-OST0000-osc-ffff9fab503be800: Connection restored to 192.168.204.154@tcp (at 192.168.204.154@tcp) [ 4725.024225] Lustre: 95305:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788574305/real 1788574305] req@000000006a0c683d x1875455940499712/t0(0) o400->lustre-OST0000-osc-ffff9fab503be800@192.168.204.154@tcp:28/4 lens 224/224 e 0 to 1 dl 1788574321 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4728.296461] Lustre: lustre-MDT0000-mdc-ffff9fab503be800: Connection to lustre-MDT0000 (at 192.168.204.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4729.314874] Lustre: 95306:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788574310/real 1788574310] req@000000000c8c6864 x1875455940500224/t0(0) o400->lustre-OST0000-osc-ffff9fab503be800@192.168.204.154@tcp:28/4 lens 224/224 e 0 to 1 dl 1788574326 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4744.672493] Lustre: 95308:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788574325/real 1788574325] req@00000000cebc5f2b x1875455940502144/t0(0) o400->MGC192.168.204.154@tcp@192.168.204.154@tcp:26/25 lens 224/224 e 0 to 1 dl 1788574341 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4744.712928] LustreError: MGC192.168.204.154@tcp: Connection to MGS (at 192.168.204.154@tcp) was lost; in progress operations using this service will fail [ 4753.899398] Lustre: Evicted from MGS (at 192.168.204.154@tcp) after server handle changed from 0x8173936cbaeeae5a to 0x8173936cbaeeb052 [ 4753.915841] Lustre: MGC192.168.204.154@tcp: Connection restored to 192.168.204.154@tcp (at 192.168.204.154@tcp) [ 4759.531923] Lustre: lustre-MDT0000-mdc-ffff9fab503be800: Connection restored to 192.168.204.154@tcp (at 192.168.204.154@tcp) [ 4765.820142] Lustre: Unmounted lustre-client [ 4795.298735] Key type lgssc unregistered [ 4795.534147] LNet: 99608:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4795.542141] LNetError: 99608:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4795.564589] LNet: Removed LNI 192.168.204.54@tcp [ 4796.558825] Key type .llcrypt unregistered [ 4796.561617] Key type ._llcrypt unregistered [ 4814.404529] Key type ._llcrypt registered [ 4814.410272] Key type .llcrypt registered [ 4815.914229] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 22:13:31 (1788574411) [ 4832.050779] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4832.165128] alg: No test for adler32 (adler32-zlib) [ 4833.806636] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [ 4834.212704] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 4836.016254] Key type lgssc registered [ 4838.044781] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4883.605518] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4988.380980] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4993.901534] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5017.187076] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5019.848908] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 5022.176829] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 5023.766651] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 22:16:59 (1788574619) [ 5316.198247] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5326.622923] Lustre: Unmounted lustre-client [ 5472.191606] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5478.355663] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5503.778613] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5558.924224] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 22:25:54 (1788575154) [ 5666.668271] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5674.484181] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5699.203292] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5700.647901] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 5706.860720] Lustre: Unmounted lustre-client [ 5752.999837] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 22:29:08 (1788575348) [ 5857.308660] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5864.282492] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5892.245411] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5892.863566] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 5896.755647] Lustre: Unmounted lustre-client [ 5932.361912] Key type lgssc unregistered [ 5932.641267] LNet: 122659:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5932.648937] LNetError: 122659:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5932.668214] LNet: Removed LNI 192.168.204.54@tcp [ 5933.600299] Key type .llcrypt unregistered [ 5933.603291] Key type ._llcrypt unregistered [ 5949.613435] Key type ._llcrypt registered [ 5949.615619] Key type .llcrypt registered [ 5950.874660] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 22:32:26 (1788575546) [ 5953.421678] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5953.434355] alg: No test for adler32 (adler32-zlib) [ 5954.541224] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [ 5954.765315] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5956.441619] Key type lgssc registered [ 5957.712887] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6012.862760] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6018.720967] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6041.306136] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6044.122994] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 6061.583690] Lustre: Unmounted lustre-client [ 6092.103926] Key type lgssc unregistered [ 6092.347786] LNet: 126260:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6092.352670] LNetError: 126260:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6092.370604] LNet: Removed LNI 192.168.204.54@tcp [ 6092.859584] Key type .llcrypt unregistered [ 6092.861979] Key type ._llcrypt unregistered [ 6109.135483] Key type ._llcrypt registered [ 6109.143926] Key type .llcrypt registered [ 6110.550510] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 22:35:06 (1788575706) [ 6113.240875] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6113.281610] alg: No test for adler32 (adler32-zlib) [ 6114.318367] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [ 6114.516470] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 6116.144202] Key type lgssc registered [ 6117.502787] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6167.065047] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6172.876401] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6193.115679] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6194.306874] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 6199.278571] Lustre: lustre-OST0000-osc-ffff9fab585fd000: Connection to lustre-OST0000 (at 192.168.204.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6213.802617] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff9fab585fd000.ost_server_uuid 50 [ 6215.561609] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 6227.134477] Lustre: Unmounted lustre-client [ 6250.475674] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 22:37:26 (1788575846) [ 6285.829918] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6291.831434] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6316.060851] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6341.976650] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6342.748256] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 6347.768380] Lustre: lustre-OST0000-osc-ffff9fab4558f800: Connection to lustre-OST0000 (at 192.168.204.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6376.584037] Lustre: Unmounted lustre-client [ 6428.903418] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 22:40:24 (1788576024) [ 6469.051917] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6475.744991] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6500.904307] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6520.713261] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6521.251947] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 6526.446688] Lustre: lustre-OST0000-osc-ffff9fab46369000: Connection to lustre-OST0000 (at 192.168.204.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6543.299683] Lustre: Unmounted lustre-client [ 6593.473673] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 22:43:09 (1788576189) [ 6684.875981] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6691.127777] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6713.810751] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6782.630436] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6787.891932] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6807.430621] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6847.379240] LustreError: 127218:0:(lmv_obd.c:211:lmv_notify()) activation of lustre-MDT0001_UUID failed: -22 [ 6847.437701] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 6893.180140] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6894.974578] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6896.519128] Lustre: Unmounted lustre-client [ 6937.174155] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 22:48:52 (1788576532) [ 6973.902960] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6981.574635] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7006.818164] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7046.404174] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7087.767615] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 7132.892858] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7134.512257] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7146.941670] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7157.290085] Lustre: Unmounted lustre-client [ 7231.144534] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 22:53:46 (1788576826) [ 7264.587060] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7270.347570] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7292.717562] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7293.767529] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 7320.180967] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7321.733188] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7336.112797] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9fab51bea800.ost_server_uuid 50 [ 7337.400178] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9fab51bea800.ost_server_uuid in IDLE state after 0 sec [ 7340.244745] Lustre: Unmounted lustre-client [ 7340.525571] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 7340.532291] Lustre: Skipped 3 previous similar messages [ 7340.662597] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 7341.798841] LustreError: 149701:0:(sec.c:403:import_sec_validate_get()) import 00000000bc7ff35b (NEW) with no sec [ 7399.417970] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 22:56:35 (1788576995) [ 7433.553947] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7440.032248] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7463.904775] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7485.763215] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7486.297721] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 7486.482668] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 7498.779512] Lustre: Unmounted lustre-client [ 7498.781218] Lustre: Skipped 1 previous similar message [ 7545.135764] Key type lgssc unregistered [ 7545.392733] LNet: 153116:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7545.397868] LNetError: 153116:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7545.423854] LNet: Removed LNI 192.168.204.54@tcp [ 7546.252844] Key type .llcrypt unregistered [ 7546.258001] Key type ._llcrypt unregistered [ 7564.019596] Key type ._llcrypt registered [ 7564.023903] Key type .llcrypt registered [ 7565.341801] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 22:59:21 (1788577161) [ 7566.488656] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7566.507764] alg: No test for adler32 (adler32-zlib) [ 7567.537829] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [ 7567.737987] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 7569.440232] Key type lgssc registered [ 7570.553910] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7621.995152] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7627.599093] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7649.446528] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7675.012337] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7675.761943] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7676.993180] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 7684.648919] LustreError: lustre-MDT0001-mdc-ffff9fab605ee000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 7684.687527] Lustre: lustre-MDT0001-mdc-ffff9fab605ee000: Connection restored to 192.168.204.154@tcp (at 192.168.204.154@tcp) [ 7692.267374] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7694.559907] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7717.372663] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7722.160971] Lustre: Unmounted lustre-client [ 7745.771285] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 23:02:21 (1788577341) [ 7841.346650] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7847.416460] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7872.603492] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7873.433449] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 7902.560602] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7904.273939] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7916.954650] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7927.278021] Lustre: Unmounted lustre-client [ 7961.864545] Key type lgssc unregistered [ 7962.307743] LNet: 162750:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7962.321575] LNetError: 162750:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7962.357352] LNet: Removed LNI 192.168.204.54@tcp [ 7963.561825] Key type .llcrypt unregistered [ 7963.575693] Key type ._llcrypt unregistered [ 7999.832744] Key type ._llcrypt registered [ 7999.836898] Key type .llcrypt registered [ 8001.626544] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 23:06:36 (1788577596) [ 8004.731795] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8004.755505] alg: No test for adler32 (adler32-zlib) [ 8005.925554] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [ 8006.258642] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 8008.003048] Key type lgssc registered [ 8009.393950] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8063.023815] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8070.038895] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8094.141694] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8096.931607] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 8144.697228] Lustre: Unmounted lustre-client [ 8190.035623] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8190.919050] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 8227.596493] Lustre: Unmounted lustre-client [ 8257.178356] Key type lgssc unregistered [ 8257.467553] LNet: 167206:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8257.475214] LNetError: 167206:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8257.495139] LNet: Removed LNI 192.168.204.54@tcp [ 8258.307481] Key type .llcrypt unregistered [ 8258.312403] Key type ._llcrypt unregistered [ 8276.905887] Key type ._llcrypt registered [ 8276.918609] Key type .llcrypt registered [ 8278.605986] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 23:11:13 (1788577873) [ 8281.792122] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8281.821142] alg: No test for adler32 (adler32-zlib) [ 8283.031348] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [ 8283.513555] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 8285.273226] Key type lgssc registered [ 8286.377415] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8340.138173] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8346.272091] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8366.734507] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8368.641146] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 8393.235583] Lustre: Unmounted lustre-client [ 8421.564723] Key type lgssc unregistered [ 8421.757701] LNet: 170968:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8421.764469] LNetError: 170968:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8421.793369] LNet: Removed LNI 192.168.204.54@tcp [ 8422.412351] Key type .llcrypt unregistered [ 8422.414789] Key type ._llcrypt unregistered [ 8434.327589] Key type ._llcrypt registered [ 8434.330442] Key type .llcrypt registered [ 8434.977989] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8435.001797] alg: No test for adler32 (adler32-zlib) [ 8436.156192] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [ 8436.439941] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 8438.147095] Key type lgssc registered [ 8439.321632] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8492.557804] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8498.935906] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8519.000509] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8520.948255] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 8531.347996] Lustre: Unmounted lustre-client [ 8559.847783] Key type lgssc unregistered [ 8560.111545] LNet: 174038:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8560.124925] LNetError: 174038:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8560.147574] LNet: Removed LNI 192.168.204.54@tcp [ 8560.869677] Key type .llcrypt unregistered [ 8560.875410] Key type ._llcrypt unregistered [ 8577.070554] Key type ._llcrypt registered [ 8577.072390] Key type .llcrypt registered [ 8578.459270] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 23:16:14 (1788578174) [ 8580.934272] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8581.033975] alg: No test for adler32 (adler32-zlib) [ 8582.074146] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [ 8582.294418] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 8584.065295] Key type lgssc registered [ 8585.484816] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8632.179108] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8639.038188] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8660.167952] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8662.997851] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 8684.163166] Lustre: Unmounted lustre-client [ 8712.109327] Key type lgssc unregistered [ 8712.344702] LNet: 177797:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8712.349532] LNetError: 177797:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8712.382499] LNet: Removed LNI 192.168.204.54@tcp [ 8712.991331] Key type .llcrypt unregistered [ 8712.995354] Key type ._llcrypt unregistered [ 8725.027809] Key type ._llcrypt registered [ 8725.029810] Key type .llcrypt registered [ 8725.680079] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8725.707775] alg: No test for adler32 (adler32-zlib) [ 8726.802322] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [ 8727.057247] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 8728.720434] Key type lgssc registered [ 8729.854797] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8777.816931] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8783.141939] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8807.734715] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8809.378105] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 8819.994320] Lustre: Unmounted lustre-client [ 8857.102561] Key type lgssc unregistered [ 8857.385833] LNet: 180866:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8857.401625] LNetError: 180866:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8857.425901] LNet: Removed LNI 192.168.204.54@tcp [ 8858.157818] Key type .llcrypt unregistered [ 8858.163969] Key type ._llcrypt unregistered [ 8872.481555] Key type ._llcrypt registered [ 8872.483233] Key type .llcrypt registered [ 8873.749826] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 23:21:09 (1788578469) [ 8897.222202] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8897.243284] alg: No test for adler32 (adler32-zlib) [ 8898.314191] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [ 8898.570792] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 8900.256246] Key type lgssc registered [ 8901.482595] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8976.000717] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8981.611489] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9005.274150] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9048.568270] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 23:24:04 (1788578644) [ 9083.326521] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9087.959757] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9107.329272] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9109.926695] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 9114.149506] Lustre: Unmounted lustre-client [ 9139.499069] Key type lgssc unregistered [ 9139.704666] LNet: 187976:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9139.714511] LNetError: 187976:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9139.730148] LNet: Removed LNI 192.168.204.54@tcp [ 9140.283091] Key type .llcrypt unregistered [ 9140.285736] Key type ._llcrypt unregistered [ 9153.723288] Key type ._llcrypt registered [ 9153.727117] Key type .llcrypt registered [ 9154.872463] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 9156.252590] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 23:25:52 (1788578752) [ 9171.510473] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9171.532180] alg: No test for adler32 (adler32-zlib) [ 9172.591627] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [ 9172.860133] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 9174.536214] Key type lgssc registered [ 9176.071686] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9274.088251] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9280.219887] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9302.786314] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9330.502510] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9332.062188] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 9355.433022] Lustre: Unmounted lustre-client [ 9493.111293] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9498.348523] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9520.961293] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9566.651550] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 23:32:41 (1788579161) [ 9568.493153] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 9570.343858] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 23:32:46 (1788579166) [ 9670.682138] Key type lgssc unregistered [ 9670.971050] LNet: 199425:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9670.983497] LNetError: 199425:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9671.002546] LNet: Removed LNI 192.168.204.54@tcp [ 9671.642533] Key type .llcrypt unregistered [ 9671.645180] Key type ._llcrypt unregistered [ 9690.032294] Key type ._llcrypt registered [ 9690.034561] Key type .llcrypt registered [ 9691.398292] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 23:34:47 (1788579287) [ 9734.777759] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9734.797984] alg: No test for adler32 (adler32-zlib) [ 9736.121724] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [ 9736.655224] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 9738.480519] Key type lgssc registered [ 9740.667189] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9787.593774] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9793.727991] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9828.466844] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9856.229565] Key type lgssc unregistered [ 9856.591471] LNet: 203163:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9856.616051] LNetError: 203163:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9856.646786] LNet: Removed LNI 192.168.204.54@tcp [ 9857.443763] Key type .llcrypt unregistered [ 9857.445650] Key type ._llcrypt unregistered [ 9872.643353] Key type ._llcrypt registered [ 9872.645096] Key type .llcrypt registered [ 9874.019200] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 23:37:49 (1788579469) [ 9876.519726] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9876.533693] alg: No test for adler32 (adler32-zlib) [ 9877.597777] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [ 9877.792826] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 9879.450721] Key type lgssc registered [ 9880.666139] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9928.609639] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9935.438485] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9963.077908] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9964.716522] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [ 9975.285767] Lustre: lustre-MDT0000-mdc-ffff9fab51bed800: Connection to lustre-MDT0000 (at 192.168.204.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9985.525065] Lustre: lustre-MDT0001-mdc-ffff9fab51bed800: Connection to lustre-MDT0001 (at 192.168.204.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9996.704369] Lustre: 204153:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788579577/real 1788579577] req@00000000de10146a x1875461511659520/t0(0) o400->MGC192.168.204.154@tcp@192.168.204.154@tcp:26/25 lens 224/224 e 0 to 1 dl 1788579593 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9996.736875] LustreError: MGC192.168.204.154@tcp: Connection to MGS (at 192.168.204.154@tcp) was lost; in progress operations using this service will fail [10005.997766] Lustre: Evicted from MGS (at 192.168.204.154@tcp) after server handle changed from 0x2978a2134ee8d097 to 0x2978a2134ee8e9db [10006.020051] Lustre: MGC192.168.204.154@tcp: Connection restored to 192.168.204.154@tcp (at 192.168.204.154@tcp) [10025.452529] LustreError: lustre-MDT0000-mdc-ffff9fab51bed800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [10025.471386] Lustre: lustre-MDT0000-mdc-ffff9fab51bed800: Connection restored to 192.168.204.154@tcp (at 192.168.204.154@tcp) [10031.073358] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10032.839332] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10038.740604] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10040.815758] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [10045.023279] Lustre: Unmounted lustre-client [10075.208292] Key type lgssc unregistered [10075.604843] LNet: 208117:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10075.618440] LNetError: 208117:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10075.644039] LNet: Removed LNI 192.168.204.54@tcp [10076.486983] Key type .llcrypt unregistered [10076.491617] Key type ._llcrypt unregistered [10100.685698] Key type ._llcrypt registered [10100.688221] Key type .llcrypt registered [10102.638377] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 23:41:37 (1788579697) [10105.228695] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10105.241586] alg: No test for adler32 (adler32-zlib) [10106.289775] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [10106.531621] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [10108.296191] Key type lgssc registered [10109.972730] Lustre: Echo OBD driver; http://www.lustre.org/ [10165.174663] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10171.789689] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10234.745364] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10240.777127] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10280.480939] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10303.438939] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10353.783484] Key type lgssc unregistered [10354.074569] LNet: 213420:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10354.087695] LNetError: 213420:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10354.107355] LNet: Removed LNI 192.168.204.54@tcp [10354.716138] Key type .llcrypt unregistered [10354.721815] Key type ._llcrypt unregistered [10403.875669] Key type ._llcrypt registered [10403.878485] Key type .llcrypt registered [10405.498347] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 23:46:40 (1788580000) [10448.167508] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10448.228613] alg: No test for adler32 (adler32-zlib) [10449.331257] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [10449.530744] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [10451.194405] Key type lgssc registered [10452.460419] Lustre: Echo OBD driver; http://www.lustre.org/ [10549.499131] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10556.601550] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10585.666853] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10640.816317] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 23:50:36 (1788580236) [10650.648417] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 23:50:46 (1788580246) [10698.557187] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10704.483983] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10729.643551] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10736.757144] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [10742.045984] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [10744.494242] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [10746.192360] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [10748.014994] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [10751.987573] Lustre: lustre-MDT0000-mdc-ffff9fab4438f000: Connection to lustre-MDT0000 (at 192.168.204.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [10768.354841] Lustre: 215315:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788580349/real 1788580349] req@000000001a8fc9a3 x1875462110324608/t0(0) o400->MGC192.168.204.154@tcp@192.168.204.154@tcp:26/25 lens 224/224 e 0 to 1 dl 1788580365 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10768.383806] LustreError: MGC192.168.204.154@tcp: Connection to MGS (at 192.168.204.154@tcp) was lost; in progress operations using this service will fail [10778.646889] Lustre: Evicted from MGS (at 192.168.204.154@tcp) after server handle changed from 0xfe7aaedfcd299782 to 0xfe7aaedfcd2999f8 [10778.678894] Lustre: MGC192.168.204.154@tcp: Connection restored to 192.168.204.154@tcp (at 192.168.204.154@tcp) [10783.198058] Lustre: lustre-MDT0000-mdc-ffff9fab4438f000: Connection restored to 192.168.204.154@tcp (at 192.168.204.154@tcp) [10795.408713] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10797.283609] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10801.949668] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [10809.330191] Lustre: lustre-MDT0000-mdc-ffff9fab4438f000: Connection to lustre-MDT0000 (at 192.168.204.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [10825.696152] Lustre: 215316:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788580406/real 1788580406] req@000000007d256b1d x1875462110333952/t0(0) o400->MGC192.168.204.154@tcp@192.168.204.154@tcp:26/25 lens 224/224 e 0 to 1 dl 1788580422 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10825.745353] LustreError: MGC192.168.204.154@tcp: Connection to MGS (at 192.168.204.154@tcp) was lost; in progress operations using this service will fail [10835.966346] Lustre: Evicted from MGS (at 192.168.204.154@tcp) after server handle changed from 0xfe7aaedfcd2999f8 to 0xfe7aaedfcd299d08 [10836.024108] Lustre: MGC192.168.204.154@tcp: Connection restored to 192.168.204.154@tcp (at 192.168.204.154@tcp) [10838.997451] Lustre: lustre-MDT0000-mdc-ffff9fab4438f000: Connection restored to 192.168.204.154@tcp (at 192.168.204.154@tcp) [10850.907750] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10852.935866] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10856.708424] Lustre: Unmounted lustre-client [10935.855126] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10942.706740] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10966.525823] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10977.840577] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [10980.556307] Lustre: Unmounted lustre-client [11009.656501] Key type lgssc unregistered [11009.955284] LNet: 225922:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11009.969496] LNetError: 225922:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11010.006808] LNet: Removed LNI 192.168.204.54@tcp [11010.776784] Key type .llcrypt unregistered [11010.782397] Key type ._llcrypt unregistered [11028.139493] Key type ._llcrypt registered [11028.143221] Key type .llcrypt registered [11029.542939] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 23:57:05 (1788580625) [11034.659290] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11034.722569] alg: No test for adler32 (adler32-zlib) [11036.036119] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [11036.283958] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [11037.968420] Key type lgssc registered [11039.402456] Lustre: Echo OBD driver; http://www.lustre.org/ [11095.524035] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11100.727939] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11124.611707] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11127.544698] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [11137.263302] Lustre: Unmounted lustre-client [11200.213330] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11206.767795] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11230.470519] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11231.510496] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [11240.463245] Lustre: Unmounted lustre-client [11315.546249] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11322.762584] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11349.308762] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11360.302572] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [11362.531741] Lustre: Unmounted lustre-client [11409.626863] Key type lgssc unregistered [11409.903553] LNet: 232999:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11409.909080] LNetError: 232999:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11409.942256] LNet: Removed LNI 192.168.204.54@tcp [11410.733398] Key type .llcrypt unregistered [11410.739382] Key type ._llcrypt unregistered [11428.138516] Key type ._llcrypt registered [11428.141230] Key type .llcrypt registered [11429.560694] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 00:03:45 (1788581025) [11434.615000] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11434.662343] alg: No test for adler32 (adler32-zlib) [11435.953975] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [11436.381624] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [11438.201465] Key type lgssc registered [11440.304433] Lustre: Echo OBD driver; http://www.lustre.org/ [11490.664335] Key type lgssc unregistered [11490.946787] LNet: 235327:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11490.958588] LNetError: 235327:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11490.983098] LNet: Removed LNI 192.168.204.54@tcp [11491.734857] Key type .llcrypt unregistered [11491.737071] Key type ._llcrypt unregistered [11527.823201] Key type ._llcrypt registered [11527.829192] Key type .llcrypt registered [11528.428449] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11528.444370] alg: No test for adler32 (adler32-zlib) [11529.603685] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [11529.856244] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [11531.544568] Key type lgssc registered [11532.987501] Lustre: Echo OBD driver; http://www.lustre.org/ [11627.739844] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11635.197255] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11663.696958] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11717.644319] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [11720.409540] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 00:08:35 (1788581315) [11757.399561] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11763.977491] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11792.186547] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11820.095789] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11822.224901] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [11827.687028] Lustre: lustre-OST0001-osc-ffff9fab48316000: Connection to lustre-OST0001 (at 192.168.204.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [11836.159471] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11846.507619] Lustre: Unmounted lustre-client [11899.659608] Key type lgssc unregistered [11900.029774] LNet: 242464:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11900.050757] LNetError: 242464:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11900.086825] LNet: Removed LNI 192.168.204.54@tcp [11901.005159] Key type .llcrypt unregistered [11901.015435] Key type ._llcrypt unregistered [11938.704239] Key type ._llcrypt registered [11938.709369] Key type .llcrypt registered [11940.246412] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 00:12:15 (1788581535) [11956.260680] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11956.293534] alg: No test for adler32 (adler32-zlib) [11957.429924] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [11957.777891] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [11959.464657] Key type lgssc registered [11960.852300] Lustre: Echo OBD driver; http://www.lustre.org/ [12023.678463] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12031.188623] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12055.997642] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 00:14:11 (1788581651) [12100.789858] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12108.633825] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12133.563510] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12135.244067] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [12149.802595] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [12153.047113] Lustre: Unmounted lustre-client [12268.787221] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12275.490848] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12329.052358] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12329.798337] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [12333.159938] Lustre: Unmounted lustre-client [12378.720424] Key type lgssc unregistered [12378.970550] LNet: 250169:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12378.977627] LNetError: 250169:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12379.000651] LNet: Removed LNI 192.168.204.54@tcp [12379.636433] Key type .llcrypt unregistered [12379.640428] Key type ._llcrypt unregistered [12413.060194] Key type ._llcrypt registered [12413.067238] Key type .llcrypt registered [12413.941544] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12413.978347] alg: No test for adler32 (adler32-zlib) [12415.312507] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [12415.607809] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [12417.448707] Key type lgssc registered [12419.284256] Lustre: Echo OBD driver; http://www.lustre.org/ [12519.171369] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12526.621261] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12552.270059] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12608.040147] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [12609.807087] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 00:23:25 (1788582205) [12645.511352] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12652.252990] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12677.360335] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12682.010382] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [12685.404480] Lustre: Unmounted lustre-client [12713.819497] Key type lgssc unregistered [12714.064953] LNet: 256821:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12714.076745] LNetError: 256821:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12714.106235] LNet: Removed LNI 192.168.204.54@tcp [12714.869700] Key type .llcrypt unregistered [12714.873958] Key type ._llcrypt unregistered [12732.718583] Key type ._llcrypt registered [12732.725620] Key type .llcrypt registered [12734.557466] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [12736.652676] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 00:25:32 (1788582332) [12746.160759] Key type .llcrypt unregistered [12746.162487] Key type ._llcrypt unregistered [12756.074762] Key type ._llcrypt registered [12756.080575] Key type .llcrypt registered [12756.805201] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12756.818557] alg: No test for adler32 (adler32-zlib) [12757.889601] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [12758.252159] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [12760.032216] Key type lgssc registered [12761.452735] Lustre: Echo OBD driver; http://www.lustre.org/ [12819.496640] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12839.199340] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [12842.346530] Lustre: Unmounted lustre-client [12878.939544] Key type lgssc unregistered [12879.185436] LNet: 260299:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12879.203519] LNetError: 260299:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12879.230626] LNet: Removed LNI 192.168.204.54@tcp [12879.852317] Key type .llcrypt unregistered [12879.855108] Key type ._llcrypt unregistered [12898.955274] Key type ._llcrypt registered [12898.960874] Key type .llcrypt registered [12900.389090] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 00:28:15 (1788582495) [12903.663308] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12903.709050] alg: No test for adler32 (adler32-zlib) [12904.819813] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [12904.997111] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [12906.698569] Key type lgssc registered [12907.826686] Lustre: Echo OBD driver; http://www.lustre.org/ [12995.148137] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13029.184280] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13035.931991] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13038.711154] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [13041.732439] Lustre: Unmounted lustre-client [13072.483556] Key type lgssc unregistered [13072.788634] LNet: 263840:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13072.794280] LNetError: 263840:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13072.814300] LNet: Removed LNI 192.168.204.54@tcp [13073.779880] Key type .llcrypt unregistered [13073.783089] Key type ._llcrypt unregistered [13092.852429] Key type ._llcrypt registered [13092.854411] Key type .llcrypt registered [13094.485574] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 00:31:29 (1788582689) [13097.198761] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13097.211186] alg: No test for adler32 (adler32-zlib) [13098.322458] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [13098.545158] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [13100.241527] Key type lgssc registered [13102.004404] Lustre: Echo OBD driver; http://www.lustre.org/ [13160.228358] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13166.427174] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13192.587334] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13205.086950] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [13210.095806] Lustre: lustre-MDT0000-mdc-ffff9fab43c46800: Connection to lustre-MDT0000 (at 192.168.204.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [13217.124205] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [13217.151956] LustreError: 267001:0:(file.c:6156:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [13217.738549] LustreError: 267012:0:(file.c:6156:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [13217.757661] LustreError: 267012:0:(file.c:6156:ll_inode_revalidate_fini()) Skipped 3 previous similar messages [13217.928523] LustreError: MGC192.168.204.154@tcp: Connection to MGS (at 192.168.204.154@tcp) was lost; in progress operations using this service will fail [13217.996843] Lustre: Unmounted lustre-client [13241.900527] Key type lgssc unregistered [13242.264071] LNet: 267420:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13242.276075] LNetError: 267420:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13242.331852] LNet: Removed LNI 192.168.204.54@tcp [13243.028277] Key type .llcrypt unregistered [13243.032483] Key type ._llcrypt unregistered [13262.583932] Key type ._llcrypt registered [13262.587156] Key type .llcrypt registered [13264.070119] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 00:34:19 (1788582859) [13267.627784] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13267.726780] alg: No test for adler32 (adler32-zlib) [13268.947450] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [13269.176716] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [13270.856278] Key type lgssc registered [13272.197636] Lustre: Echo OBD driver; http://www.lustre.org/ [13329.101598] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13335.880812] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13358.209102] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13360.966903] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [13371.397170] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [13373.748882] Lustre: Unmounted lustre-client [13395.953399] Key type lgssc unregistered [13396.277138] LNet: 271000:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13396.293802] LNetError: 271000:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13396.324915] LNet: Removed LNI 192.168.204.54@tcp [13397.277056] Key type .llcrypt unregistered [13397.283899] Key type ._llcrypt unregistered [13415.160614] Key type ._llcrypt registered [13415.165426] Key type .llcrypt registered [13416.659063] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 00:36:52 (1788583012) [13436.575643] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13436.607624] alg: No test for adler32 (adler32-zlib) [13437.665724] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [13437.981881] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [13439.697040] Key type lgssc registered [13440.931616] Lustre: Echo OBD driver; http://www.lustre.org/ [13561.964982] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13564.716566] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [13609.448397] Lustre: lustre-MDT0001-mdc-ffff9fab47dc4800: Connection to lustre-MDT0001 (at 192.168.204.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [13618.878587] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [13629.196391] Lustre: Unmounted lustre-client [13656.390574] Key type lgssc unregistered [13656.723725] LNet: 276790:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13656.733860] LNetError: 276790:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13656.761551] LNet: Removed LNI 192.168.204.54@tcp [13657.555391] Key type .llcrypt unregistered [13657.559664] Key type ._llcrypt unregistered [13676.591142] Key type ._llcrypt registered [13676.592789] Key type .llcrypt registered [13677.957859] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 00:41:13 (1788583273) [13679.726525] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [13681.663544] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 00:41:17 (1788583277) [13683.343513] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [13685.228528] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 00:41:21 (1788583281) [13687.187966] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [13688.865579] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 00:41:24 (1788583284) [13690.471810] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [13692.685260] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 00:41:28 (1788583288) [13694.313194] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [13696.258127] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 00:41:31 (1788583291) [13707.293633] Key type .llcrypt unregistered [13707.305852] Key type ._llcrypt unregistered [13715.406304] Key type ._llcrypt registered [13715.422280] Key type .llcrypt registered [13715.939356] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13715.953907] alg: No test for adler32 (adler32-zlib) [13717.037832] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [13717.218155] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [13718.864468] Key type lgssc registered [13720.036211] Lustre: Echo OBD driver; http://www.lustre.org/ [13801.633249] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13808.434451] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13835.606912] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13837.435187] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [13840.646369] Lustre: Unmounted lustre-client [13879.223664] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 00:44:35 (1788583475) [13922.496287] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13929.203787] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13965.327027] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13966.107133] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [13968.267854] Lustre: Unmounted lustre-client [14011.214995] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 00:46:46 (1788583606) [14019.298706] LNet: 1 local NIs in recovery (showing 1): 192.168.204.54@tcp [14654.110701] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14659.676818] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14694.120334] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14779.405352] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 00:59:35 (1788584375) [14791.923130] Key type lgssc unregistered [14792.243191] LNet: 290332:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14792.256944] LNetError: 290332:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14792.286369] LNet: Removed LNI 192.168.204.54@tcp [14793.165622] Key type .llcrypt unregistered [14793.168697] Key type ._llcrypt unregistered [14896.112736] Key type ._llcrypt registered [14896.116582] Key type .llcrypt registered [14896.437554] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14896.449285] alg: No test for adler32 (adler32-zlib) [14897.562552] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [14897.797663] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [14899.488812] Key type lgssc registered [14900.743969] Lustre: Echo OBD driver; http://www.lustre.org/ [14983.706893] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14989.078981] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15009.297970] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15042.888734] Key type lgssc unregistered [15043.184695] LNet: 294042:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15043.190868] LNetError: 294042:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15043.231955] LNet: Removed LNI 192.168.204.54@tcp [15044.002852] Key type .llcrypt unregistered [15044.005975] Key type ._llcrypt unregistered [15078.330307] Key type ._llcrypt registered [15078.332469] Key type .llcrypt registered [15079.779686] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 01:04:35 (1788584675) [15103.755444] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15103.771480] alg: No test for adler32 (adler32-zlib) [15104.859938] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [15105.134046] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [15106.864240] Key type lgssc registered [15108.310847] Lustre: Echo OBD driver; http://www.lustre.org/ [15157.942489] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 01:05:53 (1788584753) [15172.948696] Key type lgssc unregistered [15173.433691] LNet: 297603:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15173.438896] LNetError: 297603:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15173.472964] LNet: Removed LNI 192.168.204.54@tcp [15174.477959] Key type .llcrypt unregistered [15174.486570] Key type ._llcrypt unregistered [15186.910316] Key type ._llcrypt registered [15186.912859] Key type .llcrypt registered [15187.410572] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15187.457761] alg: No test for adler32 (adler32-zlib) [15188.453359] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [15188.678464] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [15190.328373] Key type lgssc registered [15192.071574] Lustre: Echo OBD driver; http://www.lustre.org/ [15253.924422] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15260.370890] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15285.420734] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15576.873689] LNet: Added LNI 192.168.204.54@tcp50 [8/256/0/180] [15579.479599] LNet: Removed LNI 192.168.204.54@tcp [15582.092212] Lustre: 302036:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff9fab59363000: config has no valid NIDs, force dynamic NIDs [15583.328412] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [15583.531799] Lustre: Unmounted lustre-client [15586.643514] Key type lgssc unregistered [15587.087894] LNet: 302298:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15587.117611] LNetError: 302298:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15588.168538] LNet: Removed LNI 192.168.204.54@tcp50 [15588.805288] Key type .llcrypt unregistered [15588.809712] Key type ._llcrypt unregistered [15611.634554] Key type ._llcrypt registered [15611.666058] Key type .llcrypt registered [15612.942800] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15612.999508] alg: No test for adler32 (adler32-zlib) [15614.535482] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [15615.131210] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [15617.064435] Key type lgssc registered [15618.508822] Lustre: Echo OBD driver; http://www.lustre.org/ [15682.679930] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15688.473772] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15695.992552] LNet: Added LNI 192.168.204.54@tcp50 [8/256/0/180] [15696.805754] LNet: Removed LNI 192.168.204.54@tcp [15698.018184] Lustre: 304995:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff9fab503b8000: config has no valid NIDs, force dynamic NIDs [15699.204730] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [15699.768277] Lustre: Unmounted lustre-client [15736.118550] Key type lgssc unregistered [15736.805848] LNet: 305870:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15736.823042] LNetError: 305870:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15736.845401] LNet: Removed LNI 192.168.204.54@tcp50 [15737.977564] Key type .llcrypt unregistered [15737.984640] Key type ._llcrypt unregistered [15750.904850] Key type ._llcrypt registered [15750.910122] Key type .llcrypt registered [15752.360693] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 01:15:47 (1788585347) [15773.181608] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15773.543071] alg: No test for adler32 (adler32-zlib) [15774.508363] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [15774.670831] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [15776.360558] Key type lgssc registered [15777.693789] Lustre: Echo OBD driver; http://www.lustre.org/ [15853.562455] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15859.527949] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15883.537463] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15914.661130] Key type lgssc unregistered [15914.927793] LNet: 310624:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15914.938770] LNetError: 310624:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15914.971552] LNet: Removed LNI 192.168.204.54@tcp [15915.798758] Key type .llcrypt unregistered [15915.805807] Key type ._llcrypt unregistered [15929.774592] Key type ._llcrypt registered [15929.776571] Key type .llcrypt registered [15930.908372] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 01:18:46 (1788585526) [15933.295627] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15933.351409] alg: No test for adler32 (adler32-zlib) [15934.455510] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [15934.715561] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [15936.392312] Key type lgssc registered [15937.430320] Lustre: Echo OBD driver; http://www.lustre.org/ [15988.836477] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15994.556607] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16015.699215] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16017.428740] Lustre: Mounted lustre-client - version 2.17.57_1_g3e0b683 [16078.311772] Lustre: Mounted fs15246-client - version 2.17.57_1_g3e0b683 [16093.930821] Lustre: Unmounted fs15246-client [16120.030873] Lustre: Unmounted lustre-client [16148.391958] Key type lgssc unregistered [16148.754294] LNet: 315499:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16148.765994] LNetError: 315499:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16148.790819] LNet: Removed LNI 192.168.204.54@tcp [16149.507655] Key type .llcrypt unregistered [16149.510097] Key type ._llcrypt unregistered [16160.182665] Key type ._llcrypt registered [16160.191641] Key type .llcrypt registered [16161.696549] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 01:22:37 (1788585757) [16163.399713] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16163.418737] alg: No test for adler32 (adler32-zlib) [16164.596603] Lustre: Lustre: Build Version: 2.17.57_1_g3e0b683 [16165.077570] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [16166.906862] Key type lgssc registered [16168.188876] Lustre: Echo OBD driver; http://www.lustre.org/ [16310.191967] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16317.853439] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16344.328551] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16456.656743] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 16161 sec ========= 01:27:32 (1788586052) [16458.347898] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 01:27:34 (1788586054) === [16462.436687] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 01:27:38 (1788586058) === [16497.663466] Key type lgssc unregistered [16497.996295] LNet: 324775:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16498.007923] LNetError: 324775:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16498.022391] LNet: Removed LNI 192.168.204.54@tcp [16498.894350] Key type .llcrypt unregistered [16498.902351] Key type ._llcrypt unregistered