[ 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 434131390 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524576K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001009] APIC: Switch to symmetric I/O mode setup [ 0.002321] x2apic enabled [ 0.003008] Switched APIC routing to physical x2apic. [ 0.004012] kvm-guest: setup PV IPIs [ 0.006988] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.007000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.007020] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.008010] pid_max: default: 32768 minimum: 301 [ 0.009122] LSM: Security Framework initializing [ 0.011044] Yama: becoming mindful. [ 0.011932] SELinux: Initializing. [ 0.012918] *** VALIDATE selinux *** [ 0.020478] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.025149] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.026141] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027095] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028104] *** VALIDATE tmpfs *** [ 0.029424] *** VALIDATE proc *** [ 0.031105] *** VALIDATE cgroup *** [ 0.031946] *** VALIDATE cgroup2 *** [ 0.033230] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035021] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036007] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037029] Spectre V2 : User space: Vulnerable [ 0.038008] Speculative Store Bypass: Vulnerable [ 0.041009] debug: unmapping init [mem 0xffffffff9c459000-0xffffffff9c460fff] [ 0.044000] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.044605] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.045019] ... version: 2 [ 0.046016] ... bit width: 48 [ 0.047010] ... generic registers: 4 [ 0.048011] ... value mask: 0000ffffffffffff [ 0.049011] ... max period: 00007fffffffffff [ 0.050013] ... fixed-purpose events: 3 [ 0.051009] ... event mask: 000000070000000f [ 0.052265] rcu: Hierarchical SRCU implementation. [ 0.054371] smp: Bringing up secondary CPUs ... [ 0.055518] x86: Booting SMP configuration: [ 0.056019] .... node #0, CPUs: #1 #2 #3 [ 0.059405] smp: Brought up 1 node, 4 CPUs [ 0.061012] smpboot: Max logical packages: 1 [ 0.062015] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.147040] node 0 deferred pages initialised in 82ms [ 0.150223] devtmpfs: initialized [ 0.151185] x86/mm: Memory block size: 128MB [ 0.153529] gcov: version magic: 0x41383552 [ 0.156315] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.160084] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.163308] pinctrl core: initialized pinctrl subsystem [ 0.165149] [ 0.165510] ************************************************************* [ 0.167012] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.168008] ** ** [ 0.170008] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.171008] ** ** [ 0.173008] ** This means that this kernel is built to expose internal ** [ 0.174008] ** IOMMU data structures, which may compromise security on ** [ 0.176010] ** your system. ** [ 0.178008] ** ** [ 0.179009] ** If you see this message and you are not debugging the ** [ 0.181010] ** kernel, report this immediately to your vendor! ** [ 0.184023] ** ** [ 0.186010] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.188013] ************************************************************* [ 0.190640] NET: Registered protocol family 16 [ 0.193437] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.195059] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.198060] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.201135] cpuidle: using governor menu [ 0.202627] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.205036] PCI: Using configuration type 1 for base access [ 0.207159] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.219152] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.222036] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.226039] cryptd: max_cpu_qlen set to 1000 [ 0.228224] ACPI: Added _OSI(Module Device) [ 0.230017] ACPI: Added _OSI(Processor Device) [ 0.232014] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.233013] ACPI: Added _OSI(Processor Aggregator Device) [ 0.238027] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.245671] ACPI: Interpreter enabled [ 0.247058] ACPI: PM: (supports S0 S3 S4 S5) [ 0.248015] ACPI: Using IOAPIC for interrupt routing [ 0.250091] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.253439] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.263290] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.266041] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.268018] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.272084] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.277455] acpiphp: Slot [2] registered [ 0.279097] acpiphp: Slot [5] registered [ 0.280242] acpiphp: Slot [6] registered [ 0.282125] acpiphp: Slot [3] registered [ 0.284172] acpiphp: Slot [4] registered [ 0.285137] acpiphp: Slot [7] registered [ 0.287111] acpiphp: Slot [8] registered [ 0.289110] acpiphp: Slot [9] registered [ 0.291118] acpiphp: Slot [10] registered [ 0.292102] acpiphp: Slot [11] registered [ 0.294142] acpiphp: Slot [12] registered [ 0.296109] acpiphp: Slot [13] registered [ 0.297181] acpiphp: Slot [14] registered [ 0.299092] acpiphp: Slot [15] registered [ 0.300100] acpiphp: Slot [16] registered [ 0.302097] acpiphp: Slot [17] registered [ 0.303116] acpiphp: Slot [18] registered [ 0.305075] acpiphp: Slot [19] registered [ 0.306092] acpiphp: Slot [20] registered [ 0.308132] acpiphp: Slot [21] registered [ 0.309085] acpiphp: Slot [22] registered [ 0.310080] acpiphp: Slot [23] registered [ 0.312141] acpiphp: Slot [24] registered [ 0.313127] acpiphp: Slot [25] registered [ 0.315126] acpiphp: Slot [26] registered [ 0.316133] acpiphp: Slot [27] registered [ 0.318094] acpiphp: Slot [28] registered [ 0.319090] acpiphp: Slot [29] registered [ 0.320092] acpiphp: Slot [30] registered [ 0.321090] acpiphp: Slot [31] registered [ 0.323088] PCI host bridge to bus 0000:00 [ 0.324018] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.326020] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.328024] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.331022] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.333022] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.336026] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.337174] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.340023] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.343365] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.351013] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.356106] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.360020] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.361016] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.364017] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.366606] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.369848] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.372044] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.374835] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.379013] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.388679] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.392014] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.397000] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.404015] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.410016] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.426015] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.435168] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.444019] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.450023] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.466023] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.477819] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.481634] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.485535] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.488859] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.493302] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.497218] iommu: Default domain type: Passthrough [ 0.499656] SCSI subsystem initialized [ 0.502231] ACPI: bus type USB registered [ 0.504157] usbcore: registered new interface driver usbfs [ 0.506107] usbcore: registered new interface driver hub [ 0.508114] usbcore: registered new device driver usb [ 0.510240] pps_core: LinuxPPS API ver. 1 registered [ 0.512016] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.515108] PTP clock support registered [ 0.518100] EDAC MC: Ver: 3.0.0 [ 0.520161] PCI: Using ACPI for IRQ routing [ 0.522646] NetLabel: Initializing [ 0.524015] NetLabel: domain hash size = 128 [ 0.525009] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.527204] NetLabel: unlabeled traffic allowed by default [ 0.529210] vgaarb: loaded [ 0.531359] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.532016] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.537371] clocksource: Switched to clocksource kvm-clock [ 0.640191] VFS: Disk quotas dquot_6.6.0 [ 0.641889] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.644818] *** VALIDATE ramfs *** [ 0.646282] *** VALIDATE hugetlbfs *** [ 0.648199] pnp: PnP ACPI init [ 0.650990] pnp: PnP ACPI: found 6 devices [ 0.671886] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.675421] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.677792] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.679657] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.681640] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.683571] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.685800] NET: Registered protocol family 2 [ 0.688071] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.692690] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.696804] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.702718] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.706131] TCP: Hash tables configured (established 65536 bind 65536) [ 0.708608] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.712062] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.714876] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.718266] NET: Registered protocol family 1 [ 0.720749] RPC: Registered named UNIX socket transport module. [ 0.722881] RPC: Registered udp transport module. [ 0.724511] RPC: Registered tcp transport module. [ 0.726483] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.728920] NET: Registered protocol family 44 [ 0.730071] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.731639] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.733548] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.736408] PCI: CLS 0 bytes, default 64 [ 0.738195] Unpacking initramfs... [ 2.137541] debug: unmapping init [mem 0xffff8eb63cc64000-0xffff8eb63ffcffff] [ 2.141769] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.144276] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.147536] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.650940] Initialise system trusted keyrings [ 2.652863] Key type blacklist registered [ 2.655062] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.665722] zbud: loaded [ 2.669283] *** VALIDATE nfs *** [ 2.670629] *** VALIDATE nfs4 *** [ 2.672557] pstore: using deflate compression [ 2.676608] Platform Keyring initialized [ 2.802759] NET: Registered protocol family 38 [ 2.804781] Key type asymmetric registered [ 2.806531] Asymmetric key parser 'x509' registered [ 2.809299] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.812509] io scheduler mq-deadline registered [ 2.813911] io scheduler kyber registered [ 2.815962] io scheduler bfq registered [ 2.822173] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.825972] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.831412] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.835401] ACPI: Power Button [PWRF] [ 2.841342] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.850121] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.862891] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.898367] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.937173] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.944723] Non-volatile memory driver v1.3 [ 2.947218] Linux agpgart interface v0.103 [ 3.025730] virtio_blk virtio1: [vda] 149784 512-byte logical blocks (76.7 MB/73.1 MiB) [ 3.030149] vda: detected capacity change from 0 to 76689408 [ 3.092787] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.101260] vdb: detected capacity change from 0 to 1073741824 [ 3.132975] libphy: Fixed MDIO Bus: probed [ 3.145173] usbcore: registered new interface driver usbserial_generic [ 3.147711] usbserial: USB Serial support registered for generic [ 3.150476] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.155563] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.161531] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.171583] mousedev: PS/2 mouse device common for all mice [ 3.179477] rtc_cmos 00:05: RTC can wake from S4 [ 3.185697] rtc_cmos 00:05: registered as rtc0 [ 3.188146] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.192259] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.192313] intel_pstate: CPU model not supported [ 3.205958] hid: raw HID events driver (C) Jiri Kosina [ 3.214102] usbcore: registered new interface driver usbhid [ 3.218259] usbhid: USB HID core driver [ 3.222556] drop_monitor: Initializing network drop monitor service [ 3.224213] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.227715] Initializing XFRM netlink socket [ 3.230798] NET: Registered protocol family 10 [ 3.237930] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.247864] Segment Routing with IPv6 [ 3.251983] NET: Registered protocol family 17 [ 3.254216] mpls_gso: MPLS GSO support [ 3.274710] RAS: Correctable Errors collector initialized. [ 3.278051] AVX version of gcm_enc/dec engaged. [ 3.281182] AES CTR mode by8 optimization enabled [ 3.454634] sched_clock: Marking stable (3454563852, 0)->(4325068297, -870504445) [ 3.464068] registered taskstats version 1 [ 3.470728] Loading compiled-in X.509 certificates [ 3.472935] zswap: loaded using pool lzo/zbud [ 3.566322] Key type big_key registered [ 3.595166] Key type encrypted registered [ 3.598542] ima: No TPM chip found, activating TPM-bypass! [ 3.602385] ima: Allocated hash algorithm: sha1 [ 3.605479] ima: No architecture policies found [ 3.608489] evm: Initialising EVM extended attributes: [ 3.611992] evm: security.selinux [ 3.613823] evm: security.ima [ 3.615801] evm: security.capability [ 3.617617] evm: HMAC attrs: 0x1 [ 3.620946] rtc_cmos 00:05: setting system clock to 2026-09-06 03:28:30 UTC (1788665310) [ 3.633440] debug: unmapping init [mem 0xffffffff9d403000-0xffffffff9d5fffff] [ 3.637563] debug: unmapping init [mem 0xffffffff9c182000-0xffffffff9c458fff] [ 3.648155] Write protecting the kernel read-only data: 28672k [ 3.656279] debug: unmapping init [mem 0xffffffff9a803000-0xffffffff9a9fffff] [ 3.667972] debug: unmapping init [mem 0xffffffff9b114000-0xffffffff9b1fffff] [ 3.821086] 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.855981] systemd[1]: Detected virtualization kvm. [ 3.858310] systemd[1]: Detected architecture x86-64. [ 3.860316] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.933427] systemd[1]: No hostname configured. [ 3.937616] systemd[1]: Set hostname to . [ 3.942701] random: systemd: uninitialized urandom read (16 bytes read) [ 3.949106] systemd[1]: Initializing machine ID from random generator. [ 4.066959] random: ln: uninitialized urandom read (6 bytes read) [ 4.399898] random: systemd: uninitialized urandom read (16 bytes read) [ 4.410918] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 4.424038] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ 4.438418] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. Starting Apply Kernel Variables... [ OK ] Reached target Slices. [ OK ] Listening on Journal Socket (/dev/log). Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on udev Control Socket. Starting Setup Virtual Console... [ OK ] Reached target Local File Systems. [ OK ] Reached target Swap. Starting Journal Service... Starting Create Volatile Files and Directories... [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Sockets. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. [ OK ] Started Create Volatile Files and Directories. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 6.546740] device-mapper: uevent: version 1.0.3 [ 6.554160] 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. [ 8.634819] virtio_net virtio0 ens2: renamed from eth0 [ 8.884255] scsi host0: ata_piix [ 8.896187] scsi host1: ata_piix [ 8.905383] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 8.919961] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 14.140262] random: crng init done [ 14.150934] random: 7 urandom warning(s) missed due to ratelimiting [ 15.090763] dracut-initqueue[585]: RTNETLINK answers: File exists 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. [ 17.193469] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Timers. [ OK ] Stopped dracut initqueue hook. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 20.599082] printk: systemd: 26 output lines suppressed due to ratelimiting [ 21.711549] SELinux: Disabled at runtime. [ 21.846366] 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) [ 21.862242] systemd[1]: Detected virtualization kvm. [ 21.864890] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 23.995821] systemd[1]: initrd-switch-root.service: Succeeded. [ 24.004343] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 24.031701] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 24.041314] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 24.048568] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 24.082160] systemd[1]: Starting Journal Service... Starting Journal Service... [ 24.099308] systemd[1]: Created slice system-getty.slice. [ OK ] Created slice system-getty.slice. [ OK ] Listening on Process Core Dump Socket. [ OK ] Listening on udev Kernel Socket. Starting Apply Kernel Variables... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. Activating swap /dev/disk/by-label/SWAP... Mounting Huge Pages File System... Starting Remount Root and Kernel File Systems... [ OK ] Listening on initctl Compatibility Named Pipe. [ 24.401771] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Starting Create list of required st…ce nodes for the current kernel... [ OK ] Stopped target Switch Root. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Forward Password Requests to Wall 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 POSIX Message Queue File System... [ OK ] Stopped target Initrd File Systems. [ OK ] Reached target rpc_pipefs.target. Mounting Kernel Debug File System... [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Reached target Paths. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Stopped target Initrd Root File System. [ OK ] Started Journal Service. [ OK ] Started Apply Kernel Variables. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Huge Pages File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted POSIX Message Queue File System. [ OK ] 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 /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 25.685932] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 26.835020] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 26.853678] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 27.416820] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 27.506277] EDAC sbridge: Ver: 1.1.2 [ 31.447985] Key type dns_resolver registered [* ] A start job is running for Configur…-only root support (7s / no limit)[ 32.155972] NFS: Registering the id_resolver key type [ 32.158583] Key type id_resolver registered [ 32.160762] Key type id_legacy registered [** ] A start job is running for Configur…-only root support (8s / no limit) [*** ] A start job is running for Configur…-only root support (8s / no limit) [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... Starting Rebuild Dynamic Linker Cache... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ 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 Cleanup of Temporary Directories. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started irqbalance daemon. Starting Login Service... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started D-Bus System Message Bus. Starting Restore /run/initramfs on shutdown... [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Reached target sshd-keygen.target. Starting Network Manager... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... [ OK ] Started Login Service. Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting System Logging Service... 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 oleg346-client login: [ 47.042325] hrtimer: interrupt took 4473567 ns [ 104.942673] libcfs: loading out-of-tree module taints kernel. [ 105.217687] Key type ._llcrypt registered [ 105.224871] Key type .llcrypt registered [ 105.840663] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 105.857276] alg: No test for adler32 (adler32-zlib) [ 107.470995] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [ 108.288703] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 110.007305] Key type lgssc registered [ 111.974476] Lustre: Echo OBD driver; http://www.lustre.org/ [ 290.027731] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 296.025193] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 314.002967] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing check_logdir /tmp/testlogs/ [ 315.872217] Lustre: lustre-OST0000-osc-ffff8eb698e12800: disconnect after 22s idle [ 318.879404] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing yml_node [ 322.986086] Lustre: DEBUG MARKER: Client: 2.17.58.3 [ 325.561872] Lustre: DEBUG MARKER: MDS: 2.17.58.3 [ 327.737949] Lustre: DEBUG MARKER: OSS: 2.17.58.3 [ 329.239965] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Sat Sep 5 23:33:54 EDT 2026 [ 344.892906] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 346.217752] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 348.514516] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 358.879307] Lustre: Unmounted lustre-client [ 476.651893] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 482.238463] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 504.602675] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 557.671316] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 23:37:43 (1788665863) [ 560.104376] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 561.836214] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 23:37:47 (1788665867) [ 594.931345] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 600.345271] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 620.399970] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 621.025966] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 626.100177] Lustre: Unmounted lustre-client [ 653.561660] Key type lgssc unregistered [ 653.850329] LNet: 15073:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 653.865693] LNetError: 15073:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 653.892404] LNet: Removed LNI 192.168.203.46@tcp [ 654.717276] Key type .llcrypt unregistered [ 654.720089] Key type ._llcrypt unregistered [ 668.885993] Key type ._llcrypt registered [ 668.892281] Key type .llcrypt registered [ 670.273372] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 23:39:35 (1788665975) [ 671.613493] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 671.644757] alg: No test for adler32 (adler32-zlib) [ 672.662151] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [ 672.920802] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 674.607364] Key type lgssc registered [ 675.891473] Lustre: Echo OBD driver; http://www.lustre.org/ [ 743.104354] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 831.682399] Lustre: Unmounted lustre-client [ 866.210692] Key type lgssc unregistered [ 866.461043] LNet: 18853:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 866.474543] LNetError: 18853:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 866.495678] LNet: Removed LNI 192.168.203.46@tcp [ 867.128904] Key type .llcrypt unregistered [ 867.132067] Key type ._llcrypt unregistered [ 877.624286] Key type ._llcrypt registered [ 877.626779] Key type .llcrypt registered [ 878.971138] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 23:43:04 (1788666184) [ 881.127749] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 881.145784] alg: No test for adler32 (adler32-zlib) [ 882.280706] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [ 882.668091] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 884.457050] Key type lgssc registered [ 886.357529] Lustre: Echo OBD driver; http://www.lustre.org/ [ 941.560290] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 947.128511] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 968.426939] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 971.073233] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 985.751864] Lustre: Unmounted lustre-client [ 1008.058362] Key type lgssc unregistered [ 1008.298996] LNet: 22455:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1008.312929] LNetError: 22455:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1008.343266] LNet: Removed LNI 192.168.203.46@tcp [ 1008.949602] Key type .llcrypt unregistered [ 1008.951127] Key type ._llcrypt unregistered [ 1018.370776] Key type ._llcrypt registered [ 1018.372663] Key type .llcrypt registered [ 1019.563167] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 23:45:25 (1788666325) [ 1021.625950] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1021.655347] alg: No test for adler32 (adler32-zlib) [ 1022.748606] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [ 1022.974312] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 1024.735198] Key type lgssc registered [ 1025.949715] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1075.416449] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1081.632608] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1101.141091] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1102.716593] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 1111.895068] Lustre: Unmounted lustre-client [ 1138.341791] Key type lgssc unregistered [ 1138.588994] LNet: 25645:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1138.602352] LNetError: 25645:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1138.617294] LNet: Removed LNI 192.168.203.46@tcp [ 1139.254247] Key type .llcrypt unregistered [ 1139.261377] Key type ._llcrypt unregistered [ 1152.386943] Key type ._llcrypt registered [ 1152.390075] Key type .llcrypt registered [ 1153.627227] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 23:47:39 (1788666459) [ 1155.520762] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1155.758847] alg: No test for adler32 (adler32-zlib) [ 1156.758207] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [ 1156.954902] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 1158.623422] Key type lgssc registered [ 1159.753109] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1216.387281] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1221.388460] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1242.503187] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1245.181189] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 1250.288921] Lustre: lustre-MDT0000-mdc-ffff8eb688c51000: Connection to lustre-MDT0000 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1260.526731] Lustre: lustre-MDT0001-mdc-ffff8eb688c51000: Connection to lustre-MDT0001 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1268.853306] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1276.895282] Lustre: 26599:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788666567/real 1788666567] req@00000000ba3c02ec x1875552725112832/t0(0) o400->MGC192.168.203.146@tcp@192.168.203.146@tcp:26/25 lens 224/224 e 0 to 1 dl 1788666583 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1276.945798] LustreError: MGC192.168.203.146@tcp: Connection to MGS (at 192.168.203.146@tcp) was lost; in progress operations using this service will fail [ 1279.201603] Lustre: Unmounted lustre-client [ 1298.388684] Key type lgssc unregistered [ 1298.694289] LNet: 29299:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1298.706810] LNetError: 29299:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1298.723542] LNet: Removed LNI 192.168.203.46@tcp [ 1299.652538] Key type .llcrypt unregistered [ 1299.667603] Key type ._llcrypt unregistered [ 1316.213336] Key type ._llcrypt registered [ 1316.217698] Key type .llcrypt registered [ 1317.587705] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 23:50:22 (1788666622) [ 1319.783298] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1319.817443] alg: No test for adler32 (adler32-zlib) [ 1320.915311] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [ 1321.171158] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 1322.899309] Key type lgssc registered [ 1324.200790] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1373.815817] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1379.731680] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1400.260714] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1402.962178] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 1407.988799] Lustre: lustre-OST0000-osc-ffff8eb698690000: Connection to lustre-OST0000 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1424.422945] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1434.830655] Lustre: Unmounted lustre-client [ 1463.019716] Key type lgssc unregistered [ 1463.298920] LNet: 32905:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1463.314224] LNetError: 32905:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1463.331325] LNet: Removed LNI 192.168.203.46@tcp [ 1464.063740] Key type .llcrypt unregistered [ 1464.066228] Key type ._llcrypt unregistered [ 1478.905776] Key type ._llcrypt registered [ 1478.909916] Key type .llcrypt registered [ 1480.454637] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 23:53:05 (1788666785) [ 1483.106581] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1483.129929] alg: No test for adler32 (adler32-zlib) [ 1484.162554] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [ 1484.432319] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 1486.143336] Key type lgssc registered [ 1487.190414] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1533.682247] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1539.073337] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1558.794121] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1561.589132] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 1565.220563] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1568.783835] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1580.493972] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-09-0523h54m45s [ 1582.458922] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1587.198573] Lustre: lustre-MDT0000-mdc-ffff8eb687cba800: Connection to lustre-MDT0000 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1592.683581] LustreError: 36687:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff8eb687cba800: can't stat MDS #0: rc = -19 [ 1593.974783] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1604.594917] LustreError: MGC192.168.203.146@tcp: Connection to MGS (at 192.168.203.146@tcp) was lost; in progress operations using this service will fail [ 1604.640903] Lustre: Evicted from MGS (at 192.168.203.146@tcp) after server handle changed from 0xb0538f509f0ad75d to 0xb0538f509f0ada20 [ 1604.679174] Lustre: MGC192.168.203.146@tcp: Connection restored to 192.168.203.146@tcp (at 192.168.203.146@tcp) [ 1605.690796] LustreError: lustre-MDT0000-mdc-ffff8eb687cba800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1605.715030] Lustre: lustre-MDT0000-mdc-ffff8eb687cba800: Connection restored to 192.168.203.146@tcp (at 192.168.203.146@tcp) [ 1609.374081] Lustre: DEBUG MARKER: Wait for df (36687) ... [ 1611.203876] Lustre: DEBUG MARKER: done [ 1614.299162] Lustre: Unmounted lustre-client [ 1642.098539] Key type lgssc unregistered [ 1642.311907] LNet: 37698:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1642.318525] LNetError: 37698:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1642.334919] LNet: Removed LNI 192.168.203.46@tcp [ 1643.247493] Key type .llcrypt unregistered [ 1643.255687] Key type ._llcrypt unregistered [ 1670.441262] Key type ._llcrypt registered [ 1670.446489] Key type .llcrypt registered [ 1671.685950] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 23:56:17 (1788666977) [ 1673.937519] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1673.962524] alg: No test for adler32 (adler32-zlib) [ 1675.088185] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [ 1675.314263] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 1677.039264] Key type lgssc registered [ 1678.511202] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1729.021774] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1734.114100] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1754.911452] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1757.582947] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 1761.987646] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-09-0523h57m47s [ 1763.270812] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1770.308718] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1772.873432] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1793.505489] Lustre: 41842:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788667080/real 1788667080] req@00000000a83db79e x1875553269323648/t0(0) o101->lustre-MDT0000-mdc-ffff8eb687739000@192.168.203.146@tcp:12/10 lens 576/1152 e 0 to 1 dl 1788667100 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1793.547323] Lustre: lustre-MDT0000-mdc-ffff8eb687739000: Connection to lustre-MDT0000 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1793.598112] Lustre: lustre-MDT0000-mdc-ffff8eb687739000: Connection restored to 192.168.203.146@tcp (at 192.168.203.146@tcp) [ 1815.520342] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1819.371275] Lustre: DEBUG MARKER: done [ 1823.616393] Lustre: Unmounted lustre-client [ 1859.421329] Key type lgssc unregistered [ 1859.741528] LNet: 42721:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1859.758706] LNetError: 42721:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1859.780357] LNet: Removed LNI 192.168.203.46@tcp [ 1860.535749] Key type .llcrypt unregistered [ 1860.542715] Key type ._llcrypt unregistered [ 1888.431358] Key type ._llcrypt registered [ 1888.433893] Key type .llcrypt registered [ 1889.458311] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 23:59:55 (1788667195) [ 1891.014858] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1892.638624] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 23:59:58 (1788667198) [ 1896.525481] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1896.560708] alg: No test for adler32 (adler32-zlib) [ 1897.592499] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [ 1897.835262] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 1899.632445] Key type lgssc registered [ 1901.399979] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1928.674101] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 00:00:34 (1788667234) [ 1962.850180] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1968.929634] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1993.348996] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1996.016761] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 1999.477332] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 2003.403967] Lustre: Unmounted lustre-client [ 2021.143810] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 2072.794409] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2079.326633] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2080.116349] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 2084.509630] Lustre: Unmounted lustre-client [ 2147.103121] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2153.336403] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2153.975683] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 2157.770347] Lustre: Unmounted lustre-client [ 2175.646493] Lustre: DEBUG MARKER: files compared the same [ 2198.685383] Key type lgssc unregistered [ 2198.997090] LNet: 50360:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2199.008066] LNetError: 50360:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2199.028503] LNet: Removed LNI 192.168.203.46@tcp [ 2199.801872] Key type .llcrypt unregistered [ 2199.805867] Key type ._llcrypt unregistered [ 2214.664594] Key type ._llcrypt registered [ 2214.670200] Key type .llcrypt registered [ 2216.216788] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 00:05:21 (1788667521) [ 2218.273756] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2218.334032] alg: No test for adler32 (adler32-zlib) [ 2219.494139] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [ 2219.739457] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 2221.399534] Key type lgssc registered [ 2222.372720] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2269.343852] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2274.619097] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2294.521463] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2296.128081] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 2300.350640] Lustre: Unmounted lustre-client [ 2328.978946] Key type lgssc unregistered [ 2329.322227] LNet: 53941:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2329.335872] LNetError: 53941:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2329.380187] LNet: Removed LNI 192.168.203.46@tcp [ 2330.414618] Key type .llcrypt unregistered [ 2330.422207] Key type ._llcrypt unregistered [ 2347.520346] Key type ._llcrypt registered [ 2347.523118] Key type .llcrypt registered [ 2349.002421] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 00:07:34 (1788667654) [ 2351.449995] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2351.665503] alg: No test for adler32 (adler32-zlib) [ 2352.812587] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [ 2353.103229] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 2354.953337] Key type lgssc registered [ 2356.378214] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2442.883453] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2475.089450] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2480.659303] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2508.942764] Key type lgssc unregistered [ 2509.194631] LNet: 57427:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2509.206911] LNetError: 57427:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2509.232604] LNet: Removed LNI 192.168.203.46@tcp [ 2509.812793] Key type .llcrypt unregistered [ 2509.814594] Key type ._llcrypt unregistered [ 2527.372175] Key type ._llcrypt registered [ 2527.374076] Key type .llcrypt registered [ 2529.117875] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 00:10:34 (1788667834) [ 2532.392657] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2532.449362] alg: No test for adler32 (adler32-zlib) [ 2533.588252] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [ 2533.935722] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 2535.615180] Key type lgssc registered [ 2536.947058] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2609.685580] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 2616.978882] Lustre: Unmounted lustre-client [ 2659.004419] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 00:12:44 (1788667964) [ 2787.017551] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2808.379488] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 2814.987493] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2815.004725] LustreError: 63904:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff8eb698e16800: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2815.043764] Lustre: 58408:0:(llite_lib.c:4340:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.203.146@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2825.393721] Lustre: Unmounted lustre-client [ 2870.017060] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 00:16:14 (1788668174) [ 2884.946139] Key type lgssc unregistered [ 2885.308043] LNet: 65119:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2885.313969] LNetError: 65119:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2885.332692] LNet: Removed LNI 192.168.203.46@tcp [ 2886.125683] Key type .llcrypt unregistered [ 2886.132669] Key type ._llcrypt unregistered [ 2900.084290] Key type ._llcrypt registered [ 2900.086584] Key type .llcrypt registered [ 2900.648546] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2900.675897] alg: No test for adler32 (adler32-zlib) [ 2901.797700] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [ 2902.115372] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 2903.791165] Key type lgssc registered [ 2905.356480] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2955.018774] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2960.637381] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2984.238927] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2986.066559] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 2994.034188] Lustre: Unmounted lustre-client [ 3026.871721] Key type lgssc unregistered [ 3027.162177] LNet: 68129:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3027.171510] LNetError: 68129:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3027.199793] LNet: Removed LNI 192.168.203.46@tcp [ 3027.982765] Key type .llcrypt unregistered [ 3027.985873] Key type ._llcrypt unregistered [ 3040.739801] Key type ._llcrypt registered [ 3040.745324] Key type .llcrypt registered [ 3041.458911] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3041.472707] alg: No test for adler32 (adler32-zlib) [ 3042.742911] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [ 3043.117695] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 3044.879218] Key type lgssc registered [ 3046.423413] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3160.059709] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3166.216729] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3189.442047] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3191.089596] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 3199.646021] Lustre: Unmounted lustre-client [ 3229.001794] Key type lgssc unregistered [ 3229.344381] LNet: 71561:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3229.354571] LNetError: 71561:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3229.377348] LNet: Removed LNI 192.168.203.46@tcp [ 3230.117067] Key type .llcrypt unregistered [ 3230.124784] Key type ._llcrypt unregistered [ 3248.456913] Key type ._llcrypt registered [ 3248.461656] Key type .llcrypt registered [ 3250.074692] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 00:22:35 (1788668555) [ 3252.795114] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3252.828617] alg: No test for adler32 (adler32-zlib) [ 3253.953684] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [ 3254.403988] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 3256.208152] Key type lgssc registered [ 3257.982741] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3312.064426] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3317.431455] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3337.540100] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3340.374271] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 3345.893969] Lustre: Unmounted lustre-client [ 3346.374087] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 3350.554352] Lustre: Unmounted lustre-client [ 3381.664836] Key type lgssc unregistered [ 3382.225224] LNet: 75248:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3382.234548] LNetError: 75248:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3382.267356] LNet: Removed LNI 192.168.203.46@tcp [ 3382.967373] Key type .llcrypt unregistered [ 3382.969726] Key type ._llcrypt unregistered [ 3395.110609] Key type ._llcrypt registered [ 3395.114838] Key type .llcrypt registered [ 3395.724593] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3395.740539] alg: No test for adler32 (adler32-zlib) [ 3396.866510] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [ 3397.143432] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 3398.800236] Key type lgssc registered [ 3400.200705] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3455.971389] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3462.052871] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3485.263887] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3487.839831] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 3492.479401] Lustre: Unmounted lustre-client [ 3519.842563] Key type lgssc unregistered [ 3520.133805] LNet: 78279:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3520.147530] LNetError: 78279:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3520.163286] LNet: Removed LNI 192.168.203.46@tcp [ 3520.828712] Key type .llcrypt unregistered [ 3520.831937] Key type ._llcrypt unregistered [ 3537.996736] Key type ._llcrypt registered [ 3537.998648] Key type .llcrypt registered [ 3539.089813] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 00:27:24 (1788668844) [ 3541.183925] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3541.288075] alg: No test for adler32 (adler32-zlib) [ 3542.331468] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [ 3542.615645] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 3544.303313] Key type lgssc registered [ 3545.617469] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3595.590255] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3601.947905] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3622.261421] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3623.864676] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 3634.099508] Lustre: lustre: root_squash is set to 0:0 [ 3643.427506] Lustre: lustre: nosquash_nids is cleared [ 3643.446951] Lustre: lustre: root_squash is set to 500:500 [ 3652.010291] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.203.46@tcp 192.168.0.[2,10]@tcp [ 3662.087400] Lustre: lustre: nosquash_nids set to 192.168.203.46/24@tcp [ 3669.286483] Lustre: Unmounted lustre-client [ 3697.067951] Key type lgssc unregistered [ 3697.313835] LNet: 82225:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3697.330652] LNetError: 82225:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3697.354543] LNet: Removed LNI 192.168.203.46@tcp [ 3698.071097] Key type .llcrypt unregistered [ 3698.080325] Key type ._llcrypt unregistered [ 3713.124686] Key type ._llcrypt registered [ 3713.130608] Key type .llcrypt registered [ 3714.301481] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 00:30:19 (1788669019) [ 3716.631831] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3718.198582] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 00:30:23 (1788669023) [ 3720.766739] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3720.788403] alg: No test for adler32 (adler32-zlib) [ 3721.862918] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [ 3722.042374] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 3723.679775] Key type lgssc registered [ 3724.939333] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3773.852820] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3779.382593] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3798.827433] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3800.280695] Lustre: lustre: root_squash is set to 500:500 [ 3800.307974] Lustre: lustre: nosquash_nids set to 192.168.203.46/24@tcp [ 3801.422515] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 3806.817941] Lustre: Unmounted lustre-client [ 3831.603577] Key type lgssc unregistered [ 3831.817539] LNet: 86177:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3831.823640] LNetError: 86177:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3831.839941] LNet: Removed LNI 192.168.203.46@tcp [ 3832.549604] Key type .llcrypt unregistered [ 3832.554870] Key type ._llcrypt unregistered [ 3847.474358] Key type ._llcrypt registered [ 3847.478120] Key type .llcrypt registered [ 3849.084452] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 3851.212737] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 00:32:36 (1788669156) [ 3872.433024] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3872.459417] alg: No test for adler32 (adler32-zlib) [ 3873.624031] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [ 3873.905353] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 3875.607824] Key type lgssc registered [ 3876.741587] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3957.272646] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3962.983434] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3986.294725] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4060.628579] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4066.119723] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4086.988764] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4099.704362] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 4134.167670] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8eb688c52800.ost_server_uuid 50 [ 4135.667556] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8eb688c52800.ost_server_uuid in IDLE state after 0 sec [ 4136.040934] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 4139.526520] Lustre: Unmounted lustre-client [ 4141.909240] Lustre: Unmounted lustre-client [ 4192.186338] Key type lgssc unregistered [ 4192.422869] LNet: 93847:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4192.439155] LNetError: 93847:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4192.460633] LNet: Removed LNI 192.168.203.46@tcp [ 4193.226103] Key type .llcrypt unregistered [ 4193.228426] Key type ._llcrypt unregistered [ 4225.340882] Key type ._llcrypt registered [ 4225.343784] Key type .llcrypt registered [ 4227.449757] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 00:38:52 (1788669532) [ 4246.207492] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4246.242775] alg: No test for adler32 (adler32-zlib) [ 4247.434352] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [ 4247.721899] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 4249.415422] Key type lgssc registered [ 4250.824279] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4331.403154] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4339.178451] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4363.769481] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4366.579258] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 4387.299621] Lustre: 95205:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788669678/real 1788669678] req@0000000092af1461 x1875555966260480/t0(0) o400->lustre-OST0000-osc-ffff8eb6893dc000@192.168.203.146@tcp:28/4 lens 224/224 e 0 to 1 dl 1788669694 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4387.355847] Lustre: lustre-OST0000-osc-ffff8eb6893dc000: Connection to lustre-OST0000 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4391.955563] Lustre: lustre-OST0000-osc-ffff8eb6893dc000: Connection restored to 192.168.203.146@tcp (at 192.168.203.146@tcp) [ 4392.415512] Lustre: 95206:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788669683/real 1788669683] req@00000000b6b4c096 x1875555966260992/t0(0) o400->lustre-OST0000-osc-ffff8eb6893dc000@192.168.203.146@tcp:28/4 lens 224/224 e 0 to 1 dl 1788669699 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4397.561242] Lustre: lustre-MDT0000-mdc-ffff8eb6893dc000: Connection to lustre-MDT0000 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4398.627908] Lustre: 95206:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788669689/real 1788669689] req@0000000014efc8f3 x1875555966261504/t0(0) o400->lustre-OST0000-osc-ffff8eb6893dc000@192.168.203.146@tcp:28/4 lens 224/224 e 0 to 1 dl 1788669705 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4413.919345] Lustre: 95205:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788669704/real 1788669704] req@0000000092af1461 x1875555966263552/t0(0) o400->MGC192.168.203.146@tcp@192.168.203.146@tcp:26/25 lens 224/224 e 0 to 1 dl 1788669720 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4413.952197] LustreError: MGC192.168.203.146@tcp: Connection to MGS (at 192.168.203.146@tcp) was lost; in progress operations using this service will fail [ 4423.142969] Lustre: Evicted from MGS (at 192.168.203.146@tcp) after server handle changed from 0x335327e6ec5714e8 to 0x335327e6ec57167e [ 4423.164334] Lustre: MGC192.168.203.146@tcp: Connection restored to 192.168.203.146@tcp (at 192.168.203.146@tcp) [ 4429.341535] Lustre: lustre-MDT0000-mdc-ffff8eb6893dc000: Connection restored to 192.168.203.146@tcp (at 192.168.203.146@tcp) [ 4434.974994] Lustre: Unmounted lustre-client [ 4462.846485] Key type lgssc unregistered [ 4463.057928] LNet: 99507:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4463.064492] LNetError: 99507:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4463.084377] LNet: Removed LNI 192.168.203.46@tcp [ 4463.799592] Key type .llcrypt unregistered [ 4463.807081] Key type ._llcrypt unregistered [ 4479.066061] Key type ._llcrypt registered [ 4479.068706] Key type .llcrypt registered [ 4480.324739] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 00:43:05 (1788669785) [ 4494.955647] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4494.981851] alg: No test for adler32 (adler32-zlib) [ 4496.122528] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [ 4496.334853] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 4498.015392] Key type lgssc registered [ 4499.429446] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4540.686959] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4643.660273] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4649.358809] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4674.534994] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4677.610469] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 4679.621301] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 4681.504864] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 00:46:26 (1788669986) [ 4913.364673] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4923.686252] Lustre: Unmounted lustre-client [ 5045.331643] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5051.411679] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5078.188682] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5127.106867] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 00:53:52 (1788670432) [ 5232.116861] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5240.451366] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5267.904921] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5268.712872] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 5273.673893] Lustre: Unmounted lustre-client [ 5305.152146] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 00:56:50 (1788670610) [ 5410.417223] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5415.958221] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5439.799645] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5440.477309] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 5444.549252] Lustre: Unmounted lustre-client [ 5471.145510] Key type lgssc unregistered [ 5471.521111] LNet: 122543:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5471.533621] LNetError: 122543:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5471.565787] LNet: Removed LNI 192.168.203.46@tcp [ 5472.530977] Key type .llcrypt unregistered [ 5472.536261] Key type ._llcrypt unregistered [ 5489.533993] Key type ._llcrypt registered [ 5489.536476] Key type .llcrypt registered [ 5491.325377] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 00:59:56 (1788670796) [ 5493.705045] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5493.795894] alg: No test for adler32 (adler32-zlib) [ 5495.015920] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [ 5495.269924] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 5496.967175] Key type lgssc registered [ 5498.297200] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5550.631668] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5557.666588] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5579.255972] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5581.048256] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 5597.969704] Lustre: Unmounted lustre-client [ 5626.362685] Key type lgssc unregistered [ 5626.660975] LNet: 126151:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5626.673305] LNetError: 126151:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5626.695889] LNet: Removed LNI 192.168.203.46@tcp [ 5627.484157] Key type .llcrypt unregistered [ 5627.489858] Key type ._llcrypt unregistered [ 5645.638513] Key type ._llcrypt registered [ 5645.648348] Key type .llcrypt registered [ 5647.201031] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 01:02:32 (1788670952) [ 5649.898936] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5649.973930] alg: No test for adler32 (adler32-zlib) [ 5651.103908] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [ 5651.465448] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 5653.223237] Key type lgssc registered [ 5654.598468] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5704.827509] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5710.270957] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5729.901646] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5732.754496] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 5737.961748] Lustre: lustre-OST0000-osc-ffff8eb686c79800: Connection to lustre-OST0000 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5751.280616] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff8eb686c79800.ost_server_uuid 50 [ 5753.034979] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 5764.771792] Lustre: Unmounted lustre-client [ 5789.316859] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 01:04:54 (1788671094) [ 5824.567489] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5829.829948] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5849.876623] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5874.370564] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5875.105085] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 5880.299781] Lustre: lustre-OST0000-osc-ffff8eb69151c000: Connection to lustre-OST0000 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5909.079581] Lustre: Unmounted lustre-client [ 5958.711877] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 01:07:43 (1788671263) [ 5997.976723] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6004.479835] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6026.824381] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6050.780991] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6051.485638] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 6056.941317] Lustre: lustre-OST0000-osc-ffff8eb68723a000: Connection to lustre-OST0000 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6073.985333] Lustre: Unmounted lustre-client [ 6127.045923] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 01:10:32 (1788671432) [ 6227.316300] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6232.885848] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6257.684765] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6326.797311] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6333.066566] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6355.903313] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6388.666525] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 6431.637456] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6435.698970] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 2 sec [ 6438.346713] Lustre: Unmounted lustre-client [ 6467.987741] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 01:16:13 (1788671773) [ 6502.976881] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6509.480673] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6530.380452] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6562.537801] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6593.964636] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 6637.650260] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6639.820534] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6661.136629] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6671.496876] Lustre: Unmounted lustre-client [ 6724.326705] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 01:20:29 (1788672029) [ 6758.049553] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6764.009569] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6787.049907] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6787.707775] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 6810.981137] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6812.300928] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6827.894696] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8eb6980de000.ost_server_uuid 50 [ 6829.786627] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8eb6980de000.ost_server_uuid in IDLE state after 0 sec [ 6833.008412] Lustre: Unmounted lustre-client [ 6833.338329] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6833.344101] Lustre: Skipped 3 previous similar messages [ 6833.431206] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 6834.481224] LustreError: 149588:0:(sec.c:403:import_sec_validate_get()) import 000000001e416a3a (NEW) with no sec [ 6892.630497] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 01:23:18 (1788672198) [ 6929.666116] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6936.379360] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6959.555704] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6979.898042] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6980.590607] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 6980.739117] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 6996.928022] Lustre: Unmounted lustre-client [ 6996.934225] Lustre: Skipped 1 previous similar message [ 7047.763557] Key type lgssc unregistered [ 7047.980170] LNet: 153021:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7047.987969] LNetError: 153021:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7048.010688] LNet: Removed LNI 192.168.203.46@tcp [ 7048.894782] Key type .llcrypt unregistered [ 7048.899182] Key type ._llcrypt unregistered [ 7069.006259] Key type ._llcrypt registered [ 7069.009306] Key type .llcrypt registered [ 7070.224174] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 01:26:15 (1788672375) [ 7071.778447] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7071.792461] alg: No test for adler32 (adler32-zlib) [ 7072.884596] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [ 7073.172656] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 7074.839205] Key type lgssc registered [ 7076.046227] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7132.538220] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7138.041741] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7156.383817] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7182.904541] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7184.518255] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7185.700775] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 7190.818883] LustreError: lustre-MDT0001-mdc-ffff8eb690563000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 7190.857107] Lustre: lustre-MDT0001-mdc-ffff8eb690563000: Connection restored to 192.168.203.146@tcp (at 192.168.203.146@tcp) [ 7197.879763] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7199.424525] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7222.316847] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7225.777103] Lustre: Unmounted lustre-client [ 7254.261400] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 01:29:19 (1788672559) [ 7351.468729] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7359.330383] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7385.738767] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7386.397909] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 7414.530653] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7416.587898] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7421.936584] Lustre: lustre-OST0001-osc-ffff8eb698e11800: Connection to lustre-OST0001 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7437.832711] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7448.173546] Lustre: Unmounted lustre-client [ 7480.798913] Key type lgssc unregistered [ 7480.998652] LNet: 162632:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7481.013872] LNetError: 162632:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7481.036376] LNet: Removed LNI 192.168.203.46@tcp [ 7481.682140] Key type .llcrypt unregistered [ 7481.687129] Key type ._llcrypt unregistered [ 7509.163138] Key type ._llcrypt registered [ 7509.169233] Key type .llcrypt registered [ 7510.563361] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 01:33:35 (1788672815) [ 7512.450124] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7512.578193] alg: No test for adler32 (adler32-zlib) [ 7513.630464] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [ 7513.941817] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 7515.639190] Key type lgssc registered [ 7516.817569] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7567.383244] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7572.228174] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7593.248495] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7594.940426] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 7641.974886] Lustre: Unmounted lustre-client [ 7681.844111] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7682.414862] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 7718.845652] Lustre: Unmounted lustre-client [ 7748.678858] Key type lgssc unregistered [ 7749.016046] LNet: 167078:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7749.021318] LNetError: 167078:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7749.060235] LNet: Removed LNI 192.168.203.46@tcp [ 7749.798114] Key type .llcrypt unregistered [ 7749.800251] Key type ._llcrypt unregistered [ 7765.088718] Key type ._llcrypt registered [ 7765.091857] Key type .llcrypt registered [ 7766.475544] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 01:37:51 (1788673071) [ 7768.527551] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7768.580054] alg: No test for adler32 (adler32-zlib) [ 7769.699896] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [ 7770.036409] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 7771.711213] Key type lgssc registered [ 7773.108872] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7824.997643] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7831.373604] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7854.989500] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7856.748787] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 7880.133973] Lustre: Unmounted lustre-client [ 7914.453691] Key type lgssc unregistered [ 7914.747741] LNet: 170825:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7914.762925] LNetError: 170825:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7914.780655] LNet: Removed LNI 192.168.203.46@tcp [ 7915.413281] Key type .llcrypt unregistered [ 7915.420820] Key type ._llcrypt unregistered [ 7928.386818] Key type ._llcrypt registered [ 7928.403870] Key type .llcrypt registered [ 7928.879785] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7928.897945] alg: No test for adler32 (adler32-zlib) [ 7929.892703] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [ 7930.177432] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 7931.863232] Key type lgssc registered [ 7933.163607] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7980.992497] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7986.583382] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8007.720529] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8009.507598] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 8016.356653] Lustre: Unmounted lustre-client [ 8044.843776] Key type lgssc unregistered [ 8045.152965] LNet: 173896:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8045.159876] LNetError: 173896:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8045.182286] LNet: Removed LNI 192.168.203.46@tcp [ 8045.953148] Key type .llcrypt unregistered [ 8045.956382] Key type ._llcrypt unregistered [ 8062.064171] Key type ._llcrypt registered [ 8062.066601] Key type .llcrypt registered [ 8063.465945] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 01:42:48 (1788673368) [ 8065.431971] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8065.507223] alg: No test for adler32 (adler32-zlib) [ 8066.495812] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [ 8066.695233] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 8068.359338] Key type lgssc registered [ 8069.498541] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8121.510578] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8127.156289] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8148.989856] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8151.798732] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 8173.037418] Lustre: Unmounted lustre-client [ 8199.166456] Key type lgssc unregistered [ 8199.400643] LNet: 177648:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8199.405486] LNetError: 177648:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8199.424066] LNet: Removed LNI 192.168.203.46@tcp [ 8200.078795] Key type .llcrypt unregistered [ 8200.081360] Key type ._llcrypt unregistered [ 8211.502831] Key type ._llcrypt registered [ 8211.507903] Key type .llcrypt registered [ 8212.174023] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8212.201063] alg: No test for adler32 (adler32-zlib) [ 8213.299617] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [ 8213.538147] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 8215.183384] Key type lgssc registered [ 8216.498699] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8266.134929] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8273.283619] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8296.206374] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8299.149273] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 8311.674771] Lustre: Unmounted lustre-client [ 8341.613649] Key type lgssc unregistered [ 8342.024845] LNet: 180707:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8342.038127] LNetError: 180707:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8342.083269] LNet: Removed LNI 192.168.203.46@tcp [ 8343.062248] Key type .llcrypt unregistered [ 8343.071209] Key type ._llcrypt unregistered [ 8361.661918] Key type ._llcrypt registered [ 8361.664384] Key type .llcrypt registered [ 8363.275203] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 01:47:48 (1788673668) [ 8390.481714] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8390.556377] alg: No test for adler32 (adler32-zlib) [ 8392.013289] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [ 8392.565694] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 8394.327316] Key type lgssc registered [ 8396.005591] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8482.431801] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8489.214978] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8516.645449] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8561.800582] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 01:51:06 (1788673866) [ 8601.323907] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8607.635904] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8630.809478] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8633.463994] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 8639.466715] Lustre: Unmounted lustre-client [ 8669.187742] Key type lgssc unregistered [ 8669.520297] LNet: 187801:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8669.530597] LNetError: 187801:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8669.548277] LNet: Removed LNI 192.168.203.46@tcp [ 8670.467971] Key type .llcrypt unregistered [ 8670.469843] Key type ._llcrypt unregistered [ 8687.684413] Key type ._llcrypt registered [ 8687.686116] Key type .llcrypt registered [ 8689.263452] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 8691.228937] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 01:53:16 (1788673996) [ 8709.343472] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8709.361218] alg: No test for adler32 (adler32-zlib) [ 8710.423456] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [ 8710.685777] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 8712.407215] Key type lgssc registered [ 8713.433436] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8803.127300] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8807.699505] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8830.194872] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8853.055821] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8855.704366] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 8875.462770] Lustre: Unmounted lustre-client [ 8998.752165] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9005.544143] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9030.413156] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9076.811489] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 01:59:42 (1788674382) [ 9078.489179] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 9080.160697] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 01:59:45 (1788674385) [ 9177.046402] Key type lgssc unregistered [ 9177.323364] LNet: 199241:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9177.334029] LNetError: 199241:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9177.362256] LNet: Removed LNI 192.168.203.46@tcp [ 9178.192467] Key type .llcrypt unregistered [ 9178.199377] Key type ._llcrypt unregistered [ 9194.809111] Key type ._llcrypt registered [ 9194.811377] Key type .llcrypt registered [ 9196.209957] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 02:01:41 (1788674501) [ 9236.840746] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9236.868507] alg: No test for adler32 (adler32-zlib) [ 9237.936209] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [ 9238.197440] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 9239.887247] Key type lgssc registered [ 9241.271762] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9289.281189] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9295.861808] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9328.725814] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9355.897228] Key type lgssc unregistered [ 9356.271205] LNet: 202967:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9356.281220] LNetError: 202967:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9356.326763] LNet: Removed LNI 192.168.203.46@tcp [ 9356.957852] Key type .llcrypt unregistered [ 9356.961311] Key type ._llcrypt unregistered [ 9372.334159] Key type ._llcrypt registered [ 9372.337440] Key type .llcrypt registered [ 9373.475692] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 02:04:39 (1788674679) [ 9375.725775] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9375.752623] alg: No test for adler32 (adler32-zlib) [ 9376.774979] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [ 9377.024885] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 9378.703194] Key type lgssc registered [ 9379.801667] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9429.936226] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9434.565426] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9453.779883] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9456.492972] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [ 9466.857971] Lustre: lustre-MDT0000-mdc-ffff8eb6980de000: Connection to lustre-MDT0000 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9477.099868] Lustre: lustre-MDT0001-mdc-ffff8eb6980de000: Connection to lustre-MDT0001 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9488.099348] Lustre: 203951:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788674778/real 1788674778] req@00000000a80d3634 x1875561344483456/t0(0) o400->MGC192.168.203.146@tcp@192.168.203.146@tcp:26/25 lens 224/224 e 0 to 1 dl 1788674794 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9488.151968] LustreError: MGC192.168.203.146@tcp: Connection to MGS (at 192.168.203.146@tcp) was lost; in progress operations using this service will fail [ 9513.965979] Lustre: Evicted from MGS (at 192.168.203.146@tcp) after server handle changed from 0x2460e8b9ac05b750 to 0x2460e8b9ac05d094 [ 9513.977743] Lustre: MGC192.168.203.146@tcp: Connection restored to 192.168.203.146@tcp (at 192.168.203.146@tcp) [ 9543.700486] LustreError: lustre-MDT0000-mdc-ffff8eb6980de000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 9543.747382] Lustre: lustre-MDT0000-mdc-ffff8eb6980de000: Connection restored to 192.168.203.146@tcp (at 192.168.203.146@tcp) [ 9547.876521] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9549.996207] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9555.757736] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9557.407945] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9560.388920] Lustre: Unmounted lustre-client [ 9596.244630] Key type lgssc unregistered [ 9596.560894] LNet: 207912:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9596.580803] LNetError: 207912:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9596.604346] LNet: Removed LNI 192.168.203.46@tcp [ 9597.377989] Key type .llcrypt unregistered [ 9597.381822] Key type ._llcrypt unregistered [ 9613.908803] Key type ._llcrypt registered [ 9613.911428] Key type .llcrypt registered [ 9615.635545] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 02:08:40 (1788674920) [ 9618.211916] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9618.339343] alg: No test for adler32 (adler32-zlib) [ 9619.542189] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [ 9619.732059] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 9621.408410] Key type lgssc registered [ 9623.162590] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9675.537973] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9681.678605] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9744.334912] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9749.844614] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9783.052792] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9804.574476] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9861.436458] Key type lgssc unregistered [ 9861.725649] LNet: 213203:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9861.732576] LNetError: 213203:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9861.763633] LNet: Removed LNI 192.168.203.46@tcp [ 9862.355325] Key type .llcrypt unregistered [ 9862.359796] Key type ._llcrypt unregistered [ 9904.784490] Key type ._llcrypt registered [ 9904.789063] Key type .llcrypt registered [ 9906.188513] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 02:13:31 (1788675211) [ 9945.820049] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9945.916341] alg: No test for adler32 (adler32-zlib) [ 9947.044706] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [ 9947.384913] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [ 9949.151686] Key type lgssc registered [ 9950.396386] Lustre: Echo OBD driver; http://www.lustre.org/ [10043.183858] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10049.893802] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10075.416293] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10118.299408] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 02:17:03 (1788675423) [10126.940483] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 02:17:12 (1788675432) [10169.297851] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10175.880092] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10198.026851] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10200.790404] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [10210.226855] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [10212.732379] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [10214.406873] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [10216.160536] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [10221.553319] Lustre: lustre-MDT0000-mdc-ffff8eb687cbe800: Connection to lustre-MDT0000 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [10237.791942] Lustre: 215099:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788675528/real 1788675528] req@000000007e7bdafb x1875561943148672/t0(0) o400->MGC192.168.203.146@tcp@192.168.203.146@tcp:26/25 lens 224/224 e 0 to 1 dl 1788675544 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10237.847225] LustreError: MGC192.168.203.146@tcp: Connection to MGS (at 192.168.203.146@tcp) was lost; in progress operations using this service will fail [10247.151562] Lustre: Evicted from MGS (at 192.168.203.146@tcp) after server handle changed from 0xa6dded06349b91da to 0xa6dded06349b9354 [10247.170920] Lustre: MGC192.168.203.146@tcp: Connection restored to 192.168.203.146@tcp (at 192.168.203.146@tcp) [10254.350504] Lustre: lustre-MDT0000-mdc-ffff8eb687cbe800: Connection restored to 192.168.203.146@tcp (at 192.168.203.146@tcp) [10263.880142] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10265.786297] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10270.615067] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [10277.891818] Lustre: lustre-MDT0000-mdc-ffff8eb687cbe800: Connection to lustre-MDT0000 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [10294.111242] Lustre: 215100:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788675584/real 1788675584] req@000000004d3fac16 x1875561943158528/t0(0) o400->MGC192.168.203.146@tcp@192.168.203.146@tcp:26/25 lens 224/224 e 0 to 1 dl 1788675600 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10294.146440] LustreError: MGC192.168.203.146@tcp: Connection to MGS (at 192.168.203.146@tcp) was lost; in progress operations using this service will fail [10294.181687] Lustre: Evicted from MGS (at 192.168.203.146@tcp) after server handle changed from 0xa6dded06349b9354 to 0xa6dded06349b9664 [10294.202576] Lustre: MGC192.168.203.146@tcp: Connection restored to 192.168.203.146@tcp (at 192.168.203.146@tcp) [10301.448155] Lustre: 221091:0:(mgc_request.c:1898:mgc_process_log()) MGC192.168.203.146@tcp: IR log lustre-cliir failed, not fatal: rc = -2 [10307.075590] Lustre: lustre-MDT0000-mdc-ffff8eb687cbe800: Connection restored to 192.168.203.146@tcp (at 192.168.203.146@tcp) [10315.564967] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10316.964586] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10320.579325] Lustre: Unmounted lustre-client [10393.137310] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10399.798924] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10421.746744] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10422.499905] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [10431.058274] Lustre: Unmounted lustre-client [10466.483705] Key type lgssc unregistered [10466.766778] LNet: 225676:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10466.772032] LNetError: 225676:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10466.782520] LNet: Removed LNI 192.168.203.46@tcp [10467.557393] Key type .llcrypt unregistered [10467.559930] Key type ._llcrypt unregistered [10483.809338] Key type ._llcrypt registered [10483.811800] Key type .llcrypt registered [10485.091639] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 02:23:10 (1788675790) [10489.604539] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10489.638798] alg: No test for adler32 (adler32-zlib) [10490.827802] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [10491.054494] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [10492.831231] Key type lgssc registered [10494.294315] Lustre: Echo OBD driver; http://www.lustre.org/ [10545.249945] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10549.951169] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10570.280992] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10572.002868] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [10579.380343] Lustre: Unmounted lustre-client [10641.603492] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10646.904750] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10668.649141] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10669.280687] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [10678.157516] Lustre: Unmounted lustre-client [10749.867378] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10757.439671] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10778.341929] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10778.990818] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [10781.381936] Lustre: Unmounted lustre-client [10803.016506] Key type lgssc unregistered [10803.374728] LNet: 232715:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10803.385832] LNetError: 232715:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10803.414311] LNet: Removed LNI 192.168.203.46@tcp [10804.224594] Key type .llcrypt unregistered [10804.226962] Key type ._llcrypt unregistered [10819.745127] Key type ._llcrypt registered [10819.749288] Key type .llcrypt registered [10821.244813] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 02:28:46 (1788676126) [10824.296912] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10824.408465] alg: No test for adler32 (adler32-zlib) [10825.583698] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [10825.912488] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [10827.663200] Key type lgssc registered [10828.874434] Lustre: Echo OBD driver; http://www.lustre.org/ [10872.758370] Key type lgssc unregistered [10873.041903] LNet: 235030:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10873.056539] LNetError: 235030:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10873.082223] LNet: Removed LNI 192.168.203.46@tcp [10873.777257] Key type .llcrypt unregistered [10873.787245] Key type ._llcrypt unregistered [10901.948414] Key type ._llcrypt registered [10901.952207] Key type .llcrypt registered [10902.372258] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10902.384752] alg: No test for adler32 (adler32-zlib) [10903.450865] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [10903.647594] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [10905.327202] Key type lgssc registered [10906.441527] Lustre: Echo OBD driver; http://www.lustre.org/ [10980.283373] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10985.511592] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11005.686714] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11047.269100] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [11049.506994] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 02:32:34 (1788676354) [11081.304084] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11086.265186] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11103.924433] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11125.276409] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11126.834285] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [11131.883939] Lustre: lustre-OST0001-osc-ffff8eb68723e000: Connection to lustre-OST0001 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [11137.625951] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11147.934716] Lustre: Unmounted lustre-client [11179.258948] Key type lgssc unregistered [11179.526627] LNet: 242135:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11179.541231] LNetError: 242135:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11179.584032] LNet: Removed LNI 192.168.203.46@tcp [11180.262866] Key type .llcrypt unregistered [11180.265154] Key type ._llcrypt unregistered [11208.118805] Key type ._llcrypt registered [11208.121111] Key type .llcrypt registered [11209.227501] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 02:35:14 (1788676514) [11221.782152] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11221.829161] alg: No test for adler32 (adler32-zlib) [11222.878439] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [11223.143598] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [11224.872083] Key type lgssc registered [11226.324888] Lustre: Echo OBD driver; http://www.lustre.org/ [11273.932131] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11279.325619] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11301.608455] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 02:36:46 (1788676606) [11337.977529] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11344.197795] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11363.854970] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11366.453217] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [11377.684696] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [11382.417900] Lustre: Unmounted lustre-client [11474.098760] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11479.285968] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11527.827438] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11528.575509] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [11531.448900] Lustre: Unmounted lustre-client [11566.262030] Key type lgssc unregistered [11566.544897] LNet: 249833:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11566.551950] LNetError: 249833:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11566.567569] LNet: Removed LNI 192.168.203.46@tcp [11567.261246] Key type .llcrypt unregistered [11567.267073] Key type ._llcrypt unregistered [11594.326133] Key type ._llcrypt registered [11594.328456] Key type .llcrypt registered [11594.640569] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11594.652432] alg: No test for adler32 (adler32-zlib) [11595.759827] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [11595.961642] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [11597.610815] Key type lgssc registered [11598.607872] Lustre: Echo OBD driver; http://www.lustre.org/ [11680.807442] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11686.312731] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11708.373676] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11747.946895] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [11749.407714] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 02:44:15 (1788677055) [11780.311489] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11785.954265] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11804.291512] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11808.670526] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [11811.100374] Lustre: Unmounted lustre-client [11848.052706] Key type lgssc unregistered [11848.290422] LNet: 256464:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11848.300735] LNetError: 256464:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11848.328608] LNet: Removed LNI 192.168.203.46@tcp [11848.900569] Key type .llcrypt unregistered [11848.907255] Key type ._llcrypt unregistered [11865.234660] Key type ._llcrypt registered [11865.239571] Key type .llcrypt registered [11866.497938] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [11868.038943] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 02:46:13 (1788677173) [11876.529569] Key type .llcrypt unregistered [11876.542061] Key type ._llcrypt unregistered [11885.095236] Key type ._llcrypt registered [11885.104860] Key type .llcrypt registered [11885.505042] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11885.524338] alg: No test for adler32 (adler32-zlib) [11886.618068] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [11886.913649] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [11888.655181] Key type lgssc registered [11890.218641] Lustre: Echo OBD driver; http://www.lustre.org/ [11943.532054] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11958.222421] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [11960.896736] Lustre: Unmounted lustre-client [11990.099543] Key type lgssc unregistered [11990.316150] LNet: 259916:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11990.324294] LNetError: 259916:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11990.351563] LNet: Removed LNI 192.168.203.46@tcp [11990.991744] Key type .llcrypt unregistered [11990.994649] Key type ._llcrypt unregistered [12005.434448] Key type ._llcrypt registered [12005.439963] Key type .llcrypt registered [12006.835553] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 02:48:32 (1788677312) [12009.063086] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12009.200095] alg: No test for adler32 (adler32-zlib) [12010.358813] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [12010.738505] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [12012.471246] Key type lgssc registered [12013.626607] Lustre: Echo OBD driver; http://www.lustre.org/ [12099.535464] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12132.743811] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12138.932017] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12140.493708] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [12143.038622] Lustre: Unmounted lustre-client [12162.146880] Key type lgssc unregistered [12162.600815] LNet: 263453:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12162.620430] LNetError: 263453:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12162.639577] LNet: Removed LNI 192.168.203.46@tcp [12163.664086] Key type .llcrypt unregistered [12163.670138] Key type ._llcrypt unregistered [12180.274977] Key type ._llcrypt registered [12180.277496] Key type .llcrypt registered [12181.539577] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 02:51:26 (1788677486) [12184.057750] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12184.105318] alg: No test for adler32 (adler32-zlib) [12185.319540] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [12185.631916] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [12187.431191] Key type lgssc registered [12188.689698] Lustre: Echo OBD driver; http://www.lustre.org/ [12238.904440] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12244.568947] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12264.962303] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12266.501431] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [12271.592438] Lustre: lustre-MDT0000-mdc-ffff8eb688c51800: Connection to lustre-MDT0000 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [12276.506525] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12276.541871] LustreError: 266608:0:(file.c:6167:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [12276.874100] LustreError: MGC192.168.203.146@tcp: Connection to MGS (at 192.168.203.146@tcp) was lost; in progress operations using this service will fail [12276.930109] Lustre: Unmounted lustre-client [12296.790276] Key type lgssc unregistered [12297.128545] LNet: 267019:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12297.137438] LNetError: 267019:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12297.156724] LNet: Removed LNI 192.168.203.46@tcp [12297.842586] Key type .llcrypt unregistered [12297.845204] Key type ._llcrypt unregistered [12313.291537] Key type ._llcrypt registered [12313.293355] Key type .llcrypt registered [12315.084783] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 02:53:39 (1788677619) [12317.618447] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12317.754298] alg: No test for adler32 (adler32-zlib) [12319.010809] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [12319.295559] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [12321.059176] Key type lgssc registered [12322.589428] Lustre: Echo OBD driver; http://www.lustre.org/ [12377.402557] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12383.755567] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12405.535885] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12407.200198] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [12417.763161] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [12419.867963] Lustre: Unmounted lustre-client [12440.634587] Key type lgssc unregistered [12440.913795] LNet: 270592:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12440.925130] LNetError: 270592:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12440.947644] LNet: Removed LNI 192.168.203.46@tcp [12441.642599] Key type .llcrypt unregistered [12441.644411] Key type ._llcrypt unregistered [12457.456887] Key type ._llcrypt registered [12457.460362] Key type .llcrypt registered [12458.979969] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 02:56:04 (1788677764) [12476.106191] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12476.146566] alg: No test for adler32 (adler32-zlib) [12477.375803] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [12477.688468] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [12479.351160] Key type lgssc registered [12481.042559] Lustre: Echo OBD driver; http://www.lustre.org/ [12579.294488] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12581.848893] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [12620.270407] Lustre: lustre-MDT0001-mdc-ffff8eb688c55800: Connection to lustre-MDT0001 (at 192.168.203.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [12630.293603] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12640.626039] Lustre: Unmounted lustre-client [12666.834916] Key type lgssc unregistered [12667.053896] LNet: 276370:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12667.065810] LNetError: 276370:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12667.083187] LNet: Removed LNI 192.168.203.46@tcp [12667.672662] Key type .llcrypt unregistered [12667.677712] Key type ._llcrypt unregistered [12680.883982] Key type ._llcrypt registered [12680.887709] Key type .llcrypt registered [12682.015714] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 02:59:47 (1788677987) [12683.437326] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [12684.914457] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 02:59:50 (1788677990) [12686.458816] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [12688.179208] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 02:59:53 (1788677993) [12689.623913] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [12691.564312] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 02:59:56 (1788677996) [12693.014620] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [12694.961754] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 03:00:00 (1788678000) [12696.590730] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [12698.495537] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 03:00:03 (1788678003) [12707.477159] Key type .llcrypt unregistered [12707.481869] Key type ._llcrypt unregistered [12714.999106] Key type ._llcrypt registered [12715.001290] Key type .llcrypt registered [12715.493482] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12715.524269] alg: No test for adler32 (adler32-zlib) [12716.764846] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [12717.105709] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [12718.911187] Key type lgssc registered [12720.539329] Lustre: Echo OBD driver; http://www.lustre.org/ [12792.657446] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12797.475913] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12818.662638] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12820.543171] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [12823.534850] Lustre: Unmounted lustre-client [12860.352932] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 03:02:45 (1788678165) [12893.585429] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12899.044654] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12934.240617] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12934.799897] LustreError: 279140:0:(lmv_obd.c:211:lmv_notify()) activation of lustre-MDT0001_UUID failed: -22 [12940.367215] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [12942.531119] Lustre: Unmounted lustre-client [12993.631178] LNet: 1 local NIs in recovery (showing 1): 192.168.203.46@tcp [12993.982242] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 03:04:59 (1788678299) [13639.627963] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13645.375748] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13679.241195] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13784.944454] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 03:18:10 (1788679090) [13798.117733] Key type lgssc unregistered [13798.550611] LNet: 289900:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13798.561114] LNetError: 289900:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13798.576777] LNet: Removed LNI 192.168.203.46@tcp [13799.343454] Key type .llcrypt unregistered [13799.347814] Key type ._llcrypt unregistered [13931.280811] Key type ._llcrypt registered [13931.288948] Key type .llcrypt registered [13931.865974] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13931.889590] alg: No test for adler32 (adler32-zlib) [13933.010536] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [13933.346915] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [13935.127657] Key type lgssc registered [13936.354835] Lustre: Echo OBD driver; http://www.lustre.org/ [14033.352172] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14041.152256] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14067.916108] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14101.887372] Key type lgssc unregistered [14102.156246] LNet: 293600:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14102.167113] LNetError: 293600:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14102.196093] LNet: Removed LNI 192.168.203.46@tcp [14102.914832] Key type .llcrypt unregistered [14102.917753] Key type ._llcrypt unregistered [14140.007506] Key type ._llcrypt registered [14140.013674] Key type .llcrypt registered [14141.279740] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 03:24:06 (1788679446) [14163.420993] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14163.439815] alg: No test for adler32 (adler32-zlib) [14164.597955] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [14164.932564] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [14166.631226] Key type lgssc registered [14168.421890] Lustre: Echo OBD driver; http://www.lustre.org/ [14227.607206] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 03:25:32 (1788679532) [14241.351935] Key type lgssc unregistered [14241.697458] LNet: 297153:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14241.715889] LNetError: 297153:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14241.738681] LNet: Removed LNI 192.168.203.46@tcp [14242.704644] Key type .llcrypt unregistered [14242.707654] Key type ._llcrypt unregistered [14255.067325] Key type ._llcrypt registered [14255.070953] Key type .llcrypt registered [14255.289912] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14255.307467] alg: No test for adler32 (adler32-zlib) [14256.395537] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [14256.648207] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [14258.327229] Key type lgssc registered [14259.369520] Lustre: Echo OBD driver; http://www.lustre.org/ [14314.766665] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14321.020430] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14343.576905] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14620.671758] LNet: Added LNI 192.168.203.46@tcp50 [8/256/0/180] [14623.099609] LNet: Removed LNI 192.168.203.46@tcp [14625.273423] Lustre: 301552:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff8eb698637000: config has no valid NIDs, force dynamic NIDs [14626.490038] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [14626.634615] Lustre: Unmounted lustre-client [14629.344948] Key type lgssc unregistered [14629.746866] LNet: 301811:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14629.752853] LNetError: 301811:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14629.768803] LNet: Removed LNI 192.168.203.46@tcp50 [14630.442130] Key type .llcrypt unregistered [14630.444807] Key type ._llcrypt unregistered [14647.781019] Key type ._llcrypt registered [14647.783359] Key type .llcrypt registered [14648.312238] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14648.327532] alg: No test for adler32 (adler32-zlib) [14649.435275] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [14649.661845] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [14651.408762] Key type lgssc registered [14652.812863] Lustre: Echo OBD driver; http://www.lustre.org/ [14712.712804] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14718.714385] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14726.573790] LNet: Added LNI 192.168.203.46@tcp50 [8/256/0/180] [14727.442188] LNet: Removed LNI 192.168.203.46@tcp [14728.688873] Lustre: 304500:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff8eb684dcc000: config has no valid NIDs, force dynamic NIDs [14729.913356] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [14730.496305] Lustre: Unmounted lustre-client [14764.036117] Key type lgssc unregistered [14764.438881] LNet: 305371:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14764.452648] LNetError: 305371:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14765.483623] LNet: Removed LNI 192.168.203.46@tcp50 [14766.110791] Key type .llcrypt unregistered [14766.113329] Key type ._llcrypt unregistered [14777.330360] Key type ._llcrypt registered [14777.332936] Key type .llcrypt registered [14779.269412] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 03:34:43 (1788680083) [14797.965761] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14798.018371] alg: No test for adler32 (adler32-zlib) [14799.172405] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [14799.437594] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [14801.167323] Key type lgssc registered [14802.655695] Lustre: Echo OBD driver; http://www.lustre.org/ [14888.723589] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14893.668590] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14920.769422] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14964.120626] Key type lgssc unregistered [14964.378702] LNet: 310118:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14964.387623] LNetError: 310118:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14964.410558] LNet: Removed LNI 192.168.203.46@tcp [14965.181619] Key type .llcrypt unregistered [14965.183337] Key type ._llcrypt unregistered [14975.799264] Key type ._llcrypt registered [14975.808275] Key type .llcrypt registered [14977.351443] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 03:38:02 (1788680282) [14979.563818] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14979.588936] alg: No test for adler32 (adler32-zlib) [14980.699499] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [14980.963481] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [14982.671366] Key type lgssc registered [14983.857157] Lustre: Echo OBD driver; http://www.lustre.org/ [15030.124911] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15035.664619] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15054.647406] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15057.382369] Lustre: Mounted lustre-client - version 2.17.58_3_gb7bcc87 [15115.890388] Lustre: Mounted fs15246-client - version 2.17.58_3_gb7bcc87 [15128.344239] Lustre: Unmounted fs15246-client [15152.921374] Lustre: Unmounted lustre-client [15179.924989] Key type lgssc unregistered [15180.252602] LNet: 314968:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15180.271855] LNetError: 314968:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15180.298139] LNet: Removed LNI 192.168.203.46@tcp [15181.063213] Key type .llcrypt unregistered [15181.068708] Key type ._llcrypt unregistered [15191.728357] Key type ._llcrypt registered [15191.736486] Key type .llcrypt registered [15192.941682] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 03:41:38 (1788680498) [15194.500022] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15194.535749] alg: No test for adler32 (adler32-zlib) [15195.666645] Lustre: Lustre: Build Version: 2.17.58_3_gb7bcc87 [15195.929563] LNet: Added LNI 192.168.203.46@tcp [8/256/0/180] [15197.591238] Key type lgssc registered [15198.717161] Lustre: Echo OBD driver; http://www.lustre.org/ [15325.269641] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15331.118917] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15354.106991] Lustre: DEBUG MARKER: oleg346-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15448.643985] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 15118 sec ========= 03:45:54 (1788680754) [15449.952491] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 03:45:55 (1788680755) === [15452.534943] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 03:45:58 (1788680758) === [15483.445857] Key type lgssc unregistered [15483.689039] LNet: 324224:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15483.705749] LNetError: 324224:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15483.736712] LNet: Removed LNI 192.168.203.46@tcp [15484.365722] Key type .llcrypt unregistered [15484.368809] Key type ._llcrypt unregistered