[ 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 448550990 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.001011] APIC: Switch to symmetric I/O mode setup [ 0.003177] x2apic enabled [ 0.004010] Switched APIC routing to physical x2apic. [ 0.005011] kvm-guest: setup PV IPIs [ 0.008000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008021] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009012] pid_max: default: 32768 minimum: 301 [ 0.011128] LSM: Security Framework initializing [ 0.012052] Yama: becoming mindful. [ 0.013037] SELinux: Initializing. [ 0.014069] *** VALIDATE selinux *** [ 0.022788] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.027348] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.028146] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029111] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030114] *** VALIDATE tmpfs *** [ 0.031442] *** VALIDATE proc *** [ 0.033189] *** VALIDATE cgroup *** [ 0.034010] *** VALIDATE cgroup2 *** [ 0.035263] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.036161] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.037009] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.038027] Spectre V2 : User space: Vulnerable [ 0.039021] Speculative Store Bypass: Vulnerable [ 0.042004] debug: unmapping init [mem 0xffffffffa5859000-0xffffffffa5860fff] [ 0.044172] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.045698] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.046027] ... version: 2 [ 0.047014] ... bit width: 48 [ 0.048016] ... generic registers: 4 [ 0.049014] ... value mask: 0000ffffffffffff [ 0.050014] ... max period: 00007fffffffffff [ 0.051015] ... fixed-purpose events: 3 [ 0.052013] ... event mask: 000000070000000f [ 0.054213] rcu: Hierarchical SRCU implementation. [ 0.056323] smp: Bringing up secondary CPUs ... [ 0.057521] x86: Booting SMP configuration: [ 0.058032] .... node #0, CPUs: #1 #2 #3 [ 0.062239] smp: Brought up 1 node, 4 CPUs [ 0.064016] smpboot: Max logical packages: 1 [ 0.065020] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.241050] node 0 deferred pages initialised in 174ms [ 0.244258] devtmpfs: initialized [ 0.246341] x86/mm: Memory block size: 128MB [ 0.250255] gcov: version magic: 0x41383552 [ 0.253355] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.256173] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.258437] pinctrl core: initialized pinctrl subsystem [ 0.261279] [ 0.261919] ************************************************************* [ 0.265020] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.267016] ** ** [ 0.270018] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.272015] ** ** [ 0.274021] ** This means that this kernel is built to expose internal ** [ 0.277112] ** IOMMU data structures, which may compromise security on ** [ 0.279017] ** your system. ** [ 0.284023] ** ** [ 0.286035] ** If you see this message and you are not debugging the ** [ 0.289017] ** kernel, report this immediately to your vendor! ** [ 0.291019] ** ** [ 0.294020] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.296017] ************************************************************* [ 0.298980] NET: Registered protocol family 16 [ 0.301514] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.304097] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.307106] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.311087] cpuidle: using governor menu [ 0.312818] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.316023] PCI: Using configuration type 1 for base access [ 0.318132] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.327093] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.328041] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.330073] cryptd: max_cpu_qlen set to 1000 [ 0.332200] ACPI: Added _OSI(Module Device) [ 0.333023] ACPI: Added _OSI(Processor Device) [ 0.334025] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.336045] ACPI: Added _OSI(Processor Aggregator Device) [ 0.341039] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.347387] ACPI: Interpreter enabled [ 0.348057] ACPI: PM: (supports S0 S3 S4 S5) [ 0.350015] ACPI: Using IOAPIC for interrupt routing [ 0.352124] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.356377] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.366923] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.369040] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.375023] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.378077] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.384493] acpiphp: Slot [2] registered [ 0.386109] acpiphp: Slot [5] registered [ 0.388187] acpiphp: Slot [6] registered [ 0.389127] acpiphp: Slot [3] registered [ 0.390106] acpiphp: Slot [4] registered [ 0.392094] acpiphp: Slot [7] registered [ 0.393109] acpiphp: Slot [8] registered [ 0.395105] acpiphp: Slot [9] registered [ 0.396112] acpiphp: Slot [10] registered [ 0.397115] acpiphp: Slot [11] registered [ 0.399101] acpiphp: Slot [12] registered [ 0.401109] acpiphp: Slot [13] registered [ 0.402192] acpiphp: Slot [14] registered [ 0.404101] acpiphp: Slot [15] registered [ 0.405135] acpiphp: Slot [16] registered [ 0.406106] acpiphp: Slot [17] registered [ 0.408122] acpiphp: Slot [18] registered [ 0.409116] acpiphp: Slot [19] registered [ 0.411117] acpiphp: Slot [20] registered [ 0.412121] acpiphp: Slot [21] registered [ 0.414138] acpiphp: Slot [22] registered [ 0.416145] acpiphp: Slot [23] registered [ 0.417103] acpiphp: Slot [24] registered [ 0.418163] acpiphp: Slot [25] registered [ 0.419026] acpiphp: Slot [26] registered [ 0.421178] acpiphp: Slot [27] registered [ 0.423108] acpiphp: Slot [28] registered [ 0.424129] acpiphp: Slot [29] registered [ 0.426101] acpiphp: Slot [30] registered [ 0.428104] acpiphp: Slot [31] registered [ 0.429090] PCI host bridge to bus 0000:00 [ 0.430018] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.432021] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.435024] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.438023] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.441042] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.443023] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.444211] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.447936] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.450006] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.458015] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.463118] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.466018] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.468016] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.471026] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.473630] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.476825] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.480042] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.482768] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.486954] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.497018] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.502014] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.509114] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.514014] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.524012] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.535841] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.542969] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.549902] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.558013] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.574017] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.584710] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.586351] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.588271] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.591374] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.594264] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.598207] iommu: Default domain type: Passthrough [ 0.601396] SCSI subsystem initialized [ 0.602149] ACPI: bus type USB registered [ 0.604099] usbcore: registered new interface driver usbfs [ 0.606112] usbcore: registered new interface driver hub [ 0.608078] usbcore: registered new device driver usb [ 0.609154] pps_core: LinuxPPS API ver. 1 registered [ 0.611014] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.614055] PTP clock support registered [ 0.616188] EDAC MC: Ver: 3.0.0 [ 0.619013] PCI: Using ACPI for IRQ routing [ 0.620781] NetLabel: Initializing [ 0.621011] NetLabel: domain hash size = 128 [ 0.622014] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.623172] NetLabel: unlabeled traffic allowed by default [ 0.625218] vgaarb: loaded [ 0.626307] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.628018] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.636363] clocksource: Switched to clocksource kvm-clock [ 0.747034] VFS: Disk quotas dquot_6.6.0 [ 0.748750] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.750911] *** VALIDATE ramfs *** [ 0.751909] *** VALIDATE hugetlbfs *** [ 0.753861] pnp: PnP ACPI init [ 0.756361] pnp: PnP ACPI: found 6 devices [ 0.772904] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.776895] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.779253] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.781585] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.784587] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.787431] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.790681] NET: Registered protocol family 2 [ 0.793539] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.798699] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.802209] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.807874] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.811241] TCP: Hash tables configured (established 65536 bind 65536) [ 0.814379] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.817589] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.820765] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.823917] NET: Registered protocol family 1 [ 0.826914] RPC: Registered named UNIX socket transport module. [ 0.829183] RPC: Registered udp transport module. [ 0.831082] RPC: Registered tcp transport module. [ 0.833275] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.835520] NET: Registered protocol family 44 [ 0.837224] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.839610] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.841952] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.844514] PCI: CLS 0 bytes, default 64 [ 0.846424] Unpacking initramfs... [ 2.328266] debug: unmapping init [mem 0xffff91043cc64000-0xffff91043ffcffff] [ 2.332553] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.334991] software IO TLB: mapped [mem 0x00000000a0a00000-0x00000000a4a00000] (64MB) [ 2.337869] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.841627] Initialise system trusted keyrings [ 2.843559] Key type blacklist registered [ 2.845840] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.854731] zbud: loaded [ 2.857698] *** VALIDATE nfs *** [ 2.859096] *** VALIDATE nfs4 *** [ 2.860415] pstore: using deflate compression [ 2.864926] Platform Keyring initialized [ 2.984669] NET: Registered protocol family 38 [ 2.987460] Key type asymmetric registered [ 2.989025] Asymmetric key parser 'x509' registered [ 2.991882] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.996447] io scheduler mq-deadline registered [ 2.999226] io scheduler kyber registered [ 3.000977] io scheduler bfq registered [ 3.003163] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.005659] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.008031] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.010537] ACPI: Power Button [PWRF] [ 3.016546] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.024090] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.038831] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.066975] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.095786] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.100682] Non-volatile memory driver v1.3 [ 3.102521] Linux agpgart interface v0.103 [ 3.136795] virtio_blk virtio1: [vda] 149960 512-byte logical blocks (76.8 MB/73.2 MiB) [ 3.139800] vda: detected capacity change from 0 to 76779520 [ 3.154771] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.157138] vdb: detected capacity change from 0 to 1073741824 [ 3.164424] libphy: Fixed MDIO Bus: probed [ 3.170427] usbcore: registered new interface driver usbserial_generic [ 3.173130] usbserial: USB Serial support registered for generic [ 3.175913] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.180276] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.181746] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.185197] mousedev: PS/2 mouse device common for all mice [ 3.189317] rtc_cmos 00:05: RTC can wake from S4 [ 3.191846] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.193556] rtc_cmos 00:05: registered as rtc0 [ 3.197641] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.200665] intel_pstate: CPU model not supported [ 3.203861] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.206263] hid: raw HID events driver (C) Jiri Kosina [ 3.209434] usbcore: registered new interface driver usbhid [ 3.211435] usbhid: USB HID core driver [ 3.213472] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.213505] drop_monitor: Initializing network drop monitor service [ 3.218616] Initializing XFRM netlink socket [ 3.220475] NET: Registered protocol family 10 [ 3.223328] Segment Routing with IPv6 [ 3.224642] NET: Registered protocol family 17 [ 3.226618] mpls_gso: MPLS GSO support [ 3.232783] RAS: Correctable Errors collector initialized. [ 3.234969] AVX version of gcm_enc/dec engaged. [ 3.236715] AES CTR mode by8 optimization enabled [ 3.313052] sched_clock: Marking stable (3313019914, 0)->(4206902611, -893882697) [ 3.317339] registered taskstats version 1 [ 3.319355] Loading compiled-in X.509 certificates [ 3.321650] zswap: loaded using pool lzo/zbud [ 3.347989] Key type big_key registered [ 3.362957] Key type encrypted registered [ 3.364619] ima: No TPM chip found, activating TPM-bypass! [ 3.366842] ima: Allocated hash algorithm: sha1 [ 3.368590] ima: No architecture policies found [ 3.370596] evm: Initialising EVM extended attributes: [ 3.372543] evm: security.selinux [ 3.373679] evm: security.ima [ 3.374927] evm: security.capability [ 3.376210] evm: HMAC attrs: 0x1 [ 3.379305] rtc_cmos 00:05: setting system clock to 2026-09-07 17:26:14 UTC (1788801974) [ 3.386250] debug: unmapping init [mem 0xffffffffa6803000-0xffffffffa69fffff] [ 3.389926] debug: unmapping init [mem 0xffffffffa5582000-0xffffffffa5858fff] [ 3.399102] Write protecting the kernel read-only data: 28672k [ 3.402480] debug: unmapping init [mem 0xffffffffa3c03000-0xffffffffa3dfffff] [ 3.407286] debug: unmapping init [mem 0xffffffffa4514000-0xffffffffa45fffff] [ 3.447197] 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.456681] systemd[1]: Detected virtualization kvm. [ 3.458801] systemd[1]: Detected architecture x86-64. [ 3.460959] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.487611] systemd[1]: No hostname configured. [ 3.489170] systemd[1]: Set hostname to . [ 3.490977] random: systemd: uninitialized urandom read (16 bytes read) [ 3.493917] systemd[1]: Initializing machine ID from random generator. [ 3.544096] random: ln: uninitialized urandom read (6 bytes read) [ 3.653924] random: systemd: uninitialized urandom read (16 bytes read) [ 3.657432] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 3.663320] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 3.670736] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ OK ] Reached target Initrd Root Device. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Swap. [ OK ] Reached target Paths. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Local File Systems. [ OK ] Listening on Journal Socket. Starting Create Volatile Files and Directories... [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Sockets. Starting Journal Service... Starting Setup Virtual Console... Starting Apply Kernel Variables... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. 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... [ 5.135742] device-mapper: uevent: version 1.0.3 [ 5.144191] 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. [ 6.625381] virtio_net virtio0 ens2: renamed from eth0 [ 6.855448] scsi host0: ata_piix [ 6.902768] scsi host1: ata_piix [ 6.904644] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 6.908103] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 11.825492] dracut-initqueue[577]: RTNETLINK answers: File exists [ 11.892223] random: crng init done [ 11.896596] 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. [ 14.126444] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Timers. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. Stopping udev Kernel Device Manager... [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Swap. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ 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... [ 16.325316] printk: systemd: 26 output lines suppressed due to ratelimiting [ 17.194382] SELinux: Disabled at runtime. [ 17.308516] 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) [ 17.319700] systemd[1]: Detected virtualization kvm. [ 17.321359] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 18.578259] systemd[1]: initrd-switch-root.service: Succeeded. [ 18.583537] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 18.593541] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 18.603395] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 18.610921] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 18.628629] systemd[1]: Starting Journal Service... Starting Journal Service... [ 18.640819] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ OK ] Started Forward Password Requests to Wall Directory Watch. Starting Apply Kernel Variables... Mounting POSIX Message Queue File System... Activating swap /dev/disk/by-label/SWAP... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Mounting Huge Pages File System... [ OK ] Listening on udev Kernel Socket. [ 18.854109] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Stopped target Switch Root. [ OK ] Created slice system-sshd\x2dkeygen.slice. Starting Remount Root and Kernel File Systems... Mounting Kernel Debug File System... [ OK ] Reached target Paths. [ OK ] Listening on Process Core Dump Socket. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Reached target rpc_pipefs.target. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Stopped target Initrd File Systems. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Stopped target Initrd Root File System. [ OK ] Created slice system-getty.slice. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Started Journal Service. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Huge Pages File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Kernel Debug File System. 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 udev Coldplug all Devices. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... Starting udev Kernel Device Manager... [ 19.652505] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /mnt. [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 20.352572] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 20.603925] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 20.880844] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 20.932498] EDAC sbridge: Ver: 1.1.2 [ 23.570494] Key type dns_resolver registered [ 24.172731] NFS: Registering the id_resolver key type [ 24.180446] Key type id_resolver registered [ 24.185373] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] 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 D-Bus System Message Bus. Starting Network Manager... Starting Restore /run/initramfs on shutdown... [ OK ] Reached target sshd-keygen.target. Starting Login Service... [ OK ] Started irqbalance daemon. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg657-client login: [ 67.691643] hrtimer: interrupt took 3953474 ns [ 76.437981] libcfs: loading out-of-tree module taints kernel. [ 76.792921] Key type ._llcrypt registered [ 76.795904] Key type .llcrypt registered [ 77.251229] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 77.274233] alg: No test for adler32 (adler32-zlib) [ 78.822590] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 79.750528] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 81.535234] Key type lgssc registered [ 83.053187] Lustre: Echo OBD driver; http://www.lustre.org/ [ 224.738671] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 228.985717] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 238.684808] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing check_logdir /tmp/testlogs/ [ 242.827577] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing yml_node [ 246.654433] Lustre: DEBUG MARKER: Client: 2.17.58.39 [ 248.685279] Lustre: DEBUG MARKER: MDS: 2.17.58.39 [ 250.342165] Lustre: lustre-OST0000-osc-ffff910498053000: disconnect after 23s idle [ 250.838859] Lustre: DEBUG MARKER: OSS: 2.17.58.39 [ 252.166984] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Mon Sep 7 13:30:22 EDT 2026 [ 265.439137] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 266.675317] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 268.558758] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 278.870297] Lustre: Unmounted lustre-client [ 381.277918] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 386.522664] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 410.521285] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 444.848771] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 13:33:34 (1788802414) [ 446.689703] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 448.285180] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 13:33:37 (1788802417) [ 483.150673] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 488.984762] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 509.673716] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 510.352273] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 518.394133] Lustre: Unmounted lustre-client [ 544.937455] Key type lgssc unregistered [ 545.242131] LNet: 15008:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 545.248359] LNetError: 15008:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 545.266278] LNet: Removed LNI 192.168.206.57@tcp [ 546.033159] Key type .llcrypt unregistered [ 546.038890] Key type ._llcrypt unregistered [ 562.504560] Key type ._llcrypt registered [ 562.510260] Key type .llcrypt registered [ 563.992914] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 13:35:33 (1788802533) [ 565.802362] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 565.829458] alg: No test for adler32 (adler32-zlib) [ 566.982988] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 567.227318] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 568.903247] Key type lgssc registered [ 570.198056] Lustre: Echo OBD driver; http://www.lustre.org/ [ 641.317231] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 725.754733] Lustre: Unmounted lustre-client [ 749.047717] Key type lgssc unregistered [ 749.331097] LNet: 18793:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 749.338345] LNetError: 18793:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 749.360648] LNet: Removed LNI 192.168.206.57@tcp [ 750.232881] Key type .llcrypt unregistered [ 750.235124] Key type ._llcrypt unregistered [ 759.780868] Key type ._llcrypt registered [ 759.784622] Key type .llcrypt registered [ 761.234814] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 13:38:50 (1788802730) [ 763.178479] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 763.288621] alg: No test for adler32 (adler32-zlib) [ 764.390689] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 764.681229] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 766.407394] Key type lgssc registered [ 767.706875] Lustre: Echo OBD driver; http://www.lustre.org/ [ 813.066412] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 818.755462] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 838.973499] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 841.707691] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 853.033628] Lustre: Unmounted lustre-client [ 880.413861] Key type lgssc unregistered [ 880.728619] LNet: 22386:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 880.739723] LNetError: 22386:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 880.761551] LNet: Removed LNI 192.168.206.57@tcp [ 881.857599] Key type .llcrypt unregistered [ 881.860826] Key type ._llcrypt unregistered [ 892.444718] Key type ._llcrypt registered [ 892.447520] Key type .llcrypt registered [ 893.680809] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 13:41:03 (1788802863) [ 896.717392] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 896.749030] alg: No test for adler32 (adler32-zlib) [ 897.738162] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 897.971711] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 899.711566] Key type lgssc registered [ 900.798119] Lustre: Echo OBD driver; http://www.lustre.org/ [ 946.301067] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 951.625788] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 972.175897] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 974.827993] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 980.920150] Lustre: Unmounted lustre-client [ 1006.439464] Key type lgssc unregistered [ 1006.675931] LNet: 25573:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1006.687309] LNetError: 25573:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1006.716345] LNet: Removed LNI 192.168.206.57@tcp [ 1007.410415] Key type .llcrypt unregistered [ 1007.413940] Key type ._llcrypt unregistered [ 1021.525173] Key type ._llcrypt registered [ 1021.528836] Key type .llcrypt registered [ 1022.555087] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 13:43:12 (1788802992) [ 1024.444795] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1024.593805] alg: No test for adler32 (adler32-zlib) [ 1025.648472] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 1025.838846] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 1027.511327] Key type lgssc registered [ 1028.508863] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1073.336781] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1079.884672] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1101.510611] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1103.263637] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 1108.464791] Lustre: lustre-MDT0000-mdc-ffff910487c03000: Connection to lustre-MDT0000 (at 192.168.206.157@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1118.699168] Lustre: lustre-MDT0001-mdc-ffff910487c03000: Connection to lustre-MDT0001 (at 192.168.206.157@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1125.783549] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1128.927378] Lustre: 26527:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788803084/real 1788803084] req@0000000088bddb63 x1875695890339200/t0(0) o400->MGC192.168.206.157@tcp@192.168.206.157@tcp:26/25 lens 224/224 e 0 to 1 dl 1788803100 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1128.959034] LustreError: MGC192.168.206.157@tcp: Connection to MGS (at 192.168.206.157@tcp) was lost; in progress operations using this service will fail [ 1136.101306] Lustre: Unmounted lustre-client [ 1155.464943] Key type lgssc unregistered [ 1155.782872] LNet: 29221:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1155.788638] LNetError: 29221:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1156.840174] LNet: Removed LNI 192.168.206.57@tcp [ 1157.749202] Key type .llcrypt unregistered [ 1157.751062] Key type ._llcrypt unregistered [ 1174.446958] Key type ._llcrypt registered [ 1174.449738] Key type .llcrypt registered [ 1175.484886] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 13:45:45 (1788803145) [ 1177.510835] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1177.544181] alg: No test for adler32 (adler32-zlib) [ 1178.598804] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 1178.853718] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 1180.511210] Key type lgssc registered [ 1181.512818] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1225.819872] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1230.880862] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1250.303127] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1252.931715] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 1257.970645] Lustre: lustre-OST0000-osc-ffff910487c03000: Connection to lustre-OST0000 (at 192.168.206.157@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1264.668762] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1275.001801] Lustre: Unmounted lustre-client [ 1308.866310] Key type lgssc unregistered [ 1309.137633] LNet: 32827:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1309.148302] LNetError: 32827:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1309.168281] LNet: Removed LNI 192.168.206.57@tcp [ 1309.915350] Key type .llcrypt unregistered [ 1309.917166] Key type ._llcrypt unregistered [ 1327.114508] Key type ._llcrypt registered [ 1327.116279] Key type .llcrypt registered [ 1328.658822] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 13:48:18 (1788803298) [ 1330.918907] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1330.990556] alg: No test for adler32 (adler32-zlib) [ 1332.132302] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 1332.442696] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 1334.095248] Key type lgssc registered [ 1335.132332] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1391.315121] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1397.772741] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1418.115994] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1420.925896] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 1427.332274] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1430.503632] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1442.047310] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-09-0713h50m11s [ 1444.550535] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1451.499941] Lustre: lustre-MDT0000-mdc-ffff910498053000: Connection to lustre-MDT0000 (at 192.168.206.157@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1455.434563] LustreError: 36612:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff910498053000: can't stat MDS #0: rc = -19 [ 1456.764890] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1464.813106] LustreError: MGC192.168.206.157@tcp: Connection to MGS (at 192.168.206.157@tcp) was lost; in progress operations using this service will fail [ 1464.822108] LustreError: lustre-MDT0000-mdc-ffff910498053000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1464.857597] Lustre: lustre-MDT0000-mdc-ffff910498053000: Connection restored to 192.168.206.157@tcp (at 192.168.206.157@tcp) [ 1464.858240] Lustre: Evicted from MGS (at 192.168.206.157@tcp) after server handle changed from 0x31c12f5af113afd6 to 0x31c12f5af113b28b [ 1471.997547] Lustre: DEBUG MARKER: Wait for df (36612) ... [ 1473.485225] Lustre: DEBUG MARKER: done [ 1475.693247] Lustre: Unmounted lustre-client [ 1510.884391] Key type lgssc unregistered [ 1511.104583] LNet: 37628:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1511.128216] LNetError: 37628:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1511.151327] LNet: Removed LNI 192.168.206.57@tcp [ 1511.834959] Key type .llcrypt unregistered [ 1511.839233] Key type ._llcrypt unregistered [ 1535.789865] Key type ._llcrypt registered [ 1535.792812] Key type .llcrypt registered [ 1536.863955] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 13:51:46 (1788803506) [ 1539.120986] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1539.161197] alg: No test for adler32 (adler32-zlib) [ 1540.236270] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 1540.505202] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 1542.199716] Key type lgssc registered [ 1543.515580] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1596.502163] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1603.254861] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1624.739665] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1626.558546] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 1630.900933] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-09-0713h53m20s [ 1632.324203] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1638.885719] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1641.276459] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1661.919155] Lustre: 41772:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788803612/real 1788803612] req@000000001755e734 x1875696430356608/t0(0) o101->lustre-MDT0000-mdc-ffff910487e0d000@192.168.206.157@tcp:12/10 lens 576/1152 e 0 to 1 dl 1788803632 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1661.946447] Lustre: lustre-MDT0000-mdc-ffff910487e0d000: Connection to lustre-MDT0000 (at 192.168.206.157@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1661.996165] Lustre: lustre-MDT0000-mdc-ffff910487e0d000: Connection restored to 192.168.206.157@tcp (at 192.168.206.157@tcp) [ 1683.499280] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1686.320936] Lustre: DEBUG MARKER: done [ 1690.246212] Lustre: Unmounted lustre-client [ 1714.505915] Key type lgssc unregistered [ 1714.770908] LNet: 42647:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1714.777975] LNetError: 42647:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1714.809772] LNet: Removed LNI 192.168.206.57@tcp [ 1715.652234] Key type .llcrypt unregistered [ 1715.655708] Key type ._llcrypt unregistered [ 1744.738724] Key type ._llcrypt registered [ 1744.744124] Key type .llcrypt registered [ 1746.018597] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 13:55:15 (1788803715) [ 1747.470947] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1748.911295] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 13:55:18 (1788803718) [ 1752.773663] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1752.794623] alg: No test for adler32 (adler32-zlib) [ 1754.133810] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 1754.547616] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 1756.247333] Key type lgssc registered [ 1758.054280] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1789.051928] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 13:55:58 (1788803758) [ 1821.985663] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1826.928797] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1849.087732] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1851.059426] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 1854.572231] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1858.712752] Lustre: Unmounted lustre-client [ 1874.783509] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 1924.210684] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1931.731451] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1932.522920] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 1936.045370] Lustre: Unmounted lustre-client [ 2005.230309] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2010.783898] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2011.534048] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 2015.777719] Lustre: Unmounted lustre-client [ 2039.817890] Lustre: DEBUG MARKER: files compared the same [ 2061.865624] Key type lgssc unregistered [ 2062.148420] LNet: 50272:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2062.160559] LNetError: 50272:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2062.188091] LNet: Removed LNI 192.168.206.57@tcp [ 2062.701890] Key type .llcrypt unregistered [ 2062.707659] Key type ._llcrypt unregistered [ 2082.107882] Key type ._llcrypt registered [ 2082.110643] Key type .llcrypt registered [ 2083.531664] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 14:00:53 (1788804053) [ 2086.197256] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2086.221275] alg: No test for adler32 (adler32-zlib) [ 2087.527128] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 2087.960937] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 2089.777220] Key type lgssc registered [ 2091.373463] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2140.557128] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2146.412474] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2172.420390] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2174.603739] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 2179.971128] Lustre: Unmounted lustre-client [ 2209.184501] Key type lgssc unregistered [ 2209.529896] LNet: 53865:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2209.544848] LNetError: 53865:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2209.580245] LNet: Removed LNI 192.168.206.57@tcp [ 2210.619092] Key type .llcrypt unregistered [ 2210.622038] Key type ._llcrypt unregistered [ 2227.601586] Key type ._llcrypt registered [ 2227.603165] Key type .llcrypt registered [ 2228.850718] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 14:03:18 (1788804198) [ 2231.537586] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2231.547208] alg: No test for adler32 (adler32-zlib) [ 2232.644242] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 2232.842716] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 2234.463307] Key type lgssc registered [ 2235.343384] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2320.319701] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2356.177442] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2361.914626] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2392.520698] Key type lgssc unregistered [ 2392.852507] LNet: 57346:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2392.866066] LNetError: 57346:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2392.889274] LNet: Removed LNI 192.168.206.57@tcp [ 2393.621224] Key type .llcrypt unregistered [ 2393.625796] Key type ._llcrypt unregistered [ 2410.697641] Key type ._llcrypt registered [ 2410.701844] Key type .llcrypt registered [ 2412.447501] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 14:06:21 (1788804381) [ 2416.161970] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2416.200171] alg: No test for adler32 (adler32-zlib) [ 2417.493196] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 2417.899303] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 2419.640237] Key type lgssc registered [ 2421.275834] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2497.172334] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 2504.401912] Lustre: Unmounted lustre-client [ 2550.551268] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 14:08:40 (1788804520) [ 2676.575166] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2698.322778] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 2706.760757] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2706.773289] LustreError: 63817:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff910487c01800: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2706.818173] Lustre: 58328:0:(llite_lib.c:4342:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.206.157@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2717.138865] Lustre: Unmounted lustre-client [ 2768.432980] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 14:12:18 (1788804738) [ 2782.314794] Key type lgssc unregistered [ 2782.567399] LNet: 65030:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2782.572961] LNetError: 65030:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2782.587418] LNet: Removed LNI 192.168.206.57@tcp [ 2783.410474] Key type .llcrypt unregistered [ 2783.413845] Key type ._llcrypt unregistered [ 2794.412462] Key type ._llcrypt registered [ 2794.417276] Key type .llcrypt registered [ 2794.797053] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2794.833207] alg: No test for adler32 (adler32-zlib) [ 2795.891271] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 2796.140326] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 2797.847899] Key type lgssc registered [ 2798.922240] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2847.604780] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2853.158806] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2874.348610] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2875.987687] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 2882.818509] Lustre: Unmounted lustre-client [ 2906.334412] Key type lgssc unregistered [ 2906.633238] LNet: 68036:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2906.640157] LNetError: 68036:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2906.660840] LNet: Removed LNI 192.168.206.57@tcp [ 2907.455645] Key type .llcrypt unregistered [ 2907.457845] Key type ._llcrypt unregistered [ 2918.802287] Key type ._llcrypt registered [ 2918.810162] Key type .llcrypt registered [ 2919.438434] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2919.470619] alg: No test for adler32 (adler32-zlib) [ 2920.519160] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 2920.777163] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 2922.511692] Key type lgssc registered [ 2923.429689] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3015.621918] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3019.652440] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3037.760925] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3043.397802] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 3045.318127] Lustre: Unmounted lustre-client [ 3068.878653] Key type lgssc unregistered [ 3069.097723] LNet: 71471:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3069.103783] LNetError: 71471:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3069.122553] LNet: Removed LNI 192.168.206.57@tcp [ 3069.721718] Key type .llcrypt unregistered [ 3069.724560] Key type ._llcrypt unregistered [ 3082.752648] Key type ._llcrypt registered [ 3082.755655] Key type .llcrypt registered [ 3083.850383] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 14:17:33 (1788805053) [ 3085.572624] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3085.625159] alg: No test for adler32 (adler32-zlib) [ 3086.598472] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 3086.801931] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 3088.439211] Key type lgssc registered [ 3089.198661] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3127.868191] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3132.366229] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3149.046883] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3156.000486] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 3160.339130] Lustre: Unmounted lustre-client [ 3160.746573] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 3163.178368] Lustre: Unmounted lustre-client [ 3192.255820] Key type lgssc unregistered [ 3192.475960] LNet: 75147:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3192.481834] LNetError: 75147:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3192.499966] LNet: Removed LNI 192.168.206.57@tcp [ 3193.069882] Key type .llcrypt unregistered [ 3193.073458] Key type ._llcrypt unregistered [ 3203.015026] Key type ._llcrypt registered [ 3203.017735] Key type .llcrypt registered [ 3203.501139] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3203.516090] alg: No test for adler32 (adler32-zlib) [ 3204.511371] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 3204.689559] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 3206.343218] Key type lgssc registered [ 3207.336150] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3246.071258] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3250.105563] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3267.512856] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3273.245350] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 3277.021137] Lustre: Unmounted lustre-client [ 3300.491746] Key type lgssc unregistered [ 3300.678718] LNet: 78179:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3300.686928] LNetError: 78179:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3300.705928] LNet: Removed LNI 192.168.206.57@tcp [ 3301.252629] Key type .llcrypt unregistered [ 3301.254653] Key type ._llcrypt unregistered [ 3312.627996] Key type ._llcrypt registered [ 3312.631025] Key type .llcrypt registered [ 3313.609851] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 14:21:23 (1788805283) [ 3315.198497] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3315.323792] alg: No test for adler32 (adler32-zlib) [ 3316.326219] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 3316.548104] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 3318.183230] Key type lgssc registered [ 3319.120403] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3357.923745] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3361.608893] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3375.870539] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3387.921992] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 3392.914898] Lustre: lustre: root_squash is set to 0:0 [ 3402.778860] Lustre: lustre: nosquash_nids is cleared [ 3402.790286] Lustre: lustre: root_squash is set to 500:500 [ 3409.999440] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.206.57@tcp 192.168.0.[2,10]@tcp [ 3419.687985] Lustre: lustre: nosquash_nids set to 192.168.206.57/24@tcp [ 3422.906920] Lustre: Unmounted lustre-client [ 3453.194977] Key type lgssc unregistered [ 3453.373316] LNet: 82133:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3453.377747] LNetError: 82133:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3453.388897] LNet: Removed LNI 192.168.206.57@tcp [ 3453.806503] Key type .llcrypt unregistered [ 3453.808543] Key type ._llcrypt unregistered [ 3462.783191] Key type ._llcrypt registered [ 3462.784754] Key type .llcrypt registered [ 3463.573245] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 14:23:53 (1788805433) [ 3464.990491] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3465.890840] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 14:23:56 (1788805436) [ 3467.307258] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3467.318723] alg: No test for adler32 (adler32-zlib) [ 3468.294551] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 3468.453777] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 3470.079232] Key type lgssc registered [ 3470.816975] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3503.592733] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3507.411475] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3523.108395] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3524.505857] Lustre: lustre: root_squash is set to 500:500 [ 3524.522205] Lustre: lustre: nosquash_nids set to 192.168.206.57/24@tcp [ 3534.881184] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 3539.349203] Lustre: Unmounted lustre-client [ 3561.374552] Key type lgssc unregistered [ 3561.585655] LNet: 86088:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3561.594314] LNetError: 86088:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3561.613604] LNet: Removed LNI 192.168.206.57@tcp [ 3562.180283] Key type .llcrypt unregistered [ 3562.183637] Key type ._llcrypt unregistered [ 3572.734562] Key type ._llcrypt registered [ 3572.735829] Key type .llcrypt registered [ 3573.546524] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 3574.713697] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 14:25:44 (1788805544) [ 3588.047709] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3588.164424] alg: No test for adler32 (adler32-zlib) [ 3589.186803] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 3589.433674] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 3591.111367] Key type lgssc registered [ 3591.907640] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3644.629269] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3648.612108] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3665.164504] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3714.850686] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3718.406295] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3731.112091] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3739.967287] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 3763.709789] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff910491e4c800.ost_server_uuid 50 [ 3764.606592] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff910491e4c800.ost_server_uuid in FULL state after 0 sec [ 3764.970931] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 3767.077551] Lustre: Unmounted lustre-client [ 3768.149808] Lustre: Unmounted lustre-client [ 3807.668918] Key type lgssc unregistered [ 3807.865727] LNet: 93738:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3807.872461] LNetError: 93738:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3807.888730] LNet: Removed LNI 192.168.206.57@tcp [ 3808.271428] Key type .llcrypt unregistered [ 3808.273136] Key type ._llcrypt unregistered [ 3828.655432] Key type ._llcrypt registered [ 3828.656837] Key type .llcrypt registered [ 3829.365426] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 14:29:59 (1788805799) [ 3841.990365] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3842.034650] alg: No test for adler32 (adler32-zlib) [ 3843.002605] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 3843.153705] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 3844.783547] Key type lgssc registered [ 3845.578188] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3897.191197] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3900.116844] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3913.509709] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3914.917349] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 3935.718135] Lustre: lustre-OST0000-osc-ffff910488923800: Connection to lustre-OST0000 (at 192.168.206.157@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3936.735328] Lustre: 95095:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788805891/real 1788805891] req@00000000bea88fac x1875698845226112/t0(0) o400->lustre-OST0000-osc-ffff910488923800@192.168.206.157@tcp:28/4 lens 224/224 e 0 to 1 dl 1788805907 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3937.450970] Lustre: lustre-OST0000-osc-ffff910488923800: Connection restored to 192.168.206.157@tcp (at 192.168.206.157@tcp) [ 3940.836571] Lustre: lustre-MDT0000-mdc-ffff910488923800: Connection to lustre-MDT0000 (at 192.168.206.157@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3941.919150] Lustre: 95095:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788805896/real 1788805896] req@0000000036be49a0 x1875698845226624/t0(0) o400->lustre-OST0000-osc-ffff910488923800@192.168.206.157@tcp:28/4 lens 224/224 e 0 to 1 dl 1788805912 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3946.976442] Lustre: 95095:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788805901/real 1788805901] req@00000000051063de x1875698845227136/t0(0) o400->lustre-OST0000-osc-ffff910488923800@192.168.206.157@tcp:28/4 lens 224/224 e 0 to 1 dl 1788805917 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3954.655332] Lustre: 98261:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788805909/real 1788805909] req@000000008fbb8dd4 x1875698845228416/t0(0) o101->MGC192.168.206.157@tcp@192.168.206.157@tcp:26/25 lens 328/344 e 0 to 1 dl 1788805925 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3954.670729] LustreError: MGC192.168.206.157@tcp: Connection to MGS (at 192.168.206.157@tcp) was lost; in progress operations using this service will fail [ 3954.676084] Lustre: 98261:0:(mgc_request.c:1900:mgc_process_log()) MGC192.168.206.157@tcp: IR log lustre-cliir failed, not fatal: rc = -5 [ 3954.687141] Lustre: Evicted from MGS (at 192.168.206.157@tcp) after server handle changed from 0xebcdcb80e90dcee to 0xebcdcb80e90df79 [ 3954.693130] Lustre: MGC192.168.206.157@tcp: Connection restored to 192.168.206.157@tcp (at 192.168.206.157@tcp) [ 3958.948663] Lustre: lustre-MDT0000-mdc-ffff910488923800: Connection restored to 192.168.206.157@tcp (at 192.168.206.157@tcp) [ 3959.721317] Lustre: Unmounted lustre-client [ 3988.134181] Key type lgssc unregistered [ 3988.280338] LNet: 99379:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3988.286232] LNetError: 99379:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3988.296021] LNet: Removed LNI 192.168.206.57@tcp [ 3988.633760] Key type .llcrypt unregistered [ 3988.635651] Key type ._llcrypt unregistered [ 3995.922908] Key type ._llcrypt registered [ 3995.924800] Key type .llcrypt registered [ 3996.566533] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 14:32:46 (1788805966) [ 4009.090486] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4009.099637] alg: No test for adler32 (adler32-zlib) [ 4010.066100] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 4010.206720] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 4011.831241] Key type lgssc registered [ 4012.532786] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4035.085703] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4085.160190] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4087.520969] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4099.621429] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4100.971752] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 4101.925223] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 4102.748609] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 14:34:33 (1788806073) [ 4207.886928] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4218.175525] Lustre: Unmounted lustre-client [ 4290.814584] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4293.513354] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4305.594480] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4343.585787] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 14:38:33 (1788806313) [ 4387.640355] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4390.168129] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4401.460376] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4401.730415] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 4403.223643] Lustre: Unmounted lustre-client [ 4425.060495] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 14:39:55 (1788806395) [ 4470.449971] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4473.311720] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4485.069774] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4485.431412] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 4487.307875] Lustre: Unmounted lustre-client [ 4514.439613] Key type lgssc unregistered [ 4514.572796] LNet: 122317:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4514.576640] LNetError: 122317:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4514.587761] LNet: Removed LNI 192.168.206.57@tcp [ 4514.892443] Key type .llcrypt unregistered [ 4514.894217] Key type ._llcrypt unregistered [ 4521.397994] Key type ._llcrypt registered [ 4521.399525] Key type .llcrypt registered [ 4522.071376] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 14:41:32 (1788806492) [ 4523.165039] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4523.176275] alg: No test for adler32 (adler32-zlib) [ 4524.077668] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 4524.213800] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 4525.823256] Key type lgssc registered [ 4526.357641] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4550.763385] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4553.021305] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4562.335968] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4569.067092] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 4583.714646] Lustre: Unmounted lustre-client [ 4602.234101] Key type lgssc unregistered [ 4602.358389] LNet: 125896:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4602.361967] LNetError: 125896:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4602.369630] LNet: Removed LNI 192.168.206.57@tcp [ 4602.645245] Key type .llcrypt unregistered [ 4602.647115] Key type ._llcrypt unregistered [ 4608.496394] Key type ._llcrypt registered [ 4608.498011] Key type .llcrypt registered [ 4609.041794] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 14:42:59 (1788806579) [ 4609.979771] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4610.011715] alg: No test for adler32 (adler32-zlib) [ 4610.888895] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 4610.995306] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 4612.591207] Key type lgssc registered [ 4613.016350] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4634.242847] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4636.464312] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4644.147460] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4649.470253] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 4654.564460] Lustre: lustre-OST0000-osc-ffff910484409800: Connection to lustre-OST0000 (at 192.168.206.157@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4660.489178] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff910484409800.ost_server_uuid 50 [ 4661.164913] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 4670.762281] Lustre: Unmounted lustre-client [ 4682.659588] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 14:44:13 (1788806653) [ 4695.675476] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4697.928599] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4705.373877] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4714.442328] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4714.672339] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 4720.104197] Lustre: lustre-OST0000-osc-ffff910484215000: Connection to lustre-OST0000 (at 192.168.206.157@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4735.461339] Lustre: Unmounted lustre-client [ 4760.548617] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 14:45:31 (1788806731) [ 4772.770270] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4774.928920] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4782.960340] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4790.804237] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4791.027083] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 4796.389754] Lustre: lustre-OST0000-osc-ffff910484409000: Connection to lustre-OST0000 (at 192.168.206.157@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4808.090387] Lustre: Unmounted lustre-client [ 4832.983110] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 14:46:43 (1788806803) [ 4869.346395] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4871.582307] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4880.228430] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4914.490268] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4916.720142] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4924.701770] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4943.497669] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 4971.413350] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4971.945577] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 4972.308053] Lustre: Unmounted lustre-client [ 4983.299147] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 14:49:13 (1788806953) [ 4994.653445] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4996.624576] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5003.992785] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5021.488207] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5036.694676] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 5065.189501] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5065.763373] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 5067.750498] Lustre: lustre-OST0001-osc-ffff9104a0b96800: Connection to lustre-OST0001 (at 192.168.206.157@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5069.477499] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5079.741416] Lustre: Unmounted lustre-client [ 5120.117842] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 14:51:30 (1788807090) [ 5132.802403] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5135.065538] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5143.026142] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5143.219279] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 5151.185434] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5151.704733] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 5158.985276] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff910487899800.ost_server_uuid 50 [ 5159.524419] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff910487899800.ost_server_uuid in FULL state after 0 sec [ 5160.286276] Lustre: Unmounted lustre-client [ 5160.394250] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 5160.397369] Lustre: Skipped 3 previous similar messages [ 5160.432333] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 5160.868679] LustreError: 149197:0:(sec.c:409:import_sec_validate_get()) import 000000007ea92594 (NEW) with no sec [ 5193.572280] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 14:52:44 (1788807164) [ 5206.545825] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5208.565706] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5215.885276] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5223.424625] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5223.589619] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 5223.639876] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 5233.553114] Lustre: Unmounted lustre-client [ 5233.554402] Lustre: Skipped 1 previous similar message [ 5266.945709] Key type lgssc unregistered [ 5267.079742] LNet: 152602:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5267.082810] LNetError: 152602:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5267.096931] LNet: Removed LNI 192.168.206.57@tcp [ 5267.391741] Key type .llcrypt unregistered [ 5267.393096] Key type ._llcrypt unregistered [ 5273.098658] Key type ._llcrypt registered [ 5273.099845] Key type .llcrypt registered [ 5273.629480] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 14:54:04 (1788807244) [ 5274.031978] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5274.038253] alg: No test for adler32 (adler32-zlib) [ 5274.910385] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 5275.008959] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 5276.599218] Key type lgssc registered [ 5277.032275] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5297.936636] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5299.898653] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5306.543632] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5313.367956] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5313.539625] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 5314.591399] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 5323.244480] LustreError: lustre-MDT0001-mdc-ffff91048789e000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 5323.250672] Lustre: lustre-MDT0001-mdc-ffff91048789e000: Connection restored to 192.168.206.157@tcp (at 192.168.206.157@tcp) [ 5325.149117] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5325.671104] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 5338.085309] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 5339.682180] Lustre: Unmounted lustre-client [ 5358.939171] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 14:55:29 (1788807329) [ 5391.811163] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5393.782532] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5401.995649] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5402.183619] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 5412.793292] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5413.299305] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 5424.611751] Lustre: lustre-OST0001-osc-ffff910491e4e000: Connection to lustre-OST0001 (at 192.168.206.157@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5430.602891] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5440.887152] Lustre: Unmounted lustre-client [ 5464.498670] Key type lgssc unregistered [ 5464.616585] LNet: 162205:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5464.619448] LNetError: 162205:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5464.630977] LNet: Removed LNI 192.168.206.57@tcp [ 5464.893690] Key type .llcrypt unregistered [ 5464.894787] Key type ._llcrypt unregistered [ 5475.491167] Key type ._llcrypt registered [ 5475.492510] Key type .llcrypt registered [ 5475.971722] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 14:57:26 (1788807446) [ 5476.732310] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5476.740571] alg: No test for adler32 (adler32-zlib) [ 5477.606496] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 5477.700537] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 5479.287546] Key type lgssc registered [ 5479.733256] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5497.654655] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5499.301679] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5505.447319] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5506.621639] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 5543.271164] Lustre: Unmounted lustre-client [ 5559.682959] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5559.820262] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 5591.911945] Lustre: Unmounted lustre-client [ 5617.199973] Key type lgssc unregistered [ 5617.314679] LNet: 166625:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5617.317027] LNetError: 166625:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5617.326597] LNet: Removed LNI 192.168.206.57@tcp [ 5617.571312] Key type .llcrypt unregistered [ 5617.572146] Key type ._llcrypt unregistered [ 5623.062189] Key type ._llcrypt registered [ 5623.063065] Key type .llcrypt registered [ 5623.543227] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 14:59:54 (1788807594) [ 5624.392913] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5624.405978] alg: No test for adler32 (adler32-zlib) [ 5625.258494] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 5625.355916] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 5626.935347] Key type lgssc registered [ 5627.309192] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5644.846279] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5646.536705] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5652.951673] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5663.729031] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 5670.452934] Lustre: Unmounted lustre-client [ 5687.480227] Key type lgssc unregistered [ 5687.604697] LNet: 170363:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5687.608288] LNetError: 170363:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5687.615859] LNet: Removed LNI 192.168.206.57@tcp [ 5687.868093] Key type .llcrypt unregistered [ 5687.869169] Key type ._llcrypt unregistered [ 5692.304509] Key type ._llcrypt registered [ 5692.305955] Key type .llcrypt registered [ 5692.558040] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5692.565114] alg: No test for adler32 (adler32-zlib) [ 5693.428278] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 5693.534765] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 5695.127281] Key type lgssc registered [ 5695.513824] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5712.975249] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5714.886498] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5722.389340] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5732.847882] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 5735.700173] Lustre: Unmounted lustre-client [ 5752.852128] Key type lgssc unregistered [ 5752.978753] LNet: 173419:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5752.981943] LNetError: 173419:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5752.993454] LNet: Removed LNI 192.168.206.57@tcp [ 5753.232886] Key type .llcrypt unregistered [ 5753.234334] Key type ._llcrypt unregistered [ 5758.448716] Key type ._llcrypt registered [ 5758.449838] Key type .llcrypt registered [ 5758.955432] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 15:02:09 (1788807729) [ 5759.831591] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5759.844561] alg: No test for adler32 (adler32-zlib) [ 5760.724834] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 5760.841964] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 5762.431266] Key type lgssc registered [ 5762.872652] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5781.650296] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5783.462301] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5789.995783] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5800.434446] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 5806.836297] Lustre: Unmounted lustre-client [ 5823.804322] Key type lgssc unregistered [ 5823.929762] LNet: 177153:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5823.933336] LNetError: 177153:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5823.945257] LNet: Removed LNI 192.168.206.57@tcp [ 5824.199644] Key type .llcrypt unregistered [ 5824.200632] Key type ._llcrypt unregistered [ 5828.135579] Key type ._llcrypt registered [ 5828.136511] Key type .llcrypt registered [ 5828.310946] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5828.318311] alg: No test for adler32 (adler32-zlib) [ 5829.167324] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 5829.268284] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 5830.855159] Key type lgssc registered [ 5831.278070] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5848.908821] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5850.621723] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5857.044534] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5867.504915] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 5870.289264] Lustre: Unmounted lustre-client [ 5887.404322] Key type lgssc unregistered [ 5887.510427] LNet: 180197:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5887.513231] LNetError: 180197:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5887.524708] LNet: Removed LNI 192.168.206.57@tcp [ 5887.755888] Key type .llcrypt unregistered [ 5887.757594] Key type ._llcrypt unregistered [ 5892.697173] Key type ._llcrypt registered [ 5892.698130] Key type .llcrypt registered [ 5893.146467] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 15:04:23 (1788807863) [ 5900.730422] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5900.736414] alg: No test for adler32 (adler32-zlib) [ 5901.597740] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 5901.690749] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 5903.279173] Key type lgssc registered [ 5903.674565] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5933.083996] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5934.871314] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5942.755664] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5965.457245] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 15:05:36 (1788807936) [ 5975.734840] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5977.516696] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5983.878696] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5994.480302] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 5996.390840] Lustre: Unmounted lustre-client [ 6013.552249] Key type lgssc unregistered [ 6013.682314] LNet: 187259:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6013.684706] LNetError: 187259:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6013.693762] LNet: Removed LNI 192.168.206.57@tcp [ 6013.932046] Key type .llcrypt unregistered [ 6013.932887] Key type ._llcrypt unregistered [ 6019.104590] Key type ._llcrypt registered [ 6019.105793] Key type .llcrypt registered [ 6019.540058] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 6020.097387] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 15:06:30 (1788807990) [ 6025.952411] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6025.965338] alg: No test for adler32 (adler32-zlib) [ 6026.826242] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 6026.911364] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 6028.495255] Key type lgssc registered [ 6028.875291] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6059.767182] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6061.462630] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6069.434810] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6075.967659] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6077.158330] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 6083.248195] Lustre: Unmounted lustre-client [ 6144.864772] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6146.666703] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6154.218514] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6186.702702] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 15:09:17 (1788808157) [ 6187.216959] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 6187.762861] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 15:09:18 (1788808158) [ 6232.012849] Key type lgssc unregistered [ 6232.129388] LNet: 198642:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6232.132587] LNetError: 198642:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6232.141835] LNet: Removed LNI 192.168.206.57@tcp [ 6232.412181] Key type .llcrypt unregistered [ 6232.413500] Key type ._llcrypt unregistered [ 6237.911379] Key type ._llcrypt registered [ 6237.912335] Key type .llcrypt registered [ 6238.370624] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 15:10:08 (1788808208) [ 6261.341661] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6261.385182] alg: No test for adler32 (adler32-zlib) [ 6262.252957] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 6262.344910] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 6263.927207] Key type lgssc registered [ 6264.301875] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6279.804802] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6281.605750] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6301.325294] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6311.884114] Key type lgssc unregistered [ 6311.991343] LNet: 202353:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6311.994832] LNetError: 202353:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6312.003279] LNet: Removed LNI 192.168.206.57@tcp [ 6312.212684] Key type .llcrypt unregistered [ 6312.213508] Key type ._llcrypt unregistered [ 6316.682089] Key type ._llcrypt registered [ 6316.682973] Key type .llcrypt registered [ 6317.067447] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 15:11:27 (1788808287) [ 6317.753525] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6317.768217] alg: No test for adler32 (adler32-zlib) [ 6318.610534] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 6318.685860] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 6320.263230] Key type lgssc registered [ 6320.604350] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6337.701765] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6339.298249] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6347.977615] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6359.514772] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 6364.642503] Lustre: lustre-MDT0000-mdc-ffff9104a017b000: Connection to lustre-MDT0000 (at 192.168.206.157@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6369.761499] Lustre: lustre-MDT0001-mdc-ffff9104a017b000: Connection to lustre-MDT0001 (at 192.168.206.157@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6385.119200] Lustre: 203338:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788808340/real 1788808340] req@00000000b32769db x1875701440528768/t0(0) o400->MGC192.168.206.157@tcp@192.168.206.157@tcp:26/25 lens 224/224 e 0 to 1 dl 1788808356 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6385.121262] LustreError: MGC192.168.206.157@tcp: Connection to MGS (at 192.168.206.157@tcp) was lost; in progress operations using this service will fail [ 6385.137418] Lustre: Evicted from MGS (at 192.168.206.157@tcp) after server handle changed from 0xbb1157226c087351 to 0xbb1157226c088cb8 [ 6385.141041] Lustre: MGC192.168.206.157@tcp: Connection restored to 192.168.206.157@tcp (at 192.168.206.157@tcp) [ 6402.613460] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6405.603236] LustreError: lustre-MDT0000-mdc-ffff9104a017b000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 6405.621951] Lustre: lustre-MDT0000-mdc-ffff9104a017b000: Connection restored to 192.168.206.157@tcp (at 192.168.206.157@tcp) [ 6406.227869] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 3 sec [ 6408.051969] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6408.567730] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6409.210462] Lustre: Unmounted lustre-client [ 6434.539664] Key type lgssc unregistered [ 6434.668646] LNet: 207297:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6434.671105] LNetError: 207297:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6434.680883] LNet: Removed LNI 192.168.206.57@tcp [ 6434.963115] Key type .llcrypt unregistered [ 6434.964083] Key type ._llcrypt unregistered [ 6440.649399] Key type ._llcrypt registered [ 6440.650399] Key type .llcrypt registered [ 6441.098945] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 15:13:31 (1788808411) [ 6441.961194] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6442.029843] alg: No test for adler32 (adler32-zlib) [ 6442.902587] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 6442.994828] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 6444.575234] Key type lgssc registered [ 6444.984597] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6463.409311] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6465.324849] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6487.216825] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6489.007987] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6503.032592] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6510.204552] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6543.578984] Key type lgssc unregistered [ 6543.701575] LNet: 212565:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6543.706624] LNetError: 212565:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6543.719453] LNet: Removed LNI 192.168.206.57@tcp [ 6544.005843] Key type .llcrypt unregistered [ 6544.008520] Key type ._llcrypt unregistered [ 6565.765057] Key type ._llcrypt registered [ 6565.766026] Key type .llcrypt registered [ 6566.213233] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 15:15:36 (1788808536) [ 6578.652731] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6578.689948] alg: No test for adler32 (adler32-zlib) [ 6579.557341] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 6579.654061] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 6581.255264] Key type lgssc registered [ 6581.674195] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6612.454180] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6614.318245] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6622.610160] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6643.833092] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 15:16:54 (1788808614) [ 6646.338853] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 15:16:56 (1788808616) [ 6659.124888] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6660.939240] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6667.647735] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6678.512897] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 6681.632983] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [ 6682.330174] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 6682.875928] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 6683.414542] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [ 6688.738044] Lustre: lustre-MDT0000-mdc-ffff910491e49800: Connection to lustre-MDT0000 (at 192.168.206.157@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6698.977794] LustreError: MGC192.168.206.157@tcp: Connection to MGS (at 192.168.206.157@tcp) was lost; in progress operations using this service will fail [ 6698.986673] Lustre: Evicted from MGS (at 192.168.206.157@tcp) after server handle changed from 0x52ba5197366c7704 to 0x52ba5197366c7996 [ 6698.991641] Lustre: MGC192.168.206.157@tcp: Connection restored to 192.168.206.157@tcp (at 192.168.206.157@tcp) [ 6702.230889] Lustre: lustre-MDT0000-mdc-ffff910491e49800: Connection restored to 192.168.206.157@tcp (at 192.168.206.157@tcp) [ 6704.067173] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6704.559341] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6706.361069] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [ 6709.217829] Lustre: lustre-MDT0000-mdc-ffff910491e49800: Connection to lustre-MDT0000 (at 192.168.206.157@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6724.063139] Lustre: 220414:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788808679/real 1788808679] req@00000000e5f596e1 x1875701714139392/t0(0) o101->MGC192.168.206.157@tcp@192.168.206.157@tcp:26/25 lens 328/344 e 0 to 1 dl 1788808695 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6724.069812] LustreError: MGC192.168.206.157@tcp: Connection to MGS (at 192.168.206.157@tcp) was lost; in progress operations using this service will fail [ 6724.073769] Lustre: 220414:0:(mgc_request.c:1900:mgc_process_log()) MGC192.168.206.157@tcp: IR log lustre-cliir failed, not fatal: rc = -5 [ 6724.076697] Lustre: Evicted from MGS (at 192.168.206.157@tcp) after server handle changed from 0x52ba5197366c7996 to 0x52ba5197366c7c8a [ 6724.081404] Lustre: MGC192.168.206.157@tcp: Connection restored to 192.168.206.157@tcp (at 192.168.206.157@tcp) [ 6727.507699] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6728.045940] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6729.668613] Lustre: Unmounted lustre-client [ 6763.264814] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6765.075880] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6772.092602] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6782.471911] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 6782.855220] Lustre: Unmounted lustre-client [ 6792.189464] Key type lgssc unregistered [ 6792.302357] LNet: 224980:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6792.305023] LNetError: 224980:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6792.313392] LNet: Removed LNI 192.168.206.57@tcp [ 6792.565536] Key type .llcrypt unregistered [ 6792.566825] Key type ._llcrypt unregistered [ 6797.779273] Key type ._llcrypt registered [ 6797.781141] Key type .llcrypt registered [ 6798.217498] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 15:19:28 (1788808768) [ 6799.667385] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6799.675229] alg: No test for adler32 (adler32-zlib) [ 6800.526219] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 6800.609336] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 6802.191183] Key type lgssc registered [ 6802.581036] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6820.670813] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6822.509692] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6829.196305] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6839.794180] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 6842.820168] Lustre: Unmounted lustre-client [ 6875.361350] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6877.071138] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6883.752752] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6894.063126] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 6900.160187] Lustre: Unmounted lustre-client [ 6933.636311] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6935.403221] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6942.022563] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6952.433999] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 6952.786217] Lustre: Unmounted lustre-client [ 6970.193262] Key type lgssc unregistered [ 6970.317528] LNet: 231993:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6970.319946] LNetError: 231993:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6970.329779] LNet: Removed LNI 192.168.206.57@tcp [ 6970.577779] Key type .llcrypt unregistered [ 6970.578683] Key type ._llcrypt unregistered [ 6976.116590] Key type ._llcrypt registered [ 6976.118654] Key type .llcrypt registered [ 6976.565146] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 15:22:27 (1788808947) [ 6977.668094] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6977.713466] alg: No test for adler32 (adler32-zlib) [ 6978.581539] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 6978.695437] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 6980.287224] Key type lgssc registered [ 6980.663707] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6997.039249] Key type lgssc unregistered [ 6997.161332] LNet: 234302:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6997.163654] LNetError: 234302:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6997.172240] LNet: Removed LNI 192.168.206.57@tcp [ 6997.420084] Key type .llcrypt unregistered [ 6997.421682] Key type ._llcrypt unregistered [ 7006.876189] Key type ._llcrypt registered [ 7006.878290] Key type .llcrypt registered [ 7007.091025] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7007.100292] alg: No test for adler32 (adler32-zlib) [ 7007.959182] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 7008.045981] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 7009.623150] Key type lgssc registered [ 7010.001511] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7039.059164] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7040.906390] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7048.784292] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7078.469812] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [ 7079.034903] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 15:24:09 (1788809049) [ 7089.933267] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7091.797364] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7098.462033] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7106.798319] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7107.963940] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 7111.315176] LustreError: lustre-OST0001-osc-ffff9104909af800: operation ost_statfs to node 192.168.206.157@tcp failed: rc = -107 [ 7111.320151] Lustre: lustre-OST0001-osc-ffff9104909af800: Connection to lustre-OST0001 (at 192.168.206.157@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7111.583346] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7121.849891] Lustre: Unmounted lustre-client [ 7145.185498] Key type lgssc unregistered [ 7145.310485] LNet: 241378:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7145.312648] LNetError: 241378:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7145.320556] LNet: Removed LNI 192.168.206.57@tcp [ 7145.563899] Key type .llcrypt unregistered [ 7145.565478] Key type ._llcrypt unregistered [ 7155.935557] Key type ._llcrypt registered [ 7155.936569] Key type .llcrypt registered [ 7156.429416] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 15:25:27 (1788809127) [ 7160.882231] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7160.891756] alg: No test for adler32 (adler32-zlib) [ 7161.753639] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 7161.861519] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 7163.463203] Key type lgssc registered [ 7163.848933] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7182.320984] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7184.074407] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7195.317312] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 15:26:05 (1788809165) [ 7205.987238] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7207.795593] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7214.465240] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7215.620531] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 7221.575493] Lustre: Unmounted lustre-client [ 7276.812131] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7278.723077] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7311.646447] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7322.098663] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 7322.542896] Lustre: Unmounted lustre-client [ 7340.601931] Key type lgssc unregistered [ 7340.734747] LNet: 249045:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7340.737490] LNetError: 249045:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7340.745499] LNet: Removed LNI 192.168.206.57@tcp [ 7341.027099] Key type .llcrypt unregistered [ 7341.028689] Key type ._llcrypt unregistered [ 7351.183204] Key type ._llcrypt registered [ 7351.190397] Key type .llcrypt registered [ 7351.391385] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7351.396757] alg: No test for adler32 (adler32-zlib) [ 7352.254030] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 7352.357246] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 7353.951310] Key type lgssc registered [ 7354.344853] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7389.357763] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7391.689303] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7400.408382] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7439.224850] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [ 7439.805547] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 15:30:10 (1788809410) [ 7452.723251] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7454.641124] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7461.759380] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7468.026405] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 7468.927242] Lustre: Unmounted lustre-client [ 7486.380527] Key type lgssc unregistered [ 7486.506473] LNet: 255651:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7486.509367] LNetError: 255651:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7486.516471] LNet: Removed LNI 192.168.206.57@tcp [ 7486.777060] Key type .llcrypt unregistered [ 7486.778306] Key type ._llcrypt unregistered [ 7492.341230] Key type ._llcrypt registered [ 7492.342672] Key type .llcrypt registered [ 7493.049939] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [ 7493.681071] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 15:31:04 (1788809464) [ 7497.036384] Key type .llcrypt unregistered [ 7497.037432] Key type ._llcrypt unregistered [ 7500.169338] Key type ._llcrypt registered [ 7500.170727] Key type .llcrypt registered [ 7500.336964] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7500.344178] alg: No test for adler32 (adler32-zlib) [ 7501.206172] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 7501.310728] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 7502.895367] Key type lgssc registered [ 7503.347420] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7521.426353] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7531.506987] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 7532.042784] Lustre: Unmounted lustre-client [ 7549.380763] Key type lgssc unregistered [ 7549.497701] LNet: 259085:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7549.502261] LNetError: 259085:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7549.513632] LNet: Removed LNI 192.168.206.57@tcp [ 7549.807059] Key type .llcrypt unregistered [ 7549.808474] Key type ._llcrypt unregistered [ 7555.367447] Key type ._llcrypt registered [ 7555.368454] Key type .llcrypt registered [ 7555.859886] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 15:32:06 (1788809526) [ 7556.812162] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7556.834068] alg: No test for adler32 (adler32-zlib) [ 7557.697180] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 7557.791944] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 7559.375215] Key type lgssc registered [ 7559.784315] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7622.565758] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7635.561274] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7637.701678] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7638.855280] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 7639.325100] Lustre: Unmounted lustre-client [ 7657.391432] Key type lgssc unregistered [ 7657.513755] LNet: 262609:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7657.516515] LNetError: 262609:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7657.526790] LNet: Removed LNI 192.168.206.57@tcp [ 7657.771890] Key type .llcrypt unregistered [ 7657.772891] Key type ._llcrypt unregistered [ 7664.308507] Key type ._llcrypt registered [ 7664.309826] Key type .llcrypt registered [ 7664.776929] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 15:33:55 (1788809635) [ 7665.641709] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7665.668057] alg: No test for adler32 (adler32-zlib) [ 7666.520212] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 7666.604741] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 7668.191343] Key type lgssc registered [ 7668.661609] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7687.324280] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7689.104813] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7696.382558] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7707.133836] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 7712.230085] Lustre: lustre-MDT0000-mdc-ffff9104a0b19800: Connection to lustre-MDT0000 (at 192.168.206.157@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7715.343660] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7715.355513] LustreError: 265754:0:(file.c:6167:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [ 7715.515237] LustreError: MGC192.168.206.157@tcp: Connection to MGS (at 192.168.206.157@tcp) was lost; in progress operations using this service will fail [ 7715.554319] Lustre: Unmounted lustre-client [ 7727.147503] Key type lgssc unregistered [ 7727.287376] LNet: 266166:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7727.289942] LNetError: 266166:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7727.296368] LNet: Removed LNI 192.168.206.57@tcp [ 7727.604375] Key type .llcrypt unregistered [ 7727.605543] Key type ._llcrypt unregistered [ 7734.689010] Key type ._llcrypt registered [ 7734.690030] Key type .llcrypt registered [ 7735.273179] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 15:35:05 (1788809705) [ 7736.099738] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7736.132361] alg: No test for adler32 (adler32-zlib) [ 7736.983203] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 7737.064655] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 7738.647242] Key type lgssc registered [ 7739.178852] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7760.680143] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7762.703275] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7770.366777] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7781.859144] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 7786.981275] Lustre: lustre-MDT0001-mdc-ffff9104909a9000: Connection to lustre-MDT0001 (at 192.168.206.157@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7790.099905] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7790.520249] Lustre: Unmounted lustre-client [ 7801.891794] Key type lgssc unregistered [ 7802.037584] LNet: 269728:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7802.042846] LNetError: 269728:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7802.055524] LNet: Removed LNI 192.168.206.57@tcp [ 7802.420074] Key type .llcrypt unregistered [ 7802.421935] Key type ._llcrypt unregistered [ 7808.145405] Key type ._llcrypt registered [ 7808.146741] Key type .llcrypt registered [ 7808.598243] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 15:36:19 (1788809779) [ 7814.744982] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7814.751719] alg: No test for adler32 (adler32-zlib) [ 7815.601083] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 7815.690295] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 7817.279275] Key type lgssc registered [ 7817.672318] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7860.582552] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7866.843954] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 7885.284529] Lustre: lustre-MDT0001-mdc-ffff9104909ab000: Connection to lustre-MDT0001 (at 192.168.206.157@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7890.085842] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7900.346814] Lustre: Unmounted lustre-client [ 7919.168694] Key type lgssc unregistered [ 7919.294470] LNet: 275486:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7919.299314] LNetError: 275486:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7919.311018] LNet: Removed LNI 192.168.206.57@tcp [ 7919.558900] Key type .llcrypt unregistered [ 7919.560514] Key type ._llcrypt unregistered [ 7926.757822] Key type ._llcrypt registered [ 7926.759257] Key type .llcrypt registered [ 7927.429710] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 15:38:17 (1788809897) [ 7928.245846] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [ 7929.158304] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 15:38:19 (1788809899) [ 7929.976635] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [ 7930.828637] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 15:38:21 (1788809901) [ 7931.665432] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [ 7932.454368] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 15:38:22 (1788809902) [ 7933.210137] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [ 7933.976081] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 15:38:24 (1788809904) [ 7934.806645] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [ 7935.598197] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 15:38:26 (1788809906) [ 7940.015500] Key type .llcrypt unregistered [ 7940.017676] Key type ._llcrypt unregistered [ 7942.813696] Key type ._llcrypt registered [ 7942.814662] Key type .llcrypt registered [ 7943.003970] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7943.012091] alg: No test for adler32 (adler32-zlib) [ 7943.880816] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 7943.968463] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 7945.543182] Key type lgssc registered [ 7946.074254] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7981.234607] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7983.990933] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7994.947431] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8001.512458] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 8002.172320] Lustre: Unmounted lustre-client [ 8027.647918] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 15:39:58 (1788809998) [ 8041.605767] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8044.212930] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8066.641126] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8077.310869] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 8077.776245] Lustre: Unmounted lustre-client [ 8108.140072] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 15:41:18 (1788810078) [ 8118.047297] LNet: 1 local NIs in recovery (showing 1): 192.168.206.57@tcp [ 8701.117627] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8703.673348] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8726.107855] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8792.718505] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 15:52:43 (1788810763) [ 8798.552096] Key type lgssc unregistered [ 8798.715579] LNet: 288966:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8798.720984] LNetError: 288966:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8798.735566] LNet: Removed LNI 192.168.206.57@tcp [ 8799.167815] Key type .llcrypt unregistered [ 8799.169911] Key type ._llcrypt unregistered [ 8849.889721] Key type ._llcrypt registered [ 8849.890610] Key type .llcrypt registered [ 8850.109547] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8850.113941] alg: No test for adler32 (adler32-zlib) [ 8850.966758] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 8851.056757] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 8852.639189] Key type lgssc registered [ 8853.082810] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8891.062495] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8893.402780] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8902.861912] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8924.174099] Key type lgssc unregistered [ 8924.340773] LNet: 292634:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8924.346830] LNetError: 292634:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8924.363211] LNet: Removed LNI 192.168.206.57@tcp [ 8924.762512] Key type .llcrypt unregistered [ 8924.764830] Key type ._llcrypt unregistered [ 8958.823202] Key type ._llcrypt registered [ 8958.824079] Key type .llcrypt registered [ 8959.214789] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 15:55:29 (1788810929) [ 8965.957251] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8965.963708] alg: No test for adler32 (adler32-zlib) [ 8966.806684] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 8966.885604] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 8968.471153] Key type lgssc registered [ 8968.780211] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8984.287517] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 15:55:54 (1788810954) [ 8987.797893] Key type lgssc unregistered [ 8987.896346] LNet: 296176:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8987.898206] LNetError: 296176:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8987.907672] LNet: Removed LNI 192.168.206.57@tcp [ 8988.126646] Key type .llcrypt unregistered [ 8988.127659] Key type ._llcrypt unregistered [ 8991.393898] Key type ._llcrypt registered [ 8991.394822] Key type .llcrypt registered [ 8991.550474] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8991.554769] alg: No test for adler32 (adler32-zlib) [ 8992.394266] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 8992.472687] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 8994.047158] Key type lgssc registered [ 8994.388235] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9018.917504] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9021.304543] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9031.263320] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9145.432468] LNet: Added LNI 192.168.206.57@tcp50 [8/256/0/180] [ 9146.446964] LNet: Removed LNI 192.168.206.57@tcp [ 9147.899351] Lustre: 300539:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff9104850b6800: config has no valid NIDs, force dynamic NIDs [ 9148.984499] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 9149.064259] Lustre: Unmounted lustre-client [ 9150.533724] Key type lgssc unregistered [ 9150.682403] LNet: 300799:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9150.684456] LNetError: 300799:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9150.692607] LNet: Removed LNI 192.168.206.57@tcp50 [ 9150.939691] Key type .llcrypt unregistered [ 9150.942245] Key type ._llcrypt unregistered [ 9161.680122] Key type ._llcrypt registered [ 9161.681341] Key type .llcrypt registered [ 9161.864108] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9161.869172] alg: No test for adler32 (adler32-zlib) [ 9162.764778] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 9162.886618] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 9164.519269] Key type lgssc registered [ 9165.263279] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9194.976898] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9197.115482] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9200.419282] LNet: Added LNI 192.168.206.57@tcp50 [8/256/0/180] [ 9200.683158] LNet: Removed LNI 192.168.206.57@tcp [ 9201.785370] Lustre: 303475:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff910487e09000: config has no valid NIDs, force dynamic NIDs [ 9202.858426] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 9203.033137] Lustre: Unmounted lustre-client [ 9222.126829] Key type lgssc unregistered [ 9222.279555] LNet: 304345:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9222.282785] LNetError: 304345:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9222.293070] LNet: Removed LNI 192.168.206.57@tcp50 [ 9222.534338] Key type .llcrypt unregistered [ 9222.535572] Key type ._llcrypt unregistered [ 9226.201560] Key type ._llcrypt registered [ 9226.202576] Key type .llcrypt registered [ 9226.625566] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 15:59:57 (1788811197) [ 9232.275413] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9232.286698] alg: No test for adler32 (adler32-zlib) [ 9233.138957] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 9233.227819] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 9234.807282] Key type lgssc registered [ 9235.444807] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9271.695794] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9274.216344] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9284.378208] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9302.133637] Key type lgssc unregistered [ 9302.250487] LNet: 309084:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9302.252549] LNetError: 309084:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9302.261435] LNet: Removed LNI 192.168.206.57@tcp [ 9302.487975] Key type .llcrypt unregistered [ 9302.489314] Key type ._llcrypt unregistered [ 9306.030747] Key type ._llcrypt registered [ 9306.032018] Key type .llcrypt registered [ 9306.627409] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 16:01:17 (1788811277) [ 9307.589317] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9307.626818] alg: No test for adler32 (adler32-zlib) [ 9308.522926] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 9308.646483] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 9310.263333] Key type lgssc registered [ 9310.913674] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9334.289425] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9336.865231] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9346.757054] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9353.191872] Lustre: Mounted lustre-client - version 2.17.58_39_gb44c962 [ 9381.889858] Lustre: Mounted fs15246-client - version 2.17.58_39_gb44c962 [ 9388.503949] Lustre: Unmounted fs15246-client [ 9404.176289] Lustre: Unmounted lustre-client [ 9431.571478] Key type lgssc unregistered [ 9431.707989] LNet: 313923:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9431.712706] LNetError: 313923:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9431.724738] LNet: Removed LNI 192.168.206.57@tcp [ 9432.082828] Key type .llcrypt unregistered [ 9432.085044] Key type ._llcrypt unregistered [ 9436.620949] Key type ._llcrypt registered [ 9436.621920] Key type .llcrypt registered [ 9437.006870] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 16:03:27 (1788811407) [ 9437.686785] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9437.724889] alg: No test for adler32 (adler32-zlib) [ 9438.579619] Lustre: Lustre: Build Version: 2.17.58_39_gb44c962 [ 9438.663874] LNet: Added LNI 192.168.206.57@tcp [8/256/0/180] [ 9440.255193] Key type lgssc registered [ 9440.832928] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9506.447553] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9509.267664] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9520.685166] Lustre: DEBUG MARKER: oleg657-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9574.607077] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 9321 sec ========== 16:05:44 (1788811544) [ 9575.514090] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 16:05:45 (1788811545) === [ 9577.158300] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 16:05:47 (1788811547) === [ 9593.726869] Key type lgssc unregistered [ 9593.884616] LNet: 323157:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9593.888641] LNetError: 323157:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9593.900102] LNet: Removed LNI 192.168.206.57@tcp [ 9594.269239] Key type .llcrypt unregistered [ 9594.271556] Key type ._llcrypt unregistered