[ 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 437681233 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001009] APIC: Switch to symmetric I/O mode setup [ 0.002386] x2apic enabled [ 0.003009] Switched APIC routing to physical x2apic. [ 0.004012] kvm-guest: setup PV IPIs [ 0.007654] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008021] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009016] pid_max: default: 32768 minimum: 301 [ 0.010156] LSM: Security Framework initializing [ 0.011088] Yama: becoming mindful. [ 0.012051] SELinux: Initializing. [ 0.013083] *** VALIDATE selinux *** [ 0.023106] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.029110] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.030197] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.032086] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.033119] *** VALIDATE tmpfs *** [ 0.034522] *** VALIDATE proc *** [ 0.035310] *** VALIDATE cgroup *** [ 0.036012] *** VALIDATE cgroup2 *** [ 0.037302] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.038156] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.039008] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.040027] Spectre V2 : User space: Vulnerable [ 0.041011] Speculative Store Bypass: Vulnerable [ 0.043756] debug: unmapping init [mem 0xffffffffa0c59000-0xffffffffa0c60fff] [ 0.045949] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.046745] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.047024] ... version: 2 [ 0.048014] ... bit width: 48 [ 0.049012] ... generic registers: 4 [ 0.050014] ... value mask: 0000ffffffffffff [ 0.051015] ... max period: 00007fffffffffff [ 0.052015] ... fixed-purpose events: 3 [ 0.053018] ... event mask: 000000070000000f [ 0.054321] rcu: Hierarchical SRCU implementation. [ 0.056431] smp: Bringing up secondary CPUs ... [ 0.057682] x86: Booting SMP configuration: [ 0.058029] .... node #0, CPUs: #1 #2 #3 [ 0.061444] smp: Brought up 1 node, 4 CPUs [ 0.063012] smpboot: Max logical packages: 1 [ 0.064017] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.143332] node 0 deferred pages initialised in 77ms [ 0.146531] devtmpfs: initialized [ 0.148258] x86/mm: Memory block size: 128MB [ 0.150709] gcov: version magic: 0x41383552 [ 0.154314] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.158128] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.161289] pinctrl core: initialized pinctrl subsystem [ 0.163169] [ 0.163786] ************************************************************* [ 0.166012] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.170012] ** ** [ 0.173012] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.177014] ** ** [ 0.180012] ** This means that this kernel is built to expose internal ** [ 0.183015] ** IOMMU data structures, which may compromise security on ** [ 0.186012] ** your system. ** [ 0.189013] ** ** [ 0.192024] ** If you see this message and you are not debugging the ** [ 0.195012] ** kernel, report this immediately to your vendor! ** [ 0.198013] ** ** [ 0.200012] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.202012] ************************************************************* [ 0.205625] NET: Registered protocol family 16 [ 0.207451] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.209040] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.212059] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.216029] cpuidle: using governor menu [ 0.218721] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.221617] PCI: Using configuration type 1 for base access [ 0.223181] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.232252] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.234031] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.238136] cryptd: max_cpu_qlen set to 1000 [ 0.241268] ACPI: Added _OSI(Module Device) [ 0.242014] ACPI: Added _OSI(Processor Device) [ 0.243018] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.245041] ACPI: Added _OSI(Processor Aggregator Device) [ 0.251115] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.257368] ACPI: Interpreter enabled [ 0.259068] ACPI: PM: (supports S0 S3 S4 S5) [ 0.261030] ACPI: Using IOAPIC for interrupt routing [ 0.263169] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.266385] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.278290] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.280043] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.282018] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.286081] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.292447] acpiphp: Slot [2] registered [ 0.293138] acpiphp: Slot [5] registered [ 0.295156] acpiphp: Slot [6] registered [ 0.296129] acpiphp: Slot [3] registered [ 0.298204] acpiphp: Slot [4] registered [ 0.299212] acpiphp: Slot [7] registered [ 0.301130] acpiphp: Slot [8] registered [ 0.303520] acpiphp: Slot [9] registered [ 0.305144] acpiphp: Slot [10] registered [ 0.306169] acpiphp: Slot [11] registered [ 0.308139] acpiphp: Slot [12] registered [ 0.310116] acpiphp: Slot [13] registered [ 0.311121] acpiphp: Slot [14] registered [ 0.313081] acpiphp: Slot [15] registered [ 0.314129] acpiphp: Slot [16] registered [ 0.316100] acpiphp: Slot [17] registered [ 0.317089] acpiphp: Slot [18] registered [ 0.318057] acpiphp: Slot [19] registered [ 0.319045] acpiphp: Slot [20] registered [ 0.320121] acpiphp: Slot [21] registered [ 0.321133] acpiphp: Slot [22] registered [ 0.322129] acpiphp: Slot [23] registered [ 0.323085] acpiphp: Slot [24] registered [ 0.325087] acpiphp: Slot [25] registered [ 0.326080] acpiphp: Slot [26] registered [ 0.327132] acpiphp: Slot [27] registered [ 0.328101] acpiphp: Slot [28] registered [ 0.329133] acpiphp: Slot [29] registered [ 0.331089] acpiphp: Slot [30] registered [ 0.332095] acpiphp: Slot [31] registered [ 0.333000] PCI host bridge to bus 0000:00 [ 0.333000] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.335021] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.337017] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.339026] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.341025] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.345013] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.347173] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.350076] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.354350] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.362016] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.367058] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.370020] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.372017] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.375021] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.377589] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.379688] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.382045] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.385845] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.389748] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.400019] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.404013] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.410495] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.418013] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.428013] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.447022] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.453075] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.461012] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.465014] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.479014] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.490115] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.493456] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.496329] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.498380] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.500162] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.504122] iommu: Default domain type: Passthrough [ 0.505484] SCSI subsystem initialized [ 0.506127] ACPI: bus type USB registered [ 0.508108] usbcore: registered new interface driver usbfs [ 0.510063] usbcore: registered new interface driver hub [ 0.512079] usbcore: registered new device driver usb [ 0.514184] pps_core: LinuxPPS API ver. 1 registered [ 0.516011] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.519058] PTP clock support registered [ 0.521121] EDAC MC: Ver: 3.0.0 [ 0.522375] PCI: Using ACPI for IRQ routing [ 0.524669] NetLabel: Initializing [ 0.526010] NetLabel: domain hash size = 128 [ 0.527008] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.529068] NetLabel: unlabeled traffic allowed by default [ 0.531091] vgaarb: loaded [ 0.532258] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.534013] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.540004] clocksource: Switched to clocksource kvm-clock [ 0.645108] VFS: Disk quotas dquot_6.6.0 [ 0.647470] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.650890] *** VALIDATE ramfs *** [ 0.652332] *** VALIDATE hugetlbfs *** [ 0.653905] pnp: PnP ACPI init [ 0.656858] pnp: PnP ACPI: found 6 devices [ 0.673822] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.677153] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.679387] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.681688] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.684076] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.686561] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.689572] NET: Registered protocol family 2 [ 0.691662] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.697105] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.700750] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.705643] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.708945] TCP: Hash tables configured (established 65536 bind 65536) [ 0.711695] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.714654] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.717716] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.720368] NET: Registered protocol family 1 [ 0.722905] RPC: Registered named UNIX socket transport module. [ 0.725023] RPC: Registered udp transport module. [ 0.729758] RPC: Registered tcp transport module. [ 0.731371] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.734090] NET: Registered protocol family 44 [ 0.735812] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.737612] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.739808] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.741903] PCI: CLS 0 bytes, default 64 [ 0.743786] Unpacking initramfs... [ 2.156482] debug: unmapping init [mem 0xffff94147cc64000-0xffff94147ffcffff] [ 2.160600] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.162862] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.165473] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.669423] Initialise system trusted keyrings [ 2.671059] Key type blacklist registered [ 2.674112] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.683469] zbud: loaded [ 2.687300] *** VALIDATE nfs *** [ 2.688356] *** VALIDATE nfs4 *** [ 2.690554] pstore: using deflate compression [ 2.696336] Platform Keyring initialized [ 2.812928] NET: Registered protocol family 38 [ 2.815088] Key type asymmetric registered [ 2.816807] Asymmetric key parser 'x509' registered [ 2.818951] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.822402] io scheduler mq-deadline registered [ 2.824235] io scheduler kyber registered [ 2.825915] io scheduler bfq registered [ 2.827854] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.831259] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.835343] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.837918] ACPI: Power Button [PWRF] [ 2.842978] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.849069] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.857806] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.886209] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.914911] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.919899] Non-volatile memory driver v1.3 [ 2.921779] Linux agpgart interface v0.103 [ 2.955594] virtio_blk virtio1: [vda] 146656 512-byte logical blocks (75.1 MB/71.6 MiB) [ 2.958720] vda: detected capacity change from 0 to 75087872 [ 2.972963] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.975754] vdb: detected capacity change from 0 to 1073741824 [ 2.982579] libphy: Fixed MDIO Bus: probed [ 2.987951] usbcore: registered new interface driver usbserial_generic [ 2.990187] usbserial: USB Serial support registered for generic [ 2.992358] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.996533] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.998639] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.001280] mousedev: PS/2 mouse device common for all mice [ 3.003485] rtc_cmos 00:05: RTC can wake from S4 [ 3.006213] rtc_cmos 00:05: registered as rtc0 [ 3.007927] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.010176] intel_pstate: CPU model not supported [ 3.010253] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.016305] hid: raw HID events driver (C) Jiri Kosina [ 3.018585] usbcore: registered new interface driver usbhid [ 3.021420] usbhid: USB HID core driver [ 3.022119] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.022960] drop_monitor: Initializing network drop monitor service [ 3.027902] Initializing XFRM netlink socket [ 3.030114] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.030365] NET: Registered protocol family 10 [ 3.035213] Segment Routing with IPv6 [ 3.036275] NET: Registered protocol family 17 [ 3.037865] mpls_gso: MPLS GSO support [ 3.044448] RAS: Correctable Errors collector initialized. [ 3.046763] AVX version of gcm_enc/dec engaged. [ 3.048505] AES CTR mode by8 optimization enabled [ 3.124714] sched_clock: Marking stable (3124695901, 0)->(4025097101, -900401200) [ 3.128486] registered taskstats version 1 [ 3.130344] Loading compiled-in X.509 certificates [ 3.132473] zswap: loaded using pool lzo/zbud [ 3.158411] Key type big_key registered [ 3.170705] Key type encrypted registered [ 3.172314] ima: No TPM chip found, activating TPM-bypass! [ 3.174589] ima: Allocated hash algorithm: sha1 [ 3.176159] ima: No architecture policies found [ 3.177957] evm: Initialising EVM extended attributes: [ 3.179956] evm: security.selinux [ 3.181232] evm: security.ima [ 3.182219] evm: security.capability [ 3.183601] evm: HMAC attrs: 0x1 [ 3.186103] rtc_cmos 00:05: setting system clock to 2026-08-27 11:13:13 UTC (1787829193) [ 3.192686] debug: unmapping init [mem 0xffffffffa1c03000-0xffffffffa1dfffff] [ 3.195916] debug: unmapping init [mem 0xffffffffa0982000-0xffffffffa0c58fff] [ 3.200106] Write protecting the kernel read-only data: 28672k [ 3.203392] debug: unmapping init [mem 0xffffffff9f003000-0xffffffff9f1fffff] [ 3.206565] debug: unmapping init [mem 0xffffffff9f914000-0xffffffff9f9fffff] [ 3.238806] 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.247341] systemd[1]: Detected virtualization kvm. [ 3.248985] systemd[1]: Detected architecture x86-64. [ 3.250815] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.276863] systemd[1]: No hostname configured. [ 3.278564] systemd[1]: Set hostname to . [ 3.280627] random: systemd: uninitialized urandom read (16 bytes read) [ 3.283195] systemd[1]: Initializing machine ID from random generator. [ 3.325561] random: ln: uninitialized urandom read (6 bytes read) [ 3.413906] random: systemd: uninitialized urandom read (16 bytes read) [ 3.417329] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 3.422827] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 3.428373] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ OK ] Listening on Journal Socket. [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Timers. Starting Setup Virtual Console... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Local File Systems. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. Starting Create Volatile Files and Directories... [ OK ] Reached target Swap. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. Starting Journal Service... Starting Apply Kernel Variables... [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.054354] device-mapper: uevent: version 1.0.3 [ 4.056342] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 4.817656] virtio_net virtio0 ens2: renamed from eth0 [ 4.852043] scsi host0: ata_piix [ 4.909045] scsi host1: ata_piix [ 4.912344] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.914639] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.203758] dracut-initqueue[586]: RTNETLINK answers: File exists [ 9.691560] random: crng init done [ 9.693189] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 10.087553] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.275249] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.535778] SELinux: Disabled at runtime. [ 11.593208] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 11.602130] systemd[1]: Detected virtualization kvm. [ 11.604156] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.092942] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.095975] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.099787] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.103276] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.106524] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.114527] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.119427] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. Mounting Huge Pages File System... [ OK ] Listening on Process Core Dump Socket. [ OK ] Listening on udev Kernel Socket. Mounting POSIX Message Queue File System... Starting udev Coldplug all Devices... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Created slice system-sshd\x2dkeygen.slice. Starting Apply Kernel Variables... Starting Remount Root and Kernel File Systems... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Created slice system-getty.slice. Starting Create list of required st…ce nodes for the current kernel... Activating swap /dev/disk/by-label/SWAP... [ OK ] Created slice system-serial\x2dgetty.slice. Mounting Kernel Debug File System... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK [0[ 12.289882] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS m] Reached target rpc_pipefs.target. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Stopped target Initrd Root File System. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Started Journal Service. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Apply Kernel Variables. [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 ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Kernel Debug File System. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... Starting udev Kernel Device Manager... [ OK ] Started udev Coldplug all Devices. [ 12.547473] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /mnt. [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 12.868122] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 12.940448] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.015992] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.031488] EDAC sbridge: Ver: 1.1.2 [ 14.008837] Key type dns_resolver registered [ 14.328604] NFS: Registering the id_resolver key type [ 14.330654] Key type id_resolver registered [ 14.332333] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started dnf makecache --timer. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Reached target Basic System. Starting Login Service... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Reached target sshd-keygen.target. Starting Restore /run/initramfs on shutdown... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started irqbalance daemon. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg617-client login: [ 70.558931] libcfs: loading out-of-tree module taints kernel. [ 70.769957] Key type ._llcrypt registered [ 70.777450] Key type .llcrypt registered [ 71.262403] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 71.275982] alg: No test for adler32 (adler32-zlib) [ 72.640579] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 73.494414] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 75.271537] Key type lgssc registered [ 77.055686] Lustre: Echo OBD driver; http://www.lustre.org/ [ 77.667329] hrtimer: interrupt took 6820460 ns [ 242.808971] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 248.221319] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 262.794965] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing check_logdir /tmp/testlogs/ [ 267.146949] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing yml_node [ 268.255174] Lustre: lustre-OST0000-osc-ffff9414c4f97000: disconnect after 23s idle [ 272.366119] Lustre: DEBUG MARKER: Client: 2.17.57.64 [ 274.823873] Lustre: DEBUG MARKER: MDS: 2.17.57.64 [ 278.043984] Lustre: DEBUG MARKER: OSS: 2.17.57.64 [ 279.839232] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Thu Aug 27 07:17:48 EDT 2026 [ 300.237351] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 301.417425] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 305.140791] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 315.521410] Lustre: Unmounted lustre-client [ 433.883351] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 440.986827] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 467.849348] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 508.280035] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 07:21:37 (1787829697) [ 510.517757] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 512.143796] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 07:21:41 (1787829701) [ 548.158398] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 552.996504] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 576.206641] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 577.036226] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 586.006889] Lustre: Unmounted lustre-client [ 624.250953] Key type lgssc unregistered [ 624.601278] LNet: 15038:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 624.626465] LNetError: 15038:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 624.642454] LNet: Removed LNI 192.168.206.17@tcp [ 625.429159] Key type .llcrypt unregistered [ 625.435939] Key type ._llcrypt unregistered [ 644.821863] Key type ._llcrypt registered [ 644.825257] Key type .llcrypt registered [ 646.326855] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 07:23:54 (1787829834) [ 648.132249] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 648.274883] alg: No test for adler32 (adler32-zlib) [ 649.512971] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 649.846131] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 651.623206] Key type lgssc registered [ 653.544475] Lustre: Echo OBD driver; http://www.lustre.org/ [ 725.519836] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 808.586348] Lustre: Unmounted lustre-client [ 838.005368] Key type lgssc unregistered [ 838.322828] LNet: 18826:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 838.334860] LNetError: 18826:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 838.362029] LNet: Removed LNI 192.168.206.17@tcp [ 839.109106] Key type .llcrypt unregistered [ 839.110514] Key type ._llcrypt unregistered [ 848.915234] Key type ._llcrypt registered [ 848.920676] Key type .llcrypt registered [ 850.357631] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 07:27:19 (1787830039) [ 852.422443] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 852.454409] alg: No test for adler32 (adler32-zlib) [ 853.557982] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 853.832191] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 855.599111] Key type lgssc registered [ 856.723496] Lustre: Echo OBD driver; http://www.lustre.org/ [ 904.384427] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 910.261894] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 931.454707] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 934.517671] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 949.346296] Lustre: Unmounted lustre-client [ 974.504798] Key type lgssc unregistered [ 974.723493] LNet: 22434:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 974.734364] LNetError: 22434:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 974.755683] LNet: Removed LNI 192.168.206.17@tcp [ 975.523979] Key type .llcrypt unregistered [ 975.537448] Key type ._llcrypt unregistered [ 985.965665] Key type ._llcrypt registered [ 985.967871] Key type .llcrypt registered [ 987.331479] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 07:29:36 (1787830176) [ 989.759607] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 989.805453] alg: No test for adler32 (adler32-zlib) [ 990.876636] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 991.151316] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 992.815195] Key type lgssc registered [ 994.189829] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1050.171309] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1055.833534] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1077.406289] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1080.227062] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 1089.618714] Lustre: Unmounted lustre-client [ 1118.376670] Key type lgssc unregistered [ 1118.672968] LNet: 25626:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1118.688872] LNetError: 25626:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1119.720381] LNet: Removed LNI 192.168.206.17@tcp [ 1120.566514] Key type .llcrypt unregistered [ 1120.570606] Key type ._llcrypt unregistered [ 1137.328578] Key type ._llcrypt registered [ 1137.330824] Key type .llcrypt registered [ 1138.653860] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 07:32:07 (1787830327) [ 1141.091669] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1141.138592] alg: No test for adler32 (adler32-zlib) [ 1142.225649] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 1142.472746] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 1144.135489] Key type lgssc registered [ 1145.320377] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1195.843895] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1202.114188] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1226.509088] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1228.318345] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 1238.509883] Lustre: lustre-MDT0000-mdc-ffff9414c42d4000: Connection to lustre-MDT0000 (at 192.168.206.117@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1248.771211] Lustre: lustre-MDT0001-mdc-ffff9414c42d4000: Connection to lustre-MDT0001 (at 192.168.206.117@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1255.197244] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1258.975577] Lustre: 26583:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787830433/real 1787830433] req@00000000768bbb63 x1874675978213376/t0(0) o400->MGC192.168.206.117@tcp@192.168.206.117@tcp:26/25 lens 224/224 e 0 to 1 dl 1787830449 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1259.014137] LustreError: MGC192.168.206.117@tcp: Connection to MGS (at 192.168.206.117@tcp) was lost; in progress operations using this service will fail [ 1265.530564] Lustre: Unmounted lustre-client [ 1283.449713] Key type lgssc unregistered [ 1283.778663] LNet: 29281:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1283.791031] LNetError: 29281:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1283.823956] LNet: Removed LNI 192.168.206.17@tcp [ 1284.567061] Key type .llcrypt unregistered [ 1284.569450] Key type ._llcrypt unregistered [ 1297.661651] Key type ._llcrypt registered [ 1297.663462] Key type .llcrypt registered [ 1298.892289] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 07:34:47 (1787830487) [ 1300.898300] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1300.928350] alg: No test for adler32 (adler32-zlib) [ 1302.051640] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 1302.227941] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 1303.903254] Key type lgssc registered [ 1304.870636] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1354.102311] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1359.999579] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1380.721153] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1383.219206] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 1394.563541] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1404.879172] Lustre: Unmounted lustre-client [ 1430.952649] Key type lgssc unregistered [ 1431.160131] LNet: 32893:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1431.167537] LNetError: 32893:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1431.183706] LNet: Removed LNI 192.168.206.17@tcp [ 1431.823127] Key type .llcrypt unregistered [ 1431.826292] Key type ._llcrypt unregistered [ 1445.741647] Key type ._llcrypt registered [ 1445.745251] Key type .llcrypt registered [ 1446.859965] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 07:37:15 (1787830635) [ 1449.297123] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1449.453112] alg: No test for adler32 (adler32-zlib) [ 1450.654295] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 1451.074704] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 1452.895197] Key type lgssc registered [ 1453.982440] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1504.412822] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1510.234573] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1531.765446] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1533.585598] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 1540.503427] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1544.552070] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1556.615423] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-08-27 7h39m05s [ 1559.328816] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1564.148159] Lustre: lustre-MDT0000-mdc-ffff9414c2bec800: Connection to lustre-MDT0000 (at 192.168.206.117@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1569.836357] LustreError: 36677:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff9414c2bec800: can't stat MDS #0: rc = -19 [ 1571.564344] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1582.569102] LustreError: MGC192.168.206.117@tcp: Connection to MGS (at 192.168.206.117@tcp) was lost; in progress operations using this service will fail [ 1582.596995] Lustre: Evicted from MGS (at 192.168.206.117@tcp) after server handle changed from 0xcaf2a89458471872 to 0xcaf2a89458471b3c [ 1582.617223] Lustre: MGC192.168.206.117@tcp: Connection restored to 192.168.206.117@tcp (at 192.168.206.117@tcp) [ 1583.668200] LustreError: lustre-MDT0000-mdc-ffff9414c2bec800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1583.686392] Lustre: lustre-MDT0000-mdc-ffff9414c2bec800: Connection restored to 192.168.206.117@tcp (at 192.168.206.117@tcp) [ 1587.453945] Lustre: DEBUG MARKER: Wait for df (36677) ... [ 1588.812466] Lustre: DEBUG MARKER: done [ 1592.021130] Lustre: Unmounted lustre-client [ 1619.263048] Key type lgssc unregistered [ 1619.598102] LNet: 37695:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1619.606809] LNetError: 37695:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1619.626211] LNet: Removed LNI 192.168.206.17@tcp [ 1620.176566] Key type .llcrypt unregistered [ 1620.178943] Key type ._llcrypt unregistered [ 1654.142192] Key type ._llcrypt registered [ 1654.143682] Key type .llcrypt registered [ 1655.492837] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 07:40:44 (1787830844) [ 1657.866656] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1657.892444] alg: No test for adler32 (adler32-zlib) [ 1658.908629] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 1659.089753] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 1660.735207] Key type lgssc registered [ 1661.806729] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1706.938725] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1713.054341] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1734.124585] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1735.850989] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 1740.033449] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-08-27 7h42m09s [ 1741.476703] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1748.255745] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1750.343509] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1770.975145] Lustre: 41863:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787830940/real 1787830940] req@000000002ffac883 x1874676520327296/t0(0) o101->lustre-MDT0000-mdc-ffff9414c5455800@192.168.206.117@tcp:12/10 lens 576/1152 e 0 to 1 dl 1787830960 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1771.014078] Lustre: lustre-MDT0000-mdc-ffff9414c5455800: Connection to lustre-MDT0000 (at 192.168.206.117@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1771.053664] Lustre: lustre-MDT0000-mdc-ffff9414c5455800: Connection restored to 192.168.206.117@tcp (at 192.168.206.117@tcp) [ 1792.393693] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1796.264630] Lustre: DEBUG MARKER: done [ 1801.590619] Lustre: Unmounted lustre-client [ 1831.807303] Key type lgssc unregistered [ 1832.180203] LNet: 42742:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1832.190123] LNetError: 42742:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1832.235232] LNet: Removed LNI 192.168.206.17@tcp [ 1833.104254] Key type .llcrypt unregistered [ 1833.106346] Key type ._llcrypt unregistered [ 1865.097499] Key type ._llcrypt registered [ 1865.102674] Key type .llcrypt registered [ 1866.521157] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 07:44:15 (1787831055) [ 1868.253993] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1870.235429] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 07:44:19 (1787831059) [ 1874.290582] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1874.531427] alg: No test for adler32 (adler32-zlib) [ 1875.723570] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 1876.037178] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 1877.831175] Key type lgssc registered [ 1879.079799] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1906.122879] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 07:44:54 (1787831094) [ 1941.134560] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1947.183327] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1966.951324] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1969.690782] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 1973.468753] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1977.033503] Lustre: Unmounted lustre-client [ 1994.131508] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 2038.990469] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2043.633831] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2044.187930] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 2048.066420] Lustre: Unmounted lustre-client [ 2109.280478] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2114.568520] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2115.049942] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 2118.682806] Lustre: Unmounted lustre-client [ 2135.885171] Lustre: DEBUG MARKER: files compared the same [ 2160.028264] Key type lgssc unregistered [ 2160.384153] LNet: 50357:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2160.394757] LNetError: 50357:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2160.409616] LNet: Removed LNI 192.168.206.17@tcp [ 2161.009862] Key type .llcrypt unregistered [ 2161.013802] Key type ._llcrypt unregistered [ 2178.145342] Key type ._llcrypt registered [ 2178.147990] Key type .llcrypt registered [ 2179.829597] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 07:49:28 (1787831368) [ 2182.280726] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2182.311426] alg: No test for adler32 (adler32-zlib) [ 2183.389963] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 2183.666336] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 2185.327157] Key type lgssc registered [ 2186.589695] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2234.921109] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2239.821666] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2257.322427] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2259.847838] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 2263.101168] Lustre: Unmounted lustre-client [ 2297.305287] Key type lgssc unregistered [ 2297.578774] LNet: 53939:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2297.589425] LNetError: 53939:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2297.613319] LNet: Removed LNI 192.168.206.17@tcp [ 2298.348871] Key type .llcrypt unregistered [ 2298.350970] Key type ._llcrypt unregistered [ 2313.384157] Key type ._llcrypt registered [ 2313.389264] Key type .llcrypt registered [ 2314.596637] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 07:51:43 (1787831503) [ 2316.850298] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2316.881250] alg: No test for adler32 (adler32-zlib) [ 2317.957210] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 2318.191257] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 2319.927192] Key type lgssc registered [ 2320.999421] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2402.185172] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2437.591632] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2443.918663] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2468.321844] Key type lgssc unregistered [ 2468.536666] LNet: 57427:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2468.553369] LNetError: 57427:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2468.569314] LNet: Removed LNI 192.168.206.17@tcp [ 2469.088658] Key type .llcrypt unregistered [ 2469.091638] Key type ._llcrypt unregistered [ 2482.509065] Key type ._llcrypt registered [ 2482.513267] Key type .llcrypt registered [ 2483.612944] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 07:54:32 (1787831672) [ 2486.741770] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2486.772739] alg: No test for adler32 (adler32-zlib) [ 2487.929694] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 2488.237328] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 2489.975228] Key type lgssc registered [ 2490.932341] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2554.482764] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 2561.703759] Lustre: Unmounted lustre-client [ 2598.791264] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 07:56:27 (1787831787) [ 2719.819861] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2741.798958] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 2748.677220] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2748.682179] LustreError: 63897:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff9414c836a000: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2748.697747] Lustre: 58411:0:(llite_lib.c:4342:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.206.117@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2758.994214] Lustre: Unmounted lustre-client [ 2798.690484] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 07:59:47 (1787831987) [ 2808.881226] Key type lgssc unregistered [ 2809.143656] LNet: 65110:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2809.154985] LNetError: 65110:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2810.214847] LNet: Removed LNI 192.168.206.17@tcp [ 2810.900643] Key type .llcrypt unregistered [ 2810.904079] Key type ._llcrypt unregistered [ 2823.405408] Key type ._llcrypt registered [ 2823.409504] Key type .llcrypt registered [ 2823.965320] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2823.978828] alg: No test for adler32 (adler32-zlib) [ 2825.064853] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 2825.284233] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 2826.927191] Key type lgssc registered [ 2828.084637] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2873.041291] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2878.071167] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2895.586918] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2897.087431] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 2900.648804] Lustre: Unmounted lustre-client [ 2935.465234] Key type lgssc unregistered [ 2935.717694] LNet: 68143:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2935.740665] LNetError: 68143:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2935.757435] LNet: Removed LNI 192.168.206.17@tcp [ 2936.614636] Key type .llcrypt unregistered [ 2936.617360] Key type ._llcrypt unregistered [ 2946.866651] Key type ._llcrypt registered [ 2946.868594] Key type .llcrypt registered [ 2947.306881] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2947.328568] alg: No test for adler32 (adler32-zlib) [ 2948.364902] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 2948.587896] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 2950.263202] Key type lgssc registered [ 2951.245606] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3049.500930] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3053.965564] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3074.381272] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3076.271899] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 3078.727228] Lustre: Unmounted lustre-client [ 3112.659973] Key type lgssc unregistered [ 3112.898711] LNet: 71573:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3112.908349] LNetError: 71573:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3112.924410] LNet: Removed LNI 192.168.206.17@tcp [ 3113.735566] Key type .llcrypt unregistered [ 3113.737381] Key type ._llcrypt unregistered [ 3129.581222] Key type ._llcrypt registered [ 3129.586620] Key type .llcrypt registered [ 3130.934746] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 08:05:19 (1787832319) [ 3133.536588] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3133.568146] alg: No test for adler32 (adler32-zlib) [ 3134.748452] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 3134.989412] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 3136.735242] Key type lgssc registered [ 3138.472348] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3192.429702] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3200.262760] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3220.825461] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3223.412655] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 3229.791188] Lustre: Unmounted lustre-client [ 3230.410538] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 3234.161196] Lustre: Unmounted lustre-client [ 3262.917845] Key type lgssc unregistered [ 3263.550935] LNet: 75263:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3263.572443] LNetError: 75263:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3263.596396] LNet: Removed LNI 192.168.206.17@tcp [ 3264.168152] Key type .llcrypt unregistered [ 3264.170068] Key type ._llcrypt unregistered [ 3274.270742] Key type ._llcrypt registered [ 3274.279447] Key type .llcrypt registered [ 3274.971075] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3274.990514] alg: No test for adler32 (adler32-zlib) [ 3276.104971] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 3276.317679] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 3277.975147] Key type lgssc registered [ 3279.633579] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3329.345590] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3335.648544] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3356.127947] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3357.913539] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 3366.564852] Lustre: Unmounted lustre-client [ 3401.921433] Key type lgssc unregistered [ 3402.258289] LNet: 78297:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3402.268796] LNetError: 78297:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3402.306811] LNet: Removed LNI 192.168.206.17@tcp [ 3402.971059] Key type .llcrypt unregistered [ 3402.980196] Key type ._llcrypt unregistered [ 3418.326555] Key type ._llcrypt registered [ 3418.330165] Key type .llcrypt registered [ 3419.803188] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 08:10:08 (1787832608) [ 3422.334033] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3422.406263] alg: No test for adler32 (adler32-zlib) [ 3423.450396] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 3423.758248] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 3425.447253] Key type lgssc registered [ 3426.860638] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3474.759033] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3480.113233] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3499.851754] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3502.836350] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 3507.615603] Lustre: lustre: root_squash is set to 0:0 [ 3514.368967] Lustre: lustre: nosquash_nids is cleared [ 3514.389894] Lustre: lustre: root_squash is set to 500:500 [ 3520.004303] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.206.17@tcp 192.168.0.[2,10]@tcp [ 3537.469053] Lustre: lustre: nosquash_nids set to 192.168.206.17/24@tcp [ 3540.412475] Lustre: Unmounted lustre-client [ 3559.240484] Key type lgssc unregistered [ 3559.492264] LNet: 82242:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3559.501227] LNetError: 82242:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3559.521390] LNet: Removed LNI 192.168.206.17@tcp [ 3560.097865] Key type .llcrypt unregistered [ 3560.101125] Key type ._llcrypt unregistered [ 3574.509393] Key type ._llcrypt registered [ 3574.513231] Key type .llcrypt registered [ 3575.508127] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 08:12:44 (1787832764) [ 3577.415378] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3578.795804] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 08:12:48 (1787832768) [ 3580.747048] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3580.763283] alg: No test for adler32 (adler32-zlib) [ 3581.788548] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 3582.030221] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 3583.671750] Key type lgssc registered [ 3584.809793] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3635.181983] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3641.428914] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3662.828873] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3664.323412] Lustre: lustre: root_squash is set to 500:500 [ 3664.352377] Lustre: lustre: nosquash_nids set to 192.168.206.17/24@tcp [ 3665.406243] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 3674.179168] Lustre: Unmounted lustre-client [ 3700.197363] Key type lgssc unregistered [ 3700.414247] LNet: 86205:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3700.429454] LNetError: 86205:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3700.447306] LNet: Removed LNI 192.168.206.17@tcp [ 3701.198041] Key type .llcrypt unregistered [ 3701.200943] Key type ._llcrypt unregistered [ 3714.930023] Key type ._llcrypt registered [ 3714.935135] Key type .llcrypt registered [ 3716.101853] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 3717.413444] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 08:15:06 (1787832906) [ 3735.187325] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3735.208140] alg: No test for adler32 (adler32-zlib) [ 3736.186253] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 3736.426457] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 3738.103576] Key type lgssc registered [ 3739.337592] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3812.733633] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3818.333209] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3840.804164] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3905.517557] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3911.376405] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3933.287321] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3943.725819] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 3976.660859] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9414c6c11800.ost_server_uuid 50 [ 3978.312850] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9414c6c11800.ost_server_uuid in FULL state after 0 sec [ 3978.707976] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 3982.387455] Lustre: Unmounted lustre-client [ 3984.068860] Lustre: Unmounted lustre-client [ 4026.854130] Key type lgssc unregistered [ 4027.092298] LNet: 93868:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4027.103034] LNetError: 93868:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4027.122133] LNet: Removed LNI 192.168.206.17@tcp [ 4027.819909] Key type .llcrypt unregistered [ 4027.823144] Key type ._llcrypt unregistered [ 4056.265224] Key type ._llcrypt registered [ 4056.267947] Key type .llcrypt registered [ 4057.321396] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 08:20:46 (1787833246) [ 4074.304780] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4074.317943] alg: No test for adler32 (adler32-zlib) [ 4075.370773] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 4075.619693] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 4077.391175] Key type lgssc registered [ 4078.492522] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4158.010639] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4163.164844] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4184.689692] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4187.370358] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 4209.119184] Lustre: 95231:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787833383/real 1787833383] req@000000000284aeb8 x1874679053686016/t0(0) o400->lustre-OST0000-osc-ffff9414c42bc800@192.168.206.117@tcp:28/4 lens 224/224 e 0 to 1 dl 1787833399 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4209.159694] Lustre: lustre-OST0000-osc-ffff9414c42bc800: Connection to lustre-OST0000 (at 192.168.206.117@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4213.535585] Lustre: lustre-OST0000-osc-ffff9414c42bc800: Connection restored to 192.168.206.117@tcp (at 192.168.206.117@tcp) [ 4214.111720] Lustre: 95230:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787833388/real 1787833388] req@0000000045b6d034 x1874679053686528/t0(0) o400->lustre-OST0000-osc-ffff9414c42bc800@192.168.206.117@tcp:28/4 lens 224/224 e 0 to 1 dl 1787833404 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4219.360032] Lustre: 95231:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787833393/real 1787833393] req@000000006a82806b x1874679053687040/t0(0) o400->lustre-OST0000-osc-ffff9414c42bc800@192.168.206.117@tcp:28/4 lens 224/224 e 0 to 1 dl 1787833409 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4219.373655] Lustre: lustre-MDT0000-mdc-ffff9414c42bc800: Connection to lustre-MDT0000 (at 192.168.206.117@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4225.503150] Lustre: 95231:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787833398/real 1787833398] req@00000000d5c12fe7 x1874679053687552/t0(0) o400->lustre-OST0000-osc-ffff9414c42bc800@192.168.206.117@tcp:28/4 lens 224/224 e 0 to 1 dl 1787833414 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4234.719815] Lustre: 95233:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787833409/real 1787833409] req@00000000a079f95d x1874679053689472/t0(0) o400->MGC192.168.206.117@tcp@192.168.206.117@tcp:26/25 lens 224/224 e 0 to 1 dl 1787833425 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4234.751149] LustreError: MGC192.168.206.117@tcp: Connection to MGS (at 192.168.206.117@tcp) was lost; in progress operations using this service will fail [ 4244.982430] Lustre: Evicted from MGS (at 192.168.206.117@tcp) after server handle changed from 0x722776f7731b1ba0 to 0x722776f7731b1e71 [ 4245.009771] Lustre: MGC192.168.206.117@tcp: Connection restored to 192.168.206.117@tcp (at 192.168.206.117@tcp) [ 4249.401972] Lustre: lustre-MDT0000-mdc-ffff9414c42bc800: Connection restored to 192.168.206.117@tcp (at 192.168.206.117@tcp) [ 4254.288166] Lustre: Unmounted lustre-client [ 4281.842238] Key type lgssc unregistered [ 4282.201982] LNet: 99532:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4282.212919] LNetError: 99532:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4282.254282] LNet: Removed LNI 192.168.206.17@tcp [ 4283.024733] Key type .llcrypt unregistered [ 4283.026723] Key type ._llcrypt unregistered [ 4297.012278] Key type ._llcrypt registered [ 4297.015890] Key type .llcrypt registered [ 4298.151226] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 08:24:47 (1787833487) [ 4312.640315] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4312.815416] alg: No test for adler32 (adler32-zlib) [ 4313.865420] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 4314.116305] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 4315.903325] Key type lgssc registered [ 4316.997571] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4350.517749] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4437.755414] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4442.325870] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4464.709826] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4467.458971] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 4469.490241] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 4471.763742] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 08:27:40 (1787833660) [ 4708.465902] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4718.832296] Lustre: Unmounted lustre-client [ 4852.539907] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4858.902559] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4884.099197] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4933.636279] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 08:35:22 (1787834122) [ 5021.479712] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5026.832467] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5049.641284] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5050.319893] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 5054.588233] Lustre: Unmounted lustre-client [ 5084.643106] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 08:37:53 (1787834273) [ 5178.796496] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5184.156284] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5203.431967] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5204.205846] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 5209.222260] Lustre: Unmounted lustre-client [ 5235.560249] Key type lgssc unregistered [ 5235.863888] LNet: 122538:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5235.873131] LNetError: 122538:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5235.897322] LNet: Removed LNI 192.168.206.17@tcp [ 5236.562138] Key type .llcrypt unregistered [ 5236.566837] Key type ._llcrypt unregistered [ 5254.380831] Key type ._llcrypt registered [ 5254.384385] Key type .llcrypt registered [ 5256.228246] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 08:40:44 (1787834444) [ 5258.621526] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5258.642245] alg: No test for adler32 (adler32-zlib) [ 5259.785079] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 5260.047907] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 5261.711213] Key type lgssc registered [ 5263.663452] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5312.662442] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5318.037721] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5339.178679] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5341.948361] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 5355.132429] Lustre: Unmounted lustre-client [ 5380.737653] Key type lgssc unregistered [ 5380.940607] LNet: 126134:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5380.948818] LNetError: 126134:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5380.966808] LNet: Removed LNI 192.168.206.17@tcp [ 5381.552885] Key type .llcrypt unregistered [ 5381.557674] Key type ._llcrypt unregistered [ 5397.620867] Key type ._llcrypt registered [ 5397.626955] Key type .llcrypt registered [ 5398.770870] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 08:43:07 (1787834587) [ 5401.266830] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5401.297114] alg: No test for adler32 (adler32-zlib) [ 5402.461210] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 5402.736414] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 5404.455199] Key type lgssc registered [ 5405.428260] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5452.732499] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5458.898217] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5482.354321] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5485.120472] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 5495.284484] Lustre: lustre-OST0000-osc-ffff9414d2149800: Connection to lustre-OST0000 (at 192.168.206.117@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5505.795804] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff9414d2149800.ost_server_uuid 50 [ 5507.471776] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 5518.562572] Lustre: Unmounted lustre-client [ 5539.102807] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 08:45:28 (1787834728) [ 5574.600215] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5580.774364] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5602.760553] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5628.788340] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5629.617978] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 5635.063640] Lustre: lustre-OST0000-osc-ffff9414c55a7000: Connection to lustre-OST0000 (at 192.168.206.117@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5660.698306] Lustre: Unmounted lustre-client [ 5703.377570] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 08:48:12 (1787834892) [ 5730.688934] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5735.833919] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5752.161978] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5770.048810] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5770.607827] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 5775.853277] Lustre: lustre-OST0000-osc-ffff9414c8694000: Connection to lustre-OST0000 (at 192.168.206.117@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5791.858158] Lustre: Unmounted lustre-client [ 5838.455919] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 08:50:27 (1787835027) [ 5926.492466] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5933.270346] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5956.103116] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6031.734309] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6037.037193] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6055.208537] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6081.744448] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 6124.586470] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6129.138763] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 3 sec [ 6130.975386] Lustre: Unmounted lustre-client [ 6172.514131] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 08:56:00 (1787835360) [ 6206.346386] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6212.310216] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6238.198770] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6269.026373] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6296.899737] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 6335.462694] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6336.983836] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6343.154807] Lustre: lustre-OST0001-osc-ffff9414c836b000: Connection to lustre-OST0001 (at 192.168.206.117@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6357.193468] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6367.535278] Lustre: Unmounted lustre-client [ 6427.560350] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 09:00:16 (1787835616) [ 6460.238691] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6466.222376] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6484.902115] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6485.579731] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 6509.107492] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6510.932893] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6528.490672] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9414c38d4800.ost_server_uuid 50 [ 6530.645727] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9414c38d4800.ost_server_uuid in IDLE state after 0 sec [ 6534.027669] Lustre: Unmounted lustre-client [ 6534.402680] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6534.412536] Lustre: Skipped 3 previous similar messages [ 6534.515229] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 6535.706063] LustreError: 149581:0:(sec.c:403:import_sec_validate_get()) import 000000001a65dd0e (NEW) with no sec [ 6589.953465] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 09:02:58 (1787835778) [ 6625.864414] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6631.069461] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6651.873525] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6674.939064] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6675.438123] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 6675.644595] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 6691.654293] Lustre: Unmounted lustre-client [ 6691.657517] Lustre: Skipped 1 previous similar message [ 6728.726168] Key type lgssc unregistered [ 6728.986427] LNet: 153016:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6728.995720] LNetError: 153016:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6729.023113] LNet: Removed LNI 192.168.206.17@tcp [ 6729.698580] Key type .llcrypt unregistered [ 6729.706407] Key type ._llcrypt unregistered [ 6744.895387] Key type ._llcrypt registered [ 6744.897661] Key type .llcrypt registered [ 6746.167730] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 09:05:35 (1787835935) [ 6747.443979] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6747.459694] alg: No test for adler32 (adler32-zlib) [ 6748.592013] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 6748.948092] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 6750.615222] Key type lgssc registered [ 6751.700971] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6804.391946] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6810.610875] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6834.051241] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6851.315600] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6852.001843] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 6853.156118] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 6858.335150] LustreError: lustre-MDT0001-mdc-ffff9414c2bef000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 6858.362080] Lustre: lustre-MDT0001-mdc-ffff9414c2bef000: Connection restored to 192.168.206.117@tcp (at 192.168.206.117@tcp) [ 6863.829234] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6865.346655] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6888.948057] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 6893.592342] Lustre: Unmounted lustre-client [ 6913.958767] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 09:08:23 (1787836103) [ 7014.661495] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7021.208326] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7045.317897] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7045.951992] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 7070.910160] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7072.448599] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7077.350099] Lustre: lustre-OST0001-osc-ffff9414c5919000: Connection to lustre-OST0001 (at 192.168.206.117@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7084.327171] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7094.706402] Lustre: Unmounted lustre-client [ 7127.724282] Key type lgssc unregistered [ 7127.912069] LNet: 162613:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7127.918187] LNetError: 162613:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7127.937099] LNet: Removed LNI 192.168.206.17@tcp [ 7128.522221] Key type .llcrypt unregistered [ 7128.524540] Key type ._llcrypt unregistered [ 7158.043489] Key type ._llcrypt registered [ 7158.045614] Key type .llcrypt registered [ 7159.345685] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 09:12:28 (1787836348) [ 7161.782355] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7161.801111] alg: No test for adler32 (adler32-zlib) [ 7163.002393] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 7163.321774] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 7165.023381] Key type lgssc registered [ 7166.165176] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7221.589725] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7228.522114] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7253.875563] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7255.880430] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 7303.951180] Lustre: Unmounted lustre-client [ 7345.116923] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7345.732549] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 7381.735682] Lustre: Unmounted lustre-client [ 7408.789630] Key type lgssc unregistered [ 7409.068970] LNet: 167065:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7409.079199] LNetError: 167065:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7409.110952] LNet: Removed LNI 192.168.206.17@tcp [ 7409.796429] Key type .llcrypt unregistered [ 7409.803969] Key type ._llcrypt unregistered [ 7426.376818] Key type ._llcrypt registered [ 7426.378689] Key type .llcrypt registered [ 7427.788628] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 09:16:56 (1787836616) [ 7430.303341] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7430.419319] alg: No test for adler32 (adler32-zlib) [ 7431.566899] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 7431.794623] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 7433.479201] Key type lgssc registered [ 7434.414566] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7482.673305] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7489.430392] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7509.084981] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7510.782560] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 7532.687180] Lustre: Unmounted lustre-client [ 7558.745750] Key type lgssc unregistered [ 7559.081256] LNet: 170821:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7559.088654] LNetError: 170821:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7559.110705] LNet: Removed LNI 192.168.206.17@tcp [ 7559.791700] Key type .llcrypt unregistered [ 7559.795448] Key type ._llcrypt unregistered [ 7572.831335] Key type ._llcrypt registered [ 7572.836496] Key type .llcrypt registered [ 7573.152361] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7573.176731] alg: No test for adler32 (adler32-zlib) [ 7574.275463] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 7574.594058] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 7576.359477] Key type lgssc registered [ 7577.676551] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7629.401649] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7637.524443] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7660.069079] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7661.930484] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 7669.469727] Lustre: Unmounted lustre-client [ 7707.089806] Key type lgssc unregistered [ 7707.304045] LNet: 173889:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7707.314428] LNetError: 173889:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7707.332792] LNet: Removed LNI 192.168.206.17@tcp [ 7708.004108] Key type .llcrypt unregistered [ 7708.007620] Key type ._llcrypt unregistered [ 7723.882731] Key type ._llcrypt registered [ 7723.884976] Key type .llcrypt registered [ 7725.932769] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 09:21:54 (1787836914) [ 7728.915584] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7728.940304] alg: No test for adler32 (adler32-zlib) [ 7730.055757] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 7730.569811] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 7732.319434] Key type lgssc registered [ 7734.105795] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7783.194786] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7788.958412] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7811.567165] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7814.060444] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 7837.289738] Lustre: Unmounted lustre-client [ 7867.548896] Key type lgssc unregistered [ 7867.862943] LNet: 177650:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7867.868528] LNetError: 177650:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7867.900373] LNet: Removed LNI 192.168.206.17@tcp [ 7868.996229] Key type .llcrypt unregistered [ 7868.998433] Key type ._llcrypt unregistered [ 7882.675441] Key type ._llcrypt registered [ 7882.676962] Key type .llcrypt registered [ 7883.147115] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7883.188491] alg: No test for adler32 (adler32-zlib) [ 7884.421206] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 7884.740084] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 7886.407263] Key type lgssc registered [ 7887.590019] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7938.592056] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7943.638266] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7966.718356] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7968.808773] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 7976.234504] Lustre: Unmounted lustre-client [ 8002.408434] Key type lgssc unregistered [ 8002.709985] LNet: 180721:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8002.721979] LNetError: 180721:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8002.748373] LNet: Removed LNI 192.168.206.17@tcp [ 8003.419280] Key type .llcrypt unregistered [ 8003.421722] Key type ._llcrypt unregistered [ 8018.751365] Key type ._llcrypt registered [ 8018.753623] Key type .llcrypt registered [ 8019.914598] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 09:26:48 (1787837208) [ 8040.535848] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8040.636942] alg: No test for adler32 (adler32-zlib) [ 8041.749135] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 8041.933911] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 8043.631289] Key type lgssc registered [ 8044.918347] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8122.614805] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8128.073467] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8151.373855] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8199.595789] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 09:29:48 (1787837388) [ 8228.897471] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8234.552467] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8253.592471] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8255.159576] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 8260.693819] Lustre: Unmounted lustre-client [ 8287.974423] Key type lgssc unregistered [ 8288.279965] LNet: 187826:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8288.292569] LNetError: 187826:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8288.309909] LNet: Removed LNI 192.168.206.17@tcp [ 8288.904506] Key type .llcrypt unregistered [ 8288.907535] Key type ._llcrypt unregistered [ 8305.541822] Key type ._llcrypt registered [ 8305.547556] Key type .llcrypt registered [ 8306.936541] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 8308.441671] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 09:31:37 (1787837497) [ 8324.701204] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8324.729569] alg: No test for adler32 (adler32-zlib) [ 8325.800977] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 8326.033526] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 8327.721173] Key type lgssc registered [ 8328.998310] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8409.273013] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8413.337313] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8435.774599] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8456.189824] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8459.035428] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 8479.762124] Lustre: Unmounted lustre-client [ 8598.082806] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8603.112900] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8626.037250] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8666.525141] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 09:37:35 (1787837855) [ 8668.143501] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 8669.912203] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 09:37:38 (1787837858) [ 8757.723889] Key type lgssc unregistered [ 8758.007743] LNet: 199256:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8758.021140] LNetError: 199256:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8758.052881] LNet: Removed LNI 192.168.206.17@tcp [ 8758.724678] Key type .llcrypt unregistered [ 8758.726982] Key type ._llcrypt unregistered [ 8773.835570] Key type ._llcrypt registered [ 8773.837595] Key type .llcrypt registered [ 8774.914798] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 09:39:23 (1787837963) [ 8816.760817] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8816.786078] alg: No test for adler32 (adler32-zlib) [ 8817.888843] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 8818.369299] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 8820.103204] Key type lgssc registered [ 8821.355445] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8861.307447] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8866.757517] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8898.862429] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8924.845854] Key type lgssc unregistered [ 8925.093531] LNet: 202990:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8925.109184] LNetError: 202990:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8925.124629] LNet: Removed LNI 192.168.206.17@tcp [ 8925.809969] Key type .llcrypt unregistered [ 8925.818768] Key type ._llcrypt unregistered [ 8940.664859] Key type ._llcrypt registered [ 8940.668775] Key type .llcrypt registered [ 8941.916181] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 09:42:10 (1787838130) [ 8944.624588] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8944.646914] alg: No test for adler32 (adler32-zlib) [ 8945.661389] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 8945.816507] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 8947.465153] Key type lgssc registered [ 8948.568361] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8995.156431] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9001.336870] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9025.202714] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9026.857958] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 9042.411516] Lustre: lustre-MDT0001-mdc-ffff9414c705d800: Connection to lustre-MDT0001 (at 192.168.206.117@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9042.430883] Lustre: Skipped 1 previous similar message [ 9057.763129] Lustre: 203981:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787838232/real 1787838232] req@000000007d1bafb2 x1874684161376256/t0(0) o400->MGC192.168.206.117@tcp@192.168.206.117@tcp:26/25 lens 224/224 e 0 to 1 dl 1787838248 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9057.793473] LustreError: MGC192.168.206.117@tcp: Connection to MGS (at 192.168.206.117@tcp) was lost; in progress operations using this service will fail [ 9068.077306] Lustre: Evicted from MGS (at 192.168.206.117@tcp) after server handle changed from 0xfd9b868ef3aad92f to 0xfd9b868ef3aaf472 [ 9068.092599] Lustre: MGC192.168.206.117@tcp: Connection restored to 192.168.206.117@tcp (at 192.168.206.117@tcp) [ 9087.480036] LustreError: lustre-MDT0000-mdc-ffff9414c705d800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 9087.511556] Lustre: lustre-MDT0000-mdc-ffff9414c705d800: Connection restored to 192.168.206.117@tcp (at 192.168.206.117@tcp) [ 9092.967336] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9094.612336] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9100.645237] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9102.147693] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9104.935217] Lustre: Unmounted lustre-client [ 9134.300689] Key type lgssc unregistered [ 9134.631738] LNet: 207935:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9134.640574] LNetError: 207935:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9134.665404] LNet: Removed LNI 192.168.206.17@tcp [ 9135.636521] Key type .llcrypt unregistered [ 9135.638187] Key type ._llcrypt unregistered [ 9151.634886] Key type ._llcrypt registered [ 9151.637989] Key type .llcrypt registered [ 9152.880971] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 09:45:41 (1787838341) [ 9155.715608] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9155.750843] alg: No test for adler32 (adler32-zlib) [ 9156.873593] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 9157.094175] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 9158.775458] Key type lgssc registered [ 9160.103929] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9206.271825] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9210.749752] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9264.668518] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9269.822363] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9299.812895] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9320.196303] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9357.867357] Key type lgssc unregistered [ 9358.103755] LNet: 213228:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9358.111238] LNetError: 213228:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9358.128697] LNet: Removed LNI 192.168.206.17@tcp [ 9358.770737] Key type .llcrypt unregistered [ 9358.774586] Key type ._llcrypt unregistered [ 9399.666431] Key type ._llcrypt registered [ 9399.670022] Key type .llcrypt registered [ 9400.960920] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 09:49:49 (1787838589) [ 9440.736942] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9440.749431] alg: No test for adler32 (adler32-zlib) [ 9441.827710] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 9442.063063] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 9443.727189] Key type lgssc registered [ 9445.137459] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9527.669947] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9535.004712] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9560.918267] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9612.047486] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 09:53:21 (1787838801) [ 9618.999486] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 09:53:27 (1787838807) [ 9654.627415] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9660.674743] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9681.385293] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9684.023761] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 9689.528857] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [ 9691.526696] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 9693.199890] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 9695.091641] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [ 9699.313072] Lustre: lustre-MDT0000-mdc-ffff9414c76c7000: Connection to lustre-MDT0000 (at 192.168.206.117@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9715.487800] Lustre: 215124:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787838889/real 1787838889] req@00000000c33ca968 x1874684681397632/t0(0) o400->MGC192.168.206.117@tcp@192.168.206.117@tcp:26/25 lens 224/224 e 0 to 1 dl 1787838905 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9715.505750] LustreError: MGC192.168.206.117@tcp: Connection to MGS (at 192.168.206.117@tcp) was lost; in progress operations using this service will fail [ 9724.919481] Lustre: Evicted from MGS (at 192.168.206.117@tcp) after server handle changed from 0x8af2ffef128d8d02 to 0x8af2ffef128d8e75 [ 9724.938857] Lustre: MGC192.168.206.117@tcp: Connection restored to 192.168.206.117@tcp (at 192.168.206.117@tcp) [ 9731.394684] Lustre: lustre-MDT0000-mdc-ffff9414c76c7000: Connection restored to 192.168.206.117@tcp (at 192.168.206.117@tcp) [ 9741.149542] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9743.044280] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9746.619846] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [ 9750.514255] Lustre: lustre-MDT0000-mdc-ffff9414c76c7000: Connection to lustre-MDT0000 (at 192.168.206.117@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9765.791675] Lustre: 215123:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787838940/real 1787838940] req@000000001e46778f x1874684681406848/t0(0) o400->MGC192.168.206.117@tcp@192.168.206.117@tcp:26/25 lens 224/224 e 0 to 1 dl 1787838956 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9765.839905] LustreError: MGC192.168.206.117@tcp: Connection to MGS (at 192.168.206.117@tcp) was lost; in progress operations using this service will fail [ 9773.392883] Lustre: lustre-MDT0000-mdc-ffff9414c76c7000: Connection restored to 192.168.206.117@tcp (at 192.168.206.117@tcp) [ 9776.497878] Lustre: Evicted from MGS (at 192.168.206.117@tcp) after server handle changed from 0x8af2ffef128d8e75 to 0x8af2ffef128d9384 [ 9776.528495] Lustre: MGC192.168.206.117@tcp: Connection restored to 192.168.206.117@tcp (at 192.168.206.117@tcp) [ 9782.145171] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9783.689870] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9787.743319] Lustre: Unmounted lustre-client [ 9857.676614] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9863.742353] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9882.993794] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9883.491050] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [ 9885.507551] Lustre: Unmounted lustre-client [ 9915.982794] Key type lgssc unregistered [ 9916.308603] LNet: 225710:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9916.316700] LNetError: 225710:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9916.336608] LNet: Removed LNI 192.168.206.17@tcp [ 9917.155716] Key type .llcrypt unregistered [ 9917.157864] Key type ._llcrypt unregistered [ 9931.850192] Key type ._llcrypt registered [ 9931.855019] Key type .llcrypt registered [ 9933.483916] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 09:58:41 (1787839121) [ 9937.911919] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9937.923437] alg: No test for adler32 (adler32-zlib) [ 9938.978847] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [ 9939.196798] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [ 9940.887723] Key type lgssc registered [ 9942.152749] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9984.449483] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9988.769888] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10007.176099] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10008.861301] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [10014.292761] Lustre: Unmounted lustre-client [10076.086778] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10080.489243] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10097.830409] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10103.828400] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [10112.648703] Lustre: Unmounted lustre-client [10179.320520] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10185.161748] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10206.234830] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10206.812980] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [10208.786182] Lustre: Unmounted lustre-client [10231.795797] Key type lgssc unregistered [10232.002817] LNet: 232764:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10232.008995] LNetError: 232764:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10233.072894] LNet: Removed LNI 192.168.206.17@tcp [10233.855571] Key type .llcrypt unregistered [10233.861824] Key type ._llcrypt unregistered [10247.821417] Key type ._llcrypt registered [10247.824647] Key type .llcrypt registered [10249.061478] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 10:03:57 (1787839437) [10252.385465] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10252.423641] alg: No test for adler32 (adler32-zlib) [10253.577289] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [10253.885529] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [10255.610742] Key type lgssc registered [10256.886477] Lustre: Echo OBD driver; http://www.lustre.org/ [10298.678934] Key type lgssc unregistered [10299.116841] LNet: 235085:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10299.127319] LNetError: 235085:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10299.150605] LNet: Removed LNI 192.168.206.17@tcp [10299.958179] Key type .llcrypt unregistered [10299.960444] Key type ._llcrypt unregistered [10324.624587] Key type ._llcrypt registered [10324.628625] Key type .llcrypt registered [10325.087145] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10325.097690] alg: No test for adler32 (adler32-zlib) [10326.150403] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [10326.419122] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [10328.127235] Key type lgssc registered [10329.233281] Lustre: Echo OBD driver; http://www.lustre.org/ [10408.489504] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10415.207983] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10438.869408] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10477.342373] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [10478.980847] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 10:07:47 (1787839667) [10510.002602] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10515.266938] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10533.413890] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10553.505695] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10555.070177] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [10560.493802] Lustre: lustre-OST0001-osc-ffff9414c4048800: Connection to lustre-OST0001 (at 192.168.206.117@tcp) was lost; in progress operations using this service will wait for recovery to complete [10565.933655] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [10576.306916] Lustre: Unmounted lustre-client [10608.577437] Key type lgssc unregistered [10608.793496] LNet: 242196:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10608.810737] LNetError: 242196:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10608.837933] LNet: Removed LNI 192.168.206.17@tcp [10609.601070] Key type .llcrypt unregistered [10609.615924] Key type ._llcrypt unregistered [10637.681312] Key type ._llcrypt registered [10637.685371] Key type .llcrypt registered [10639.149057] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 10:10:27 (1787839827) [10653.584951] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10653.625515] alg: No test for adler32 (adler32-zlib) [10654.848600] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [10655.185518] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [10656.879205] Key type lgssc registered [10658.740972] Lustre: Echo OBD driver; http://www.lustre.org/ [10708.976745] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10715.281170] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10735.392432] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 10:12:04 (1787839924) [10763.644606] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10769.153192] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10786.843477] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10789.304805] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [10795.055754] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [10801.807402] Lustre: Unmounted lustre-client [10900.354573] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10904.724273] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10950.262806] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10950.864639] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [10953.662805] Lustre: Unmounted lustre-client [10979.542735] Key type lgssc unregistered [10979.782903] LNet: 249897:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10979.795895] LNetError: 249897:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10979.814199] LNet: Removed LNI 192.168.206.17@tcp [10980.399343] Key type .llcrypt unregistered [10980.403258] Key type ._llcrypt unregistered [11006.746954] Key type ._llcrypt registered [11006.748819] Key type .llcrypt registered [11007.128586] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11007.145474] alg: No test for adler32 (adler32-zlib) [11008.263092] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [11008.522797] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [11010.327255] Key type lgssc registered [11012.105719] Lustre: Echo OBD driver; http://www.lustre.org/ [11091.879343] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11097.007550] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11118.945538] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11159.009682] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [11160.808475] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 10:19:09 (1787840349) [11194.846625] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11200.804749] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11223.296536] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11227.386523] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [11230.766415] Lustre: Unmounted lustre-client [11256.175695] Key type lgssc unregistered [11256.393220] LNet: 256532:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11256.402129] LNetError: 256532:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11256.419877] LNet: Removed LNI 192.168.206.17@tcp [11257.043456] Key type .llcrypt unregistered [11257.048183] Key type ._llcrypt unregistered [11270.978713] Key type ._llcrypt registered [11270.981497] Key type .llcrypt registered [11272.289477] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [11273.738616] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 10:21:02 (1787840462) [11281.257601] Key type .llcrypt unregistered [11281.262347] Key type ._llcrypt unregistered [11287.459402] Key type ._llcrypt registered [11287.462964] Key type .llcrypt registered [11288.150966] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11288.166172] alg: No test for adler32 (adler32-zlib) [11289.208822] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [11289.399683] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [11291.034216] Key type lgssc registered [11292.214758] Lustre: Echo OBD driver; http://www.lustre.org/ [11342.220258] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11356.311617] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [11360.406121] Lustre: Unmounted lustre-client [11387.395243] Key type lgssc unregistered [11387.691291] LNet: 259992:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11387.723073] LNetError: 259992:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11387.775647] LNet: Removed LNI 192.168.206.17@tcp [11388.545095] Key type .llcrypt unregistered [11388.547776] Key type ._llcrypt unregistered [11403.352442] Key type ._llcrypt registered [11403.354620] Key type .llcrypt registered [11404.559600] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 10:23:13 (1787840593) [11406.354752] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11406.475608] alg: No test for adler32 (adler32-zlib) [11407.605475] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [11407.902180] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [11409.567213] Key type lgssc registered [11410.598210] Lustre: Echo OBD driver; http://www.lustre.org/ [11487.264990] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11512.592884] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11517.347603] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11519.847839] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [11521.881145] Lustre: Unmounted lustre-client [11553.088519] Key type lgssc unregistered [11553.321158] LNet: 263532:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11553.327315] LNetError: 263532:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11553.349892] LNet: Removed LNI 192.168.206.17@tcp [11554.258428] Key type .llcrypt unregistered [11554.261244] Key type ._llcrypt unregistered [11570.923746] Key type ._llcrypt registered [11570.927767] Key type .llcrypt registered [11572.230713] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 10:26:01 (1787840761) [11574.304431] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11574.324283] alg: No test for adler32 (adler32-zlib) [11575.388357] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [11575.606703] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [11577.295276] Key type lgssc registered [11578.487869] Lustre: Echo OBD driver; http://www.lustre.org/ [11621.923257] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11628.097217] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11644.276097] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11651.112693] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [11656.182676] Lustre: lustre-MDT0000-mdc-ffff9414e010b800: Connection to lustre-MDT0000 (at 192.168.206.117@tcp) was lost; in progress operations using this service will wait for recovery to complete [11660.878201] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11660.904767] LustreError: 266688:0:(file.c:6184:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [11661.218868] LustreError: MGC192.168.206.117@tcp: Connection to MGS (at 192.168.206.117@tcp) was lost; in progress operations using this service will fail [11661.256469] Lustre: Unmounted lustre-client [11681.039241] Key type lgssc unregistered [11681.310579] LNet: 267104:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11681.313868] LNetError: 267104:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11681.327401] LNet: Removed LNI 192.168.206.17@tcp [11681.885162] Key type .llcrypt unregistered [11681.887524] Key type ._llcrypt unregistered [11695.057758] Key type ._llcrypt registered [11695.060238] Key type .llcrypt registered [11696.231082] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 10:28:05 (1787840885) [11698.181252] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11698.201312] alg: No test for adler32 (adler32-zlib) [11699.211362] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [11699.386839] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [11701.007203] Key type lgssc registered [11701.970524] Lustre: Echo OBD driver; http://www.lustre.org/ [11746.157278] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11750.812283] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11768.661605] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11771.388179] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [11776.489572] Lustre: lustre-MDT0001-mdc-ffff9414c55a4800: Connection to lustre-MDT0001 (at 192.168.206.117@tcp) was lost; in progress operations using this service will wait for recovery to complete [11782.165631] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [11783.816881] Lustre: Unmounted lustre-client [11805.688298] Key type lgssc unregistered [11806.099738] LNet: 270682:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11806.109853] LNetError: 270682:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11806.135673] LNet: Removed LNI 192.168.206.17@tcp [11806.991566] Key type .llcrypt unregistered [11806.995530] Key type ._llcrypt unregistered [11820.802921] Key type ._llcrypt registered [11820.805900] Key type .llcrypt registered [11821.858739] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 10:30:10 (1787841010) [11838.158661] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11838.173448] alg: No test for adler32 (adler32-zlib) [11839.143391] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [11839.379346] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [11841.047191] Key type lgssc registered [11842.124545] Lustre: Echo OBD driver; http://www.lustre.org/ [11946.741579] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11947.936094] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [11989.475729] Lustre: lustre-MDT0001-mdc-ffff9414c5b00800: Connection to lustre-MDT0001 (at 192.168.206.117@tcp) was lost; in progress operations using this service will wait for recovery to complete [11996.424292] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12006.769987] Lustre: Unmounted lustre-client [12050.969832] Key type lgssc unregistered [12051.233160] LNet: 276472:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12051.248933] LNetError: 276472:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12051.295828] LNet: Removed LNI 192.168.206.17@tcp [12052.185979] Key type .llcrypt unregistered [12052.195982] Key type ._llcrypt unregistered [12067.905536] Key type ._llcrypt registered [12067.908109] Key type .llcrypt registered [12069.410479] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 10:34:18 (1787841258) [12071.704981] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [12074.071714] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 10:34:22 (1787841262) [12076.102344] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [12078.151215] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 10:34:26 (1787841266) [12079.833322] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [12081.684102] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 10:34:30 (1787841270) [12083.455544] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [12085.204652] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 10:34:34 (1787841274) [12087.285319] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [12089.067878] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 10:34:37 (1787841277) [12097.474919] Key type .llcrypt unregistered [12097.477100] Key type ._llcrypt unregistered [12103.436624] Key type ._llcrypt registered [12103.438309] Key type .llcrypt registered [12103.807785] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12103.818609] alg: No test for adler32 (adler32-zlib) [12104.812481] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [12105.049958] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [12106.673201] Key type lgssc registered [12107.953696] Lustre: Echo OBD driver; http://www.lustre.org/ [12186.113162] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12191.296880] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12210.909290] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12212.388467] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [12214.346871] Lustre: Unmounted lustre-client [12251.167972] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 10:37:19 (1787841439) [12283.609747] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12289.273315] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12323.673945] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12334.696226] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [12338.377498] Lustre: Unmounted lustre-client [12382.560842] LNet: 1 local NIs in recovery (showing 1): 192.168.206.17@tcp [12384.123642] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 10:39:32 (1787841572) [13027.301197] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13033.090806] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13065.180954] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13154.140243] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 10:52:23 (1787842343) [13164.903069] Key type lgssc unregistered [13165.153013] LNet: 290003:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13165.160759] LNetError: 290003:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13165.181785] LNet: Removed LNI 192.168.206.17@tcp [13165.847654] Key type .llcrypt unregistered [13165.849889] Key type ._llcrypt unregistered [13265.346275] Key type ._llcrypt registered [13265.360632] Key type .llcrypt registered [13265.921205] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13265.936375] alg: No test for adler32 (adler32-zlib) [13267.053158] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [13267.290299] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [13269.015169] Key type lgssc registered [13270.162239] Lustre: Echo OBD driver; http://www.lustre.org/ [13363.735802] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13370.821784] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13400.600668] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13442.399404] Key type lgssc unregistered [13442.740169] LNet: 293714:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13442.758712] LNetError: 293714:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13442.782580] LNet: Removed LNI 192.168.206.17@tcp [13443.727618] Key type .llcrypt unregistered [13443.730678] Key type ._llcrypt unregistered [13480.050459] Key type ._llcrypt registered [13480.053871] Key type .llcrypt registered [13481.356762] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 10:57:50 (1787842670) [13503.351338] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13503.417986] alg: No test for adler32 (adler32-zlib) [13504.602378] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [13504.876784] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [13506.639221] Key type lgssc registered [13508.213482] Lustre: Echo OBD driver; http://www.lustre.org/ [13561.455631] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 10:59:10 (1787842750) [13574.742301] Key type lgssc unregistered [13575.005684] LNet: 297283:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13575.011540] LNetError: 297283:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13575.039271] LNet: Removed LNI 192.168.206.17@tcp [13575.696441] Key type .llcrypt unregistered [13575.703824] Key type ._llcrypt unregistered [13587.786349] Key type ._llcrypt registered [13587.793255] Key type .llcrypt registered [13588.300122] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13588.314382] alg: No test for adler32 (adler32-zlib) [13589.486865] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [13589.724545] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [13591.455248] Key type lgssc registered [13592.946356] Lustre: Echo OBD driver; http://www.lustre.org/ [13650.879597] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13658.023718] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13682.671385] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13956.727066] LNet: Added LNI 192.168.206.17@tcp50 [8/256/0/180] [13958.733773] LNet: Removed LNI 192.168.206.17@tcp [13960.629349] Lustre: 301687:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff9414c8369800: config has no valid NIDs, force dynamic NIDs [13961.788060] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [13961.990758] Lustre: Unmounted lustre-client [13964.361662] Key type lgssc unregistered [13964.664570] LNet: 301947:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13964.673509] LNetError: 301947:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13965.748711] LNet: Removed LNI 192.168.206.17@tcp50 [13966.495325] Key type .llcrypt unregistered [13966.504311] Key type ._llcrypt unregistered [13983.182220] Key type ._llcrypt registered [13983.201054] Key type .llcrypt registered [13983.780624] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13983.819703] alg: No test for adler32 (adler32-zlib) [13985.004126] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [13985.383074] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [13987.103236] Key type lgssc registered [13988.344659] Lustre: Echo OBD driver; http://www.lustre.org/ [14050.019621] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14055.617590] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14062.444992] LNet: Added LNI 192.168.206.17@tcp50 [8/256/0/180] [14063.163417] LNet: Removed LNI 192.168.206.17@tcp [14064.313291] Lustre: 304638:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff9414c8691800: config has no valid NIDs, force dynamic NIDs [14065.542548] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [14066.173793] Lustre: Unmounted lustre-client [14095.732773] Key type lgssc unregistered [14096.129129] LNet: 305508:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14096.137655] LNetError: 305508:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14096.175425] LNet: Removed LNI 192.168.206.17@tcp50 [14096.898158] Key type .llcrypt unregistered [14096.900179] Key type ._llcrypt unregistered [14106.111522] Key type ._llcrypt registered [14106.113252] Key type .llcrypt registered [14107.656331] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 11:08:16 (1787843296) [14125.610642] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14125.640684] alg: No test for adler32 (adler32-zlib) [14126.731753] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [14126.934403] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [14128.583178] Key type lgssc registered [14129.545091] Lustre: Echo OBD driver; http://www.lustre.org/ [14208.940288] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14214.681879] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14236.294937] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14265.875897] Key type lgssc unregistered [14266.082544] LNet: 310254:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14266.092749] LNetError: 310254:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14266.127140] LNet: Removed LNI 192.168.206.17@tcp [14266.732731] Key type .llcrypt unregistered [14266.735866] Key type ._llcrypt unregistered [14274.778403] Key type ._llcrypt registered [14274.781683] Key type .llcrypt registered [14275.874145] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 11:11:04 (1787843464) [14277.867103] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14277.918809] alg: No test for adler32 (adler32-zlib) [14279.040977] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [14279.231955] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [14280.888050] Key type lgssc registered [14282.148346] Lustre: Echo OBD driver; http://www.lustre.org/ [14330.540504] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14336.004822] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14355.532156] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14358.207944] Lustre: Mounted lustre-client - version 2.17.57_64_gb96373c [14408.500862] Lustre: Mounted fs15246-client - version 2.17.57_64_gb96373c [14419.580129] Lustre: Unmounted fs15246-client [14441.668158] Lustre: Unmounted lustre-client [14476.518257] Key type lgssc unregistered [14476.860452] LNet: 315107:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14476.872304] LNetError: 315107:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14476.911375] LNet: Removed LNI 192.168.206.17@tcp [14477.672144] Key type .llcrypt unregistered [14477.678588] Key type ._llcrypt unregistered [14488.722898] Key type ._llcrypt registered [14488.726846] Key type .llcrypt registered [14489.940514] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 11:14:38 (1787843678) [14491.919675] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14491.942438] alg: No test for adler32 (adler32-zlib) [14493.063451] Lustre: Lustre: Build Version: 2.17.57_64_gb96373c [14493.330121] LNet: Added LNI 192.168.206.17@tcp [8/256/0/180] [14494.975200] Key type lgssc registered [14496.146976] Lustre: Echo OBD driver; http://www.lustre.org/ [14631.354730] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14637.439908] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14661.259215] Lustre: DEBUG MARKER: oleg617-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14762.256412] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 14480 sec ========= 11:19:10 (1787843950) [14763.803426] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 11:19:12 (1787843952) === [14767.311792] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 11:19:16 (1787843956) === [14802.097706] Key type lgssc unregistered [14802.546190] LNet: 324373:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14802.556542] LNetError: 324373:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14802.593807] LNet: Removed LNI 192.168.206.17@tcp [14803.620809] Key type .llcrypt unregistered [14803.625536] Key type ._llcrypt unregistered