[ 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 442516429 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001009] APIC: Switch to symmetric I/O mode setup [ 0.003036] x2apic enabled [ 0.003765] Switched APIC routing to physical x2apic. [ 0.004014] kvm-guest: setup PV IPIs [ 0.007507] ..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.008028] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009010] pid_max: default: 32768 minimum: 301 [ 0.010106] LSM: Security Framework initializing [ 0.011043] Yama: becoming mindful. [ 0.012025] SELinux: Initializing. [ 0.012674] *** VALIDATE selinux *** [ 0.020508] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.025515] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.026157] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028115] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029106] *** VALIDATE tmpfs *** [ 0.031113] *** VALIDATE proc *** [ 0.032265] *** VALIDATE cgroup *** [ 0.033012] *** VALIDATE cgroup2 *** [ 0.035173] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.036138] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.037000] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037000] Spectre V2 : User space: Vulnerable [ 0.038005] Speculative Store Bypass: Vulnerable [ 0.040939] debug: unmapping init [mem 0xffffffff99e59000-0xffffffff99e60fff] [ 0.042161] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.043543] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.044018] ... version: 2 [ 0.044885] ... bit width: 48 [ 0.045011] ... generic registers: 4 [ 0.046009] ... value mask: 0000ffffffffffff [ 0.047007] ... max period: 00007fffffffffff [ 0.048013] ... fixed-purpose events: 3 [ 0.048810] ... event mask: 000000070000000f [ 0.050233] rcu: Hierarchical SRCU implementation. [ 0.052491] smp: Bringing up secondary CPUs ... [ 0.053538] x86: Booting SMP configuration: [ 0.054017] .... node #0, CPUs: #1 #2 #3 [ 0.058021] smp: Brought up 1 node, 4 CPUs [ 0.059885] smpboot: Max logical packages: 1 [ 0.060018] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.242120] node 0 deferred pages initialised in 179ms [ 0.244166] devtmpfs: initialized [ 0.246326] x86/mm: Memory block size: 128MB [ 0.250702] gcov: version magic: 0x41383552 [ 0.254370] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.259115] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.262433] pinctrl core: initialized pinctrl subsystem [ 0.265259] [ 0.265842] ************************************************************* [ 0.268020] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.271020] ** ** [ 0.274018] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.277017] ** ** [ 0.280019] ** This means that this kernel is built to expose internal ** [ 0.283019] ** IOMMU data structures, which may compromise security on ** [ 0.286019] ** your system. ** [ 0.289016] ** ** [ 0.292023] ** If you see this message and you are not debugging the ** [ 0.294015] ** kernel, report this immediately to your vendor! ** [ 0.297016] ** ** [ 0.299017] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.302023] ************************************************************* [ 0.304996] NET: Registered protocol family 16 [ 0.306516] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.310116] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.313105] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.317112] cpuidle: using governor menu [ 0.318550] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.319585] PCI: Using configuration type 1 for base access [ 0.321151] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.330189] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.331033] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.333175] cryptd: max_cpu_qlen set to 1000 [ 0.336313] ACPI: Added _OSI(Module Device) [ 0.337018] ACPI: Added _OSI(Processor Device) [ 0.339017] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.341018] ACPI: Added _OSI(Processor Aggregator Device) [ 0.346468] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.352377] ACPI: Interpreter enabled [ 0.354080] ACPI: PM: (supports S0 S3 S4 S5) [ 0.355014] ACPI: Using IOAPIC for interrupt routing [ 0.357146] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.361473] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.372900] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.374072] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.376026] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.379113] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.384582] acpiphp: Slot [2] registered [ 0.385000] acpiphp: Slot [5] registered [ 0.387110] acpiphp: Slot [6] registered [ 0.388127] acpiphp: Slot [3] registered [ 0.389127] acpiphp: Slot [4] registered [ 0.390159] acpiphp: Slot [7] registered [ 0.391123] acpiphp: Slot [8] registered [ 0.392023] acpiphp: Slot [9] registered [ 0.393057] acpiphp: Slot [10] registered [ 0.394119] acpiphp: Slot [11] registered [ 0.395097] acpiphp: Slot [12] registered [ 0.396174] acpiphp: Slot [13] registered [ 0.398102] acpiphp: Slot [14] registered [ 0.399097] acpiphp: Slot [15] registered [ 0.400093] acpiphp: Slot [16] registered [ 0.401041] acpiphp: Slot [17] registered [ 0.402068] acpiphp: Slot [18] registered [ 0.403000] acpiphp: Slot [19] registered [ 0.403000] acpiphp: Slot [20] registered [ 0.405141] acpiphp: Slot [21] registered [ 0.406161] acpiphp: Slot [22] registered [ 0.408154] acpiphp: Slot [23] registered [ 0.410136] acpiphp: Slot [24] registered [ 0.411185] acpiphp: Slot [25] registered [ 0.412197] acpiphp: Slot [26] registered [ 0.414105] acpiphp: Slot [27] registered [ 0.415149] acpiphp: Slot [28] registered [ 0.416129] acpiphp: Slot [29] registered [ 0.417132] acpiphp: Slot [30] registered [ 0.419187] acpiphp: Slot [31] registered [ 0.420061] PCI host bridge to bus 0000:00 [ 0.420861] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.422027] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.424028] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.426036] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.428045] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.431040] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.433237] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.436170] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.439415] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.446612] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.451081] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.454026] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.456025] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.459036] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.462075] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.467135] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.470067] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.474284] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.480026] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.492031] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.496037] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.504365] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.515022] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.523022] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.538026] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.550569] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.557030] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.563024] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.588029] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.597823] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.600472] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.603523] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.606503] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.608257] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.613144] iommu: Default domain type: Passthrough [ 0.615586] SCSI subsystem initialized [ 0.617151] ACPI: bus type USB registered [ 0.619155] usbcore: registered new interface driver usbfs [ 0.620060] usbcore: registered new interface driver hub [ 0.621068] usbcore: registered new device driver usb [ 0.622186] pps_core: LinuxPPS API ver. 1 registered [ 0.624012] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.627081] PTP clock support registered [ 0.629205] EDAC MC: Ver: 3.0.0 [ 0.632192] PCI: Using ACPI for IRQ routing [ 0.633861] NetLabel: Initializing [ 0.636015] NetLabel: domain hash size = 128 [ 0.637012] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.640125] NetLabel: unlabeled traffic allowed by default [ 0.642439] vgaarb: loaded [ 0.644456] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.646013] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.651051] clocksource: Switched to clocksource kvm-clock [ 0.754157] VFS: Disk quotas dquot_6.6.0 [ 0.755702] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.758318] *** VALIDATE ramfs *** [ 0.759653] *** VALIDATE hugetlbfs *** [ 0.761283] pnp: PnP ACPI init [ 0.763904] pnp: PnP ACPI: found 6 devices [ 0.818980] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.822910] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.825285] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.827687] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.830604] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.833418] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.836450] NET: Registered protocol family 2 [ 0.839114] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.844157] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.848062] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.853291] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.856924] TCP: Hash tables configured (established 65536 bind 65536) [ 0.860113] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.863465] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.866200] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.869404] NET: Registered protocol family 1 [ 0.872464] RPC: Registered named UNIX socket transport module. [ 0.875150] RPC: Registered udp transport module. [ 0.876965] RPC: Registered tcp transport module. [ 0.878919] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.882508] NET: Registered protocol family 44 [ 0.884780] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.886680] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.888445] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.890740] PCI: CLS 0 bytes, default 64 [ 0.892217] Unpacking initramfs... [ 2.297256] debug: unmapping init [mem 0xffff995f3cc64000-0xffff995f3ffcffff] [ 2.303800] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.306043] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.309693] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.783511] Initialise system trusted keyrings [ 2.785134] Key type blacklist registered [ 2.786864] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.794234] zbud: loaded [ 2.796520] *** VALIDATE nfs *** [ 2.797417] *** VALIDATE nfs4 *** [ 2.798500] pstore: using deflate compression [ 2.801374] Platform Keyring initialized [ 2.894694] NET: Registered protocol family 38 [ 2.896333] Key type asymmetric registered [ 2.897481] Asymmetric key parser 'x509' registered [ 2.898921] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.901608] io scheduler mq-deadline registered [ 2.902899] io scheduler kyber registered [ 2.904168] io scheduler bfq registered [ 2.905564] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.907929] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.910295] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.912709] ACPI: Power Button [PWRF] [ 2.917187] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.922354] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.969054] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.995094] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.021842] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.026132] Non-volatile memory driver v1.3 [ 3.027382] Linux agpgart interface v0.103 [ 3.051939] virtio_blk virtio1: [vda] 145912 512-byte logical blocks (74.7 MB/71.2 MiB) [ 3.054158] vda: detected capacity change from 0 to 74706944 [ 3.065534] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.067667] vdb: detected capacity change from 0 to 1073741824 [ 3.072883] libphy: Fixed MDIO Bus: probed [ 3.081440] usbcore: registered new interface driver usbserial_generic [ 3.084368] usbserial: USB Serial support registered for generic [ 3.087204] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.092174] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.094426] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.097226] mousedev: PS/2 mouse device common for all mice [ 3.101162] rtc_cmos 00:05: RTC can wake from S4 [ 3.104121] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.105685] rtc_cmos 00:05: registered as rtc0 [ 3.109789] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.111524] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.113926] intel_pstate: CPU model not supported [ 3.117237] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.121477] hid: raw HID events driver (C) Jiri Kosina [ 3.124080] usbcore: registered new interface driver usbhid [ 3.126498] usbhid: USB HID core driver [ 3.128478] drop_monitor: Initializing network drop monitor service [ 3.131391] Initializing XFRM netlink socket [ 3.133681] NET: Registered protocol family 10 [ 3.137625] Segment Routing with IPv6 [ 3.138935] NET: Registered protocol family 17 [ 3.140747] mpls_gso: MPLS GSO support [ 3.144403] RAS: Correctable Errors collector initialized. [ 3.145859] AVX version of gcm_enc/dec engaged. [ 3.146959] AES CTR mode by8 optimization enabled [ 3.215352] sched_clock: Marking stable (3215314849, 0)->(4088999690, -873684841) [ 3.217990] registered taskstats version 1 [ 3.220216] Loading compiled-in X.509 certificates [ 3.222399] zswap: loaded using pool lzo/zbud [ 3.246399] Key type big_key registered [ 3.257156] Key type encrypted registered [ 3.259042] ima: No TPM chip found, activating TPM-bypass! [ 3.261389] ima: Allocated hash algorithm: sha1 [ 3.263294] ima: No architecture policies found [ 3.265346] evm: Initialising EVM extended attributes: [ 3.267506] evm: security.selinux [ 3.268757] evm: security.ima [ 3.270013] evm: security.capability [ 3.271476] evm: HMAC attrs: 0x1 [ 3.273856] rtc_cmos 00:05: setting system clock to 2026-08-14 22:46:36 UTC (1786747596) [ 3.280828] debug: unmapping init [mem 0xffffffff9ae03000-0xffffffff9affffff] [ 3.284299] debug: unmapping init [mem 0xffffffff99b82000-0xffffffff99e58fff] [ 3.293154] Write protecting the kernel read-only data: 28672k [ 3.296129] debug: unmapping init [mem 0xffffffff98203000-0xffffffff983fffff] [ 3.298715] debug: unmapping init [mem 0xffffffff98b14000-0xffffffff98bfffff] [ 3.330953] 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.340319] systemd[1]: Detected virtualization kvm. [ 3.342811] systemd[1]: Detected architecture x86-64. [ 3.344885] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.370704] systemd[1]: No hostname configured. [ 3.372764] systemd[1]: Set hostname to . [ 3.375108] random: systemd: uninitialized urandom read (16 bytes read) [ 3.377894] systemd[1]: Initializing machine ID from random generator. [ 3.499971] random: systemd: uninitialized urandom read (16 bytes read) [ 3.502289] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 3.505660] random: systemd: uninitialized urandom read (16 bytes read) [ 3.507911] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 3.514863] systemd[1]: Started Memstrack Anylazing Service. [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Swap. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Reached target Slices. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Reached target Sockets. Starting Setup Virtual Console... Starting Apply Kernel Variables... [ OK ] Reached target Timers. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.077289] device-mapper: uevent: version 1.0.3 [ 4.087507] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 5.585530] virtio_net virtio0 ens2: renamed from eth0 [ 6.323224] scsi host0: ata_piix [ 6.385906] scsi host1: ata_piix [ 6.389723] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 6.400597] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 10.782132] random: crng init done [ 10.784215] random: 7 urandom warning(s) missed due to ratelimiting [ 13.472180] dracut-initqueue[588]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 15.078884] 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... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Timers. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Swap. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Slices. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 17.325283] printk: systemd: 25 output lines suppressed due to ratelimiting [ 18.107806] SELinux: Disabled at runtime. [ 18.242382] 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) [ 18.265370] systemd[1]: Detected virtualization kvm. [ 18.268751] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 19.855588] systemd[1]: initrd-switch-root.service: Succeeded. [ 19.866099] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 19.881990] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 19.894811] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 19.903224] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 19.921559] systemd[1]: Starting Journal Service... Starting Journal Service... [ 19.961180] systemd[1]: Listening on Process Core Dump Socket. [ OK ] Listening on Process Core Dump Socket. [ OK ] Started Forward Password Requests to Wall Directory Watch. Activating swap /dev/disk/by-label/SWAP... Mounting Kernel Debug File System... [ OK ] Created slice User and Session Slice. [ OK ] Created slice system-serial\x2dgetty.slice. [ 20.076598] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Reached target Slices. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target rpc_pipefs.target. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... Mounting Huge Pages 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. Starting Remount Root and Kernel File Systems... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. Mounting POSIX Message Queue File System... Starting Apply Kernel Variables... [ OK ] Created slice system-getty.slice. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Journal Service. [ OK ] Mounted Huge Pages File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Apply Kernel Variables. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Reached target Swap. [ OK ] Started udev Coldplug all Devices. [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Mounted /mnt. [ 21.733804] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 22.839410] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 22.883561] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 23.525557] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 23.631447] EDAC sbridge: Ver: 1.1.2 [ 27.460992] Key type dns_resolver registered [* ] A start job is running for Configur…-only root support (8s / no limit) [** ] A start job is running for Configur…-only root support (8s / no limit)[ 28.669841] NFS: Registering the id_resolver key type [ 28.674143] Key type id_resolver registered [ 28.682843] Key type id_legacy registered [*** ] A start job is running for Configur…-only root support (9s / no limit) [ *** ] A start job is running for Configur…-only root support (9s / no limit) [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Load/Save Random Seed... Starting Create Volatile Files and Directories... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Reached target Basic System. Starting Login Service... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Reached target sshd-keygen.target. [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ OK ] Started Command Scheduler. [ 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. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg218-client login: [ 97.078692] libcfs: loading out-of-tree module taints kernel. [ 97.218758] Key type ._llcrypt registered [ 97.227230] Key type .llcrypt registered [ 97.974728] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 97.984716] alg: No test for adler32 (adler32-zlib) [ 99.512309] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [ 100.661470] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 102.359697] Key type lgssc registered [ 104.533321] Lustre: Echo OBD driver; http://www.lustre.org/ [ 138.030499] hrtimer: interrupt took 2959162 ns [ 293.557598] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 299.265535] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 314.463602] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing check_logdir /tmp/testlogs/ [ 319.453517] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing yml_node [ 319.462360] Lustre: lustre-OST0000-osc-ffff995f83dc0000: disconnect after 23s idle [ 323.609589] Lustre: DEBUG MARKER: Client: 2.17.57.1 [ 326.436882] Lustre: DEBUG MARKER: MDS: 2.17.57.1 [ 329.143504] Lustre: DEBUG MARKER: OSS: 2.17.57.1 [ 331.022583] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Fri Aug 14 18:52:02 EDT 2026 [ 347.250549] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 348.612484] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 350.995966] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 361.339106] Lustre: Unmounted lustre-client [ 482.900459] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 488.527220] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 517.268419] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 569.047225] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 18:56:00 (1786748160) [ 571.786839] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 573.412729] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 18:56:05 (1786748165) [ 606.551538] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 612.678292] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 634.587410] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 635.874226] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 640.820102] Lustre: Unmounted lustre-client [ 670.703584] Key type lgssc unregistered [ 670.893537] LNet: 15047:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 670.898728] LNetError: 15047:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 670.912657] LNet: Removed LNI 192.168.202.18@tcp [ 671.589243] Key type .llcrypt unregistered [ 671.590869] Key type ._llcrypt unregistered [ 691.152365] Key type ._llcrypt registered [ 691.154417] Key type .llcrypt registered [ 692.250854] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 18:58:04 (1786748284) [ 693.866354] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 694.010454] alg: No test for adler32 (adler32-zlib) [ 695.117339] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [ 695.384662] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 697.103223] Key type lgssc registered [ 698.744522] Lustre: Echo OBD driver; http://www.lustre.org/ [ 781.216713] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 879.397939] Lustre: Unmounted lustre-client [ 910.061176] Key type lgssc unregistered [ 910.427064] LNet: 18861:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 910.432534] LNetError: 18861:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 911.463307] LNet: Removed LNI 192.168.202.18@tcp [ 912.296677] Key type .llcrypt unregistered [ 912.302076] Key type ._llcrypt unregistered [ 923.050706] Key type ._llcrypt registered [ 923.056143] Key type .llcrypt registered [ 924.397817] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 19:01:56 (1786748516) [ 927.164673] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 927.182928] alg: No test for adler32 (adler32-zlib) [ 928.175590] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [ 928.389631] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 930.071965] Key type lgssc registered [ 931.564252] Lustre: Echo OBD driver; http://www.lustre.org/ [ 984.817620] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 991.463122] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1013.996129] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1016.678611] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 1026.208569] Lustre: Unmounted lustre-client [ 1055.451231] Key type lgssc unregistered [ 1055.595735] LNet: 22462:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1055.603971] LNetError: 22462:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1055.632240] LNet: Removed LNI 192.168.202.18@tcp [ 1056.131636] Key type .llcrypt unregistered [ 1056.133359] Key type ._llcrypt unregistered [ 1065.896780] Key type ._llcrypt registered [ 1065.898586] Key type .llcrypt registered [ 1067.274318] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 19:04:18 (1786748658) [ 1069.788778] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1069.820739] alg: No test for adler32 (adler32-zlib) [ 1070.829308] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [ 1071.064328] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 1072.737641] Key type lgssc registered [ 1074.215292] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1131.583739] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1138.936903] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1160.282781] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1171.554807] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 1177.111156] Lustre: Unmounted lustre-client [ 1205.961327] Key type lgssc unregistered [ 1206.212558] LNet: 25654:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1206.218879] LNetError: 25654:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1206.232783] LNet: Removed LNI 192.168.202.18@tcp [ 1206.871592] Key type .llcrypt unregistered [ 1206.882995] Key type ._llcrypt unregistered [ 1223.584733] Key type ._llcrypt registered [ 1223.586608] Key type .llcrypt registered [ 1225.569642] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 19:06:56 (1786748816) [ 1228.678823] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1228.721880] alg: No test for adler32 (adler32-zlib) [ 1230.166407] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [ 1230.533652] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 1232.279808] Key type lgssc registered [ 1233.816731] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1288.290892] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1294.134892] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1317.823608] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1320.544365] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 1330.668543] Lustre: lustre-MDT0000-mdc-ffff995f845dd000: Connection to lustre-MDT0000 (at 192.168.202.118@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1340.929892] Lustre: lustre-MDT0001-mdc-ffff995f845dd000: Connection to lustre-MDT0001 (at 192.168.202.118@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1347.328361] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1352.162861] Lustre: 26614:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786748929/real 1786748929] req@000000003118bb55 x1873541933832320/t0(0) o400->MGC192.168.202.118@tcp@192.168.202.118@tcp:26/25 lens 224/224 e 0 to 1 dl 1786748945 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1352.193058] LustreError: MGC192.168.202.118@tcp: Connection to MGS (at 192.168.202.118@tcp) was lost; in progress operations using this service will fail [ 1357.704505] Lustre: Unmounted lustre-client [ 1370.804237] Key type lgssc unregistered [ 1371.036707] LNet: 29315:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1371.041906] LNetError: 29315:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1371.056468] LNet: Removed LNI 192.168.202.18@tcp [ 1371.623546] Key type .llcrypt unregistered [ 1371.628328] Key type ._llcrypt unregistered [ 1386.326524] Key type ._llcrypt registered [ 1386.328970] Key type .llcrypt registered [ 1387.860800] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 19:09:39 (1786748979) [ 1390.811821] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1390.843745] alg: No test for adler32 (adler32-zlib) [ 1392.027170] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [ 1392.278406] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 1393.976207] Key type lgssc registered [ 1395.483551] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1455.910970] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1462.428536] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1483.646683] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1486.433226] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 1497.707653] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1508.104085] Lustre: Unmounted lustre-client [ 1547.157608] Key type lgssc unregistered [ 1547.508760] LNet: 32934:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1547.521886] LNetError: 32934:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1547.551706] LNet: Removed LNI 192.168.202.18@tcp [ 1548.506286] Key type .llcrypt unregistered [ 1548.513729] Key type ._llcrypt unregistered [ 1565.582281] Key type ._llcrypt registered [ 1565.586525] Key type .llcrypt registered [ 1566.912401] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 19:12:38 (1786749158) [ 1569.699351] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1569.731040] alg: No test for adler32 (adler32-zlib) [ 1570.763228] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [ 1570.947577] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 1572.623847] Key type lgssc registered [ 1573.883258] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1622.611551] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1629.817985] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1652.029303] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1653.949526] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 1661.451226] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1665.402833] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1678.124255] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-08-1419h14m28s [ 1680.781419] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1684.975573] Lustre: lustre-MDT0000-mdc-ffff995f845da000: Connection to lustre-MDT0000 (at 192.168.202.118@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1692.354703] LustreError: 36725:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff995f845da000: can't stat MDS #0: rc = -19 [ 1693.552783] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1703.415506] LustreError: MGC192.168.202.118@tcp: Connection to MGS (at 192.168.202.118@tcp) was lost; in progress operations using this service will fail [ 1703.440298] Lustre: Evicted from MGS (at 192.168.202.118@tcp) after server handle changed from 0x47af8a1c02a45c34 to 0x47af8a1c02a45efe [ 1703.450755] Lustre: MGC192.168.202.118@tcp: Connection restored to 192.168.202.118@tcp (at 192.168.202.118@tcp) [ 1704.496204] LustreError: lustre-MDT0000-mdc-ffff995f845da000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1704.531672] Lustre: lustre-MDT0000-mdc-ffff995f845da000: Connection restored to 192.168.202.118@tcp (at 192.168.202.118@tcp) [ 1708.123419] Lustre: DEBUG MARKER: Wait for df (36725) ... [ 1710.001412] Lustre: DEBUG MARKER: done [ 1712.157654] Lustre: Unmounted lustre-client [ 1752.453813] Key type lgssc unregistered [ 1752.685173] LNet: 37749:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1752.706947] LNetError: 37749:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1752.731628] LNet: Removed LNI 192.168.202.18@tcp [ 1753.755721] Key type .llcrypt unregistered [ 1753.759633] Key type ._llcrypt unregistered [ 1786.324708] Key type ._llcrypt registered [ 1786.327444] Key type .llcrypt registered [ 1787.361615] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 19:16:19 (1786749379) [ 1790.266039] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1790.290055] alg: No test for adler32 (adler32-zlib) [ 1791.487684] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [ 1791.732047] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 1793.391325] Key type lgssc registered [ 1795.109844] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1850.784343] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1855.733731] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1879.330251] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1882.042591] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 1886.616355] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-08-1419h17m58s [ 1888.435549] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1896.611906] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1899.415286] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1919.457240] Lustre: 41895:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786749492/real 1786749492] req@00000000692944a7 x1873542522083968/t0(0) o101->lustre-MDT0000-mdc-ffff995fa079f000@192.168.202.118@tcp:12/10 lens 576/1152 e 0 to 1 dl 1786749512 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1919.494517] Lustre: lustre-MDT0000-mdc-ffff995fa079f000: Connection to lustre-MDT0000 (at 192.168.202.118@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1919.533260] Lustre: lustre-MDT0000-mdc-ffff995fa079f000: Connection restored to 192.168.202.118@tcp (at 192.168.202.118@tcp) [ 1941.564118] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1943.892764] Lustre: DEBUG MARKER: done [ 1946.449369] Lustre: Unmounted lustre-client [ 1973.790948] Key type lgssc unregistered [ 1974.011857] LNet: 42784:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1974.016058] LNetError: 42784:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1974.034347] LNet: Removed LNI 192.168.202.18@tcp [ 1974.609593] Key type .llcrypt unregistered [ 1974.612171] Key type ._llcrypt unregistered [ 2006.732068] Key type ._llcrypt registered [ 2006.741714] Key type .llcrypt registered [ 2008.759260] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 19:19:59 (1786749599) [ 2010.515497] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 2012.639468] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 19:20:04 (1786749604) [ 2016.814538] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2016.845254] alg: No test for adler32 (adler32-zlib) [ 2018.012187] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [ 2018.268239] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 2019.970458] Key type lgssc registered [ 2021.508079] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2050.589569] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 19:20:42 (1786749642) [ 2085.944956] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2092.953502] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2120.395751] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2122.250374] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 2126.420695] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 2129.849820] Lustre: Unmounted lustre-client [ 2149.330749] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 2199.001744] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2205.287216] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2206.110479] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 2211.493645] Lustre: Unmounted lustre-client [ 2276.865784] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2283.852310] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2284.584597] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 2288.083409] Lustre: Unmounted lustre-client [ 2305.273475] Lustre: DEBUG MARKER: files compared the same [ 2324.282296] Key type lgssc unregistered [ 2324.603873] LNet: 50416:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2324.618797] LNetError: 50416:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2324.650705] LNet: Removed LNI 192.168.202.18@tcp [ 2325.245071] Key type .llcrypt unregistered [ 2325.247171] Key type ._llcrypt unregistered [ 2343.657580] Key type ._llcrypt registered [ 2343.668103] Key type .llcrypt registered [ 2345.501569] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 19:25:36 (1786749936) [ 2349.163423] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2349.189970] alg: No test for adler32 (adler32-zlib) [ 2350.548333] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [ 2351.005898] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 2352.832422] Key type lgssc registered [ 2354.719449] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2414.074870] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2421.222761] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2445.758510] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2456.633667] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 2461.240320] Lustre: Unmounted lustre-client [ 2492.167842] Key type lgssc unregistered [ 2492.521636] LNet: 54007:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2492.534416] LNetError: 54007:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2492.552526] LNet: Removed LNI 192.168.202.18@tcp [ 2493.456567] Key type .llcrypt unregistered [ 2493.461827] Key type ._llcrypt unregistered [ 2514.229161] Key type ._llcrypt registered [ 2514.235618] Key type .llcrypt registered [ 2515.878162] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 19:28:27 (1786750107) [ 2518.951156] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2518.963376] alg: No test for adler32 (adler32-zlib) [ 2520.088365] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [ 2520.287445] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 2521.927229] Key type lgssc registered [ 2523.495166] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2610.027966] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2649.707121] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2656.957304] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2697.432218] Key type lgssc unregistered [ 2697.830403] LNet: 57506:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2697.837332] LNetError: 57506:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2697.859463] LNet: Removed LNI 192.168.202.18@tcp [ 2698.835117] Key type .llcrypt unregistered [ 2698.839785] Key type ._llcrypt unregistered [ 2716.745857] Key type ._llcrypt registered [ 2716.750739] Key type .llcrypt registered [ 2718.538833] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 19:31:49 (1786750309) [ 2721.687731] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2721.760569] alg: No test for adler32 (adler32-zlib) [ 2722.754777] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [ 2722.934411] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 2724.575274] Key type lgssc registered [ 2725.661902] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2801.007153] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 2808.916260] Lustre: Unmounted lustre-client [ 2848.334750] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 19:34:00 (1786750440) [ 2988.189878] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3011.582463] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 3019.926671] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3019.942541] LustreError: 63992:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff995f91053800: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 3019.987550] Lustre: 58490:0:(llite_lib.c:4340:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.202.118@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 3030.350015] Lustre: Unmounted lustre-client [ 3088.447616] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 19:37:59 (1786750679) [ 3102.322632] Key type lgssc unregistered [ 3102.714329] LNet: 65208:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3102.724510] LNetError: 65208:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3102.753579] LNet: Removed LNI 192.168.202.18@tcp [ 3104.151165] Key type .llcrypt unregistered [ 3104.160048] Key type ._llcrypt unregistered [ 3119.818242] Key type ._llcrypt registered [ 3119.838094] Key type .llcrypt registered [ 3120.398883] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3120.412546] alg: No test for adler32 (adler32-zlib) [ 3121.794625] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [ 3122.072729] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 3123.904901] Key type lgssc registered [ 3125.397735] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3179.099856] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3185.177172] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3211.613337] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3213.274690] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 3217.932551] Lustre: Unmounted lustre-client [ 3247.285626] Key type lgssc unregistered [ 3247.639768] LNet: 68227:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3247.653662] LNetError: 68227:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3247.674502] LNet: Removed LNI 192.168.202.18@tcp [ 3248.425563] Key type .llcrypt unregistered [ 3248.427375] Key type ._llcrypt unregistered [ 3260.082516] Key type ._llcrypt registered [ 3260.090890] Key type .llcrypt registered [ 3260.822821] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3260.838264] alg: No test for adler32 (adler32-zlib) [ 3262.293539] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [ 3262.550753] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 3264.280047] Key type lgssc registered [ 3265.831392] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3378.276103] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3384.977696] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3409.278592] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3412.330678] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 3421.936719] Lustre: Unmounted lustre-client [ 3457.747842] Key type lgssc unregistered [ 3458.001931] LNet: 71664:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3458.022610] LNetError: 71664:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3458.045628] LNet: Removed LNI 192.168.202.18@tcp [ 3458.703734] Key type .llcrypt unregistered [ 3458.706675] Key type ._llcrypt unregistered [ 3474.149463] Key type ._llcrypt registered [ 3474.151200] Key type .llcrypt registered [ 3476.054782] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 19:44:27 (1786751067) [ 3478.737490] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3478.750905] alg: No test for adler32 (adler32-zlib) [ 3479.828253] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [ 3480.042762] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 3481.743244] Key type lgssc registered [ 3482.951645] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3539.511811] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3546.068064] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3569.656203] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3571.302410] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 3575.712684] Lustre: Unmounted lustre-client [ 3576.109571] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 3579.721429] Lustre: Unmounted lustre-client [ 3616.081364] Key type lgssc unregistered [ 3616.417352] LNet: 75355:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3616.426209] LNetError: 75355:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3616.444285] LNet: Removed LNI 192.168.202.18@tcp [ 3617.043589] Key type .llcrypt unregistered [ 3617.049248] Key type ._llcrypt unregistered [ 3629.622674] Key type ._llcrypt registered [ 3629.624263] Key type .llcrypt registered [ 3630.210570] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3630.222963] alg: No test for adler32 (adler32-zlib) [ 3631.544251] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [ 3631.984900] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 3633.695658] Key type lgssc registered [ 3634.592590] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3689.345993] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3695.409200] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3720.533794] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3723.362404] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 3729.343505] Lustre: Unmounted lustre-client [ 3761.065915] Key type lgssc unregistered [ 3761.340348] LNet: 78390:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3761.351483] LNetError: 78390:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3761.368294] LNet: Removed LNI 192.168.202.18@tcp [ 3762.225755] Key type .llcrypt unregistered [ 3762.235774] Key type ._llcrypt unregistered [ 3782.295303] Key type ._llcrypt registered [ 3782.297171] Key type .llcrypt registered [ 3783.802341] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 19:49:35 (1786751375) [ 3786.877644] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3787.169569] alg: No test for adler32 (adler32-zlib) [ 3788.387523] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [ 3788.648673] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 3790.303168] Key type lgssc registered [ 3791.374454] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3846.019513] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3852.179892] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3875.395971] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3877.117294] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 3884.196729] Lustre: lustre: root_squash is set to 0:0 [ 3889.709558] Lustre: lustre: nosquash_nids is cleared [ 3897.401787] Lustre: lustre: root_squash is set to 500:500 [ 3903.548354] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.202.18@tcp 192.168.0.[2,10]@tcp [ 3912.258252] Lustre: lustre: nosquash_nids set to 192.168.202.18/24@tcp [ 3917.533478] Lustre: Unmounted lustre-client [ 3951.276534] Key type lgssc unregistered [ 3951.594098] LNet: 82330:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3951.605585] LNetError: 82330:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3951.625385] LNet: Removed LNI 192.168.202.18@tcp [ 3952.526694] Key type .llcrypt unregistered [ 3952.531830] Key type ._llcrypt unregistered [ 3972.067699] Key type ._llcrypt registered [ 3972.070487] Key type .llcrypt registered [ 3973.424538] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 19:52:45 (1786751565) [ 3975.672424] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3977.643715] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 19:52:49 (1786751569) [ 3979.899283] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3979.936251] alg: No test for adler32 (adler32-zlib) [ 3980.971717] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [ 3981.156253] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 3982.816554] Key type lgssc registered [ 3984.137543] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4035.911458] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4042.419848] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4063.580909] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4064.236618] Lustre: lustre: root_squash is set to 500:500 [ 4064.260550] Lustre: lustre: nosquash_nids set to 192.168.202.18/24@tcp [ 4065.354281] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 4073.353952] Lustre: Unmounted lustre-client [ 4109.416421] Key type lgssc unregistered [ 4109.687331] LNet: 86293:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4109.698455] LNetError: 86293:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4109.721664] LNet: Removed LNI 192.168.202.18@tcp [ 4110.726295] Key type .llcrypt unregistered [ 4110.730441] Key type ._llcrypt unregistered [ 4126.508542] Key type ._llcrypt registered [ 4126.510154] Key type .llcrypt registered [ 4128.004345] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 4129.958410] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 19:55:21 (1786751721) [ 4149.371548] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4149.409854] alg: No test for adler32 (adler32-zlib) [ 4150.528953] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [ 4150.844401] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 4152.535265] Key type lgssc registered [ 4153.988444] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4244.774755] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4251.753489] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4278.316498] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4355.807484] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4363.140587] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4386.511536] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4402.340571] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 4438.018895] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff995f908fe800.ost_server_uuid 50 [ 4439.673743] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff995f908fe800.ost_server_uuid in IDLE state after 0 sec [ 4440.120128] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 4442.768579] Lustre: Unmounted lustre-client [ 4444.533548] Lustre: Unmounted lustre-client [ 4496.300452] Key type lgssc unregistered [ 4496.476792] LNet: 93973:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4496.481750] LNetError: 93973:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4496.507349] LNet: Removed LNI 192.168.202.18@tcp [ 4497.081378] Key type .llcrypt unregistered [ 4497.083163] Key type ._llcrypt unregistered [ 4527.796874] Key type ._llcrypt registered [ 4527.798257] Key type .llcrypt registered [ 4529.006439] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 20:02:00 (1786752120) [ 4550.746027] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4550.785441] alg: No test for adler32 (adler32-zlib) [ 4551.870700] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [ 4552.081795] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 4553.767555] Key type lgssc registered [ 4556.238517] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4656.265202] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4662.436333] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4690.375694] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4692.353120] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 4712.939292] Lustre: 95339:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786752290/real 1786752290] req@00000000d7560a16 x1873545417200768/t0(0) o400->lustre-OST0000-osc-ffff995f85a5c000@192.168.202.118@tcp:28/4 lens 224/224 e 0 to 1 dl 1786752306 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4712.959507] Lustre: lustre-OST0000-osc-ffff995f85a5c000: Connection to lustre-OST0000 (at 192.168.202.118@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4718.051394] Lustre: 95338:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786752295/real 1786752295] req@00000000534774d6 x1873545417201280/t0(0) o400->lustre-OST0000-osc-ffff995f85a5c000@192.168.202.118@tcp:28/4 lens 224/224 e 0 to 1 dl 1786752311 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4723.259571] Lustre: lustre-OST0000-osc-ffff995f85a5c000: Connection restored to 192.168.202.118@tcp (at 192.168.202.118@tcp) [ 4724.319399] Lustre: 95339:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786752301/real 1786752301] req@00000000266b796e x1873545417201792/t0(0) o400->lustre-OST0000-osc-ffff995f85a5c000@192.168.202.118@tcp:28/4 lens 224/224 e 0 to 1 dl 1786752317 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4729.311282] Lustre: 95338:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786752306/real 1786752306] req@00000000d5efa2a9 x1873545417202304/t0(0) o400->lustre-OST0000-osc-ffff995f85a5c000@192.168.202.118@tcp:28/4 lens 224/224 e 0 to 1 dl 1786752322 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4733.430896] Lustre: lustre-MDT0000-mdc-ffff995f85a5c000: Connection to lustre-MDT0000 (at 192.168.202.118@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4749.728341] Lustre: 95340:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786752326/real 1786752326] req@00000000266b796e x1873545417204736/t0(0) o400->MGC192.168.202.118@tcp@192.168.202.118@tcp:26/25 lens 224/224 e 0 to 1 dl 1786752342 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4749.755319] LustreError: MGC192.168.202.118@tcp: Connection to MGS (at 192.168.202.118@tcp) was lost; in progress operations using this service will fail [ 4759.044287] Lustre: Evicted from MGS (at 192.168.202.118@tcp) after server handle changed from 0x9f17a3f80c032a23 to 0x9f17a3f80c032cb5 [ 4759.079366] Lustre: MGC192.168.202.118@tcp: Connection restored to 192.168.202.118@tcp (at 192.168.202.118@tcp) [ 4764.164485] Lustre: lustre-MDT0000-mdc-ffff995f85a5c000: Connection restored to 192.168.202.118@tcp (at 192.168.202.118@tcp) [ 4771.767214] Lustre: Unmounted lustre-client [ 4799.984370] Key type lgssc unregistered [ 4800.366032] LNet: 99647:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4800.382021] LNetError: 99647:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4800.410655] LNet: Removed LNI 192.168.202.18@tcp [ 4801.112517] Key type .llcrypt unregistered [ 4801.115524] Key type ._llcrypt unregistered [ 4819.617124] Key type ._llcrypt registered [ 4819.618343] Key type .llcrypt registered [ 4821.202485] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 20:06:52 (1786752412) [ 4837.646573] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4837.681805] alg: No test for adler32 (adler32-zlib) [ 4838.925765] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [ 4839.208103] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 4840.858175] Key type lgssc registered [ 4842.131412] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4887.124344] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4997.551402] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5003.013874] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5025.678946] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5028.568660] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 5031.229898] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 5032.658332] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 20:10:24 (1786752624) [ 5284.913194] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5295.266602] Lustre: Unmounted lustre-client [ 5431.125982] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5437.682832] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5465.658408] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5513.893734] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 20:18:24 (1786753104) [ 5623.399392] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5630.375554] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5657.417115] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5658.202168] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 5666.349377] Lustre: Unmounted lustre-client [ 5707.045736] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 20:21:38 (1786753298) [ 5812.447202] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5818.482251] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5845.232536] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5845.920475] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 5850.016037] Lustre: Unmounted lustre-client [ 5879.808471] Key type lgssc unregistered [ 5880.201164] LNet: 122680:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5880.207803] LNetError: 122680:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5880.235207] LNet: Removed LNI 192.168.202.18@tcp [ 5881.624725] Key type .llcrypt unregistered [ 5881.634823] Key type ._llcrypt unregistered [ 5904.715209] Key type ._llcrypt registered [ 5904.718433] Key type .llcrypt registered [ 5906.415500] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 20:24:57 (1786753497) [ 5908.921236] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5908.992394] alg: No test for adler32 (adler32-zlib) [ 5910.315858] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [ 5910.836696] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 5912.599322] Key type lgssc registered [ 5913.948548] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5972.284165] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5980.290315] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6005.373364] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6012.538301] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 6027.824148] Lustre: Unmounted lustre-client [ 6056.984508] Key type lgssc unregistered [ 6057.201847] LNet: 126278:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6057.212966] LNetError: 126278:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6057.236621] LNet: Removed LNI 192.168.202.18@tcp [ 6058.033993] Key type .llcrypt unregistered [ 6058.038842] Key type ._llcrypt unregistered [ 6074.439039] Key type ._llcrypt registered [ 6074.440892] Key type .llcrypt registered [ 6075.706673] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 20:27:47 (1786753667) [ 6078.689515] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6078.724368] alg: No test for adler32 (adler32-zlib) [ 6079.853672] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [ 6080.054965] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 6081.719763] Key type lgssc registered [ 6082.951861] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6146.701959] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6153.946277] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6177.151671] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6189.111367] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 6194.170994] Lustre: lustre-OST0000-osc-ffff995f90c4d800: Connection to lustre-OST0000 (at 192.168.202.118@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6207.903609] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff995f90c4d800.ost_server_uuid 50 [ 6209.739964] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 6221.387643] Lustre: Unmounted lustre-client [ 6243.450202] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 20:30:35 (1786753835) [ 6280.351911] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6288.936985] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6313.063330] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6341.965803] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6342.555261] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 6347.806735] Lustre: lustre-OST0000-osc-ffff995f87ed9800: Connection to lustre-OST0000 (at 192.168.202.118@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6377.530291] Lustre: Unmounted lustre-client [ 6430.529335] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 20:33:42 (1786754022) [ 6468.199537] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6475.428554] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6497.562783] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6519.796036] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6520.857332] LustreError: 127235:0:(lmv_obd.c:211:lmv_notify()) activation of lustre-MDT0001_UUID failed: -22 [ 6521.009915] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 6525.951041] Lustre: lustre-OST0000-osc-ffff995f87edb800: Connection to lustre-OST0000 (at 192.168.202.118@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6543.808119] Lustre: Unmounted lustre-client [ 6596.225743] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 20:36:28 (1786754188) [ 6690.103822] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6695.784803] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6723.973410] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6796.349396] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6802.102313] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6825.292876] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6858.414737] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 6906.446490] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6908.022786] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6909.682425] Lustre: Unmounted lustre-client [ 6945.049838] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 20:42:16 (1786754536) [ 6981.617116] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6989.811789] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7019.525296] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7058.694128] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7093.922878] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 7139.699845] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7140.891727] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7153.785530] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7164.156503] Lustre: Unmounted lustre-client [ 7231.037808] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 20:47:02 (1786754822) [ 7269.470317] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7276.586852] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7302.548967] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7303.496041] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 7331.242834] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7333.444694] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7355.177976] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff995f892a8800.ost_server_uuid 50 [ 7357.387252] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff995f892a8800.ost_server_uuid in IDLE state after 0 sec [ 7361.118761] Lustre: Unmounted lustre-client [ 7361.533399] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 7361.545828] Lustre: Skipped 3 previous similar messages [ 7361.713435] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 7362.801868] LustreError: 149700:0:(sec.c:403:import_sec_validate_get()) import 00000000bff2bdf4 (NEW) with no sec [ 7428.131787] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 20:50:19 (1786755019) [ 7468.433874] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7475.062841] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7499.366749] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7524.070777] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7525.116299] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 7525.359938] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 7536.186114] Lustre: Unmounted lustre-client [ 7536.188151] Lustre: Skipped 1 previous similar message [ 7576.469693] Key type lgssc unregistered [ 7576.769736] LNet: 153112:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7576.775769] LNetError: 153112:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7576.788397] LNet: Removed LNI 192.168.202.18@tcp [ 7577.654826] Key type .llcrypt unregistered [ 7577.658928] Key type ._llcrypt unregistered [ 7593.747739] Key type ._llcrypt registered [ 7593.751683] Key type .llcrypt registered [ 7595.043676] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 20:53:06 (1786755186) [ 7596.482403] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7596.522492] alg: No test for adler32 (adler32-zlib) [ 7597.655960] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [ 7598.077531] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 7599.807247] Key type lgssc registered [ 7600.570493] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7662.371269] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7671.375711] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7699.554380] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7725.650682] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7727.323320] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7728.512724] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 7732.438833] LustreError: lustre-MDT0001-mdc-ffff995fa0596800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 7732.473690] Lustre: lustre-MDT0001-mdc-ffff995fa0596800: Connection restored to 192.168.202.118@tcp (at 192.168.202.118@tcp) [ 7739.266733] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7740.777729] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7766.541423] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7771.404175] Lustre: Unmounted lustre-client [ 7804.048664] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 20:56:35 (1786755395) [ 7899.382086] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7905.435585] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7930.334416] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7931.211677] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 7961.338927] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7963.811778] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7985.274444] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7995.641369] Lustre: Unmounted lustre-client [ 8037.059253] Key type lgssc unregistered [ 8037.279361] LNet: 162709:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8037.286852] LNetError: 162709:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8037.301329] LNet: Removed LNI 192.168.202.18@tcp [ 8037.790664] Key type .llcrypt unregistered [ 8037.792560] Key type ._llcrypt unregistered [ 8066.416377] Key type ._llcrypt registered [ 8066.418388] Key type .llcrypt registered [ 8067.728334] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 21:00:59 (1786755659) [ 8071.238190] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8071.370430] alg: No test for adler32 (adler32-zlib) [ 8072.893994] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [ 8073.432367] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 8075.167226] Key type lgssc registered [ 8076.937760] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8130.309224] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8136.220832] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8156.118971] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8158.843337] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 8205.076994] Lustre: Unmounted lustre-client [ 8243.291616] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8243.749404] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 8280.013697] Lustre: Unmounted lustre-client [ 8306.799211] Key type lgssc unregistered [ 8307.026165] LNet: 167168:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8307.033110] LNetError: 167168:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8307.053540] LNet: Removed LNI 192.168.202.18@tcp [ 8307.744651] Key type .llcrypt unregistered [ 8307.745946] Key type ._llcrypt unregistered [ 8321.970349] Key type ._llcrypt registered [ 8321.972799] Key type .llcrypt registered [ 8323.199298] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 21:05:15 (1786755915) [ 8325.845192] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8325.875543] alg: No test for adler32 (adler32-zlib) [ 8326.947988] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [ 8327.242354] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 8328.911355] Key type lgssc registered [ 8330.669728] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8385.560326] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8392.020890] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8413.827579] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8416.496232] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 8439.212493] Lustre: Unmounted lustre-client [ 8476.819638] Key type lgssc unregistered [ 8477.259281] LNet: 170924:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8477.267422] LNetError: 170924:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8477.287881] LNet: Removed LNI 192.168.202.18@tcp [ 8478.050540] Key type .llcrypt unregistered [ 8478.052382] Key type ._llcrypt unregistered [ 8491.302359] Key type ._llcrypt registered [ 8491.304406] Key type .llcrypt registered [ 8491.829454] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8491.853280] alg: No test for adler32 (adler32-zlib) [ 8493.103695] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [ 8493.377790] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 8495.127251] Key type lgssc registered [ 8496.750284] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8547.595332] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8553.899700] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8573.747732] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8576.497783] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 8587.700197] Lustre: Unmounted lustre-client [ 8624.516614] Key type lgssc unregistered [ 8624.747649] LNet: 173991:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8624.761839] LNetError: 173991:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8624.780421] LNet: Removed LNI 192.168.202.18@tcp [ 8625.327298] Key type .llcrypt unregistered [ 8625.334074] Key type ._llcrypt unregistered [ 8640.682431] Key type ._llcrypt registered [ 8640.690427] Key type .llcrypt registered [ 8642.054592] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 21:10:33 (1786756233) [ 8644.354277] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8644.366880] alg: No test for adler32 (adler32-zlib) [ 8645.475819] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [ 8645.672580] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 8647.327314] Key type lgssc registered [ 8648.621734] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8702.972407] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8708.325139] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8730.942369] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8732.788745] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 8757.327129] Lustre: Unmounted lustre-client [ 8794.782348] Key type lgssc unregistered [ 8795.073183] LNet: 177749:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8795.079753] LNetError: 177749:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8795.096436] LNet: Removed LNI 192.168.202.18@tcp [ 8795.994225] Key type .llcrypt unregistered [ 8796.003116] Key type ._llcrypt unregistered [ 8810.769926] Key type ._llcrypt registered [ 8810.781639] Key type .llcrypt registered [ 8811.546093] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8811.562121] alg: No test for adler32 (adler32-zlib) [ 8812.599578] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [ 8812.882553] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 8814.623302] Key type lgssc registered [ 8815.818712] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8873.878396] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8880.083170] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8904.417290] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8915.562959] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 8925.910085] Lustre: Unmounted lustre-client [ 8954.749600] Key type lgssc unregistered [ 8955.011347] LNet: 180817:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8955.021958] LNetError: 180817:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8955.043347] LNet: Removed LNI 192.168.202.18@tcp [ 8955.857619] Key type .llcrypt unregistered [ 8955.861577] Key type ._llcrypt unregistered [ 8973.709968] Key type ._llcrypt registered [ 8973.713497] Key type .llcrypt registered [ 8975.365495] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 21:16:06 (1786756566) [ 8998.580752] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8998.641592] alg: No test for adler32 (adler32-zlib) [ 8999.910124] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [ 9000.127353] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 9001.783324] Key type lgssc registered [ 9003.092665] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9088.209767] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9094.524617] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9117.134037] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9167.098638] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 21:19:18 (1786756758) [ 9206.257466] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9212.090658] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9233.669343] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9236.313205] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 9242.406812] Lustre: Unmounted lustre-client [ 9274.373918] Key type lgssc unregistered [ 9274.665824] LNet: 187916:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9274.672075] LNetError: 187916:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9274.694326] LNet: Removed LNI 192.168.202.18@tcp [ 9275.799377] Key type .llcrypt unregistered [ 9275.801610] Key type ._llcrypt unregistered [ 9296.949742] Key type ._llcrypt registered [ 9296.953789] Key type .llcrypt registered [ 9299.621985] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 9302.528817] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 21:21:33 (1786756893) [ 9325.311725] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9325.324294] alg: No test for adler32 (adler32-zlib) [ 9326.595838] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [ 9327.127814] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 9328.872180] Key type lgssc registered [ 9330.263494] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9422.116753] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9427.259590] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9451.376368] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9475.872123] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9477.861807] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [ 9504.732328] Lustre: Unmounted lustre-client [ 9646.934638] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9653.635076] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9678.628169] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9736.421189] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 21:28:47 (1786757327) [ 9738.743706] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 9740.791810] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 21:28:52 (1786757332) [ 9844.444149] Key type lgssc unregistered [ 9844.712900] LNet: 199366:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9844.719724] LNetError: 199366:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9844.740378] LNet: Removed LNI 192.168.202.18@tcp [ 9846.005636] Key type .llcrypt unregistered [ 9846.007570] Key type ._llcrypt unregistered [ 9864.663251] Key type ._llcrypt registered [ 9864.672622] Key type .llcrypt registered [ 9866.634212] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 21:30:57 (1786757457) [ 9915.507672] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9915.519909] alg: No test for adler32 (adler32-zlib) [ 9916.703722] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [ 9917.085664] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 9918.840228] Key type lgssc registered [ 9920.435917] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9970.860068] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9978.321268] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10018.711858] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10063.855925] Key type lgssc unregistered [10064.139236] LNet: 203105:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10064.145383] LNetError: 203105:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10064.166877] LNet: Removed LNI 192.168.202.18@tcp [10065.245219] Key type .llcrypt unregistered [10065.248153] Key type ._llcrypt unregistered [10085.925988] Key type ._llcrypt registered [10085.930920] Key type .llcrypt registered [10087.774870] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 21:34:38 (1786757678) [10091.981882] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10092.082590] alg: No test for adler32 (adler32-zlib) [10093.346195] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [10093.615781] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [10095.320397] Key type lgssc registered [10097.427723] Lustre: Echo OBD driver; http://www.lustre.org/ [10159.100298] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10166.096995] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10192.871849] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10199.161310] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [10209.281259] Lustre: lustre-MDT0000-mdc-ffff995f87d02800: Connection to lustre-MDT0000 (at 192.168.202.118@tcp) was lost; in progress operations using this service will wait for recovery to complete [10219.511830] Lustre: lustre-MDT0001-mdc-ffff995f87d02800: Connection to lustre-MDT0001 (at 192.168.202.118@tcp) was lost; in progress operations using this service will wait for recovery to complete [10230.751642] Lustre: 204095:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786757807/real 1786757807] req@00000000b0057d10 x1873551227437184/t0(0) o400->MGC192.168.202.118@tcp@192.168.202.118@tcp:26/25 lens 224/224 e 0 to 1 dl 1786757823 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10230.820331] LustreError: MGC192.168.202.118@tcp: Connection to MGS (at 192.168.202.118@tcp) was lost; in progress operations using this service will fail [10256.558901] Lustre: Evicted from MGS (at 192.168.202.118@tcp) after server handle changed from 0xbab822ae73470b7e to 0xbab822ae73472723 [10256.588496] Lustre: MGC192.168.202.118@tcp: Connection restored to 192.168.202.118@tcp (at 192.168.202.118@tcp) [10289.644798] LustreError: lustre-MDT0000-mdc-ffff995f87d02800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [10289.673797] Lustre: lustre-MDT0000-mdc-ffff995f87d02800: Connection restored to 192.168.202.118@tcp (at 192.168.202.118@tcp) [10294.490525] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10296.261972] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10302.772940] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10304.350887] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [10308.021771] Lustre: Unmounted lustre-client [10337.086826] Key type lgssc unregistered [10337.365840] LNet: 208060:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10337.371765] LNetError: 208060:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10338.415202] LNet: Removed LNI 192.168.202.18@tcp [10339.195267] Key type .llcrypt unregistered [10339.200140] Key type ._llcrypt unregistered [10361.126601] Key type ._llcrypt registered [10361.128255] Key type .llcrypt registered [10362.547474] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 21:39:14 (1786757954) [10366.154801] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10366.192747] alg: No test for adler32 (adler32-zlib) [10367.350058] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [10367.666974] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [10369.455274] Key type lgssc registered [10371.143896] Lustre: Echo OBD driver; http://www.lustre.org/ [10423.235526] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10429.996867] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10497.083352] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10503.710844] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10545.707371] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10570.909355] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10620.060295] Key type lgssc unregistered [10620.309292] LNet: 213363:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10620.321692] LNetError: 213363:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10620.343671] LNet: Removed LNI 192.168.202.18@tcp [10621.031382] Key type .llcrypt unregistered [10621.033447] Key type ._llcrypt unregistered [10671.436528] Key type ._llcrypt registered [10671.438115] Key type .llcrypt registered [10672.769160] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 21:44:24 (1786758264) [10720.985037] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10721.086637] alg: No test for adler32 (adler32-zlib) [10722.362644] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [10722.736565] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [10724.552667] Key type lgssc registered [10726.666552] Lustre: Echo OBD driver; http://www.lustre.org/ [10820.684799] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10826.909397] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10852.834518] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10911.025792] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 21:48:22 (1786758502) [10921.187920] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 21:48:32 (1786758512) [10969.551641] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10975.722349] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10999.307681] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11005.534801] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [11010.273904] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [11012.888389] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [11014.782801] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [11017.103876] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [11020.811526] Lustre: lustre-MDT0000-mdc-ffff995f869f6000: Connection to lustre-MDT0000 (at 192.168.202.118@tcp) was lost; in progress operations using this service will wait for recovery to complete [11037.152481] Lustre: 215259:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786758614/real 1786758614] req@00000000c2865b12 x1873551886919040/t0(0) o400->MGC192.168.202.118@tcp@192.168.202.118@tcp:26/25 lens 224/224 e 0 to 1 dl 1786758630 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [11037.196731] LustreError: MGC192.168.202.118@tcp: Connection to MGS (at 192.168.202.118@tcp) was lost; in progress operations using this service will fail [11047.418761] Lustre: Evicted from MGS (at 192.168.202.118@tcp) after server handle changed from 0xe27e0b593acfb77f to 0xe27e0b593acfb8f9 [11047.457675] Lustre: MGC192.168.202.118@tcp: Connection restored to 192.168.202.118@tcp (at 192.168.202.118@tcp) [11056.511746] Lustre: lustre-MDT0000-mdc-ffff995f869f6000: Connection restored to 192.168.202.118@tcp (at 192.168.202.118@tcp) [11068.535973] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11070.703264] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [11074.501418] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [11078.135435] Lustre: lustre-MDT0000-mdc-ffff995f869f6000: Connection to lustre-MDT0000 (at 192.168.202.118@tcp) was lost; in progress operations using this service will wait for recovery to complete [11094.496095] Lustre: 215261:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786758671/real 1786758671] req@00000000078c865e x1873551886928512/t0(0) o400->MGC192.168.202.118@tcp@192.168.202.118@tcp:26/25 lens 224/224 e 0 to 1 dl 1786758687 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [11094.542281] LustreError: MGC192.168.202.118@tcp: Connection to MGS (at 192.168.202.118@tcp) was lost; in progress operations using this service will fail [11104.776891] Lustre: Evicted from MGS (at 192.168.202.118@tcp) after server handle changed from 0xe27e0b593acfb8f9 to 0xe27e0b593acfbc09 [11104.807475] Lustre: MGC192.168.202.118@tcp: Connection restored to 192.168.202.118@tcp (at 192.168.202.118@tcp) [11114.414671] Lustre: lustre-MDT0000-mdc-ffff995f869f6000: Connection restored to 192.168.202.118@tcp (at 192.168.202.118@tcp) [11126.504439] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11128.389664] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [11132.131309] Lustre: Unmounted lustre-client [11217.754654] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11224.885257] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11252.383752] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11258.501968] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [11260.035676] Lustre: Unmounted lustre-client [11293.596849] Key type lgssc unregistered [11293.945784] LNet: 225869:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11293.960866] LNetError: 225869:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11293.981968] LNet: Removed LNI 192.168.202.18@tcp [11294.892403] Key type .llcrypt unregistered [11294.900982] Key type ._llcrypt unregistered [11318.967662] Key type ._llcrypt registered [11318.969661] Key type .llcrypt registered [11320.123929] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 21:55:12 (1786758912) [11326.345661] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11326.435979] alg: No test for adler32 (adler32-zlib) [11327.680253] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [11327.936252] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [11329.639237] Key type lgssc registered [11330.657443] Lustre: Echo OBD driver; http://www.lustre.org/ [11384.098339] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11390.459705] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11414.926385] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11417.826412] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [11424.333807] Lustre: Unmounted lustre-client [11499.897964] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11505.972345] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11530.657784] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11531.776709] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [11542.437242] Lustre: Unmounted lustre-client [11623.739258] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11630.295453] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11653.023623] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11653.750920] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [11656.063057] Lustre: Unmounted lustre-client [11680.664869] Key type lgssc unregistered [11680.903473] LNet: 232944:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11680.936820] LNetError: 232944:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11680.970812] LNet: Removed LNI 192.168.202.18@tcp [11681.864720] Key type .llcrypt unregistered [11681.867748] Key type ._llcrypt unregistered [11703.202909] Key type ._llcrypt registered [11703.204579] Key type .llcrypt registered [11704.786789] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 22:01:36 (1786759296) [11708.845565] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11709.019192] alg: No test for adler32 (adler32-zlib) [11710.069252] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [11710.325686] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [11712.015233] Key type lgssc registered [11713.155439] Lustre: Echo OBD driver; http://www.lustre.org/ [11760.353552] Key type lgssc unregistered [11760.666885] LNet: 235272:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11760.671338] LNetError: 235272:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11760.689408] LNet: Removed LNI 192.168.202.18@tcp [11761.656775] Key type .llcrypt unregistered [11761.660083] Key type ._llcrypt unregistered [11794.956502] Key type ._llcrypt registered [11794.961620] Key type .llcrypt registered [11795.202304] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11795.216274] alg: No test for adler32 (adler32-zlib) [11796.509750] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [11796.863734] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [11798.615916] Key type lgssc registered [11799.872870] Lustre: Echo OBD driver; http://www.lustre.org/ [11890.538502] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11897.280844] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11925.686991] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11978.279165] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [11980.083422] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 22:06:11 (1786759571) [12018.892225] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12028.374587] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12055.741785] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12081.443938] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12083.787075] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [12088.856094] Lustre: lustre-OST0001-osc-ffff995f92d60000: Connection to lustre-OST0001 (at 192.168.202.118@tcp) was lost; in progress operations using this service will wait for recovery to complete [12099.415889] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12109.769804] Lustre: Unmounted lustre-client [12147.934558] Key type lgssc unregistered [12148.145079] LNet: 242399:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12148.153604] LNetError: 242399:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12148.170976] LNet: Removed LNI 192.168.202.18@tcp [12149.039145] Key type .llcrypt unregistered [12149.040309] Key type ._llcrypt unregistered [12183.106659] Key type ._llcrypt registered [12183.109784] Key type .llcrypt registered [12184.312609] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 22:09:36 (1786759776) [12199.126747] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12199.195333] alg: No test for adler32 (adler32-zlib) [12200.256742] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [12200.545902] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [12202.242536] Key type lgssc registered [12203.388892] Lustre: Echo OBD driver; http://www.lustre.org/ [12259.678577] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12266.300239] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12291.152344] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 22:11:22 (1786759882) [12326.210375] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12332.458561] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12355.048925] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12356.365906] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [12365.229738] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [12373.014467] Lustre: Unmounted lustre-client [12483.778778] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12489.773596] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12544.617811] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12545.448242] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [12548.247905] Lustre: Unmounted lustre-client [12577.173335] Key type lgssc unregistered [12577.599056] LNet: 250102:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12577.614197] LNetError: 250102:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12577.630780] LNet: Removed LNI 192.168.202.18@tcp [12578.321946] Key type .llcrypt unregistered [12578.324184] Key type ._llcrypt unregistered [12611.631120] Key type ._llcrypt registered [12611.639295] Key type .llcrypt registered [12612.557618] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12612.573251] alg: No test for adler32 (adler32-zlib) [12613.881763] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [12614.440831] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [12616.303293] Key type lgssc registered [12618.167228] Lustre: Echo OBD driver; http://www.lustre.org/ [12709.422810] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12715.153950] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12739.370904] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12786.921940] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [12789.895056] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 22:19:40 (1786760380) [12830.156902] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12836.690271] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12867.608342] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12873.300693] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [12877.554279] Lustre: Unmounted lustre-client [12910.710739] Key type lgssc unregistered [12911.195499] LNet: 256751:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12911.199538] LNetError: 256751:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12912.235772] LNet: Removed LNI 192.168.202.18@tcp [12913.620875] Key type .llcrypt unregistered [12913.623798] Key type ._llcrypt unregistered [12937.178028] Key type ._llcrypt registered [12937.181224] Key type .llcrypt registered [12939.105447] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [12941.846040] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 22:22:13 (1786760533) [12953.027518] Key type .llcrypt unregistered [12953.031672] Key type ._llcrypt unregistered [12964.399508] Key type ._llcrypt registered [12964.408964] Key type .llcrypt registered [12964.833425] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12964.846620] alg: No test for adler32 (adler32-zlib) [12965.980562] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [12966.222455] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [12967.911302] Key type lgssc registered [12969.395330] Lustre: Echo OBD driver; http://www.lustre.org/ [13026.690564] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13043.305532] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [13046.790181] Lustre: Unmounted lustre-client [13074.200201] Key type lgssc unregistered [13074.443195] LNet: 260218:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13074.454791] LNetError: 260218:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13074.494618] LNet: Removed LNI 192.168.202.18@tcp [13075.338657] Key type .llcrypt unregistered [13075.344108] Key type ._llcrypt unregistered [13095.145735] Key type ._llcrypt registered [13095.149803] Key type .llcrypt registered [13096.859563] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 22:24:48 (1786760688) [13098.794863] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13099.037785] alg: No test for adler32 (adler32-zlib) [13100.282712] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [13100.722383] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [13102.463244] Key type lgssc registered [13103.638701] Lustre: Echo OBD driver; http://www.lustre.org/ [13199.161762] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13233.572136] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13239.118285] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13241.880062] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [13243.594459] Lustre: Unmounted lustre-client [13268.366872] Key type lgssc unregistered [13268.628786] LNet: 263767:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13268.638189] LNetError: 263767:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13268.661708] LNet: Removed LNI 192.168.202.18@tcp [13269.574844] Key type .llcrypt unregistered [13269.578754] Key type ._llcrypt unregistered [13286.452272] Key type ._llcrypt registered [13286.453962] Key type .llcrypt registered [13288.416153] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 22:27:59 (1786760879) [13291.602746] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13291.670872] alg: No test for adler32 (adler32-zlib) [13292.817509] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [13293.044546] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [13294.831384] Key type lgssc registered [13296.513096] Lustre: Echo OBD driver; http://www.lustre.org/ [13353.191603] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13359.078599] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13384.947370] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13388.125913] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [13393.398174] Lustre: lustre-MDT0000-mdc-ffff995f908fd800: Connection to lustre-MDT0000 (at 192.168.202.118@tcp) was lost; in progress operations using this service will wait for recovery to complete [13395.702152] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [13395.724591] LustreError: 266928:0:(file.c:6156:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [13396.231936] LustreError: 266947:0:(file.c:6156:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [13396.245075] LustreError: 266947:0:(file.c:6156:ll_inode_revalidate_fini()) Skipped 5 previous similar messages [13396.288669] LustreError: MGC192.168.202.118@tcp: Connection to MGS (at 192.168.202.118@tcp) was lost; in progress operations using this service will fail [13396.353656] Lustre: Unmounted lustre-client [13419.528425] Key type lgssc unregistered [13419.715926] LNet: 267345:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13419.725318] LNetError: 267345:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13419.758351] LNet: Removed LNI 192.168.202.18@tcp [13420.387733] Key type .llcrypt unregistered [13420.394728] Key type ._llcrypt unregistered [13441.166892] Key type ._llcrypt registered [13441.170070] Key type .llcrypt registered [13442.691645] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 22:30:34 (1786761034) [13445.006073] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13445.108038] alg: No test for adler32 (adler32-zlib) [13446.355861] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [13446.586808] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [13448.311630] Key type lgssc registered [13449.600979] Lustre: Echo OBD driver; http://www.lustre.org/ [13515.895591] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13523.472742] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13548.673845] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13556.336808] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [13561.324723] Lustre: lustre-MDT0001-mdc-ffff995f87c7b800: Connection to lustre-MDT0001 (at 192.168.202.118@tcp) was lost; in progress operations using this service will wait for recovery to complete [13567.267967] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [13569.713253] Lustre: Unmounted lustre-client [13592.011660] Key type lgssc unregistered [13592.315136] LNet: 270935:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13592.319414] LNetError: 270935:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13593.383388] LNet: Removed LNI 192.168.202.18@tcp [13593.955918] Key type .llcrypt unregistered [13593.961968] Key type ._llcrypt unregistered [13609.966333] Key type ._llcrypt registered [13609.968221] Key type .llcrypt registered [13611.111055] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 22:33:23 (1786761203) [13631.484746] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13631.554086] alg: No test for adler32 (adler32-zlib) [13632.717227] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [13633.028082] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [13634.776611] Key type lgssc registered [13636.083948] Lustre: Echo OBD driver; http://www.lustre.org/ [13766.703411] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13769.539935] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [13816.303125] Lustre: lustre-MDT0001-mdc-ffff995fa0595000: Connection to lustre-MDT0001 (at 192.168.202.118@tcp) was lost; in progress operations using this service will wait for recovery to complete [13823.320090] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [13833.695975] Lustre: Unmounted lustre-client [13871.224883] Key type lgssc unregistered [13871.450862] LNet: 276731:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13871.465763] LNetError: 276731:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13871.490979] LNet: Removed LNI 192.168.202.18@tcp [13872.050661] Key type .llcrypt unregistered [13872.052947] Key type ._llcrypt unregistered [13891.488479] Key type ._llcrypt registered [13891.490099] Key type .llcrypt registered [13893.546326] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 22:38:04 (1786761484) [13895.033331] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [13897.124934] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 22:38:09 (1786761489) [13898.675722] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [13901.232763] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 22:38:12 (1786761492) [13903.408321] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [13904.847396] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 22:38:16 (1786761496) [13906.626364] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [13908.646325] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 22:38:20 (1786761500) [13910.652032] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [13913.129660] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 22:38:24 (1786761504) [13923.792453] Key type .llcrypt unregistered [13923.796893] Key type ._llcrypt unregistered [13932.308106] Key type ._llcrypt registered [13932.312257] Key type .llcrypt registered [13932.886064] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13932.914360] alg: No test for adler32 (adler32-zlib) [13934.090899] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [13934.425294] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [13936.207239] Key type lgssc registered [13937.600918] Lustre: Echo OBD driver; http://www.lustre.org/ [14012.626343] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14019.716675] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14051.218538] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14053.075626] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [14057.566746] Lustre: Unmounted lustre-client [14098.108757] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 22:41:30 (1786761690) [14134.782329] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14141.018707] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14177.459109] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14183.515192] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [14185.892588] Lustre: Unmounted lustre-client [14232.753246] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 22:43:44 (1786761824) [14236.511900] LNet: 1 local NIs in recovery (showing 1): 192.168.202.18@tcp [14887.738812] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14893.765883] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14934.137490] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15042.180914] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 22:57:14 (1786762634) [15056.241307] Key type lgssc unregistered [15056.612872] LNet: 290278:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15056.648741] LNetError: 290278:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15056.677252] LNet: Removed LNI 192.168.202.18@tcp [15057.937536] Key type .llcrypt unregistered [15057.940846] Key type ._llcrypt unregistered [15183.235961] Key type ._llcrypt registered [15183.241873] Key type .llcrypt registered [15183.743987] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15183.756216] alg: No test for adler32 (adler32-zlib) [15184.839200] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [15185.048862] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [15186.783379] Key type lgssc registered [15187.883178] Lustre: Echo OBD driver; http://www.lustre.org/ [15288.585985] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15295.730524] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15320.927136] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15356.755520] Key type lgssc unregistered [15356.964622] LNet: 294000:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15356.972102] LNetError: 294000:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15356.985034] LNet: Removed LNI 192.168.202.18@tcp [15357.847086] Key type .llcrypt unregistered [15357.850960] Key type ._llcrypt unregistered [15396.617642] Key type ._llcrypt registered [15396.620659] Key type .llcrypt registered [15398.705915] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 23:03:09 (1786762989) [15425.107314] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15425.345503] alg: No test for adler32 (adler32-zlib) [15426.352947] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [15426.547118] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [15428.223834] Key type lgssc registered [15430.012753] Lustre: Echo OBD driver; http://www.lustre.org/ [15491.547242] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 23:04:43 (1786763083) [15507.858839] Key type lgssc unregistered [15508.171772] LNet: 297561:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15508.183443] LNetError: 297561:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15508.248317] LNet: Removed LNI 192.168.202.18@tcp [15509.534513] Key type .llcrypt unregistered [15509.549093] Key type ._llcrypt unregistered [15523.350665] Key type ._llcrypt registered [15523.358512] Key type .llcrypt registered [15524.226585] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15524.245234] alg: No test for adler32 (adler32-zlib) [15525.515474] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [15525.828593] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [15527.639210] Key type lgssc registered [15529.362882] Lustre: Echo OBD driver; http://www.lustre.org/ [15597.664087] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15605.760704] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15634.247544] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15982.623778] LNet: Added LNI 192.168.202.18@tcp50 [8/256/0/180] [15985.280495] LNet: Removed LNI 192.168.202.18@tcp [15987.699935] Lustre: 301969:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff995f86a75000: config has no valid NIDs, force dynamic NIDs [15988.995685] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [15989.130004] Lustre: Unmounted lustre-client [15991.418904] Key type lgssc unregistered [15991.707669] LNet: 302232:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15991.713910] LNetError: 302232:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15991.729201] LNet: Removed LNI 192.168.202.18@tcp50 [15992.359048] Key type .llcrypt unregistered [15992.365281] Key type ._llcrypt unregistered [16013.257886] Key type ._llcrypt registered [16013.268378] Key type .llcrypt registered [16013.926451] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [16013.976193] alg: No test for adler32 (adler32-zlib) [16015.386328] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [16015.829462] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [16017.623504] Key type lgssc registered [16019.328220] Lustre: Echo OBD driver; http://www.lustre.org/ [16082.204190] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16089.308613] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16097.351255] LNet: Added LNI 192.168.202.18@tcp50 [8/256/0/180] [16098.233610] LNet: Removed LNI 192.168.202.18@tcp [16099.450958] Lustre: 304924:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff995f905cd800: config has no valid NIDs, force dynamic NIDs [16100.720853] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [16101.330958] Lustre: Unmounted lustre-client [16138.258695] Key type lgssc unregistered [16138.665996] LNet: 305797:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16138.674019] LNetError: 305797:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16138.691739] LNet: Removed LNI 192.168.202.18@tcp50 [16139.224392] Key type .llcrypt unregistered [16139.227477] Key type ._llcrypt unregistered [16153.195625] Key type ._llcrypt registered [16153.197646] Key type .llcrypt registered [16155.068563] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 23:15:46 (1786763746) [16175.819031] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16175.844357] alg: No test for adler32 (adler32-zlib) [16177.180546] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [16177.550202] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [16179.303216] Key type lgssc registered [16180.555155] Lustre: Echo OBD driver; http://www.lustre.org/ [16271.161279] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16278.346946] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16304.496530] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16348.276701] Key type lgssc unregistered [16348.610104] LNet: 310552:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16348.617908] LNetError: 310552:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16348.638755] LNet: Removed LNI 192.168.202.18@tcp [16349.406220] Key type .llcrypt unregistered [16349.408571] Key type ._llcrypt unregistered [16362.043980] Key type ._llcrypt registered [16362.046855] Key type .llcrypt registered [16363.788812] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 23:19:15 (1786763955) [16366.480092] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16366.491152] alg: No test for adler32 (adler32-zlib) [16367.674176] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [16368.061802] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [16369.759196] Key type lgssc registered [16370.985857] Lustre: Echo OBD driver; http://www.lustre.org/ [16425.735678] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16431.141289] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16453.861928] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16456.592864] Lustre: Mounted lustre-client - version 2.17.57_1_g6e92ec5 [16526.660414] Lustre: Mounted fs15246-client - version 2.17.57_1_g6e92ec5 [16541.505216] Lustre: Unmounted fs15246-client [16568.975881] Lustre: Unmounted lustre-client [16606.793733] Key type lgssc unregistered [16607.098493] LNet: 315423:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16607.106896] LNetError: 315423:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16607.133201] LNet: Removed LNI 192.168.202.18@tcp [16607.752960] Key type .llcrypt unregistered [16607.760486] Key type ._llcrypt unregistered [16624.083187] Key type ._llcrypt registered [16624.088838] Key type .llcrypt registered [16626.436441] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 23:23:37 (1786764217) [16629.922729] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16629.963317] alg: No test for adler32 (adler32-zlib) [16631.056969] Lustre: Lustre: Build Version: 2.17.57_1_g6e92ec5 [16631.245747] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [16632.895308] Key type lgssc registered [16634.195722] Lustre: Echo OBD driver; http://www.lustre.org/ [16796.452790] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16802.624662] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16828.815871] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16935.561419] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 16603 sec ========= 23:28:47 (1786764527) [16937.202822] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 23:28:49 (1786764529) === [16940.204727] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 23:28:52 (1786764532) === [16976.393412] Key type lgssc unregistered [16976.788575] LNet: 324694:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16976.795869] LNetError: 324694:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16976.835990] LNet: Removed LNI 192.168.202.18@tcp [16977.735981] Key type .llcrypt unregistered [16977.738354] Key type ._llcrypt unregistered