[ 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 416492864 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524584K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001012] APIC: Switch to symmetric I/O mode setup [ 0.003396] x2apic enabled [ 0.004010] Switched APIC routing to physical x2apic. [ 0.005015] kvm-guest: setup PV IPIs [ 0.007816] ..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.008023] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009017] pid_max: default: 32768 minimum: 301 [ 0.010143] LSM: Security Framework initializing [ 0.011064] Yama: becoming mindful. [ 0.012044] SELinux: Initializing. [ 0.013079] *** VALIDATE selinux *** [ 0.021585] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.025746] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.026150] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027116] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028139] *** VALIDATE tmpfs *** [ 0.030180] *** VALIDATE proc *** [ 0.031253] *** VALIDATE cgroup *** [ 0.032009] *** VALIDATE cgroup2 *** [ 0.033283] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.034175] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.035010] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.036033] Spectre V2 : User space: Vulnerable [ 0.037010] Speculative Store Bypass: Vulnerable [ 0.040463] debug: unmapping init [mem 0xffffffffafc59000-0xffffffffafc60fff] [ 0.043000] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.043759] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.044027] ... version: 2 [ 0.045012] ... bit width: 48 [ 0.046014] ... generic registers: 4 [ 0.047013] ... value mask: 0000ffffffffffff [ 0.048014] ... max period: 00007fffffffffff [ 0.049012] ... fixed-purpose events: 3 [ 0.049773] ... event mask: 000000070000000f [ 0.050298] rcu: Hierarchical SRCU implementation. [ 0.052467] smp: Bringing up secondary CPUs ... [ 0.053580] x86: Booting SMP configuration: [ 0.054023] .... node #0, CPUs: #1 #2 #3 [ 0.057454] smp: Brought up 1 node, 4 CPUs [ 0.059014] smpboot: Max logical packages: 1 [ 0.060016] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.144339] node 0 deferred pages initialised in 82ms [ 0.146125] devtmpfs: initialized [ 0.147276] x86/mm: Memory block size: 128MB [ 0.151086] gcov: version magic: 0x41383552 [ 0.154284] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.155078] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.156272] pinctrl core: initialized pinctrl subsystem [ 0.157213] [ 0.157735] ************************************************************* [ 0.158012] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.159010] ** ** [ 0.160012] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.161013] ** ** [ 0.162013] ** This means that this kernel is built to expose internal ** [ 0.163010] ** IOMMU data structures, which may compromise security on ** [ 0.164013] ** your system. ** [ 0.165021] ** ** [ 0.166016] ** If you see this message and you are not debugging the ** [ 0.167015] ** kernel, report this immediately to your vendor! ** [ 0.168016] ** ** [ 0.169015] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.170015] ************************************************************* [ 0.171678] NET: Registered protocol family 16 [ 0.172480] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.173061] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.174067] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.175502] cpuidle: using governor menu [ 0.177884] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.180469] PCI: Using configuration type 1 for base access [ 0.182130] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.190052] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.191020] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.195070] cryptd: max_cpu_qlen set to 1000 [ 0.198229] ACPI: Added _OSI(Module Device) [ 0.199000] ACPI: Added _OSI(Processor Device) [ 0.199000] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.200015] ACPI: Added _OSI(Processor Aggregator Device) [ 0.205159] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.211544] ACPI: Interpreter enabled [ 0.212052] ACPI: PM: (supports S0 S3 S4 S5) [ 0.212909] ACPI: Using IOAPIC for interrupt routing [ 0.213103] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.214382] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.222092] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.225041] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.227026] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.229082] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.232060] acpiphp: Slot [2] registered [ 0.233021] acpiphp: Slot [5] registered [ 0.233896] acpiphp: Slot [6] registered [ 0.235122] acpiphp: Slot [3] registered [ 0.236101] acpiphp: Slot [4] registered [ 0.237114] acpiphp: Slot [7] registered [ 0.239092] acpiphp: Slot [8] registered [ 0.240108] acpiphp: Slot [9] registered [ 0.241106] acpiphp: Slot [10] registered [ 0.243134] acpiphp: Slot [11] registered [ 0.244107] acpiphp: Slot [12] registered [ 0.246105] acpiphp: Slot [13] registered [ 0.247067] acpiphp: Slot [14] registered [ 0.248087] acpiphp: Slot [15] registered [ 0.249109] acpiphp: Slot [16] registered [ 0.251091] acpiphp: Slot [17] registered [ 0.252010] acpiphp: Slot [18] registered [ 0.252892] acpiphp: Slot [19] registered [ 0.253085] acpiphp: Slot [20] registered [ 0.254122] acpiphp: Slot [21] registered [ 0.256102] acpiphp: Slot [22] registered [ 0.257117] acpiphp: Slot [23] registered [ 0.259150] acpiphp: Slot [24] registered [ 0.260115] acpiphp: Slot [25] registered [ 0.262104] acpiphp: Slot [26] registered [ 0.264107] acpiphp: Slot [27] registered [ 0.265103] acpiphp: Slot [28] registered [ 0.266112] acpiphp: Slot [29] registered [ 0.267142] acpiphp: Slot [30] registered [ 0.269139] acpiphp: Slot [31] registered [ 0.270070] PCI host bridge to bus 0000:00 [ 0.272028] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.274025] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.276021] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.278019] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.279016] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.281024] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.282159] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.284464] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.285796] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.290748] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.293482] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.295015] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.296013] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.298014] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.299449] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.301453] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.303033] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.304635] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.307012] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.312672] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.315713] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.320547] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.327013] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.338016] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.360014] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.370467] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.378013] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.384017] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.400024] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.409716] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.411280] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.412227] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.413218] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.415141] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.419248] iommu: Default domain type: Passthrough [ 0.420343] SCSI subsystem initialized [ 0.421087] ACPI: bus type USB registered [ 0.421903] usbcore: registered new interface driver usbfs [ 0.423060] usbcore: registered new interface driver hub [ 0.424049] usbcore: registered new device driver usb [ 0.425047] pps_core: LinuxPPS API ver. 1 registered [ 0.426006] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.428061] PTP clock support registered [ 0.429108] EDAC MC: Ver: 3.0.0 [ 0.431173] PCI: Using ACPI for IRQ routing [ 0.432724] NetLabel: Initializing [ 0.433011] NetLabel: domain hash size = 128 [ 0.434010] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.435098] NetLabel: unlabeled traffic allowed by default [ 0.437073] vgaarb: loaded [ 0.438284] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.439010] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.444126] clocksource: Switched to clocksource kvm-clock [ 0.534449] VFS: Disk quotas dquot_6.6.0 [ 0.535882] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.538436] *** VALIDATE ramfs *** [ 0.539681] *** VALIDATE hugetlbfs *** [ 0.540760] pnp: PnP ACPI init [ 0.543203] pnp: PnP ACPI: found 6 devices [ 0.558150] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.560319] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.561737] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.563055] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.564594] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.566092] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.567852] NET: Registered protocol family 2 [ 0.569667] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.573064] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.577280] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.582427] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.585343] TCP: Hash tables configured (established 65536 bind 65536) [ 0.588077] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.590654] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.593407] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.596284] NET: Registered protocol family 1 [ 0.598469] RPC: Registered named UNIX socket transport module. [ 0.600616] RPC: Registered udp transport module. [ 0.602152] RPC: Registered tcp transport module. [ 0.603603] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.605660] NET: Registered protocol family 44 [ 0.607388] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.609444] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.611160] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.613362] PCI: CLS 0 bytes, default 64 [ 0.615250] Unpacking initramfs... [ 2.014659] debug: unmapping init [mem 0xffff8a4dbcc64000-0xffff8a4dbffcffff] [ 2.018578] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.020793] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.023653] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.509212] Initialise system trusted keyrings [ 2.510535] Key type blacklist registered [ 2.512361] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.518911] zbud: loaded [ 2.521467] *** VALIDATE nfs *** [ 2.522534] *** VALIDATE nfs4 *** [ 2.523944] pstore: using deflate compression [ 2.527432] Platform Keyring initialized [ 2.630877] NET: Registered protocol family 38 [ 2.632239] Key type asymmetric registered [ 2.633386] Asymmetric key parser 'x509' registered [ 2.635246] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.639267] io scheduler mq-deadline registered [ 2.640891] io scheduler kyber registered [ 2.642604] io scheduler bfq registered [ 2.644324] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.646392] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.649277] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.651218] ACPI: Power Button [PWRF] [ 2.656402] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.661954] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.670161] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.700630] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.734686] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.739163] Non-volatile memory driver v1.3 [ 2.740663] Linux agpgart interface v0.103 [ 2.767526] virtio_blk virtio1: [vda] 146152 512-byte logical blocks (74.8 MB/71.4 MiB) [ 2.769434] vda: detected capacity change from 0 to 74829824 [ 2.781746] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.784319] vdb: detected capacity change from 0 to 1073741824 [ 2.793160] libphy: Fixed MDIO Bus: probed [ 2.797726] usbcore: registered new interface driver usbserial_generic [ 2.800300] usbserial: USB Serial support registered for generic [ 2.802253] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.805833] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.807595] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.809851] mousedev: PS/2 mouse device common for all mice [ 2.811828] rtc_cmos 00:05: RTC can wake from S4 [ 2.816117] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.816339] rtc_cmos 00:05: registered as rtc0 [ 2.819514] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.821206] intel_pstate: CPU model not supported [ 2.823270] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.825736] hid: raw HID events driver (C) Jiri Kosina [ 2.827693] usbcore: registered new interface driver usbhid [ 2.829536] usbhid: USB HID core driver [ 2.829714] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.830626] drop_monitor: Initializing network drop monitor service [ 2.834038] Initializing XFRM netlink socket [ 2.835076] NET: Registered protocol family 10 [ 2.837503] Segment Routing with IPv6 [ 2.838913] NET: Registered protocol family 17 [ 2.840888] mpls_gso: MPLS GSO support [ 2.844172] RAS: Correctable Errors collector initialized. [ 2.845855] AVX version of gcm_enc/dec engaged. [ 2.847093] AES CTR mode by8 optimization enabled [ 2.900543] sched_clock: Marking stable (2900525458, 0)->(3813916625, -913391167) [ 2.903596] registered taskstats version 1 [ 2.905393] Loading compiled-in X.509 certificates [ 2.907248] zswap: loaded using pool lzo/zbud [ 2.929545] Key type big_key registered [ 2.939719] Key type encrypted registered [ 2.940714] ima: No TPM chip found, activating TPM-bypass! [ 2.942498] ima: Allocated hash algorithm: sha1 [ 2.944070] ima: No architecture policies found [ 2.945631] evm: Initialising EVM extended attributes: [ 2.947101] evm: security.selinux [ 2.948241] evm: security.ima [ 2.949337] evm: security.capability [ 2.950579] evm: HMAC attrs: 0x1 [ 2.952906] rtc_cmos 00:05: setting system clock to 2026-08-24 13:52:44 UTC (1787579564) [ 2.959345] debug: unmapping init [mem 0xffffffffb0c03000-0xffffffffb0dfffff] [ 2.962530] debug: unmapping init [mem 0xffffffffaf982000-0xffffffffafc58fff] [ 2.970158] Write protecting the kernel read-only data: 28672k [ 2.973311] debug: unmapping init [mem 0xffffffffae003000-0xffffffffae1fffff] [ 2.975853] debug: unmapping init [mem 0xffffffffae914000-0xffffffffae9fffff] [ 3.006792] 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.013289] systemd[1]: Detected virtualization kvm. [ 3.014940] systemd[1]: Detected architecture x86-64. [ 3.016505] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.043818] systemd[1]: No hostname configured. [ 3.044978] systemd[1]: Set hostname to . [ 3.046329] random: systemd: uninitialized urandom read (16 bytes read) [ 3.047887] systemd[1]: Initializing machine ID from random generator. [ 3.179511] random: systemd: uninitialized urandom read (16 bytes read) [ 3.182257] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 3.186085] random: systemd: uninitialized urandom read (16 bytes read) [ 3.187499] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 3.190670] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Timers. [ OK ] Listening on Journal Socket. [ OK ] Started Memstrack Anylazing Service. Starting Create Volatile Files and Directories... Starting Setup Virtual Console... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Slices. Starting Apply Kernel Variables... [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 3.767686] device-mapper: uevent: version 1.0.3 [ 3.769326] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 4.419489] virtio_net virtio0 ens2: renamed from eth0 [ 4.441635] scsi host0: ata_piix [ 4.502090] scsi host1: ata_piix [ 4.503565] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.505859] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.756322] dracut-initqueue[586]: RTNETLINK answers: File exists [ 9.455614] random: crng init done [ 9.456980] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 9.688594] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped target Local File Systems. Stopping udev Kernel Device Manager... [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Sockets. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 10.673201] printk: systemd: 26 output lines suppressed due to ratelimiting [ 10.990211] SELinux: Disabled at runtime. [ 11.047956] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 11.058070] systemd[1]: Detected virtualization kvm. [ 11.059792] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 11.459223] systemd[1]: initrd-switch-root.service: Succeeded. [ 11.462054] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 11.466535] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 11.470974] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 11.473439] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 11.479483] systemd[1]: Starting Journal Service... Starting Journal Service... [ 11.483086] systemd[1]: Created slice User and Session Slice. [ OK ] Created slice User and Session Slice. [ OK ] Listening on udev Control Socket. [ OK ] Created slice system-getty.slice. Mounting Huge Pages File System... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Reached target Slices. [ OK ] Listening on initctl Compatibility Named Pipe. Activating swap /dev/disk/by-label/SWAP... Starting Remount Root and Kernel File Systems... [ OK ] Reached target rpc_pipefs.target. [ OK ] Reached target RPC Port Mapper. [ OK ] Created slice system-sshd\x2dkeygen.slice. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Started Forward Password Requests to Wall Directory Watch. Mounting Kernel Debug File System... [ 11.553344] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Listening on Process Core Dump Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. Mounting POSIX Message Queue File System... Starting Apply Kernel Variables... [ OK ] Stopped target Initrd Root File System. [ OK ] Started Journal Service. [ OK ] Mounted Huge Pages File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Kernel Debug File System. [ 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... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ OK ] Started udev Coldplug all Devices. [ 11.883170] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 12.125457] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 12.140144] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 12.250589] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 12.263408] EDAC sbridge: Ver: 1.1.2 [ 13.185551] Key type dns_resolver registered [ 13.506377] NFS: Registering the id_resolver key type [ 13.508285] Key type id_resolver registered [ 13.509807] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started dnf makecache --timer. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started irqbalance daemon. Starting Login Service... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Reached target sshd-keygen.target. Starting Restore /run/initramfs on shutdown... [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Started Login Service. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... [ OK ] Started OpenSSH server daemon. Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting System Logging Service... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg136-client login: [ 74.953112] libcfs: loading out-of-tree module taints kernel. [ 75.047451] Key type ._llcrypt registered [ 75.049320] Key type .llcrypt registered [ 75.374527] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 75.402734] alg: No test for adler32 (adler32-zlib) [ 77.337067] hrtimer: interrupt took 7047630 ns [ 77.348530] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [ 78.027253] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [ 79.736270] Key type lgssc registered [ 82.555203] Lustre: Echo OBD driver; http://www.lustre.org/ [ 279.219363] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 284.650562] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 298.407812] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing check_logdir /tmp/testlogs/ [ 303.630250] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing yml_node [ 305.132317] Lustre: lustre-OST0000-osc-ffff8a4e07f4d000: disconnect after 23s idle [ 308.815752] Lustre: DEBUG MARKER: Client: 2.17.57.80 [ 310.931525] Lustre: DEBUG MARKER: MDS: 2.17.57.80 [ 314.589596] Lustre: DEBUG MARKER: OSS: 2.17.57.80 [ 316.822183] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Mon Aug 24 09:57:56 EDT 2026 [ 339.885553] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 341.712940] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 344.302961] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 354.661730] Lustre: Unmounted lustre-client [ 470.118342] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 475.948900] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 503.286480] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 544.509529] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 10:01:44 (1787580104) [ 547.196448] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 548.782713] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 10:01:49 (1787580109) [ 583.853810] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 590.370903] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 613.147854] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 613.978838] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 622.089590] Lustre: Unmounted lustre-client [ 652.499989] Key type lgssc unregistered [ 652.876929] LNet: 15058:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 652.886807] LNetError: 15058:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 652.912951] LNet: Removed LNI 192.168.201.36@tcp [ 653.562180] Key type .llcrypt unregistered [ 653.564380] Key type ._llcrypt unregistered [ 668.754878] Key type ._llcrypt registered [ 668.758500] Key type .llcrypt registered [ 670.288256] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 10:03:50 (1787580230) [ 672.332480] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 672.351507] alg: No test for adler32 (adler32-zlib) [ 673.452300] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [ 673.838550] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [ 675.553597] Key type lgssc registered [ 677.012420] Lustre: Echo OBD driver; http://www.lustre.org/ [ 760.106544] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 847.570771] Lustre: Unmounted lustre-client [ 878.000423] Key type lgssc unregistered [ 878.285155] LNet: 18909:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 878.301890] LNetError: 18909:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 878.321091] LNet: Removed LNI 192.168.201.36@tcp [ 879.257262] Key type .llcrypt unregistered [ 879.259599] Key type ._llcrypt unregistered [ 890.036342] Key type ._llcrypt registered [ 890.039490] Key type .llcrypt registered [ 891.534283] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 10:07:31 (1787580451) [ 893.829715] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 893.876371] alg: No test for adler32 (adler32-zlib) [ 895.051375] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [ 895.431311] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [ 897.184252] Key type lgssc registered [ 898.750018] Lustre: Echo OBD driver; http://www.lustre.org/ [ 960.806859] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 968.136337] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 994.039210] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 997.026234] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 1009.612183] Lustre: Unmounted lustre-client [ 1038.487509] Key type lgssc unregistered [ 1038.934101] LNet: 22516:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1038.947148] LNetError: 22516:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1038.964426] LNet: Removed LNI 192.168.201.36@tcp [ 1039.719927] Key type .llcrypt unregistered [ 1039.722681] Key type ._llcrypt unregistered [ 1050.860575] Key type ._llcrypt registered [ 1050.863786] Key type .llcrypt registered [ 1052.217950] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 10:10:12 (1787580612) [ 1055.080247] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1055.195401] alg: No test for adler32 (adler32-zlib) [ 1056.431571] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [ 1056.636429] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [ 1058.320206] Key type lgssc registered [ 1059.358247] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1106.188514] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1112.223568] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1136.351794] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1139.516389] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 1145.982871] Lustre: Unmounted lustre-client [ 1176.349126] Key type lgssc unregistered [ 1176.651922] LNet: 25708:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1176.662188] LNetError: 25708:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1176.696871] LNet: Removed LNI 192.168.201.36@tcp [ 1177.485398] Key type .llcrypt unregistered [ 1177.487722] Key type ._llcrypt unregistered [ 1192.461956] Key type ._llcrypt registered [ 1192.469333] Key type .llcrypt registered [ 1193.977394] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 10:12:33 (1787580753) [ 1196.142291] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1196.323492] alg: No test for adler32 (adler32-zlib) [ 1197.379609] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [ 1197.598274] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [ 1199.280383] Key type lgssc registered [ 1200.764429] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1252.003559] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1258.823752] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1281.352382] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1283.353466] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 1293.804661] Lustre: lustre-MDT0000-mdc-ffff8a4e203c5000: Connection to lustre-MDT0000 (at 192.168.201.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1304.038560] Lustre: lustre-MDT0001-mdc-ffff8a4e203c5000: Connection to lustre-MDT0001 (at 192.168.201.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1311.030555] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1315.297625] Lustre: 26667:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787580860/real 1787580860] req@00000000209ec743 x1874414281955328/t0(0) o400->MGC192.168.201.136@tcp@192.168.201.136@tcp:26/25 lens 224/224 e 0 to 1 dl 1787580876 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1315.328086] LustreError: MGC192.168.201.136@tcp: Connection to MGS (at 192.168.201.136@tcp) was lost; in progress operations using this service will fail [ 1321.415411] Lustre: Unmounted lustre-client [ 1336.175344] Key type lgssc unregistered [ 1336.585432] LNet: 29372:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1336.604417] LNetError: 29372:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1336.650469] LNet: Removed LNI 192.168.201.36@tcp [ 1337.386635] Key type .llcrypt unregistered [ 1337.389650] Key type ._llcrypt unregistered [ 1354.386783] Key type ._llcrypt registered [ 1354.388340] Key type .llcrypt registered [ 1355.571345] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 10:15:15 (1787580915) [ 1357.884337] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1357.935966] alg: No test for adler32 (adler32-zlib) [ 1359.001461] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [ 1359.207109] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [ 1360.881104] Key type lgssc registered [ 1362.002085] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1416.805337] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1423.205202] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1446.013783] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1448.851994] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 1454.071794] Lustre: lustre-OST0000-osc-ffff8a4e0816a000: Connection to lustre-OST0000 (at 192.168.201.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1472.129850] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1482.492506] Lustre: Unmounted lustre-client [ 1512.951964] Key type lgssc unregistered [ 1513.178839] LNet: 32993:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1513.186564] LNetError: 32993:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1513.202101] LNet: Removed LNI 192.168.201.36@tcp [ 1513.901469] Key type .llcrypt unregistered [ 1513.904620] Key type ._llcrypt unregistered [ 1529.213828] Key type ._llcrypt registered [ 1529.215689] Key type .llcrypt registered [ 1530.635904] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 10:18:10 (1787581090) [ 1533.552523] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1533.587690] alg: No test for adler32 (adler32-zlib) [ 1534.816441] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [ 1535.190684] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [ 1536.960940] Key type lgssc registered [ 1538.431360] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1595.681206] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1602.780524] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1626.331655] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1637.445602] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 1641.663612] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1645.803595] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1657.994432] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-08-2410h20m17s [ 1660.017935] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1662.969056] Lustre: lustre-MDT0000-mdc-ffff8a4e06017800: Connection to lustre-MDT0000 (at 192.168.201.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1670.425665] LustreError: 36785:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff8a4e06017800: can't stat MDS #0: rc = -19 [ 1671.793277] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1680.369742] LustreError: MGC192.168.201.136@tcp: Connection to MGS (at 192.168.201.136@tcp) was lost; in progress operations using this service will fail [ 1680.388817] LustreError: lustre-MDT0000-mdc-ffff8a4e06017800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1680.403674] Lustre: Evicted from MGS (at 192.168.201.136@tcp) after server handle changed from 0x18a8e47485eb2d9f to 0x18a8e47485eb3134 [ 1680.414702] Lustre: lustre-MDT0000-mdc-ffff8a4e06017800: Connection restored to 192.168.201.136@tcp (at 192.168.201.136@tcp) [ 1680.437816] Lustre: Skipped 1 previous similar message [ 1689.261253] Lustre: DEBUG MARKER: Wait for df (36785) ... [ 1690.886761] Lustre: DEBUG MARKER: done [ 1692.628424] Lustre: Unmounted lustre-client [ 1721.772760] Key type lgssc unregistered [ 1722.139625] LNet: 37806:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1722.159074] LNetError: 37806:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1722.186465] LNet: Removed LNI 192.168.201.36@tcp [ 1723.097345] Key type .llcrypt unregistered [ 1723.099520] Key type ._llcrypt unregistered [ 1759.302828] Key type ._llcrypt registered [ 1759.305707] Key type .llcrypt registered [ 1761.023432] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 10:22:00 (1787581320) [ 1763.793310] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1763.990831] alg: No test for adler32 (adler32-zlib) [ 1765.192933] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [ 1765.582750] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [ 1767.280229] Key type lgssc registered [ 1769.029595] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1832.937483] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1841.262785] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1876.377367] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1878.984326] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 1886.113445] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-08-2410h24m05s [ 1889.392538] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1898.395766] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1901.320080] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1921.504171] Lustre: 41953:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787581462/real 1787581462] req@00000000370059a3 x1874414877547648/t0(0) o101->lustre-MDT0000-mdc-ffff8a4e18689800@192.168.201.136@tcp:12/10 lens 576/1152 e 0 to 1 dl 1787581482 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1921.539554] Lustre: lustre-MDT0000-mdc-ffff8a4e18689800: Connection to lustre-MDT0000 (at 192.168.201.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1921.576223] Lustre: lustre-MDT0000-mdc-ffff8a4e18689800: Connection restored to 192.168.201.136@tcp (at 192.168.201.136@tcp) [ 1943.090231] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1946.186676] Lustre: DEBUG MARKER: done [ 1950.916893] Lustre: Unmounted lustre-client [ 1980.792356] Key type lgssc unregistered [ 1981.173470] LNet: 42843:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1981.184250] LNetError: 42843:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1981.217217] LNet: Removed LNI 192.168.201.36@tcp [ 1982.287654] Key type .llcrypt unregistered [ 1982.295075] Key type ._llcrypt unregistered [ 2017.205180] Key type ._llcrypt registered [ 2017.207586] Key type .llcrypt registered [ 2018.869217] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 10:26:18 (1787581578) [ 2020.694618] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 2022.367688] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 10:26:22 (1787581582) [ 2026.965450] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2027.007511] alg: No test for adler32 (adler32-zlib) [ 2028.156084] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [ 2028.370971] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [ 2030.056207] Key type lgssc registered [ 2031.309512] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2061.875705] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 10:27:01 (1787581621) [ 2099.083323] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2108.118559] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2137.039890] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2140.241854] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 2145.710504] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 2150.593912] Lustre: Unmounted lustre-client [ 2176.688110] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 2230.012261] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2238.388919] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2239.211460] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 2243.751963] Lustre: Unmounted lustre-client [ 2310.784631] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2317.810823] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2318.751766] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 2322.361240] Lustre: Unmounted lustre-client [ 2347.233421] Lustre: DEBUG MARKER: files compared the same [ 2370.703336] Key type lgssc unregistered [ 2371.006086] LNet: 50480:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2371.009751] LNetError: 50480:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2371.023593] LNet: Removed LNI 192.168.201.36@tcp [ 2371.762586] Key type .llcrypt unregistered [ 2371.764880] Key type ._llcrypt unregistered [ 2390.438300] Key type ._llcrypt registered [ 2390.441203] Key type .llcrypt registered [ 2391.642406] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 10:32:31 (1787581951) [ 2394.285033] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2394.332523] alg: No test for adler32 (adler32-zlib) [ 2395.494558] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [ 2395.653028] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [ 2397.360310] Key type lgssc registered [ 2398.970576] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2466.275850] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2473.882619] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2499.494029] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2505.282901] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 2510.148957] Lustre: Unmounted lustre-client [ 2540.306514] Key type lgssc unregistered [ 2540.648442] LNet: 54071:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2540.666970] LNetError: 54071:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2540.687855] LNet: Removed LNI 192.168.201.36@tcp [ 2541.587450] Key type .llcrypt unregistered [ 2541.591079] Key type ._llcrypt unregistered [ 2562.472250] Key type ._llcrypt registered [ 2562.473761] Key type .llcrypt registered [ 2563.988800] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 10:35:23 (1787582123) [ 2567.091870] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2567.121312] alg: No test for adler32 (adler32-zlib) [ 2568.197289] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [ 2568.464289] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [ 2570.161714] Key type lgssc registered [ 2571.668132] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2656.726820] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2693.011776] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2699.804966] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2729.199656] Key type lgssc unregistered [ 2729.389536] LNet: 57566:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2729.394552] LNetError: 57566:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2729.415538] LNet: Removed LNI 192.168.201.36@tcp [ 2730.154296] Key type .llcrypt unregistered [ 2730.157168] Key type ._llcrypt unregistered [ 2745.371691] Key type ._llcrypt registered [ 2745.374313] Key type .llcrypt registered [ 2746.633275] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 10:38:26 (1787582306) [ 2750.276460] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2750.370109] alg: No test for adler32 (adler32-zlib) [ 2751.515859] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [ 2751.817778] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [ 2753.512316] Key type lgssc registered [ 2754.657942] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2830.336310] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 2837.581203] Lustre: Unmounted lustre-client [ 2875.556444] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 10:40:35 (1787582435) [ 3010.818337] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3030.021058] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 3037.980307] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3037.990802] LustreError: 64051:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff8a4e0384b800: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 3038.036537] Lustre: 58551:0:(llite_lib.c:4342:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.201.136@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 3048.397167] Lustre: Unmounted lustre-client [ 3096.017399] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 10:44:15 (1787582655) [ 3109.653377] Key type lgssc unregistered [ 3109.961402] LNet: 65267:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3109.974673] LNetError: 65267:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3110.006376] LNet: Removed LNI 192.168.201.36@tcp [ 3111.315542] Key type .llcrypt unregistered [ 3111.318763] Key type ._llcrypt unregistered [ 3124.750573] Key type ._llcrypt registered [ 3124.762344] Key type .llcrypt registered [ 3125.378244] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3125.419485] alg: No test for adler32 (adler32-zlib) [ 3126.625889] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [ 3126.984325] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [ 3128.720891] Key type lgssc registered [ 3130.118163] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3191.322419] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3197.890137] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3223.384462] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3234.945150] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 3239.965349] Lustre: Unmounted lustre-client [ 3269.342072] Key type lgssc unregistered [ 3269.707187] LNet: 68287:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3269.716395] LNetError: 68287:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3269.748674] LNet: Removed LNI 192.168.201.36@tcp [ 3270.608726] Key type .llcrypt unregistered [ 3270.613306] Key type ._llcrypt unregistered [ 3283.661669] Key type ._llcrypt registered [ 3283.663811] Key type .llcrypt registered [ 3284.329378] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3284.344813] alg: No test for adler32 (adler32-zlib) [ 3285.606468] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [ 3285.973499] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [ 3287.768284] Key type lgssc registered [ 3289.034726] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3410.429539] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3417.411518] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3443.646431] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3449.503340] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 3453.998155] Lustre: Unmounted lustre-client [ 3484.156983] Key type lgssc unregistered [ 3484.554984] LNet: 71728:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3484.566040] LNetError: 71728:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3484.578089] LNet: Removed LNI 192.168.201.36@tcp [ 3485.228615] Key type .llcrypt unregistered [ 3485.232678] Key type ._llcrypt unregistered [ 3500.533937] Key type ._llcrypt registered [ 3500.538563] Key type .llcrypt registered [ 3502.033327] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 10:51:01 (1787583061) [ 3505.027563] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3505.053402] alg: No test for adler32 (adler32-zlib) [ 3506.228541] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [ 3506.448162] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [ 3508.226647] Key type lgssc registered [ 3509.986381] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3556.587083] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3561.987244] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3583.844299] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3586.642627] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 3592.438713] Lustre: Unmounted lustre-client [ 3592.946282] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 3596.402207] Lustre: Unmounted lustre-client [ 3627.380679] Key type lgssc unregistered [ 3627.756067] LNet: 75428:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3627.769542] LNetError: 75428:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3627.806177] LNet: Removed LNI 192.168.201.36@tcp [ 3628.949367] Key type .llcrypt unregistered [ 3628.954629] Key type ._llcrypt unregistered [ 3646.184479] Key type ._llcrypt registered [ 3646.192420] Key type .llcrypt registered [ 3647.592983] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3647.645288] alg: No test for adler32 (adler32-zlib) [ 3649.054775] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [ 3649.478353] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [ 3651.137600] Key type lgssc registered [ 3654.016828] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3711.315958] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3718.694654] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3742.751051] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3745.717738] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 3751.011953] Lustre: Unmounted lustre-client [ 3781.164185] Key type lgssc unregistered [ 3781.564404] LNet: 78474:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3781.571694] LNetError: 78474:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3781.599644] LNet: Removed LNI 192.168.201.36@tcp [ 3782.241649] Key type .llcrypt unregistered [ 3782.246757] Key type ._llcrypt unregistered [ 3798.648376] Key type ._llcrypt registered [ 3798.652433] Key type .llcrypt registered [ 3800.253703] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 10:55:59 (1787583359) [ 3803.094727] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3803.113198] alg: No test for adler32 (adler32-zlib) [ 3804.358274] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [ 3804.686221] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [ 3806.448631] Key type lgssc registered [ 3807.810736] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3860.609116] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3867.229636] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3891.122392] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3892.926511] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 3905.110259] Lustre: lustre: root_squash is set to 0:0 [ 3905.143241] Lustre: lustre: nosquash_nids is cleared [ 3913.265749] Lustre: lustre: root_squash is set to 500:500 [ 3922.022988] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.201.36@tcp 192.168.0.[2,10]@tcp [ 3930.119795] Lustre: lustre: nosquash_nids set to 192.168.201.36/24@tcp [ 3936.214236] Lustre: Unmounted lustre-client [ 3965.991393] Key type lgssc unregistered [ 3966.324983] LNet: 82411:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3966.338242] LNetError: 82411:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3966.360695] LNet: Removed LNI 192.168.201.36@tcp [ 3967.238188] Key type .llcrypt unregistered [ 3967.241713] Key type ._llcrypt unregistered [ 3984.178269] Key type ._llcrypt registered [ 3984.183121] Key type .llcrypt registered [ 3985.696434] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 10:59:05 (1787583545) [ 3989.321554] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3991.111705] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 10:59:11 (1787583551) [ 3994.039527] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3994.063117] alg: No test for adler32 (adler32-zlib) [ 3995.483404] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [ 3996.173957] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [ 3998.072263] Key type lgssc registered [ 3999.806492] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4054.696276] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4060.990222] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4085.655911] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4086.314915] Lustre: lustre: root_squash is set to 500:500 [ 4086.347376] Lustre: lustre: nosquash_nids set to 192.168.201.36/24@tcp [ 4087.428488] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 4094.138937] Lustre: Unmounted lustre-client [ 4127.751129] Key type lgssc unregistered [ 4128.163077] LNet: 86390:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4128.179630] LNetError: 86390:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4128.204391] LNet: Removed LNI 192.168.201.36@tcp [ 4129.001255] Key type .llcrypt unregistered [ 4129.003581] Key type ._llcrypt unregistered [ 4146.275122] Key type ._llcrypt registered [ 4146.278820] Key type .llcrypt registered [ 4148.243112] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 4150.232386] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 11:01:50 (1787583710) [ 4173.360294] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4173.399360] alg: No test for adler32 (adler32-zlib) [ 4174.619788] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [ 4174.860520] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [ 4176.544312] Key type lgssc registered [ 4177.952110] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4270.966617] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4277.682732] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4303.080375] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4376.991486] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4383.536472] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4408.624755] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4420.745122] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 4454.555565] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8a4e05ca7800.ost_server_uuid 50 [ 4456.557580] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8a4e05ca7800.ost_server_uuid in FULL state after 0 sec [ 4457.241563] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 4461.907752] Lustre: Unmounted lustre-client [ 4464.761871] Lustre: Unmounted lustre-client [ 4524.610663] Key type lgssc unregistered [ 4524.971659] LNet: 94071:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4524.985039] LNetError: 94071:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4524.998903] LNet: Removed LNI 192.168.201.36@tcp [ 4526.023493] Key type .llcrypt unregistered [ 4526.033697] Key type ._llcrypt unregistered [ 4562.126961] Key type ._llcrypt registered [ 4562.130777] Key type .llcrypt registered [ 4564.224894] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 11:08:43 (1787584123) [ 4584.365080] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4584.527159] alg: No test for adler32 (adler32-zlib) [ 4585.759653] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [ 4586.105463] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [ 4587.816321] Key type lgssc registered [ 4589.048668] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4686.327636] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4693.235435] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4725.569508] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4728.747961] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 4750.304982] Lustre: 95434:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787584295/real 1787584295] req@000000008c7f53a0 x1874417834530048/t0(0) o400->lustre-OST0000-osc-ffff8a4e100d4000@192.168.201.136@tcp:28/4 lens 224/224 e 0 to 1 dl 1787584311 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4750.335797] Lustre: lustre-OST0000-osc-ffff8a4e100d4000: Connection to lustre-OST0000 (at 192.168.201.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4754.474088] Lustre: 95434:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787584300/real 1787584300] req@000000008fd6cc80 x1874417834530560/t0(0) o400->lustre-OST0000-osc-ffff8a4e100d4000@192.168.201.136@tcp:28/4 lens 224/224 e 0 to 1 dl 1787584316 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4759.760399] Lustre: 95434:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787584305/real 1787584305] req@0000000066efc4d2 x1874417834531072/t0(0) o400->lustre-OST0000-osc-ffff8a4e100d4000@192.168.201.136@tcp:28/4 lens 224/224 e 0 to 1 dl 1787584321 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4759.849376] Lustre: lustre-OST0000-osc-ffff8a4e100d4000: Connection restored to 192.168.201.136@tcp (at 192.168.201.136@tcp) [ 4765.665307] Lustre: 95435:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787584310/real 1787584310] req@00000000aa6e3915 x1874417834531584/t0(0) o400->lustre-OST0000-osc-ffff8a4e100d4000@192.168.201.136@tcp:28/4 lens 224/224 e 0 to 1 dl 1787584326 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4774.904633] Lustre: lustre-MDT0000-mdc-ffff8a4e100d4000: Connection to lustre-MDT0000 (at 192.168.201.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4791.264900] Lustre: 95435:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787584336/real 1787584336] req@00000000de58afb2 x1874417834534912/t0(0) o400->MGC192.168.201.136@tcp@192.168.201.136@tcp:26/25 lens 224/224 e 0 to 1 dl 1787584352 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4791.315791] LustreError: MGC192.168.201.136@tcp: Connection to MGS (at 192.168.201.136@tcp) was lost; in progress operations using this service will fail [ 4800.493472] Lustre: Evicted from MGS (at 192.168.201.136@tcp) after server handle changed from 0xa407fca5ae26fb62 to 0xa407fca5ae26fcf8 [ 4800.520819] Lustre: MGC192.168.201.136@tcp: Connection restored to 192.168.201.136@tcp (at 192.168.201.136@tcp) [ 4807.460376] Lustre: lustre-MDT0000-mdc-ffff8a4e100d4000: Connection restored to 192.168.201.136@tcp (at 192.168.201.136@tcp) [ 4813.451057] Lustre: Unmounted lustre-client [ 4841.886843] Key type lgssc unregistered [ 4842.091868] LNet: 99747:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4842.098415] LNetError: 99747:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4842.117119] LNet: Removed LNI 192.168.201.36@tcp [ 4842.828405] Key type .llcrypt unregistered [ 4842.832877] Key type ._llcrypt unregistered [ 4859.061491] Key type ._llcrypt registered [ 4859.063655] Key type .llcrypt registered [ 4860.434310] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 11:13:40 (1787584420) [ 4875.477848] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4875.530890] alg: No test for adler32 (adler32-zlib) [ 4876.682391] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [ 4876.930767] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [ 4878.608187] Key type lgssc registered [ 4880.034153] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4922.605101] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5027.293749] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5033.878857] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5057.211481] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5060.088822] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 5062.580029] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 5064.698172] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 11:17:04 (1787584624) [ 5323.039186] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5333.402813] Lustre: Unmounted lustre-client [ 5465.921806] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5473.040839] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5499.239156] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5544.502764] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 11:25:04 (1787585104) [ 5663.588699] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5670.722267] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5697.365386] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5698.117286] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 5703.415369] Lustre: Unmounted lustre-client [ 5741.507918] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 11:28:20 (1787585300) [ 5846.196714] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5853.757218] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5879.443034] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5880.167085] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 5884.698619] Lustre: Unmounted lustre-client [ 5916.015757] Key type lgssc unregistered [ 5916.557741] LNet: 122785:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5916.576717] LNetError: 122785:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5916.615089] LNet: Removed LNI 192.168.201.36@tcp [ 5917.839532] Key type .llcrypt unregistered [ 5917.844973] Key type ._llcrypt unregistered [ 5939.821686] Key type ._llcrypt registered [ 5939.825716] Key type .llcrypt registered [ 5941.121946] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 11:31:41 (1787585501) [ 5945.339138] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5945.361214] alg: No test for adler32 (adler32-zlib) [ 5946.591906] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [ 5947.004767] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [ 5948.840207] Key type lgssc registered [ 5949.924964] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6010.848227] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6017.501512] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6040.474130] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6052.927184] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 6068.123868] Lustre: Unmounted lustre-client [ 6105.027837] Key type lgssc unregistered [ 6105.318514] LNet: 126390:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6105.326547] LNetError: 126390:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6105.345836] LNet: Removed LNI 192.168.201.36@tcp [ 6106.125490] Key type .llcrypt unregistered [ 6106.132876] Key type ._llcrypt unregistered [ 6124.201546] Key type ._llcrypt registered [ 6124.204855] Key type .llcrypt registered [ 6126.515747] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 11:34:45 (1787585685) [ 6129.607708] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6129.651385] alg: No test for adler32 (adler32-zlib) [ 6130.969829] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [ 6131.235702] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [ 6133.064298] Key type lgssc registered [ 6135.523659] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6199.383155] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6206.542285] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6233.802456] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6240.892113] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 6245.886346] Lustre: lustre-OST0000-osc-ffff8a4e0876d000: Connection to lustre-OST0000 (at 192.168.201.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6258.889453] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff8a4e0876d000.ost_server_uuid 50 [ 6260.417573] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 6271.808084] Lustre: Unmounted lustre-client [ 6295.169168] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 11:37:34 (1787585854) [ 6339.413287] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6346.181302] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6375.378653] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6403.706405] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6404.411173] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 6409.703635] Lustre: lustre-OST0000-osc-ffff8a4e02d16000: Connection to lustre-OST0000 (at 192.168.201.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6446.028882] Lustre: Unmounted lustre-client [ 6502.395671] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 11:41:02 (1787586062) [ 6534.403845] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6540.302480] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6560.787882] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6586.989210] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6587.848147] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 6593.014904] Lustre: lustre-OST0000-osc-ffff8a4e0548a000: Connection to lustre-OST0000 (at 192.168.201.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6608.690025] Lustre: Unmounted lustre-client [ 6665.831378] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 11:43:45 (1787586225) [ 6766.203934] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6773.007891] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6797.041869] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6875.376575] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6882.359992] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6909.209822] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6945.906180] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 6992.918462] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6994.872625] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6997.925772] Lustre: Unmounted lustre-client [ 7031.260272] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 11:49:51 (1787586591) [ 7067.664927] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7075.054367] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7098.719202] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7135.692751] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7172.105746] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 7218.213735] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7220.197465] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7232.537304] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7242.914363] Lustre: Unmounted lustre-client [ 7298.473587] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 11:54:18 (1787586858) [ 7337.068829] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7343.947119] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7369.434071] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7370.014427] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 7396.818938] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7398.820379] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7415.541704] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8a4e05c37000.ost_server_uuid 50 [ 7417.692995] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8a4e05c37000.ost_server_uuid in IDLE state after 0 sec [ 7421.174414] Lustre: Unmounted lustre-client [ 7421.631540] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 7421.637782] Lustre: Skipped 3 previous similar messages [ 7421.734261] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 7423.001494] LustreError: 149833:0:(sec.c:403:import_sec_validate_get()) import 000000004cf3380e (NEW) with no sec [ 7486.788335] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 11:57:26 (1787587046) [ 7527.114840] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7534.430227] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7558.750728] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7584.404895] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7585.181429] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 7585.348453] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 7598.242244] Lustre: Unmounted lustre-client [ 7598.249181] Lustre: Skipped 1 previous similar message [ 7637.848483] Key type lgssc unregistered [ 7638.329512] LNet: 153240:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7638.358665] LNetError: 153240:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7638.397288] LNet: Removed LNI 192.168.201.36@tcp [ 7639.718470] Key type .llcrypt unregistered [ 7639.720561] Key type ._llcrypt unregistered [ 7659.866937] Key type ._llcrypt registered [ 7659.868572] Key type .llcrypt registered [ 7661.310766] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 12:00:21 (1787587221) [ 7663.361042] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7663.468343] alg: No test for adler32 (adler32-zlib) [ 7664.720793] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [ 7665.156791] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [ 7666.992599] Key type lgssc registered [ 7668.533169] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7725.410176] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7732.241503] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7756.321928] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7783.100307] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7783.863465] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7785.051383] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 7792.184601] LustreError: lustre-MDT0001-mdc-ffff8a4e02d16000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 7792.226747] Lustre: lustre-MDT0001-mdc-ffff8a4e02d16000: Connection restored to 192.168.201.136@tcp (at 192.168.201.136@tcp) [ 7800.267360] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7802.073902] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7824.929752] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7827.634292] Lustre: Unmounted lustre-client [ 7861.117814] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 12:03:40 (1787587420) [ 7974.863557] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7982.672858] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8011.387703] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8012.335235] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 8042.976432] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8044.216816] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 8066.656131] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 8077.032393] Lustre: Unmounted lustre-client [ 8110.868507] Key type lgssc unregistered [ 8111.211688] LNet: 162870:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8111.230384] LNetError: 162870:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8112.294758] LNet: Removed LNI 192.168.201.36@tcp [ 8113.151889] Key type .llcrypt unregistered [ 8113.153585] Key type ._llcrypt unregistered [ 8145.005398] Key type ._llcrypt registered [ 8145.008837] Key type .llcrypt registered [ 8146.516739] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 12:08:26 (1787587706) [ 8149.210973] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8149.230849] alg: No test for adler32 (adler32-zlib) [ 8150.456286] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [ 8150.762215] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [ 8152.524821] Key type lgssc registered [ 8154.344680] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8208.533116] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8214.170694] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8239.555318] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8242.333418] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 8289.713332] Lustre: Unmounted lustre-client [ 8333.956937] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8335.018942] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 8372.814449] Lustre: Unmounted lustre-client [ 8401.612455] Key type lgssc unregistered [ 8402.024426] LNet: 167328:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8402.038381] LNetError: 167328:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8402.069482] LNet: Removed LNI 192.168.201.36@tcp [ 8403.213468] Key type .llcrypt unregistered [ 8403.214954] Key type ._llcrypt unregistered [ 8420.314991] Key type ._llcrypt registered [ 8420.321470] Key type .llcrypt registered [ 8421.735321] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 12:13:01 (1787587981) [ 8424.118102] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8424.158511] alg: No test for adler32 (adler32-zlib) [ 8425.501599] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [ 8426.019996] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [ 8427.714529] Key type lgssc registered [ 8428.985594] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8486.079832] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8492.569849] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8515.803973] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8517.587608] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 8536.813100] Lustre: Unmounted lustre-client [ 8567.207423] Key type lgssc unregistered [ 8567.570269] LNet: 171089:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8567.580174] LNetError: 171089:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8568.624430] LNet: Removed LNI 192.168.201.36@tcp [ 8570.166490] Key type .llcrypt unregistered [ 8570.174569] Key type ._llcrypt unregistered [ 8588.821323] Key type ._llcrypt registered [ 8588.834441] Key type .llcrypt registered [ 8589.715296] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8589.764657] alg: No test for adler32 (adler32-zlib) [ 8591.373742] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [ 8591.809949] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [ 8593.624263] Key type lgssc registered [ 8595.530136] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8656.274795] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8662.791694] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8684.584581] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8696.131902] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 8708.093880] Lustre: Unmounted lustre-client [ 8737.873704] Key type lgssc unregistered [ 8738.191347] LNet: 174174:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8738.205343] LNetError: 174174:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8738.251966] LNet: Removed LNI 192.168.201.36@tcp [ 8739.226880] Key type .llcrypt unregistered [ 8739.230991] Key type ._llcrypt unregistered [ 8757.713924] Key type ._llcrypt registered [ 8757.722664] Key type .llcrypt registered [ 8759.360747] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 12:18:38 (1787588318) [ 8762.609195] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8762.737511] alg: No test for adler32 (adler32-zlib) [ 8763.849775] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [ 8764.196140] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [ 8765.904217] Key type lgssc registered [ 8767.211557] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8816.689460] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8823.566871] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8848.853680] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8850.624650] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 8870.474800] Lustre: Unmounted lustre-client [ 8908.126363] Key type lgssc unregistered [ 8908.540749] LNet: 177934:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8908.557075] LNetError: 177934:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8909.616517] LNet: Removed LNI 192.168.201.36@tcp [ 8910.516873] Key type .llcrypt unregistered [ 8910.522475] Key type ._llcrypt unregistered [ 8923.928950] Key type ._llcrypt registered [ 8923.937608] Key type .llcrypt registered [ 8924.369857] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8924.392715] alg: No test for adler32 (adler32-zlib) [ 8925.573927] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [ 8925.917457] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [ 8927.701522] Key type lgssc registered [ 8929.044595] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8979.623185] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8985.072681] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9007.240987] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9009.070223] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 9021.922647] Lustre: Unmounted lustre-client [ 9049.680366] Key type lgssc unregistered [ 9050.021978] LNet: 181006:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9050.028522] LNetError: 181006:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9050.044487] LNet: Removed LNI 192.168.201.36@tcp [ 9050.836259] Key type .llcrypt unregistered [ 9050.851408] Key type ._llcrypt unregistered [ 9067.858742] Key type ._llcrypt registered [ 9067.862571] Key type .llcrypt registered [ 9069.412966] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 12:23:49 (1787588629) [ 9092.746479] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9092.761370] alg: No test for adler32 (adler32-zlib) [ 9093.986458] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [ 9094.349077] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [ 9096.104253] Key type lgssc registered [ 9097.748551] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9185.166618] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9191.122445] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9215.972487] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9259.510721] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 12:26:59 (1787588819) [ 9290.888263] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9295.714378] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9313.922804] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9315.627698] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 9319.904079] Lustre: Unmounted lustre-client [ 9346.785130] Key type lgssc unregistered [ 9347.079408] LNet: 188108:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9347.095317] LNetError: 188108:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9347.109665] LNet: Removed LNI 192.168.201.36@tcp [ 9347.752703] Key type .llcrypt unregistered [ 9347.754473] Key type ._llcrypt unregistered [ 9360.760590] Key type ._llcrypt registered [ 9360.763126] Key type .llcrypt registered [ 9362.070265] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 9364.006664] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 12:28:43 (1787588923) [ 9380.846964] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9380.869859] alg: No test for adler32 (adler32-zlib) [ 9381.923324] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [ 9382.235339] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [ 9383.938340] Key type lgssc registered [ 9384.992414] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9472.868806] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9479.556465] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9504.750034] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9530.623607] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9533.404224] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [ 9559.400493] Lustre: Unmounted lustre-client [ 9698.546161] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9705.635787] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9730.044381] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9772.926142] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 12:35:33 (1787589333) [ 9774.418797] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 9776.679438] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 12:35:36 (1787589336) [ 9887.295528] Key type lgssc unregistered [ 9887.613502] LNet: 199562:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9887.618168] LNetError: 199562:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9887.645754] LNet: Removed LNI 192.168.201.36@tcp [ 9888.308240] Key type .llcrypt unregistered [ 9888.310289] Key type ._llcrypt unregistered [ 9904.449344] Key type ._llcrypt registered [ 9904.451571] Key type .llcrypt registered [ 9905.743880] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 12:37:45 (1787589465) [ 9950.027864] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9950.052776] alg: No test for adler32 (adler32-zlib) [ 9951.314280] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [ 9951.718181] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [ 9953.472225] Key type lgssc registered [ 9954.900949] Lustre: Echo OBD driver; http://www.lustre.org/ [10002.102801] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10009.087487] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10044.658309] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10073.314435] Key type lgssc unregistered [10073.549690] LNet: 203300:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10073.557412] LNetError: 203300:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10073.597248] LNet: Removed LNI 192.168.201.36@tcp [10074.419206] Key type .llcrypt unregistered [10074.422421] Key type ._llcrypt unregistered [10090.792402] Key type ._llcrypt registered [10090.795603] Key type .llcrypt registered [10092.130286] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 12:40:52 (1787589652) [10095.167845] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10095.227139] alg: No test for adler32 (adler32-zlib) [10096.373544] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [10096.629429] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [10098.352211] Key type lgssc registered [10099.701942] Lustre: Echo OBD driver; http://www.lustre.org/ [10151.186199] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10159.048647] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10186.363361] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10198.608385] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [10208.756907] Lustre: lustre-MDT0000-mdc-ffff8a4e07b2c000: Connection to lustre-MDT0000 (at 192.168.201.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [10218.995146] Lustre: lustre-MDT0001-mdc-ffff8a4e07b2c000: Connection to lustre-MDT0001 (at 192.168.201.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [10230.241274] Lustre: 204291:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787589775/real 1787589775] req@000000007df670e8 x1874423613309696/t0(0) o400->MGC192.168.201.136@tcp@192.168.201.136@tcp:26/25 lens 224/224 e 0 to 1 dl 1787589791 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10230.293775] LustreError: MGC192.168.201.136@tcp: Connection to MGS (at 192.168.201.136@tcp) was lost; in progress operations using this service will fail [10254.831168] Lustre: Evicted from MGS (at 192.168.201.136@tcp) after server handle changed from 0xcdfecf18b3badd2d to 0xcdfecf18b3baf8af [10254.839750] Lustre: MGC192.168.201.136@tcp: Connection restored to 192.168.201.136@tcp (at 192.168.201.136@tcp) [10284.547777] LustreError: lustre-MDT0000-mdc-ffff8a4e07b2c000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [10284.617175] Lustre: lustre-MDT0000-mdc-ffff8a4e07b2c000: Connection restored to 192.168.201.136@tcp (at 192.168.201.136@tcp) [10291.575758] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10293.595982] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10299.716401] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10302.214656] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [10306.417612] Lustre: Unmounted lustre-client [10335.842869] Key type lgssc unregistered [10336.105942] LNet: 208259:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10336.120393] LNetError: 208259:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10337.220920] LNet: Removed LNI 192.168.201.36@tcp [10338.420266] Key type .llcrypt unregistered [10338.422277] Key type ._llcrypt unregistered [10360.059396] Key type ._llcrypt registered [10360.067581] Key type .llcrypt registered [10361.704416] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 12:45:21 (1787589921) [10364.016482] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10364.023736] alg: No test for adler32 (adler32-zlib) [10365.314625] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [10365.811907] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [10367.616356] Key type lgssc registered [10369.078169] Lustre: Echo OBD driver; http://www.lustre.org/ [10425.793095] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10432.428467] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10488.939298] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10495.415534] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10532.639074] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10554.138665] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10603.337774] Key type lgssc unregistered [10603.679723] LNet: 213559:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10603.684388] LNetError: 213559:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10603.699257] LNet: Removed LNI 192.168.201.36@tcp [10604.612833] Key type .llcrypt unregistered [10604.615208] Key type ._llcrypt unregistered [10650.560264] Key type ._llcrypt registered [10650.561842] Key type .llcrypt registered [10651.489025] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 12:50:11 (1787590211) [10694.579594] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10694.622723] alg: No test for adler32 (adler32-zlib) [10695.749278] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [10695.968879] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [10697.624201] Key type lgssc registered [10698.877227] Lustre: Echo OBD driver; http://www.lustre.org/ [10793.446562] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10799.695996] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10823.403748] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10873.412208] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 12:53:53 (1787590433) [10881.209520] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 12:54:01 (1787590441) [10920.236732] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10927.452168] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10949.644289] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10952.316791] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [10957.646858] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [10959.856870] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [10961.692672] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [10963.336873] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [10967.540926] Lustre: lustre-MDT0000-mdc-ffff8a4e20f94000: Connection to lustre-MDT0000 (at 192.168.201.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [10983.904212] Lustre: 215458:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787590529/real 1787590529] req@00000000ebf171e0 x1874424241334144/t0(0) o400->MGC192.168.201.136@tcp@192.168.201.136@tcp:26/25 lens 224/224 e 0 to 1 dl 1787590545 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10983.941237] LustreError: MGC192.168.201.136@tcp: Connection to MGS (at 192.168.201.136@tcp) was lost; in progress operations using this service will fail [10994.185181] Lustre: Evicted from MGS (at 192.168.201.136@tcp) after server handle changed from 0x90387290eb254492 to 0x90387290eb25460c [10994.218863] Lustre: MGC192.168.201.136@tcp: Connection restored to 192.168.201.136@tcp (at 192.168.201.136@tcp) [11000.138098] Lustre: lustre-MDT0000-mdc-ffff8a4e20f94000: Connection restored to 192.168.201.136@tcp (at 192.168.201.136@tcp) [11010.045292] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11012.247271] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [11016.273505] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [11019.781661] Lustre: lustre-MDT0000-mdc-ffff8a4e20f94000: Connection to lustre-MDT0000 (at 192.168.201.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [11036.128458] Lustre: 215459:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787590581/real 1787590581] req@000000005ad25712 x1874424241343104/t0(0) o400->MGC192.168.201.136@tcp@192.168.201.136@tcp:26/25 lens 224/224 e 0 to 1 dl 1787590597 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [11036.164083] LustreError: MGC192.168.201.136@tcp: Connection to MGS (at 192.168.201.136@tcp) was lost; in progress operations using this service will fail [11046.395642] Lustre: Evicted from MGS (at 192.168.201.136@tcp) after server handle changed from 0x90387290eb25460c to 0x90387290eb25491c [11046.415945] Lustre: MGC192.168.201.136@tcp: Connection restored to 192.168.201.136@tcp (at 192.168.201.136@tcp) [11052.859144] Lustre: lustre-MDT0000-mdc-ffff8a4e20f94000: Connection restored to 192.168.201.136@tcp (at 192.168.201.136@tcp) [11061.444502] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11063.173773] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [11067.348601] Lustre: Unmounted lustre-client [11144.568410] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11151.500896] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11171.606531] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11172.221265] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [11178.582448] Lustre: Unmounted lustre-client [11212.839801] Key type lgssc unregistered [11213.251341] LNet: 226061:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11213.259438] LNetError: 226061:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11214.322096] LNet: Removed LNI 192.168.201.36@tcp [11215.128275] Key type .llcrypt unregistered [11215.134864] Key type ._llcrypt unregistered [11232.277632] Key type ._llcrypt registered [11232.280877] Key type .llcrypt registered [11234.043387] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 12:59:53 (1787590793) [11238.996141] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11239.042142] alg: No test for adler32 (adler32-zlib) [11240.213913] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [11240.537958] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [11242.320286] Key type lgssc registered [11243.832576] Lustre: Echo OBD driver; http://www.lustre.org/ [11297.281866] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11304.574605] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11329.161094] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11331.040244] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [11336.864791] Lustre: Unmounted lustre-client [11412.130930] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11420.538666] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11444.588669] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11445.786175] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [11454.829365] Lustre: Unmounted lustre-client [11524.340935] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11530.604818] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11557.875074] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11558.516403] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [11561.093173] Lustre: Unmounted lustre-client [11589.845410] Key type lgssc unregistered [11590.197402] LNet: 233132:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11590.214516] LNetError: 233132:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11590.248243] LNet: Removed LNI 192.168.201.36@tcp [11591.169557] Key type .llcrypt unregistered [11591.171619] Key type ._llcrypt unregistered [11609.830131] Key type ._llcrypt registered [11609.831969] Key type .llcrypt registered [11611.157763] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 13:06:11 (1787591171) [11615.124800] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11615.279355] alg: No test for adler32 (adler32-zlib) [11616.529309] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [11616.747804] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [11618.442733] Key type lgssc registered [11619.583475] Lustre: Echo OBD driver; http://www.lustre.org/ [11671.595516] Key type lgssc unregistered [11672.063995] LNet: 235460:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11672.085715] LNetError: 235460:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11672.118353] LNet: Removed LNI 192.168.201.36@tcp [11673.404743] Key type .llcrypt unregistered [11673.416200] Key type ._llcrypt unregistered [11708.570828] Key type ._llcrypt registered [11708.576584] Key type .llcrypt registered [11709.131891] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11709.210021] alg: No test for adler32 (adler32-zlib) [11710.436833] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [11710.855122] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [11712.641539] Key type lgssc registered [11713.935319] Lustre: Echo OBD driver; http://www.lustre.org/ [11805.508727] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11812.544422] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11840.735201] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11893.987723] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [11896.340436] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 13:10:55 (1787591455) [11939.480452] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11946.745899] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11972.626151] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12004.482980] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12007.503031] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [12012.529361] Lustre: lustre-OST0001-osc-ffff8a4e05c34800: Connection to lustre-OST0001 (at 192.168.201.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [12022.540045] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12032.943705] Lustre: Unmounted lustre-client [12079.908259] Key type lgssc unregistered [12080.514450] LNet: 242590:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12080.530132] LNetError: 242590:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12080.551075] LNet: Removed LNI 192.168.201.36@tcp [12081.411799] Key type .llcrypt unregistered [12081.413728] Key type ._llcrypt unregistered [12118.799279] Key type ._llcrypt registered [12118.800949] Key type .llcrypt registered [12120.368996] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 13:14:40 (1787591680) [12136.315508] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12136.349890] alg: No test for adler32 (adler32-zlib) [12137.582626] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [12138.073783] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [12139.776336] Key type lgssc registered [12141.256599] Lustre: Echo OBD driver; http://www.lustre.org/ [12200.856911] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12207.176150] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12238.854497] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 13:16:38 (1787591798) [12272.883651] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12278.744773] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12302.473193] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12305.279074] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [12312.809197] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [12324.894271] Lustre: Unmounted lustre-client [12440.619809] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12446.742952] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12498.583763] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12499.489851] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [12501.709182] Lustre: Unmounted lustre-client [12533.837964] Key type lgssc unregistered [12534.231566] LNet: 250285:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12534.240910] LNetError: 250285:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12535.275939] LNet: Removed LNI 192.168.201.36@tcp [12536.298411] Key type .llcrypt unregistered [12536.300059] Key type ._llcrypt unregistered [12568.400316] Key type ._llcrypt registered [12568.441325] Key type .llcrypt registered [12568.871420] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12568.900635] alg: No test for adler32 (adler32-zlib) [12569.913437] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [12570.199425] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [12571.832223] Key type lgssc registered [12573.223066] Lustre: Echo OBD driver; http://www.lustre.org/ [12668.729989] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12675.521779] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12701.303403] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12758.116501] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [12760.872755] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 13:25:19 (1787592319) [12807.357458] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12814.766315] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12843.504628] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12847.805907] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [12851.009083] Lustre: Unmounted lustre-client [12883.431715] Key type lgssc unregistered [12883.909382] LNet: 256928:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12883.924113] LNetError: 256928:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12883.955838] LNet: Removed LNI 192.168.201.36@tcp [12884.869764] Key type .llcrypt unregistered [12884.873411] Key type ._llcrypt unregistered [12905.610255] Key type ._llcrypt registered [12905.615415] Key type .llcrypt registered [12907.185687] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [12909.965340] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 13:27:49 (1787592469) [12921.174826] Key type .llcrypt unregistered [12921.177906] Key type ._llcrypt unregistered [12932.065373] Key type ._llcrypt registered [12932.079628] Key type .llcrypt registered [12932.676834] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12932.693917] alg: No test for adler32 (adler32-zlib) [12933.936350] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [12934.381581] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [12936.120207] Key type lgssc registered [12937.467719] Lustre: Echo OBD driver; http://www.lustre.org/ [12988.451125] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13003.060214] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [13007.331223] Lustre: Unmounted lustre-client [13038.923825] Key type lgssc unregistered [13039.282609] LNet: 260390:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13039.298641] LNetError: 260390:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13039.333109] LNet: Removed LNI 192.168.201.36@tcp [13040.025752] Key type .llcrypt unregistered [13040.028246] Key type ._llcrypt unregistered [13059.218237] Key type ._llcrypt registered [13059.226819] Key type .llcrypt registered [13060.738234] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 13:30:20 (1787592620) [13063.591044] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13063.663231] alg: No test for adler32 (adler32-zlib) [13064.830344] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [13065.082850] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [13066.720207] Key type lgssc registered [13067.861828] Lustre: Echo OBD driver; http://www.lustre.org/ [13153.216605] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13194.801398] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13203.440788] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13206.425475] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [13209.631786] Lustre: Unmounted lustre-client [13239.185703] Key type lgssc unregistered [13239.445220] LNet: 263939:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13239.452312] LNetError: 263939:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13239.479843] LNet: Removed LNI 192.168.201.36@tcp [13240.086710] Key type .llcrypt unregistered [13240.089450] Key type ._llcrypt unregistered [13256.677694] Key type ._llcrypt registered [13256.681556] Key type .llcrypt registered [13258.433909] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 13:33:38 (1787592818) [13263.566115] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13263.599961] alg: No test for adler32 (adler32-zlib) [13265.367709] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [13265.655382] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [13267.328466] Key type lgssc registered [13268.676269] Lustre: Echo OBD driver; http://www.lustre.org/ [13326.974354] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13334.220152] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13362.786051] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13366.626089] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [13371.884337] Lustre: lustre-MDT0000-mdc-ffff8a4e03281000: Connection to lustre-MDT0000 (at 192.168.201.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [13375.934711] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [13375.973692] LustreError: 267101:0:(file.c:6167:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [13376.243509] LustreError: MGC192.168.201.136@tcp: Connection to MGS (at 192.168.201.136@tcp) was lost; in progress operations using this service will fail [13376.293506] Lustre: Unmounted lustre-client [13399.194498] Key type lgssc unregistered [13399.495610] LNet: 267518:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13399.507799] LNetError: 267518:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13399.529653] LNet: Removed LNI 192.168.201.36@tcp [13400.243695] Key type .llcrypt unregistered [13400.250641] Key type ._llcrypt unregistered [13416.308072] Key type ._llcrypt registered [13416.310501] Key type .llcrypt registered [13417.921152] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 13:36:17 (1787592977) [13421.222850] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13421.286913] alg: No test for adler32 (adler32-zlib) [13422.704426] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [13423.051359] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [13424.696323] Key type lgssc registered [13425.654692] Lustre: Echo OBD driver; http://www.lustre.org/ [13484.829743] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13494.372784] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13523.121860] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13530.199428] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [13535.220593] Lustre: lustre-MDT0001-mdc-ffff8a4e040f1800: Connection to lustre-MDT0001 (at 192.168.201.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [13542.720972] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [13545.310275] Lustre: Unmounted lustre-client [13571.824468] Key type lgssc unregistered [13572.086110] LNet: 271106:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13572.095078] LNetError: 271106:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13572.104980] LNet: Removed LNI 192.168.201.36@tcp [13572.832869] Key type .llcrypt unregistered [13572.835449] Key type ._llcrypt unregistered [13592.999873] Key type ._llcrypt registered [13593.002411] Key type .llcrypt registered [13594.747984] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 13:39:14 (1787593154) [13618.301427] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13618.338051] alg: No test for adler32 (adler32-zlib) [13619.503393] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [13619.858762] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [13621.560181] Key type lgssc registered [13623.386984] Lustre: Echo OBD driver; http://www.lustre.org/ [13743.327676] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13745.481914] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [13797.873045] Lustre: lustre-MDT0001-mdc-ffff8a4e1877e800: Connection to lustre-MDT0001 (at 192.168.201.136@tcp) was lost; in progress operations using this service will wait for recovery to complete [13806.704606] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [13817.118426] Lustre: Unmounted lustre-client [13855.989321] Key type lgssc unregistered [13856.330700] LNet: 276901:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13856.348594] LNetError: 276901:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13856.372279] LNet: Removed LNI 192.168.201.36@tcp [13857.087203] Key type .llcrypt unregistered [13857.092726] Key type ._llcrypt unregistered [13878.932339] Key type ._llcrypt registered [13878.935303] Key type .llcrypt registered [13880.804981] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 13:44:00 (1787593440) [13883.619805] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [13886.287244] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 13:44:05 (1787593445) [13889.728106] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [13892.899223] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 13:44:11 (1787593451) [13895.350282] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [13897.761643] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 13:44:17 (1787593457) [13900.708040] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [13903.224366] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 13:44:22 (1787593462) [13906.068156] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [13908.891829] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 13:44:28 (1787593468) [13922.901319] Key type .llcrypt unregistered [13922.908481] Key type ._llcrypt unregistered [13930.825645] Key type ._llcrypt registered [13930.831181] Key type .llcrypt registered [13931.811225] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13931.838834] alg: No test for adler32 (adler32-zlib) [13932.978248] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [13933.320727] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [13935.056746] Key type lgssc registered [13936.113424] Lustre: Echo OBD driver; http://www.lustre.org/ [14023.040592] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14029.989322] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14060.718689] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14063.226611] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [14066.612540] Lustre: Unmounted lustre-client [14108.046138] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 13:47:48 (1787593668) [14151.129851] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14157.059912] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14199.916985] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14200.737679] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [14203.231108] Lustre: Unmounted lustre-client [14247.878938] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 13:50:07 (1787593807) [14262.048559] LNet: 1 local NIs in recovery (showing 1): 192.168.201.36@tcp [14915.211385] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14922.425138] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14965.772501] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15084.612628] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 14:04:04 (1787594644) [15097.945145] Key type lgssc unregistered [15098.366918] LNet: 290467:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15098.377947] LNetError: 290467:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15098.410474] LNet: Removed LNI 192.168.201.36@tcp [15099.289188] Key type .llcrypt unregistered [15099.292039] Key type ._llcrypt unregistered [15211.533143] Key type ._llcrypt registered [15211.538101] Key type .llcrypt registered [15211.916847] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15211.931448] alg: No test for adler32 (adler32-zlib) [15213.137643] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [15213.452039] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [15215.208208] Key type lgssc registered [15216.606566] Lustre: Echo OBD driver; http://www.lustre.org/ [15333.114288] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15339.795076] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15367.789692] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15414.418897] Key type lgssc unregistered [15414.851797] LNet: 294175:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15414.862589] LNetError: 294175:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15414.880156] LNet: Removed LNI 192.168.201.36@tcp [15416.178933] Key type .llcrypt unregistered [15416.187535] Key type ._llcrypt unregistered [15456.501114] Key type ._llcrypt registered [15456.503127] Key type .llcrypt registered [15458.510212] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 14:10:17 (1787595017) [15483.340592] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15483.383902] alg: No test for adler32 (adler32-zlib) [15484.470604] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [15484.739661] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [15486.401504] Key type lgssc registered [15487.798601] Lustre: Echo OBD driver; http://www.lustre.org/ [15546.046991] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 14:11:45 (1787595105) [15560.842623] Key type lgssc unregistered [15561.111350] LNet: 297735:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15561.127244] LNetError: 297735:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15561.166625] LNet: Removed LNI 192.168.201.36@tcp [15562.105923] Key type .llcrypt unregistered [15562.111331] Key type ._llcrypt unregistered [15575.339860] Key type ._llcrypt registered [15575.346453] Key type .llcrypt registered [15576.053761] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15576.078095] alg: No test for adler32 (adler32-zlib) [15577.212251] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [15577.662345] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [15579.384292] Key type lgssc registered [15580.845859] Lustre: Echo OBD driver; http://www.lustre.org/ [15641.622193] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15648.741706] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15677.400463] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15979.916300] LNet: Added LNI 192.168.201.36@tcp50 [8/256/0/180] [15982.802217] LNet: Removed LNI 192.168.201.36@tcp [15985.157291] Lustre: 302136:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff8a4e0336a000: config has no valid NIDs, force dynamic NIDs [15986.448817] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [15986.561960] Lustre: Unmounted lustre-client [15989.972552] Key type lgssc unregistered [15990.257137] LNet: 302397:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15990.269249] LNetError: 302397:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15990.285959] LNet: Removed LNI 192.168.201.36@tcp50 [15990.926453] Key type .llcrypt unregistered [15990.929103] Key type ._llcrypt unregistered [16010.419905] Key type ._llcrypt registered [16010.421643] Key type .llcrypt registered [16010.891933] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [16010.909878] alg: No test for adler32 (adler32-zlib) [16012.228723] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [16012.620160] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [16014.416280] Key type lgssc registered [16016.110794] Lustre: Echo OBD driver; http://www.lustre.org/ [16080.744220] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16086.904625] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16099.290505] LNet: Added LNI 192.168.201.36@tcp50 [8/256/0/180] [16100.502347] LNet: Removed LNI 192.168.201.36@tcp [16101.732371] Lustre: 305092:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff8a4e0336b000: config has no valid NIDs, force dynamic NIDs [16103.026819] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [16103.649512] Lustre: Unmounted lustre-client [16142.345872] Key type lgssc unregistered [16142.852373] LNet: 305964:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16142.857985] LNetError: 305964:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16142.879340] LNet: Removed LNI 192.168.201.36@tcp50 [16143.932841] Key type .llcrypt unregistered [16143.938401] Key type ._llcrypt unregistered [16158.813206] Key type ._llcrypt registered [16158.818278] Key type .llcrypt registered [16160.320563] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 14:22:00 (1787595720) [16181.366358] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16181.420483] alg: No test for adler32 (adler32-zlib) [16182.534795] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [16182.906806] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [16184.633190] Key type lgssc registered [16186.141937] Lustre: Echo OBD driver; http://www.lustre.org/ [16272.681489] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16281.105892] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16307.182218] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16345.020902] Key type lgssc unregistered [16345.311575] LNet: 310716:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16345.318441] LNetError: 310716:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16345.336643] LNet: Removed LNI 192.168.201.36@tcp [16346.210985] Key type .llcrypt unregistered [16346.212490] Key type ._llcrypt unregistered [16360.393073] Key type ._llcrypt registered [16360.398272] Key type .llcrypt registered [16362.049653] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 14:25:21 (1787595921) [16364.934386] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16364.950489] alg: No test for adler32 (adler32-zlib) [16366.154446] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [16366.441969] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [16368.152246] Key type lgssc registered [16369.871538] Lustre: Echo OBD driver; http://www.lustre.org/ [16427.010874] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16434.698832] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16459.098548] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16471.604220] Lustre: Mounted lustre-client - version 2.17.57_80_g35a8f2d [16537.083194] Lustre: Mounted fs15246-client - version 2.17.57_80_g35a8f2d [16556.222466] Lustre: Unmounted fs15246-client [16586.740210] Lustre: Unmounted lustre-client [16624.521415] Key type lgssc unregistered [16624.829762] LNet: 315583:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16624.842899] LNetError: 315583:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16624.861792] LNet: Removed LNI 192.168.201.36@tcp [16626.380649] Key type .llcrypt unregistered [16626.391762] Key type ._llcrypt unregistered [16642.159696] Key type ._llcrypt registered [16642.162704] Key type .llcrypt registered [16644.152900] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 14:30:03 (1787596203) [16647.494988] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16647.545142] alg: No test for adler32 (adler32-zlib) [16648.886753] Lustre: Lustre: Build Version: 2.17.57_80_g35a8f2d [16649.217854] LNet: Added LNI 192.168.201.36@tcp [8/256/0/180] [16650.896228] Key type lgssc registered [16652.363584] Lustre: Echo OBD driver; http://www.lustre.org/ [16802.477473] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16809.002452] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16838.009417] Lustre: DEBUG MARKER: oleg136-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16958.712874] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 16640 sec ========= 14:35:18 (1787596518) [16961.176414] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 14:35:20 (1787596520) === [16965.695190] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 14:35:25 (1787596525) === [17005.305049] Key type lgssc unregistered [17005.607838] LNet: 324858:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [17005.614752] LNetError: 324858:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [17005.630517] LNet: Removed LNI 192.168.201.36@tcp [17006.379766] Key type .llcrypt unregistered [17006.385091] Key type ._llcrypt unregistered