[ 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 426482355 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 2608MB 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.002205] x2apic enabled [ 0.003004] Switched APIC routing to physical x2apic. [ 0.003959] kvm-guest: setup PV IPIs [ 0.006000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.006000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.006020] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.007007] pid_max: default: 32768 minimum: 301 [ 0.008100] LSM: Security Framework initializing [ 0.009034] Yama: becoming mindful. [ 0.009673] SELinux: Initializing. [ 0.010050] *** VALIDATE selinux *** [ 0.017712] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.021691] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.022160] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.023111] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.024126] *** VALIDATE tmpfs *** [ 0.026166] *** VALIDATE proc *** [ 0.027265] *** VALIDATE cgroup *** [ 0.028011] *** VALIDATE cgroup2 *** [ 0.029279] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.030173] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.031008] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.032033] Spectre V2 : User space: Vulnerable [ 0.033007] Speculative Store Bypass: Vulnerable [ 0.035731] debug: unmapping init [mem 0xffffffffaaa59000-0xffffffffaaa60fff] [ 0.037202] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.038805] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.039024] ... version: 2 [ 0.040013] ... bit width: 48 [ 0.041010] ... generic registers: 4 [ 0.042018] ... value mask: 0000ffffffffffff [ 0.043022] ... max period: 00007fffffffffff [ 0.044018] ... fixed-purpose events: 3 [ 0.045014] ... event mask: 000000070000000f [ 0.046363] rcu: Hierarchical SRCU implementation. [ 0.048952] smp: Bringing up secondary CPUs ... [ 0.049665] x86: Booting SMP configuration: [ 0.050040] .... node #0, CPUs: #1 #2 #3 [ 0.054134] smp: Brought up 1 node, 4 CPUs [ 0.056012] smpboot: Max logical packages: 1 [ 0.057012] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.137305] node 0 deferred pages initialised in 78ms [ 0.141129] devtmpfs: initialized [ 0.142243] x86/mm: Memory block size: 128MB [ 0.146143] gcov: version magic: 0x41383552 [ 0.149269] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.152110] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.154342] pinctrl core: initialized pinctrl subsystem [ 0.157207] [ 0.157806] ************************************************************* [ 0.159015] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.162017] ** ** [ 0.164015] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.167013] ** ** [ 0.169012] ** This means that this kernel is built to expose internal ** [ 0.171013] ** IOMMU data structures, which may compromise security on ** [ 0.174013] ** your system. ** [ 0.176015] ** ** [ 0.178012] ** If you see this message and you are not debugging the ** [ 0.180016] ** kernel, report this immediately to your vendor! ** [ 0.182014] ** ** [ 0.184012] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.186012] ************************************************************* [ 0.189766] NET: Registered protocol family 16 [ 0.191490] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.194140] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.196063] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.200033] cpuidle: using governor menu [ 0.201927] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.204458] PCI: Using configuration type 1 for base access [ 0.207269] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.217100] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.220064] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.224093] cryptd: max_cpu_qlen set to 1000 [ 0.228334] ACPI: Added _OSI(Module Device) [ 0.230015] ACPI: Added _OSI(Processor Device) [ 0.231017] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.234016] ACPI: Added _OSI(Processor Aggregator Device) [ 0.240440] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.246482] ACPI: Interpreter enabled [ 0.249077] ACPI: PM: (supports S0 S3 S4 S5) [ 0.250017] ACPI: Using IOAPIC for interrupt routing [ 0.252107] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.256457] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.265506] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.268042] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.270019] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.273090] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.278408] acpiphp: Slot [2] registered [ 0.280145] acpiphp: Slot [5] registered [ 0.281126] acpiphp: Slot [6] registered [ 0.283134] acpiphp: Slot [3] registered [ 0.284117] acpiphp: Slot [4] registered [ 0.286108] acpiphp: Slot [7] registered [ 0.287081] acpiphp: Slot [8] registered [ 0.288107] acpiphp: Slot [9] registered [ 0.290107] acpiphp: Slot [10] registered [ 0.291097] acpiphp: Slot [11] registered [ 0.292140] acpiphp: Slot [12] registered [ 0.294119] acpiphp: Slot [13] registered [ 0.295104] acpiphp: Slot [14] registered [ 0.297120] acpiphp: Slot [15] registered [ 0.298125] acpiphp: Slot [16] registered [ 0.300156] acpiphp: Slot [17] registered [ 0.301125] acpiphp: Slot [18] registered [ 0.303092] acpiphp: Slot [19] registered [ 0.304089] acpiphp: Slot [20] registered [ 0.305099] acpiphp: Slot [21] registered [ 0.307098] acpiphp: Slot [22] registered [ 0.308100] acpiphp: Slot [23] registered [ 0.310090] acpiphp: Slot [24] registered [ 0.311114] acpiphp: Slot [25] registered [ 0.313125] acpiphp: Slot [26] registered [ 0.314101] acpiphp: Slot [27] registered [ 0.315102] acpiphp: Slot [28] registered [ 0.317092] acpiphp: Slot [29] registered [ 0.318084] acpiphp: Slot [30] registered [ 0.319111] acpiphp: Slot [31] registered [ 0.320052] PCI host bridge to bus 0000:00 [ 0.320920] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.323018] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.325175] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.327020] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.329021] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.333016] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.334318] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.338000] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.340054] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.347016] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.351013] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.353017] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.355018] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.358015] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.360550] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.363093] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.366046] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.369853] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.374025] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.385015] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.389032] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.394058] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.405025] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.411016] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.425020] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.435016] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.443019] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.449020] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.464023] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.473877] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.477458] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.480374] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.483366] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.485318] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.490100] iommu: Default domain type: Passthrough [ 0.492483] SCSI subsystem initialized [ 0.494149] ACPI: bus type USB registered [ 0.495214] usbcore: registered new interface driver usbfs [ 0.497166] usbcore: registered new interface driver hub [ 0.499105] usbcore: registered new device driver usb [ 0.501183] pps_core: LinuxPPS API ver. 1 registered [ 0.503011] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.506069] PTP clock support registered [ 0.508077] EDAC MC: Ver: 3.0.0 [ 0.509404] PCI: Using ACPI for IRQ routing [ 0.510826] NetLabel: Initializing [ 0.511009] NetLabel: domain hash size = 128 [ 0.512017] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.513190] NetLabel: unlabeled traffic allowed by default [ 0.516086] vgaarb: loaded [ 0.517236] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.519013] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.524408] clocksource: Switched to clocksource kvm-clock [ 0.636568] VFS: Disk quotas dquot_6.6.0 [ 0.638441] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.641192] *** VALIDATE ramfs *** [ 0.642496] *** VALIDATE hugetlbfs *** [ 0.644204] pnp: PnP ACPI init [ 0.647041] pnp: PnP ACPI: found 6 devices [ 0.673557] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.677172] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.679644] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.681667] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.683881] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.686283] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.689349] NET: Registered protocol family 2 [ 0.691946] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.697569] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.701512] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.706276] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.709127] TCP: Hash tables configured (established 65536 bind 65536) [ 0.711852] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.714971] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.717196] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.720368] NET: Registered protocol family 1 [ 0.723388] RPC: Registered named UNIX socket transport module. [ 0.725620] RPC: Registered udp transport module. [ 0.727436] RPC: Registered tcp transport module. [ 0.728844] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.731078] NET: Registered protocol family 44 [ 0.733015] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.734552] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.736086] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.738480] PCI: CLS 0 bytes, default 64 [ 0.740370] Unpacking initramfs... [ 2.156292] debug: unmapping init [mem 0xffff9e2dbcc64000-0xffff9e2dbffcffff] [ 2.161992] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.163931] software IO TLB: mapped [mem 0x00000000b8c64000-0x00000000bcc64000] (64MB) [ 2.166478] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.697751] Initialise system trusted keyrings [ 2.700797] Key type blacklist registered [ 2.702908] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.721381] zbud: loaded [ 2.725253] *** VALIDATE nfs *** [ 2.726821] *** VALIDATE nfs4 *** [ 2.728871] pstore: using deflate compression [ 2.732938] Platform Keyring initialized [ 2.848468] NET: Registered protocol family 38 [ 2.850207] Key type asymmetric registered [ 2.851843] Asymmetric key parser 'x509' registered [ 2.853814] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.857559] io scheduler mq-deadline registered [ 2.860477] io scheduler kyber registered [ 2.862795] io scheduler bfq registered [ 2.865321] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.868774] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.872181] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.875616] ACPI: Power Button [PWRF] [ 2.883299] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.893325] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.907046] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.936941] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.966554] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.971527] Non-volatile memory driver v1.3 [ 2.972915] Linux agpgart interface v0.103 [ 3.006595] virtio_blk virtio1: [vda] 146016 512-byte logical blocks (74.8 MB/71.3 MiB) [ 3.009905] vda: detected capacity change from 0 to 74760192 [ 3.024044] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.026687] vdb: detected capacity change from 0 to 1073741824 [ 3.032380] libphy: Fixed MDIO Bus: probed [ 3.049727] usbcore: registered new interface driver usbserial_generic [ 3.052081] usbserial: USB Serial support registered for generic [ 3.053840] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.057530] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.059345] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.061854] mousedev: PS/2 mouse device common for all mice [ 3.065245] rtc_cmos 00:05: RTC can wake from S4 [ 3.067634] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.073578] rtc_cmos 00:05: registered as rtc0 [ 3.073965] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.076758] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.076809] intel_pstate: CPU model not supported [ 3.083655] hid: raw HID events driver (C) Jiri Kosina [ 3.087368] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.087686] usbcore: registered new interface driver usbhid [ 3.093403] usbhid: USB HID core driver [ 3.095244] drop_monitor: Initializing network drop monitor service [ 3.097645] Initializing XFRM netlink socket [ 3.099589] NET: Registered protocol family 10 [ 3.103737] Segment Routing with IPv6 [ 3.105197] NET: Registered protocol family 17 [ 3.107142] mpls_gso: MPLS GSO support [ 3.111853] RAS: Correctable Errors collector initialized. [ 3.113949] AVX version of gcm_enc/dec engaged. [ 3.115265] AES CTR mode by8 optimization enabled [ 3.188327] sched_clock: Marking stable (3188296846, 0)->(4069375158, -881078312) [ 3.191661] registered taskstats version 1 [ 3.193607] Loading compiled-in X.509 certificates [ 3.195762] zswap: loaded using pool lzo/zbud [ 3.221261] Key type big_key registered [ 3.234069] Key type encrypted registered [ 3.235115] ima: No TPM chip found, activating TPM-bypass! [ 3.236660] ima: Allocated hash algorithm: sha1 [ 3.237810] ima: No architecture policies found [ 3.238939] evm: Initialising EVM extended attributes: [ 3.239982] evm: security.selinux [ 3.240544] evm: security.ima [ 3.241459] evm: security.capability [ 3.242444] evm: HMAC attrs: 0x1 [ 3.244090] rtc_cmos 00:05: setting system clock to 2026-08-31 04:49:29 UTC (1788151769) [ 3.250436] debug: unmapping init [mem 0xffffffffaba03000-0xffffffffabbfffff] [ 3.253277] debug: unmapping init [mem 0xffffffffaa782000-0xffffffffaaa58fff] [ 3.262089] Write protecting the kernel read-only data: 28672k [ 3.265500] debug: unmapping init [mem 0xffffffffa8e03000-0xffffffffa8ffffff] [ 3.268359] debug: unmapping init [mem 0xffffffffa9714000-0xffffffffa97fffff] [ 3.302521] 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.309194] systemd[1]: Detected virtualization kvm. [ 3.310432] systemd[1]: Detected architecture x86-64. [ 3.312288] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.342117] systemd[1]: No hostname configured. [ 3.344051] systemd[1]: Set hostname to . [ 3.346267] random: systemd: uninitialized urandom read (16 bytes read) [ 3.348915] systemd[1]: Initializing machine ID from random generator. [ 3.507957] random: systemd: uninitialized urandom read (16 bytes read) [ 3.509738] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 3.513095] random: systemd: uninitialized urandom read (16 bytes read) [ 3.515309] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 3.519748] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. Starting Create list of required st…ce nodes for the current kernel... Starting Setup Virtual Console... [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Swap. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Listening on udev Control Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Reached target Initrd Root Device. Starting Apply Kernel Variables... [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Sockets. Starting Journal Service... [ OK ] Reached target Timers. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.147842] device-mapper: uevent: version 1.0.3 [ 4.149674] 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.936843] virtio_net virtio0 ens2: renamed from eth0 [ 4.951272] scsi host0: ata_piix [ 4.995350] scsi host1: ata_piix [ 4.996992] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.999198] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.702291] dracut-initqueue[578]: RTNETLINK answers: File exists [ 9.747140] random: crng init done [ 9.748664] 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.176697] 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 System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.340420] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.578037] SELinux: Disabled at runtime. [ 11.641382] 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.649107] systemd[1]: Detected virtualization kvm. [ 11.650738] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.168441] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.171523] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.177609] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.180335] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.183771] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.191184] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.195044] systemd[1]: Created slice User and Session Slice. [ OK ] Created slice User and Session Slice. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. Mounting POSIX Message Queue File System... [ OK ] Listening on udev Control Socket. [ OK ] Stopped target Switch Root. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Stopped target Initrd Root 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 ] Created slice system-getty.slice. Activating swap /dev/disk/by-label/SWAP... [ OK ] Reached target Slices. Mounting Kernel Debug File System... [ OK ] Stopped target Initrd File Systems. [ 12.271618] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Listening on Process Core Dump Socket. [ OK ] Reached target rpc_pipefs.target. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Listening on RPCbind Server Activation Socket. Starting Apply Kernel Variables... Mounting Huge Pages File System... [ OK ] Listening on initctl Compatibility Named Pipe. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Reached target RPC Port Mapper. Starting Remount Root and Kernel File Systems... [ OK ] Started Journal Service. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Huge Pages File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... [ OK ] Mounted /mnt. [ OK ] Started udev Coldplug all Devices. [ 12.681830] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 12.951626] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.041781] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.106298] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.116754] EDAC sbridge: Ver: 1.1.2 [ 14.330146] Key type dns_resolver registered [ 14.619363] NFS: Registering the id_resolver key type [ 14.622574] Key type id_resolver registered [ 14.624132] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ 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... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ 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 ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... [ OK ] Started irqbalance daemon. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... Starting Login Service... [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting Network Manager Wait Online... [ 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... Starting Hostname Service... [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg109-client login: [ 60.489944] libcfs: loading out-of-tree module taints kernel. [ 60.716913] Key type ._llcrypt registered [ 60.724238] Key type .llcrypt registered [ 61.444532] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 61.454426] alg: No test for adler32 (adler32-zlib) [ 63.006246] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 64.049857] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [ 65.808973] Key type lgssc registered [ 68.028143] Lustre: Echo OBD driver; http://www.lustre.org/ [ 224.176188] hrtimer: interrupt took 2105476 ns [ 249.922548] Lustre: Mounted lustre-client [ 255.596974] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 270.782570] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing check_logdir /tmp/testlogs/ [ 275.428267] Lustre: lustre-OST0000-osc-ffff9e2e06d5d800: disconnect after 23s idle [ 276.426334] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing yml_node [ 281.154270] Lustre: DEBUG MARKER: Client: 2.17.55.27 [ 284.073676] Lustre: DEBUG MARKER: MDS: 2.17.55.27 [ 286.251688] Lustre: DEBUG MARKER: OSS: 2.17.55.27 [ 288.137838] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Mon Aug 31 00:54:12 EDT 2026 [ 304.777572] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 306.583758] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 309.477838] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 319.849834] Lustre: Unmounted lustre-client [ 448.372105] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 454.225729] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 481.744243] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 521.838360] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 00:58:06 (1788152286) [ 524.492516] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 526.417598] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 00:58:11 (1788152291) [ 561.557191] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 568.064996] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 591.506920] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 592.298890] Lustre: Mounted lustre-client [ 600.960605] Lustre: Unmounted lustre-client [ 629.011563] Key type lgssc unregistered [ 629.434510] LNet: 15030:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 629.444392] LNetError: 15030:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 629.480681] LNet: Removed LNI 192.168.201.9@tcp [ 630.276162] Key type .llcrypt unregistered [ 630.279210] Key type ._llcrypt unregistered [ 646.360397] Key type ._llcrypt registered [ 646.362426] Key type .llcrypt registered [ 647.732992] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 01:00:12 (1788152412) [ 649.659321] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 649.785342] alg: No test for adler32 (adler32-zlib) [ 651.035755] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 651.323374] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [ 652.984279] Key type lgssc registered [ 654.189938] Lustre: Echo OBD driver; http://www.lustre.org/ [ 729.183436] Lustre: Mounted lustre-client [ 814.297827] Lustre: Unmounted lustre-client [ 841.856596] Key type lgssc unregistered [ 842.121889] LNet: 18876:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 842.139196] LNetError: 18876:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 842.166822] LNet: Removed LNI 192.168.201.9@tcp [ 842.842309] Key type .llcrypt unregistered [ 842.846505] Key type ._llcrypt unregistered [ 855.801653] Key type ._llcrypt registered [ 855.805309] Key type .llcrypt registered [ 857.840666] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 01:03:41 (1788152621) [ 860.681607] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 860.711097] alg: No test for adler32 (adler32-zlib) [ 861.927551] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 862.139965] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [ 863.894880] Key type lgssc registered [ 865.326307] Lustre: Echo OBD driver; http://www.lustre.org/ [ 924.377755] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 931.333814] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 952.811826] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 964.637377] Lustre: Mounted lustre-client [ 976.071995] Lustre: Unmounted lustre-client [ 1005.823451] Key type lgssc unregistered [ 1006.296400] LNet: 22481:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1006.309227] LNetError: 22481:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1006.339269] LNet: Removed LNI 192.168.201.9@tcp [ 1007.295376] Key type .llcrypt unregistered [ 1007.298785] Key type ._llcrypt unregistered [ 1017.481497] Key type ._llcrypt registered [ 1017.482791] Key type .llcrypt registered [ 1019.244187] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 01:06:23 (1788152783) [ 1022.494275] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1022.544121] alg: No test for adler32 (adler32-zlib) [ 1023.888444] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 1024.482336] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [ 1026.304296] Key type lgssc registered [ 1027.722255] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1081.888466] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1089.180441] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1113.010185] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1115.831804] Lustre: Mounted lustre-client [ 1122.162392] Lustre: Unmounted lustre-client [ 1150.700769] Key type lgssc unregistered [ 1151.062335] LNet: 25675:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1151.076937] LNetError: 25675:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1151.103051] LNet: Removed LNI 192.168.201.9@tcp [ 1152.009916] Key type .llcrypt unregistered [ 1152.011612] Key type ._llcrypt unregistered [ 1169.830330] Key type ._llcrypt registered [ 1169.832073] Key type .llcrypt registered [ 1171.119909] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 01:08:55 (1788152935) [ 1174.446492] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1174.507205] alg: No test for adler32 (adler32-zlib) [ 1175.806665] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 1176.191952] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [ 1178.012339] Key type lgssc registered [ 1179.550673] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1236.680269] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1241.868962] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1263.723296] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1265.451764] Lustre: Mounted lustre-client [ 1275.911347] Lustre: lustre-MDT0000-mdc-ffff9e2e102fa800: Connection to lustre-MDT0000 (at 192.168.201.109@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1286.163645] Lustre: lustre-MDT0001-mdc-ffff9e2e102fa800: Connection to lustre-MDT0001 (at 192.168.201.109@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1297.376272] Lustre: 26635:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788153047/real 1788153047] req@0000000066da9ac9 x1875014259316736/t0(0) o400->MGC192.168.201.109@tcp@192.168.201.109@tcp:26/25 lens 224/224 e 0 to 1 dl 1788153063 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1297.403274] LustreError: MGC192.168.201.109@tcp: Connection to MGS (at 192.168.201.109@tcp) was lost; in progress operations using this service will fail [ 1299.249717] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1309.627565] Lustre: Unmounted lustre-client [ 1321.838583] Key type lgssc unregistered [ 1322.101048] LNet: 29335:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1322.112426] LNetError: 29335:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1322.137401] LNet: Removed LNI 192.168.201.9@tcp [ 1323.055339] Key type .llcrypt unregistered [ 1323.058272] Key type ._llcrypt unregistered [ 1340.262351] Key type ._llcrypt registered [ 1340.263959] Key type .llcrypt registered [ 1341.807900] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 01:11:46 (1788153106) [ 1345.293835] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1345.328588] alg: No test for adler32 (adler32-zlib) [ 1346.552750] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 1347.091498] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [ 1348.912396] Key type lgssc registered [ 1350.472959] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1400.888635] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1406.590814] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1430.786879] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1433.478792] Lustre: Mounted lustre-client [ 1443.824218] Lustre: lustre-OST0000-osc-ffff9e2e080d1800: Connection to lustre-OST0000 (at 192.168.201.109@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1449.362544] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1459.717878] Lustre: Unmounted lustre-client [ 1487.659211] Key type lgssc unregistered [ 1487.972254] LNet: 32953:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1487.989749] LNetError: 32953:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1488.015340] LNet: Removed LNI 192.168.201.9@tcp [ 1489.000828] Key type .llcrypt unregistered [ 1489.009143] Key type ._llcrypt unregistered [ 1507.852222] Key type ._llcrypt registered [ 1507.854132] Key type .llcrypt registered [ 1509.591842] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 01:14:33 (1788153273) [ 1512.283644] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1512.306755] alg: No test for adler32 (adler32-zlib) [ 1513.459488] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 1513.700421] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [ 1515.408344] Key type lgssc registered [ 1516.898463] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1567.167772] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1573.433435] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1596.154769] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1599.038614] Lustre: Mounted lustre-client [ 1606.340309] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1610.990881] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1623.679811] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-08-31 1h16m27s [ 1626.066182] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1629.671561] Lustre: lustre-MDT0000-mdc-ffff9e2e04889000: Connection to lustre-MDT0000 (at 192.168.201.109@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1636.533718] LustreError: 36747:0:(lmv_obd.c:1468:lmv_statfs()) lustre-MDT0000-mdc-ffff9e2e04889000: can't stat MDS #0: rc = -19 [ 1638.274811] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1648.111102] LustreError: MGC192.168.201.109@tcp: Connection to MGS (at 192.168.201.109@tcp) was lost; in progress operations using this service will fail [ 1648.140313] Lustre: Evicted from MGS (at 192.168.201.109@tcp) after server handle changed from 0x3b90a61bbc976be6 to 0x3b90a61bbc976eb0 [ 1648.161210] Lustre: MGC192.168.201.109@tcp: Connection restored to 192.168.201.109@tcp (at 192.168.201.109@tcp) [ 1649.217402] LustreError: lustre-MDT0000-mdc-ffff9e2e04889000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1649.271981] Lustre: lustre-MDT0000-mdc-ffff9e2e04889000: Connection restored to 192.168.201.109@tcp (at 192.168.201.109@tcp) [ 1655.945400] Lustre: DEBUG MARKER: Wait for df (36747) ... [ 1658.327941] Lustre: DEBUG MARKER: done [ 1660.819433] Lustre: Unmounted lustre-client [ 1696.035908] Key type lgssc unregistered [ 1696.379926] LNet: 37765:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1696.392813] LNetError: 37765:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1696.416097] LNet: Removed LNI 192.168.201.9@tcp [ 1697.077779] Key type .llcrypt unregistered [ 1697.080620] Key type ._llcrypt unregistered [ 1730.749491] Key type ._llcrypt registered [ 1730.752646] Key type .llcrypt registered [ 1732.290804] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 01:18:16 (1788153496) [ 1735.702679] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1735.766148] alg: No test for adler32 (adler32-zlib) [ 1737.055617] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 1737.376562] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [ 1739.224236] Key type lgssc registered [ 1740.759863] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1795.041829] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1802.044529] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1825.038340] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1826.731098] Lustre: Mounted lustre-client [ 1831.754271] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-08-31 1h19m56s [ 1833.646494] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1842.315904] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1845.329620] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1865.696542] Lustre: 41912:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788153611/real 1788153611] req@000000002effabcb x1875014847568512/t0(0) o101->lustre-MDT0000-mdc-ffff9e2e07a71000@192.168.201.109@tcp:12/10 lens 576/1152 e 0 to 1 dl 1788153631 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1865.758404] Lustre: lustre-MDT0000-mdc-ffff9e2e07a71000: Connection to lustre-MDT0000 (at 192.168.201.109@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1865.805293] Lustre: lustre-MDT0000-mdc-ffff9e2e07a71000: Connection restored to 192.168.201.109@tcp (at 192.168.201.109@tcp) [ 1888.201705] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1892.717864] Lustre: DEBUG MARKER: done [ 1899.227476] Lustre: Unmounted lustre-client [ 1929.569318] Key type lgssc unregistered [ 1929.920432] LNet: 42798:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1929.931220] LNetError: 42798:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1929.948092] LNet: Removed LNI 192.168.201.9@tcp [ 1930.698748] Key type .llcrypt unregistered [ 1930.702964] Key type ._llcrypt unregistered [ 1964.527967] Key type ._llcrypt registered [ 1964.531424] Key type .llcrypt registered [ 1966.132904] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 01:22:10 (1788153730) [ 1968.252835] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1970.243442] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 01:22:14 (1788153734) [ 1974.785867] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1974.809535] alg: No test for adler32 (adler32-zlib) [ 1975.952680] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 1976.317854] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [ 1978.032288] Key type lgssc registered [ 1979.302214] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2010.469959] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 01:22:55 (1788153775) [ 2051.853164] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2059.419645] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2084.523830] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2086.023335] Lustre: Mounted lustre-client [ 2089.928404] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 2094.353291] Lustre: Unmounted lustre-client [ 2111.824307] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 2159.917486] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2168.379576] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2168.955089] Lustre: Mounted lustre-client [ 2174.627493] Lustre: Unmounted lustre-client [ 2242.629637] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2250.883965] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2251.842548] Lustre: Mounted lustre-client [ 2257.359671] Lustre: Unmounted lustre-client [ 2292.355254] Lustre: DEBUG MARKER: files compared the same [ 2307.104951] Key type lgssc unregistered [ 2307.405668] LNet: 50418:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2307.408777] LNetError: 50418:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2307.429451] LNet: Removed LNI 192.168.201.9@tcp [ 2308.065127] Key type .llcrypt unregistered [ 2308.067920] Key type ._llcrypt unregistered [ 2324.827945] Key type ._llcrypt registered [ 2324.838359] Key type .llcrypt registered [ 2326.177742] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 01:28:10 (1788154090) [ 2329.254761] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2329.462055] alg: No test for adler32 (adler32-zlib) [ 2330.840960] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 2331.382423] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [ 2333.168392] Key type lgssc registered [ 2334.884101] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2386.894764] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2393.105646] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2414.647993] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2416.284250] Lustre: Mounted lustre-client [ 2424.668369] Lustre: Unmounted lustre-client [ 2454.127840] Key type lgssc unregistered [ 2454.587158] LNet: 54003:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2454.603921] LNetError: 54003:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2454.636096] LNet: Removed LNI 192.168.201.9@tcp [ 2455.529075] Key type .llcrypt unregistered [ 2455.530966] Key type ._llcrypt unregistered [ 2474.760983] Key type ._llcrypt registered [ 2474.762717] Key type .llcrypt registered [ 2476.476779] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 01:30:40 (1788154240) [ 2479.349331] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2479.371164] alg: No test for adler32 (adler32-zlib) [ 2480.762663] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 2481.235616] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [ 2482.960194] Key type lgssc registered [ 2484.422492] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2571.825791] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2620.801311] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2629.681479] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2667.341124] Key type lgssc unregistered [ 2667.651534] LNet: 57494:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2667.655423] LNetError: 57494:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2667.675706] LNet: Removed LNI 192.168.201.9@tcp [ 2668.561099] Key type .llcrypt unregistered [ 2668.569653] Key type ._llcrypt unregistered [ 2685.178197] Key type ._llcrypt registered [ 2685.181589] Key type .llcrypt registered [ 2686.777128] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 01:34:11 (1788154451) [ 2691.480516] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2691.512031] alg: No test for adler32 (adler32-zlib) [ 2692.705244] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 2692.975415] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [ 2694.680636] Key type lgssc registered [ 2696.564588] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2781.093110] Lustre: Mounted lustre-client [ 2788.565775] Lustre: Unmounted lustre-client [ 2827.902275] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 01:36:32 (1788154592) [ 2956.530773] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2979.374671] Lustre: Mounted lustre-client [ 2988.414644] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2988.438953] LustreError: 63973:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff9e2e052ae800: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2988.485841] Lustre: 58478:0:(llite_lib.c:4339:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.201.109@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2998.858680] Lustre: Unmounted lustre-client [ 3061.908392] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 01:40:26 (1788154826) [ 3074.075242] Key type lgssc unregistered [ 3074.511688] LNet: 65186:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3074.518199] LNetError: 65186:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3075.566276] LNet: Removed LNI 192.168.201.9@tcp [ 3076.459313] Key type .llcrypt unregistered [ 3076.464569] Key type ._llcrypt unregistered [ 3091.371707] Key type ._llcrypt registered [ 3091.378950] Key type .llcrypt registered [ 3092.186642] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3092.217789] alg: No test for adler32 (adler32-zlib) [ 3093.564292] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 3093.954484] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [ 3095.800985] Key type lgssc registered [ 3097.605532] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3159.016895] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3165.319293] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3188.817866] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3190.589922] Lustre: Mounted lustre-client [ 3199.224619] Lustre: Unmounted lustre-client [ 3227.590934] Key type lgssc unregistered [ 3227.944222] LNet: 68204:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3227.953544] LNetError: 68204:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3227.983894] LNet: Removed LNI 192.168.201.9@tcp [ 3228.872884] Key type .llcrypt unregistered [ 3228.885117] Key type ._llcrypt unregistered [ 3240.512378] Key type ._llcrypt registered [ 3240.513623] Key type .llcrypt registered [ 3241.206670] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3241.249270] alg: No test for adler32 (adler32-zlib) [ 3242.542626] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 3242.889642] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [ 3244.616700] Key type lgssc registered [ 3246.244488] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3371.601886] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3379.312805] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3401.072758] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3402.933963] Lustre: Mounted lustre-client [ 3405.298343] Lustre: Unmounted lustre-client [ 3429.528346] Key type lgssc unregistered [ 3429.938858] LNet: 71639:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3429.944872] LNetError: 71639:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3429.977319] LNet: Removed LNI 192.168.201.9@tcp [ 3430.950806] Key type .llcrypt unregistered [ 3430.952972] Key type ._llcrypt unregistered [ 3447.345130] Key type ._llcrypt registered [ 3447.348658] Key type .llcrypt registered [ 3448.894575] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 01:46:53 (1788155213) [ 3451.345605] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3451.389285] alg: No test for adler32 (adler32-zlib) [ 3452.467925] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 3452.722043] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [ 3454.432226] Key type lgssc registered [ 3455.836908] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3505.603537] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3511.750884] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3538.626681] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3541.501403] Lustre: Mounted lustre-client [ 3546.565605] Lustre: Unmounted lustre-client [ 3546.973878] Lustre: Mounted lustre-client [ 3549.866281] Lustre: Unmounted lustre-client [ 3578.838042] Key type lgssc unregistered [ 3579.215324] LNet: 75334:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3579.228042] LNetError: 75334:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3579.254666] LNet: Removed LNI 192.168.201.9@tcp [ 3580.537702] Key type .llcrypt unregistered [ 3580.542671] Key type ._llcrypt unregistered [ 3596.886403] Key type ._llcrypt registered [ 3596.899617] Key type .llcrypt registered [ 3597.492266] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3597.526228] alg: No test for adler32 (adler32-zlib) [ 3598.656817] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 3598.851048] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [ 3600.465540] Key type lgssc registered [ 3602.408347] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3662.254881] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3669.213791] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3700.211739] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3702.387967] Lustre: Mounted lustre-client [ 3707.478587] Lustre: Unmounted lustre-client [ 3738.889849] Key type lgssc unregistered [ 3739.277935] LNet: 78375:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3739.284715] LNetError: 78375:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3739.312380] LNet: Removed LNI 192.168.201.9@tcp [ 3740.786843] Key type .llcrypt unregistered [ 3740.796416] Key type ._llcrypt unregistered [ 3760.389752] Key type ._llcrypt registered [ 3760.398369] Key type .llcrypt registered [ 3762.090984] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 01:52:06 (1788155526) [ 3765.331259] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3765.363775] alg: No test for adler32 (adler32-zlib) [ 3766.659256] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 3767.022923] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [ 3768.768289] Key type lgssc registered [ 3770.933188] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3835.208293] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3841.803118] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3865.531101] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3877.440246] Lustre: Mounted lustre-client [ 3889.210160] Lustre: lustre: root_squash is set to 0:0 [ 3889.246818] Lustre: lustre: nosquash_nids is cleared [ 3904.550865] Lustre: lustre: root_squash is set to 500:500 [ 3916.873926] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.201.9@tcp 192.168.0.[2,10]@tcp [ 3925.272074] Lustre: lustre: nosquash_nids set to 192.168.201.9/24@tcp [ 3935.434703] Lustre: Unmounted lustre-client [ 3960.835754] Key type lgssc unregistered [ 3961.175815] LNet: 82378:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3961.182760] LNetError: 82378:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3961.209262] LNet: Removed LNI 192.168.201.9@tcp [ 3962.016896] Key type .llcrypt unregistered [ 3962.019196] Key type ._llcrypt unregistered [ 3978.601230] Key type ._llcrypt registered [ 3978.604082] Key type .llcrypt registered [ 3980.351933] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 01:55:44 (1788155744) [ 3983.134191] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3985.486458] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 01:55:50 (1788155750) [ 3988.806995] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3988.941179] alg: No test for adler32 (adler32-zlib) [ 3990.131444] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 3990.464291] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [ 3992.192199] Key type lgssc registered [ 3993.721322] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4047.427834] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4054.252100] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4076.708867] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4078.292952] Lustre: lustre: root_squash is set to 500:500 [ 4078.316604] Lustre: lustre: nosquash_nids set to 192.168.201.9/24@tcp [ 4079.452899] Lustre: Mounted lustre-client [ 4085.342747] Lustre: Unmounted lustre-client [ 4114.659114] Key type lgssc unregistered [ 4115.001248] LNet: 86338:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4115.007939] LNetError: 86338:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4116.079852] LNet: Removed LNI 192.168.201.9@tcp [ 4117.380980] Key type .llcrypt unregistered [ 4117.386988] Key type ._llcrypt unregistered [ 4137.321188] Key type ._llcrypt registered [ 4137.323038] Key type .llcrypt registered [ 4139.284943] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 4141.493743] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 01:58:25 (1788155905) [ 4161.698810] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4161.736331] alg: No test for adler32 (adler32-zlib) [ 4162.937928] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 4163.292051] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [ 4165.058171] Key type lgssc registered [ 4166.713568] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4257.725489] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4264.536448] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4290.273182] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4371.914988] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4377.879305] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4400.175606] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4412.650498] Lustre: Mounted lustre-client [ 4450.558689] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9e2e080d6800.ost_server_uuid 50 [ 4452.452862] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9e2e080d6800.ost_server_uuid in IDLE state after 0 sec [ 4453.169749] Lustre: Mounted lustre-client [ 4456.924652] Lustre: Unmounted lustre-client [ 4458.775429] Lustre: Unmounted lustre-client [ 4512.328988] Key type lgssc unregistered [ 4512.701268] LNet: 94021:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4512.706889] LNetError: 94021:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4512.722507] LNet: Removed LNI 192.168.201.9@tcp [ 4513.486996] Key type .llcrypt unregistered [ 4513.494456] Key type ._llcrypt unregistered [ 4547.707812] Key type ._llcrypt registered [ 4547.712100] Key type .llcrypt registered [ 4549.232046] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 02:05:13 (1788156313) [ 4569.489420] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4569.682498] alg: No test for adler32 (adler32-zlib) [ 4570.993517] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 4571.284652] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [ 4573.104214] Key type lgssc registered [ 4574.722817] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4668.329401] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4675.110168] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4699.365423] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4702.459773] Lustre: Mounted lustre-client [ 4724.193977] Lustre: 95388:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788156474/real 1788156474] req@00000000e8c71300 x1875017819231488/t0(0) o400->lustre-OST0000-osc-ffff9e2e10b79000@192.168.201.109@tcp:28/4 lens 224/224 e 0 to 1 dl 1788156490 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4724.259603] Lustre: lustre-OST0000-osc-ffff9e2e10b79000: Connection to lustre-OST0000 (at 192.168.201.109@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4729.433664] Lustre: 95388:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788156479/real 1788156479] req@000000002b7d425c x1875017819232000/t0(0) o400->lustre-OST0000-osc-ffff9e2e10b79000@192.168.201.109@tcp:28/4 lens 224/224 e 0 to 1 dl 1788156495 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4734.516228] Lustre: lustre-OST0000-osc-ffff9e2e10b79000: Connection restored to 192.168.201.109@tcp (at 192.168.201.109@tcp) [ 4735.584172] Lustre: 95388:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788156484/real 1788156484] req@00000000c5994f99 x1875017819232512/t0(0) o400->lustre-OST0000-osc-ffff9e2e10b79000@192.168.201.109@tcp:28/4 lens 224/224 e 0 to 1 dl 1788156500 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4739.728249] Lustre: 95388:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788156489/real 1788156489] req@000000005d26d072 x1875017819233024/t0(0) o400->lustre-OST0000-osc-ffff9e2e10b79000@192.168.201.109@tcp:28/4 lens 224/224 e 0 to 1 dl 1788156505 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4744.697600] Lustre: lustre-MDT0000-mdc-ffff9e2e10b79000: Connection to lustre-MDT0000 (at 192.168.201.109@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4760.032227] Lustre: 95388:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788156510/real 1788156510] req@0000000082383a5e x1875017819235840/t0(0) o400->MGC192.168.201.109@tcp@192.168.201.109@tcp:26/25 lens 224/224 e 0 to 1 dl 1788156526 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4760.061416] LustreError: MGC192.168.201.109@tcp: Connection to MGS (at 192.168.201.109@tcp) was lost; in progress operations using this service will fail [ 4760.077746] Lustre: Evicted from MGS (at 192.168.201.109@tcp) after server handle changed from 0xc974c91f098ae83b to 0xc974c91f098ae9d1 [ 4760.085175] Lustre: MGC192.168.201.109@tcp: Connection restored to 192.168.201.109@tcp (at 192.168.201.109@tcp) [ 4766.704078] Lustre: 98565:0:(mgc_request.c:1899:mgc_process_log()) MGC192.168.201.109@tcp: IR log lustre-cliir failed, not fatal: rc = -2 [ 4773.008044] Lustre: lustre-MDT0000-mdc-ffff9e2e10b79000: Connection restored to 192.168.201.109@tcp (at 192.168.201.109@tcp) [ 4780.814694] Lustre: Unmounted lustre-client [ 4811.723497] Key type lgssc unregistered [ 4812.242187] LNet: 99698:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4812.261302] LNetError: 99698:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4812.282780] LNet: Removed LNI 192.168.201.9@tcp [ 4813.125601] Key type .llcrypt unregistered [ 4813.128495] Key type ._llcrypt unregistered [ 4838.735292] Key type ._llcrypt registered [ 4838.736772] Key type .llcrypt registered [ 4841.560989] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 02:10:04 (1788156604) [ 4858.686896] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4858.863457] alg: No test for adler32 (adler32-zlib) [ 4860.210293] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 4860.583046] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [ 4862.328229] Key type lgssc registered [ 4863.764508] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4907.590468] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5020.185604] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5028.444213] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5053.066760] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5054.810245] Lustre: Mounted lustre-client [ 5056.950963] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 5059.072942] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 02:13:43 (1788156823) [ 5335.930825] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5346.328521] Lustre: Unmounted lustre-client [ 5491.018881] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5496.332643] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5528.149501] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5590.153213] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 02:22:34 (1788157354) [ 5695.263146] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5702.745957] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5731.047714] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5731.709596] Lustre: Mounted lustre-client [ 5736.682110] Lustre: Unmounted lustre-client [ 5786.640805] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 02:25:51 (1788157551) [ 5896.580444] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5903.238729] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5929.271297] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5930.392169] Lustre: Mounted lustre-client [ 5936.629210] Lustre: Unmounted lustre-client [ 5975.748679] Key type lgssc unregistered [ 5976.068466] LNet: 122745:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5976.075571] LNetError: 122745:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5976.097573] LNet: Removed LNI 192.168.201.9@tcp [ 5976.818889] Key type .llcrypt unregistered [ 5976.821571] Key type ._llcrypt unregistered [ 5995.626048] Key type ._llcrypt registered [ 5995.629702] Key type .llcrypt registered [ 5997.502594] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 02:29:21 (1788157761) [ 5999.929860] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5999.971140] alg: No test for adler32 (adler32-zlib) [ 6001.087630] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 6001.459572] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [ 6003.184476] Key type lgssc registered [ 6004.619968] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6061.080068] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6067.677671] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6093.716492] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6095.811880] Lustre: Mounted lustre-client [ 6111.098584] Lustre: Unmounted lustre-client [ 6143.728218] Key type lgssc unregistered [ 6144.133756] LNet: 126339:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6144.162782] LNetError: 126339:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6144.180824] LNet: Removed LNI 192.168.201.9@tcp [ 6145.284260] Key type .llcrypt unregistered [ 6145.288221] Key type ._llcrypt unregistered [ 6163.559233] Key type ._llcrypt registered [ 6163.560697] Key type .llcrypt registered [ 6165.293411] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 02:32:09 (1788157929) [ 6168.102895] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6168.117189] alg: No test for adler32 (adler32-zlib) [ 6169.349173] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 6169.754438] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [ 6171.528385] Key type lgssc registered [ 6172.789107] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6227.513719] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6233.671099] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6255.076137] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6257.824870] Lustre: Mounted lustre-client [ 6267.888775] Lustre: lustre-OST0000-osc-ffff9e2e0517c800: Connection to lustre-OST0000 (at 192.168.201.109@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6288.252173] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff9e2e0517c800.ost_server_uuid 50 [ 6290.005939] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 6302.418893] Lustre: Unmounted lustre-client [ 6327.029405] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 02:34:51 (1788158091) [ 6366.385177] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6373.770349] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6396.370366] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6425.193356] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6425.912945] Lustre: Mounted lustre-client [ 6431.222133] Lustre: lustre-OST0000-osc-ffff9e2e044ba000: Connection to lustre-OST0000 (at 192.168.201.109@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6460.415861] Lustre: Unmounted lustre-client [ 6509.135640] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 02:37:53 (1788158273) [ 6545.025970] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6551.164904] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6574.798297] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6599.802174] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6600.818610] Lustre: Mounted lustre-client [ 6605.832544] Lustre: lustre-OST0000-osc-ffff9e2e13fa5800: Connection to lustre-OST0000 (at 192.168.201.109@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6623.841021] Lustre: Unmounted lustre-client [ 6675.925294] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 02:40:40 (1788158440) [ 6772.638549] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6778.977396] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6803.176690] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6879.917879] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6885.319108] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6908.488385] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6938.074557] LustreError: 127299:0:(lmv_obd.c:208:lmv_notify()) activation of lustre-MDT0001_UUID failed: -22 [ 6938.157563] Lustre: Mounted lustre-client [ 6984.876082] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6986.551427] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6989.192131] Lustre: Unmounted lustre-client [ 7025.761199] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 02:46:29 (1788158789) [ 7064.098371] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7070.831191] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7093.513793] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7132.078961] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7164.581207] Lustre: Mounted lustre-client [ 7205.158882] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7207.284560] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7211.017553] Lustre: lustre-OST0001-osc-ffff9e2e044bc800: Connection to lustre-OST0001 (at 192.168.201.109@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7227.695239] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7238.055680] Lustre: Unmounted lustre-client [ 7290.034137] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 02:50:54 (1788159054) [ 7323.576204] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7330.117919] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7350.617907] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7351.342221] Lustre: Mounted lustre-client [ 7376.184808] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7378.497582] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7396.534509] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9e2e080d4800.ost_server_uuid 50 [ 7398.762976] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9e2e080d4800.ost_server_uuid in IDLE state after 0 sec [ 7402.417350] Lustre: Unmounted lustre-client [ 7402.805791] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 7402.821850] Lustre: Skipped 3 previous similar messages [ 7402.913376] Lustre: Mounted lustre-client [ 7404.164680] LustreError: 149780:0:(sec.c:413:import_sec_validate_get()) import 00000000cf6a03dc (NEW) with no sec [ 7464.471833] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 02:53:49 (1788159229) [ 7499.382637] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7505.240832] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7528.076715] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7556.588493] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7557.133585] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 7557.324900] Lustre: Mounted lustre-client [ 7568.891117] Lustre: Unmounted lustre-client [ 7568.899041] Lustre: Skipped 1 previous similar message [ 7609.884362] Key type lgssc unregistered [ 7610.267282] LNet: 153178:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7610.279428] LNetError: 153178:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7610.316171] LNet: Removed LNI 192.168.201.9@tcp [ 7611.441791] Key type .llcrypt unregistered [ 7611.444568] Key type ._llcrypt unregistered [ 7634.817290] Key type ._llcrypt registered [ 7634.820209] Key type .llcrypt registered [ 7636.835827] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 02:56:40 (1788159400) [ 7639.475277] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7639.521742] alg: No test for adler32 (adler32-zlib) [ 7640.906397] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 7641.263772] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [ 7643.037952] Key type lgssc registered [ 7644.824476] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7705.334904] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7710.866580] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7733.637416] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7755.461634] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7757.098527] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7758.277159] Lustre: Mounted lustre-client [ 7763.474672] LustreError: lustre-MDT0001-mdc-ffff9e2e0602d800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 7763.494303] Lustre: lustre-MDT0001-mdc-ffff9e2e0602d800: Connection restored to 192.168.201.109@tcp (at 192.168.201.109@tcp) [ 7769.810545] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7771.460082] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7797.335379] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7803.277800] Lustre: Unmounted lustre-client [ 7839.580291] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 03:00:04 (1788159604) [ 7951.837353] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7959.410908] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7992.254156] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7993.180475] Lustre: Mounted lustre-client [ 8024.776971] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8026.583738] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 8039.512184] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 8049.827584] Lustre: Unmounted lustre-client [ 8085.206458] Key type lgssc unregistered [ 8085.451557] LNet: 162809:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8085.455804] LNetError: 162809:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8085.465518] LNet: Removed LNI 192.168.201.9@tcp [ 8086.307114] Key type .llcrypt unregistered [ 8086.311655] Key type ._llcrypt unregistered [ 8121.161681] Key type ._llcrypt registered [ 8121.164630] Key type .llcrypt registered [ 8122.807438] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 03:04:47 (1788159887) [ 8125.423211] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8125.506841] alg: No test for adler32 (adler32-zlib) [ 8126.688793] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 8127.151787] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [ 8128.937155] Key type lgssc registered [ 8130.287744] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8181.019639] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8187.971942] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8211.873304] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8213.701695] Lustre: Mounted lustre-client [ 8263.574576] Lustre: Unmounted lustre-client [ 8305.293121] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8305.900976] Lustre: Mounted lustre-client [ 8342.543593] Lustre: Unmounted lustre-client [ 8372.913209] Key type lgssc unregistered [ 8373.211445] LNet: 167260:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8373.218692] LNetError: 167260:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8373.242333] LNet: Removed LNI 192.168.201.9@tcp [ 8374.008676] Key type .llcrypt unregistered [ 8374.011906] Key type ._llcrypt unregistered [ 8391.336512] Key type ._llcrypt registered [ 8391.343185] Key type .llcrypt registered [ 8393.000370] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 03:09:17 (1788160157) [ 8395.768872] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8395.806661] alg: No test for adler32 (adler32-zlib) [ 8396.955815] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 8397.286856] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [ 8399.096448] Key type lgssc registered [ 8400.481062] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8457.061065] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8463.249373] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8487.126027] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8489.125294] Lustre: Mounted lustre-client [ 8513.239326] Lustre: Unmounted lustre-client [ 8547.797234] Key type lgssc unregistered [ 8548.194497] LNet: 171014:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8548.212135] LNetError: 171014:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8548.241289] LNet: Removed LNI 192.168.201.9@tcp [ 8548.914349] Key type .llcrypt unregistered [ 8548.918117] Key type ._llcrypt unregistered [ 8564.110812] Key type ._llcrypt registered [ 8564.140468] Key type .llcrypt registered [ 8564.401770] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8564.415300] alg: No test for adler32 (adler32-zlib) [ 8565.568527] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 8565.743649] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [ 8567.400556] Key type lgssc registered [ 8569.115929] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8632.781918] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8641.236327] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8666.826612] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8668.883446] Lustre: Mounted lustre-client [ 8678.854191] Lustre: Unmounted lustre-client [ 8709.169569] Key type lgssc unregistered [ 8709.826683] LNet: 174086:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8709.839283] LNetError: 174086:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8709.872910] LNet: Removed LNI 192.168.201.9@tcp [ 8711.472752] Key type .llcrypt unregistered [ 8711.474480] Key type ._llcrypt unregistered [ 8732.425610] Key type ._llcrypt registered [ 8732.428975] Key type .llcrypt registered [ 8734.219381] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 03:14:58 (1788160498) [ 8737.424588] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8737.488756] alg: No test for adler32 (adler32-zlib) [ 8738.551888] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 8738.894513] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [ 8740.648191] Key type lgssc registered [ 8741.895654] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8800.932849] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8807.475353] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8833.449774] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8844.841774] Lustre: Mounted lustre-client [ 8864.896378] Lustre: Unmounted lustre-client [ 8892.985854] Key type lgssc unregistered [ 8893.288990] LNet: 177840:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8893.293990] LNetError: 177840:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8893.307295] LNet: Removed LNI 192.168.201.9@tcp [ 8894.046614] Key type .llcrypt unregistered [ 8894.049461] Key type ._llcrypt unregistered [ 8909.535659] Key type ._llcrypt registered [ 8909.537484] Key type .llcrypt registered [ 8910.000346] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8910.011348] alg: No test for adler32 (adler32-zlib) [ 8911.369937] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 8911.784925] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [ 8913.616710] Key type lgssc registered [ 8915.135635] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8973.118127] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8980.972987] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9007.944345] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9014.350244] Lustre: Mounted lustre-client [ 9023.911103] Lustre: Unmounted lustre-client [ 9054.630853] Key type lgssc unregistered [ 9055.094699] LNet: 180904:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9055.103894] LNetError: 180904:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9055.124194] LNet: Removed LNI 192.168.201.9@tcp [ 9056.232720] Key type .llcrypt unregistered [ 9056.237532] Key type ._llcrypt unregistered [ 9079.303659] Key type ._llcrypt registered [ 9079.310156] Key type .llcrypt registered [ 9081.075711] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 03:20:45 (1788160845) [ 9106.062613] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9106.109149] alg: No test for adler32 (adler32-zlib) [ 9107.328570] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 9107.825430] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [ 9109.596182] Key type lgssc registered [ 9111.359614] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9201.672598] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9208.689270] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9234.779120] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9277.736798] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 03:24:02 (1788161042) [ 9311.544640] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9317.420201] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9338.168959] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9340.921350] Lustre: Mounted lustre-client [ 9349.113070] Lustre: Unmounted lustre-client [ 9379.532956] Key type lgssc unregistered [ 9379.946277] LNet: 188017:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9379.958022] LNetError: 188017:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9379.980253] LNet: Removed LNI 192.168.201.9@tcp [ 9380.906634] Key type .llcrypt unregistered [ 9380.908893] Key type ._llcrypt unregistered [ 9399.972519] Key type ._llcrypt registered [ 9399.975435] Key type .llcrypt registered [ 9401.758250] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 9404.485781] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 03:26:08 (1788161168) [ 9425.013380] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9425.063497] alg: No test for adler32 (adler32-zlib) [ 9426.117473] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [ 9426.495249] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [ 9428.280265] Key type lgssc registered [ 9429.729715] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9545.866122] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9554.600686] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9581.662799] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9602.249849] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9605.100371] Lustre: Mounted lustre-client [ 9632.381191] Lustre: Unmounted lustre-client [ 9763.788266] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9770.779367] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9796.314428] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9844.298321] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 03:33:28 (1788161608) [ 9847.070649] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 9849.793190] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 03:33:33 (1788161613) [ 9966.850369] Key type lgssc unregistered [ 9967.171624] LNet: 199471:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9967.177532] LNetError: 199471:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9967.196225] LNet: Removed LNI 192.168.201.9@tcp [ 9967.973112] Key type .llcrypt unregistered [ 9967.974527] Key type ._llcrypt unregistered [ 9988.766169] Key type ._llcrypt registered [ 9988.768773] Key type .llcrypt registered [ 9990.540497] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 03:35:54 (1788161754) [10034.409645] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10034.454044] alg: No test for adler32 (adler32-zlib) [10035.471351] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [10035.737597] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [10037.384214] Key type lgssc registered [10038.998249] Lustre: Echo OBD driver; http://www.lustre.org/ [10094.549724] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10100.683727] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10138.216798] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10171.413569] Key type lgssc unregistered [10171.697477] LNet: 203206:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10171.703284] LNetError: 203206:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10171.739650] LNet: Removed LNI 192.168.201.9@tcp [10172.905875] Key type .llcrypt unregistered [10172.921771] Key type ._llcrypt unregistered [10193.164618] Key type ._llcrypt registered [10193.172128] Key type .llcrypt registered [10194.897793] Lustre: DEBUG MARKER: == conf-sanity test 57c: servicenode metadata preserves legacy discovery by default ========================================================== 03:39:19 (1788161959) [10205.205461] Key type .llcrypt unregistered [10205.207084] Key type ._llcrypt unregistered [10215.208853] Key type ._llcrypt registered [10215.219996] Key type .llcrypt registered [10215.739220] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10215.760832] alg: No test for adler32 (adler32-zlib) [10216.950669] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [10217.251765] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [10218.928425] Key type lgssc registered [10220.597866] Lustre: Echo OBD driver; http://www.lustre.org/ [10274.310594] LNet: Added LNI 192.168.201.9@tcp1 [8/256/0/180] [10360.924270] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10367.774748] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10504.975320] Key type lgssc unregistered [10505.294562] LNet: 208796:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10505.304730] LNetError: 208796:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10505.314286] LNet: Removed LNI 192.168.201.9@tcp [10506.342507] LNet: Removed LNI 192.168.201.9@tcp1 [10507.271914] Key type .llcrypt unregistered [10507.273536] Key type ._llcrypt unregistered [10523.977679] Key type ._llcrypt registered [10523.981080] Key type .llcrypt registered [10525.487658] Lustre: DEBUG MARKER: == conf-sanity test 57d: servicenode policy splits OSS discovery views ========================================================== 03:44:49 (1788162289) [10537.698100] Key type .llcrypt unregistered [10537.708571] Key type ._llcrypt unregistered [10548.820547] Key type ._llcrypt registered [10548.823239] Key type .llcrypt registered [10549.683351] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10549.759225] alg: No test for adler32 (adler32-zlib) [10550.997488] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [10551.380542] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [10553.272200] Key type lgssc registered [10555.425225] Lustre: Echo OBD driver; http://www.lustre.org/ [10604.770166] LNet: Added LNI 192.168.201.9@tcp1 [8/256/0/180] [10690.940103] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10698.165187] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10839.251877] Key type lgssc unregistered [10839.674378] LNet: 213826:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10839.686273] LNetError: 213826:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10839.694383] LNet: Removed LNI 192.168.201.9@tcp [10840.756392] LNet: Removed LNI 192.168.201.9@tcp1 [10841.656638] Key type .llcrypt unregistered [10841.659411] Key type ._llcrypt unregistered [10857.331899] Key type ._llcrypt registered [10857.343397] Key type .llcrypt registered [10859.453579] Lustre: DEBUG MARKER: == conf-sanity test 57e: servicenode policy overrides self grouping ========================================================== 03:50:23 (1788162623) [10871.355507] Key type .llcrypt unregistered [10871.359872] Key type ._llcrypt unregistered [10880.601533] Key type ._llcrypt registered [10880.605988] Key type .llcrypt registered [10881.466668] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10881.478479] alg: No test for adler32 (adler32-zlib) [10882.846565] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [10883.343607] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [10885.184265] Key type lgssc registered [10887.036127] Lustre: Echo OBD driver; http://www.lustre.org/ [10932.242340] LNet: Added LNI 192.168.201.9@tcp1 [8/256/0/180] [11017.748859] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11024.300481] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11114.167150] Key type lgssc unregistered [11114.623507] LNet: 218536:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11114.640486] LNetError: 218536:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11114.648615] LNet: Removed LNI 192.168.201.9@tcp [11115.692948] LNet: Removed LNI 192.168.201.9@tcp1 [11116.818914] Key type .llcrypt unregistered [11116.824271] Key type ._llcrypt unregistered [11135.851927] Key type ._llcrypt registered [11135.856267] Key type .llcrypt registered [11138.192294] Lustre: DEBUG MARKER: == conf-sanity test 57f: servicenode policy overrides NUMA grouping ========================================================== 03:55:01 (1788162901) [11147.212540] Key type .llcrypt unregistered [11147.215347] Key type ._llcrypt unregistered [11155.543255] Key type ._llcrypt registered [11155.551693] Key type .llcrypt registered [11156.192001] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11156.231482] alg: No test for adler32 (adler32-zlib) [11157.333263] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [11157.672553] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [11159.392244] Key type lgssc registered [11160.740917] Lustre: Echo OBD driver; http://www.lustre.org/ [11207.108877] LNet: Added LNI 192.168.201.9@tcp1 [8/256/0/180] [11291.203569] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11298.025782] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11391.726488] Key type lgssc unregistered [11391.970852] LNet: 223258:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11391.975685] LNetError: 223258:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11391.982805] LNet: Removed LNI 192.168.201.9@tcp [11393.007343] LNet: Removed LNI 192.168.201.9@tcp1 [11393.908810] Key type .llcrypt unregistered [11393.910881] Key type ._llcrypt unregistered [11411.101857] Key type ._llcrypt registered [11411.107957] Key type .llcrypt registered [11413.522206] Lustre: DEBUG MARKER: == conf-sanity test 57g: servicenode policy keeps ungrouped NIDs discoverable ========================================================== 03:59:37 (1788163177) [11424.609659] Key type .llcrypt unregistered [11424.616228] Key type ._llcrypt unregistered [11433.484100] Key type ._llcrypt registered [11433.493181] Key type .llcrypt registered [11433.847655] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11433.861826] alg: No test for adler32 (adler32-zlib) [11435.186977] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [11435.613513] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [11437.426386] Key type lgssc registered [11439.639859] Lustre: Echo OBD driver; http://www.lustre.org/ [11489.749675] LNet: Added LNI 192.168.201.9@tcp1 [8/256/0/180] [11571.242672] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11577.611926] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11713.452764] Key type lgssc unregistered [11713.722972] LNet: 228336:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11713.730978] LNetError: 228336:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11713.738646] LNet: Removed LNI 192.168.201.9@tcp [11714.792193] LNet: Removed LNI 192.168.201.9@tcp1 [11715.514413] Key type .llcrypt unregistered [11715.517439] Key type ._llcrypt unregistered [11729.557912] Key type ._llcrypt registered [11729.561639] Key type .llcrypt registered [11731.257383] Lustre: DEBUG MARKER: == conf-sanity test 57h: servicenode policy is active before the first target mounts ========================================================== 04:04:55 (1788163495) [11742.133692] Key type .llcrypt unregistered [11742.135640] Key type ._llcrypt unregistered [11750.793596] Key type ._llcrypt registered [11750.817271] Key type .llcrypt registered [11751.603640] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11751.634348] alg: No test for adler32 (adler32-zlib) [11752.865745] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [11753.394192] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [11755.152238] Key type lgssc registered [11756.869494] Lustre: Echo OBD driver; http://www.lustre.org/ [11798.772861] LNet: Added LNI 192.168.201.9@tcp1 [8/256/0/180] [11825.201504] Key type lgssc unregistered [11825.566891] LNet: 231251:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11825.580461] LNetError: 231251:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11825.593885] LNet: Removed LNI 192.168.201.9@tcp [11826.663178] LNet: Removed LNI 192.168.201.9@tcp1 [11827.476831] Key type .llcrypt unregistered [11827.482294] Key type ._llcrypt unregistered [11842.125607] Key type ._llcrypt registered [11842.132675] Key type .llcrypt registered [11844.015721] Lustre: DEBUG MARKER: == conf-sanity test 57i: servicenode Push never falls back to the merged view ========================================================== 04:06:48 (1788163608) [11855.297489] Key type .llcrypt unregistered [11855.300674] Key type ._llcrypt unregistered [11865.087193] Key type ._llcrypt registered [11865.097527] Key type .llcrypt registered [11865.640929] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11865.653042] alg: No test for adler32 (adler32-zlib) [11866.873314] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [11867.221739] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [11868.896491] Key type lgssc registered [11870.276144] Lustre: Echo OBD driver; http://www.lustre.org/ [11910.260742] LNet: Added LNI 192.168.201.9@tcp1 [8/256/0/180] [11911.676666] LNet: Added LNI 192.168.201.9@tcp2 [8/256/0/180] [11990.131045] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11995.895883] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12120.898833] Key type lgssc unregistered [12121.170339] LNet: 236448:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12121.180089] LNetError: 236448:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12122.208172] LNet: Removed LNI 192.168.201.9@tcp [12122.215664] LNetError: 236448:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12122.223811] LNet: Removed LNI 192.168.201.9@tcp1 [12122.246422] LNet: Removed LNI 192.168.201.9@tcp2 [12123.365758] Key type .llcrypt unregistered [12123.375073] Key type ._llcrypt unregistered [12138.709836] Key type ._llcrypt registered [12138.712556] Key type .llcrypt registered [12140.134643] Lustre: DEBUG MARKER: == conf-sanity test 57j: failnode targets contribute no servicenode group ========================================================== 04:11:44 (1788163904) [12149.549372] Key type .llcrypt unregistered [12149.551663] Key type ._llcrypt unregistered [12156.469263] Key type ._llcrypt registered [12156.476380] Key type .llcrypt registered [12156.649412] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12156.659952] alg: No test for adler32 (adler32-zlib) [12157.855237] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [12158.142723] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [12159.888229] Key type lgssc registered [12161.360983] Lustre: Echo OBD driver; http://www.lustre.org/ [12200.425902] LNet: Added LNI 192.168.201.9@tcp1 [8/256/0/180] [12279.465993] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12286.858600] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12408.018449] Key type lgssc unregistered [12408.320311] LNet: 241482:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12408.328404] LNetError: 241482:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12408.341166] LNet: Removed LNI 192.168.201.9@tcp [12409.383423] LNet: Removed LNI 192.168.201.9@tcp1 [12410.292172] Key type .llcrypt unregistered [12410.296874] Key type ._llcrypt unregistered [12423.986884] Key type ._llcrypt registered [12423.993231] Key type .llcrypt registered [12425.715263] Lustre: DEBUG MARKER: == conf-sanity test 57k: unknown target_nid_policy stops the module load ========================================================== 04:16:29 (1788164189) [12435.521943] Key type .llcrypt unregistered [12435.525578] Key type ._llcrypt unregistered [12465.252964] Key type ._llcrypt registered [12465.254995] Key type .llcrypt registered [12466.717781] Lustre: DEBUG MARKER: == conf-sanity test 57l: nodemap nidrange visibility filters a client class at runtime ========================================================== 04:17:11 (1788164231) [12476.068661] Key type .llcrypt unregistered [12476.071277] Key type ._llcrypt unregistered [12484.612982] Key type ._llcrypt registered [12484.617059] Key type .llcrypt registered [12484.999924] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12485.012399] alg: No test for adler32 (adler32-zlib) [12486.249550] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [12486.613258] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [12488.376197] Key type lgssc registered [12490.099957] Lustre: Echo OBD driver; http://www.lustre.org/ [12528.603696] LNet: Added LNI 192.168.201.9@tcp1 [8/256/0/180] [12608.306811] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12614.025315] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12748.396399] Key type lgssc unregistered [12748.776814] LNet: 247825:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12748.793624] LNetError: 247825:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12748.801062] LNet: Removed LNI 192.168.201.9@tcp [12749.884218] LNet: Removed LNI 192.168.201.9@tcp1 [12750.887129] Key type .llcrypt unregistered [12750.888860] Key type ._llcrypt unregistered [12765.941410] Key type ._llcrypt registered [12765.942972] Key type .llcrypt registered [12767.530320] Lustre: DEBUG MARKER: == conf-sanity test 57m: nodemap visibility class unions all configured client ranges ========================================================== 04:22:11 (1788164531) [12778.024445] Key type .llcrypt unregistered [12778.027985] Key type ._llcrypt unregistered [12786.162669] Key type ._llcrypt registered [12786.167980] Key type .llcrypt registered [12786.864657] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12786.897193] alg: No test for adler32 (adler32-zlib) [12788.199450] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [12788.526147] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [12790.288757] Key type lgssc registered [12791.758756] Lustre: Echo OBD driver; http://www.lustre.org/ [12833.580473] LNet: Added LNI 192.168.201.9@tcp1 [8/256/0/180] [12915.219997] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12922.599685] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13061.533742] Key type lgssc unregistered [13062.016288] LNet: 252889:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13062.032370] LNetError: 252889:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13062.046474] LNet: Removed LNI 192.168.201.9@tcp [13063.080613] LNet: Removed LNI 192.168.201.9@tcp1 [13064.183343] Key type .llcrypt unregistered [13064.190301] Key type ._llcrypt unregistered [13079.279094] Key type ._llcrypt registered [13079.281089] Key type .llcrypt registered [13080.774356] Lustre: DEBUG MARKER: == conf-sanity test 57n: independent nodemap visibility classes select independent views ========================================================== 04:27:25 (1788164845) [13091.241765] Key type .llcrypt unregistered [13091.244470] Key type ._llcrypt unregistered [13100.650823] Key type ._llcrypt registered [13100.652387] Key type .llcrypt registered [13101.066697] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13101.089806] alg: No test for adler32 (adler32-zlib) [13102.274360] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [13102.800347] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [13104.665949] Key type lgssc registered [13106.395585] Lustre: Echo OBD driver; http://www.lustre.org/ [13148.199421] LNet: Added LNI 192.168.201.9@tcp1 [8/256/0/180] [13223.066415] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13228.692229] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13355.322940] Key type lgssc unregistered [13355.749224] LNet: 257972:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13355.763338] LNetError: 257972:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13355.769705] LNet: Removed LNI 192.168.201.9@tcp [13356.843038] LNet: Removed LNI 192.168.201.9@tcp1 [13357.856178] Key type .llcrypt unregistered [13357.858430] Key type ._llcrypt unregistered [13373.286826] Key type ._llcrypt registered [13373.289278] Key type .llcrypt registered [13374.529721] Lustre: DEBUG MARKER: == conf-sanity test 57o: nodemap visibility composes with servicenode policy ========================================================== 04:32:19 (1788165139) [13384.878512] Key type .llcrypt unregistered [13384.881195] Key type ._llcrypt unregistered [13393.869461] Key type ._llcrypt registered [13393.889641] Key type .llcrypt registered [13394.837908] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13394.866584] alg: No test for adler32 (adler32-zlib) [13396.032799] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [13396.351600] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [13398.112245] Key type lgssc registered [13401.058151] Lustre: Echo OBD driver; http://www.lustre.org/ [13444.564843] LNet: Added LNI 192.168.201.9@tcp1 [8/256/0/180] [13529.142098] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13535.790259] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13621.557364] Key type lgssc unregistered [13621.807537] LNet: 262700:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13621.818316] LNetError: 262700:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13621.827618] LNet: Removed LNI 192.168.201.9@tcp [13622.896996] LNet: Removed LNI 192.168.201.9@tcp1 [13623.584305] Key type .llcrypt unregistered [13623.586254] Key type ._llcrypt unregistered [13637.577583] Key type ._llcrypt registered [13637.583619] Key type .llcrypt registered [13639.156632] Lustre: DEBUG MARKER: == conf-sanity test 57p: nodemap visibility includes server NIDs with routed reachability ========================================================== 04:36:43 (1788165403) [13650.288372] Key type .llcrypt unregistered [13650.290161] Key type ._llcrypt unregistered [13659.002866] Key type ._llcrypt registered [13659.005853] Key type .llcrypt registered [13659.430806] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13659.441234] alg: No test for adler32 (adler32-zlib) [13660.540617] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [13660.771054] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [13662.456271] Key type lgssc registered [13664.042047] Lustre: Echo OBD driver; http://www.lustre.org/ [13701.521864] LNet: Added LNI 192.168.201.9@tcp1 [8/256/0/180] [13703.263976] LNet: Added LNI 192.168.201.9@tcp2 [8/256/0/180] [13777.830273] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13784.749757] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13870.839909] Lustre: DEBUG MARKER: SKIP: conf-sanity test_57p need a remote client with no Lustre server role for routing [13914.652809] Key type lgssc unregistered [13915.031805] LNet: 267606:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13915.036954] LNetError: 267606:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13915.045413] LNet: Removed LNI 192.168.201.9@tcp [13915.047521] LNet: Removed LNI 192.168.201.9@tcp1 [13915.067677] LNet: Removed LNI 192.168.201.9@tcp2 [13916.144634] Key type .llcrypt unregistered [13916.149640] Key type ._llcrypt unregistered [13932.122349] Key type ._llcrypt registered [13932.125727] Key type .llcrypt registered [13933.861650] Lustre: DEBUG MARKER: == conf-sanity test 57q: active nodemap visibility fails closed while state is replaced ========================================================== 04:41:38 (1788165698) [13944.897353] Key type .llcrypt unregistered [13944.905081] Key type ._llcrypt unregistered [13955.110915] Key type ._llcrypt registered [13955.115936] Key type .llcrypt registered [13955.708570] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13955.723680] alg: No test for adler32 (adler32-zlib) [13956.968216] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [13957.422697] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [13959.184231] Key type lgssc registered [13960.698466] Lustre: Echo OBD driver; http://www.lustre.org/ [14002.848994] LNet: Added LNI 192.168.201.9@tcp1 [8/256/0/180] [14077.998093] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14084.891972] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14242.370356] Key type lgssc unregistered [14242.950982] LNet: 272718:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14242.972605] LNetError: 272718:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14242.985501] LNet: Removed LNI 192.168.201.9@tcp [14243.006847] LNet: Removed LNI 192.168.201.9@tcp1 [14244.794422] Key type .llcrypt unregistered [14244.796254] Key type ._llcrypt unregistered [14265.548453] Key type ._llcrypt registered [14265.559505] Key type .llcrypt registered [14267.126660] Lustre: DEBUG MARKER: == conf-sanity test 57r: MGS restart publishes persisted nodemap visibility ========================================================== 04:47:11 (1788166031) [14279.940432] Key type .llcrypt unregistered [14279.945451] Key type ._llcrypt unregistered [14290.279525] Key type ._llcrypt registered [14290.290323] Key type .llcrypt registered [14290.962989] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14290.996424] alg: No test for adler32 (adler32-zlib) [14292.296504] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [14292.702652] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [14294.520388] Key type lgssc registered [14295.955479] Lustre: Echo OBD driver; http://www.lustre.org/ [14346.763605] LNet: Added LNI 192.168.201.9@tcp1 [8/256/0/180] [14398.348442] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14407.895072] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14446.946774] Key type lgssc unregistered [14447.288531] LNet: 276285:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14447.301207] LNetError: 276285:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14447.317707] LNet: Removed LNI 192.168.201.9@tcp [14447.333814] LNet: Removed LNI 192.168.201.9@tcp1 [14448.272572] Key type .llcrypt unregistered [14448.280079] Key type ._llcrypt unregistered [14464.347552] Key type ._llcrypt registered [14464.354158] Key type .llcrypt registered [14465.244950] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14465.276685] alg: No test for adler32 (adler32-zlib) [14466.679844] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [14467.258768] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [14469.080246] Key type lgssc registered [14470.530112] Lustre: Echo OBD driver; http://www.lustre.org/ [14542.357657] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14550.041471] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14589.195943] Key type lgssc unregistered [14589.615950] LNet: 279214:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14589.631563] LNetError: 279214:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14589.657297] LNet: Removed LNI 192.168.201.9@tcp [14590.563771] Key type .llcrypt unregistered [14590.568652] Key type ._llcrypt unregistered [14605.727631] Key type ._llcrypt registered [14605.730787] Key type .llcrypt registered [14607.526801] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 04:52:51 (1788166371) [14611.138147] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14611.179749] alg: No test for adler32 (adler32-zlib) [14612.486102] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [14612.827706] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [14614.568834] Key type lgssc registered [14616.904191] Lustre: Echo OBD driver; http://www.lustre.org/ [14677.118602] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14683.954127] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14708.886724] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14709.543277] LNetError: 279786:0:(lib-move.c:2123:lnet_handle_find_routed_path()) peer 192.168.201.109@tcp1 has no available nets [14710.772863] Lustre: Mounted lustre-client [14721.015756] Lustre: lustre-MDT0000-mdc-ffff9e2e0517d800: Connection to lustre-MDT0000 (at 192.168.201.109@tcp) was lost; in progress operations using this service will wait for recovery to complete [14729.211594] Lustre: lustre-MDT0001-mdc-ffff9e2e0517d800: Connection to lustre-MDT0001 (at 192.168.201.109@tcp) was lost; in progress operations using this service will wait for recovery to complete [14740.448322] Lustre: 279791:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788166490/real 1788166490] req@00000000099d1339 x1875028349108096/t0(0) o400->MGC192.168.201.109@tcp@192.168.201.109@tcp:26/25 lens 224/224 e 0 to 1 dl 1788166506 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [14740.489342] LustreError: MGC192.168.201.109@tcp: Connection to MGS (at 192.168.201.109@tcp) was lost; in progress operations using this service will fail [14765.036844] Lustre: Evicted from MGS (at 192.168.201.109@tcp) after server handle changed from 0xf8a8712541a04345 to 0xf8a8712541a05fae [14765.058412] Lustre: MGC192.168.201.109@tcp: Connection restored to 192.168.201.109@tcp (at 192.168.201.109@tcp) [14773.818086] LustreError: lustre-MDT0000-mdc-ffff9e2e0517d800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [14773.848350] Lustre: lustre-MDT0000-mdc-ffff9e2e0517d800: Connection restored to 192.168.201.109@tcp (at 192.168.201.109@tcp) [14786.317519] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14788.221762] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [14796.233368] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14798.547751] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [14802.895813] Lustre: Unmounted lustre-client [14843.330504] Key type lgssc unregistered [14843.653282] LNet: 283755:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14843.658923] LNetError: 283755:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14843.687968] LNet: Removed LNI 192.168.201.9@tcp [14844.742392] Key type .llcrypt unregistered [14844.749171] Key type ._llcrypt unregistered [14864.877777] Key type ._llcrypt registered [14864.880764] Key type .llcrypt registered [14866.518455] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 04:57:10 (1788166630) [14869.392046] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14869.499024] alg: No test for adler32 (adler32-zlib) [14870.818757] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [14871.188110] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [14872.913604] Key type lgssc registered [14874.439534] Lustre: Echo OBD driver; http://www.lustre.org/ [14932.504389] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14940.136874] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15001.201646] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15008.579695] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15053.254632] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15079.512982] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15118.447655] Key type lgssc unregistered [15118.688785] LNet: 289066:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15118.693591] LNetError: 289066:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15118.710269] LNet: Removed LNI 192.168.201.9@tcp [15119.437200] Key type .llcrypt unregistered [15119.441888] Key type ._llcrypt unregistered [15166.775324] Key type ._llcrypt registered [15166.777473] Key type .llcrypt registered [15168.801392] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 05:02:12 (1788166932) [15215.993697] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15216.008463] alg: No test for adler32 (adler32-zlib) [15217.369535] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [15217.764607] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [15219.488279] Key type lgssc registered [15220.795242] Lustre: Echo OBD driver; http://www.lustre.org/ [15317.807741] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15324.866136] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15356.448583] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15405.128313] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 05:06:09 (1788167169) [15414.551355] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 05:06:19 (1788167179) [15454.351111] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15461.060878] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15487.490475] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15489.367949] Lustre: Mounted lustre-client [15496.211925] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [15498.358627] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [15500.161560] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [15502.207446] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [15504.877896] Lustre: lustre-MDT0000-mdc-ffff9e2e08ecd800: Connection to lustre-MDT0000 (at 192.168.201.109@tcp) was lost; in progress operations using this service will wait for recovery to complete [15525.374714] LustreError: MGC192.168.201.109@tcp: Connection to MGS (at 192.168.201.109@tcp) was lost; in progress operations using this service will fail [15525.418721] Lustre: Evicted from MGS (at 192.168.201.109@tcp) after server handle changed from 0x7c566d92825a6e31 to 0x7c566d92825a70a0 [15525.425183] Lustre: MGC192.168.201.109@tcp: Connection restored to 192.168.201.109@tcp (at 192.168.201.109@tcp) [15530.640555] Lustre: lustre-MDT0000-mdc-ffff9e2e08ecd800: Connection restored to 192.168.201.109@tcp (at 192.168.201.109@tcp) [15540.689518] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15542.380967] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [15546.799828] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [15550.967689] Lustre: lustre-MDT0000-mdc-ffff9e2e08ecd800: Connection to lustre-MDT0000 (at 192.168.201.109@tcp) was lost; in progress operations using this service will wait for recovery to complete [15567.330496] Lustre: 290965:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788167317/real 1788167317] req@00000000d1f236d4 x1875028983432704/t0(0) o400->MGC192.168.201.109@tcp@192.168.201.109@tcp:26/25 lens 224/224 e 0 to 1 dl 1788167333 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [15567.381362] LustreError: MGC192.168.201.109@tcp: Connection to MGS (at 192.168.201.109@tcp) was lost; in progress operations using this service will fail [15577.596413] Lustre: Evicted from MGS (at 192.168.201.109@tcp) after server handle changed from 0x7c566d92825a70a0 to 0x7c566d92825a72b4 [15577.620092] Lustre: MGC192.168.201.109@tcp: Connection restored to 192.168.201.109@tcp (at 192.168.201.109@tcp) [15583.420568] Lustre: lustre-MDT0000-mdc-ffff9e2e08ecd800: Connection restored to 192.168.201.109@tcp (at 192.168.201.109@tcp) [15595.402916] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15597.351922] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [15602.092627] Lustre: Unmounted lustre-client [15683.814323] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15689.926643] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15713.259696] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15724.615476] Lustre: Mounted lustre-client [15727.296494] Lustre: Unmounted lustre-client [15757.393062] Key type lgssc unregistered [15757.871264] LNet: 301558:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15757.877368] LNetError: 301558:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15758.950891] LNet: Removed LNI 192.168.201.9@tcp [15759.801184] Key type .llcrypt unregistered [15759.805366] Key type ._llcrypt unregistered [15777.468402] Key type ._llcrypt registered [15777.473617] Key type .llcrypt registered [15778.734756] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 05:12:23 (1788167543) [15783.265259] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15783.287356] alg: No test for adler32 (adler32-zlib) [15784.461273] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [15784.743902] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [15786.416211] Key type lgssc registered [15787.642665] Lustre: Echo OBD driver; http://www.lustre.org/ [15838.242071] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15844.414762] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15864.953567] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15867.801429] Lustre: Mounted lustre-client [15878.261412] Lustre: Unmounted lustre-client [15952.682366] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15959.921969] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15980.639282] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15981.253864] Lustre: Mounted lustre-client [15990.138175] Lustre: Unmounted lustre-client [16068.295423] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16075.409587] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16099.194739] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16110.187743] Lustre: Mounted lustre-client [16113.277180] Lustre: Unmounted lustre-client [16145.921789] Key type lgssc unregistered [16146.467255] LNet: 308605:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16146.476647] LNetError: 308605:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16146.520798] LNet: Removed LNI 192.168.201.9@tcp [16147.233369] Key type .llcrypt unregistered [16147.241925] Key type ._llcrypt unregistered [16173.307820] Key type ._llcrypt registered [16173.309765] Key type .llcrypt registered [16174.742975] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 05:18:59 (1788167939) [16178.628558] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16178.720509] alg: No test for adler32 (adler32-zlib) [16180.104599] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [16180.476740] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [16182.144356] Key type lgssc registered [16184.133848] Lustre: Echo OBD driver; http://www.lustre.org/ [16236.247266] Key type lgssc unregistered [16236.551921] LNet: 310928:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16236.558588] LNetError: 310928:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16236.580229] LNet: Removed LNI 192.168.201.9@tcp [16237.601719] Key type .llcrypt unregistered [16237.603903] Key type ._llcrypt unregistered [16268.377694] Key type ._llcrypt registered [16268.383727] Key type .llcrypt registered [16269.043709] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [16269.067671] alg: No test for adler32 (adler32-zlib) [16270.279330] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [16270.607100] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [16272.385272] Key type lgssc registered [16273.928704] Lustre: Echo OBD driver; http://www.lustre.org/ [16373.770448] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16381.218718] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16408.374773] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16464.183766] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [16466.637564] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 05:23:50 (1788168230) [16505.759518] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16513.487765] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16537.504755] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16567.775684] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16569.897405] Lustre: Mounted lustre-client [16575.000341] Lustre: lustre-OST0001-osc-ffff9e2e08ecd000: Connection to lustre-OST0001 (at 192.168.201.109@tcp) was lost; in progress operations using this service will wait for recovery to complete [16586.629757] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [16597.038969] Lustre: Unmounted lustre-client [16634.505458] Key type lgssc unregistered [16634.822208] LNet: 318050:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16634.833372] LNetError: 318050:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16634.854871] LNet: Removed LNI 192.168.201.9@tcp [16635.841922] Key type .llcrypt unregistered [16635.845041] Key type ._llcrypt unregistered [16672.033482] Key type ._llcrypt registered [16672.039326] Key type .llcrypt registered [16673.481833] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 05:27:18 (1788168438) [16688.781767] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16688.853984] alg: No test for adler32 (adler32-zlib) [16690.047690] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [16690.372436] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [16692.128423] Key type lgssc registered [16693.712350] Lustre: Echo OBD driver; http://www.lustre.org/ [16753.341275] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16760.662898] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16784.819741] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 05:29:09 (1788168549) [16819.007175] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16825.652553] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16847.983803] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16850.963561] Lustre: Mounted lustre-client [16857.261775] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [16870.171609] Lustre: Unmounted lustre-client [16978.329163] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16984.162804] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [17040.082808] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [17041.054462] Lustre: Mounted lustre-client [17043.434275] Lustre: Unmounted lustre-client [17066.449622] Key type lgssc unregistered [17066.727875] LNet: 325765:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [17066.748509] LNetError: 325765:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [17066.792879] LNet: Removed LNI 192.168.201.9@tcp [17067.486480] Key type .llcrypt unregistered [17067.490839] Key type ._llcrypt unregistered [17100.147416] Key type ._llcrypt registered [17100.149477] Key type .llcrypt registered [17100.718346] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [17100.744271] alg: No test for adler32 (adler32-zlib) [17101.894288] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [17102.170801] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [17103.904183] Key type lgssc registered [17105.514626] Lustre: Echo OBD driver; http://www.lustre.org/ [17195.320683] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [17202.011821] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [17230.320172] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [17274.816683] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [17276.548588] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 05:37:21 (1788169041) [17311.581847] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [17318.101615] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [17341.762666] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [17347.125730] Lustre: Mounted lustre-client [17350.794453] Lustre: Unmounted lustre-client [17389.312641] Key type lgssc unregistered [17389.598798] LNet: 332401:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [17389.624333] LNetError: 332401:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [17389.647382] LNet: Removed LNI 192.168.201.9@tcp [17390.553257] Key type .llcrypt unregistered [17390.557413] Key type ._llcrypt unregistered [17407.517993] Key type ._llcrypt registered [17407.524225] Key type .llcrypt registered [17408.970086] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [17411.063913] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 05:39:35 (1788169175) [17421.224952] Key type .llcrypt unregistered [17421.227262] Key type ._llcrypt unregistered [17431.216647] Key type ._llcrypt registered [17431.221700] Key type .llcrypt registered [17431.474153] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [17431.497632] alg: No test for adler32 (adler32-zlib) [17432.594610] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [17432.821225] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [17434.506262] Key type lgssc registered [17435.761359] Lustre: Echo OBD driver; http://www.lustre.org/ [17491.379390] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [17505.754854] Lustre: Mounted lustre-client [17507.878637] Lustre: Unmounted lustre-client [17539.067307] Key type lgssc unregistered [17539.378779] LNet: 335854:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [17539.384445] LNetError: 335854:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [17539.406389] LNet: Removed LNI 192.168.201.9@tcp [17540.593166] Key type .llcrypt unregistered [17540.597915] Key type ._llcrypt unregistered [17556.762769] Key type ._llcrypt registered [17556.764510] Key type .llcrypt registered [17558.132158] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 05:42:02 (1788169322) [17560.656433] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [17560.671865] alg: No test for adler32 (adler32-zlib) [17561.782778] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [17562.046067] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [17563.720308] Key type lgssc registered [17565.131100] Lustre: Echo OBD driver; http://www.lustre.org/ [17652.034691] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [17692.165443] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [17701.568996] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [17703.705150] Lustre: Mounted lustre-client [17707.161101] Lustre: Unmounted lustre-client [17735.065351] Key type lgssc unregistered [17735.293764] LNet: 339391:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [17735.302365] LNetError: 339391:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [17735.319240] LNet: Removed LNI 192.168.201.9@tcp [17736.302943] Key type .llcrypt unregistered [17736.305119] Key type ._llcrypt unregistered [17756.046757] Key type ._llcrypt registered [17756.052727] Key type .llcrypt registered [17757.298493] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 05:45:22 (1788169522) [17760.825065] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [17761.011811] alg: No test for adler32 (adler32-zlib) [17762.328912] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [17762.721690] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [17764.483186] Key type lgssc registered [17766.201546] Lustre: Echo OBD driver; http://www.lustre.org/ [17833.857967] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [17839.752115] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [17864.336291] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [17876.602863] Lustre: Mounted lustre-client [17881.593420] Lustre: lustre-MDT0000-mdc-ffff9e2e040d4000: Connection to lustre-MDT0000 (at 192.168.201.109@tcp) was lost; in progress operations using this service will wait for recovery to complete [17888.076742] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [17888.131036] LustreError: 342554:0:(file.c:6154:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [17888.552258] LustreError: MGC192.168.201.109@tcp: Connection to MGS (at 192.168.201.109@tcp) was lost; in progress operations using this service will fail [17888.650328] Lustre: Unmounted lustre-client [17913.307791] Key type lgssc unregistered [17913.642813] LNet: 342967:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [17913.648636] LNetError: 342967:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [17913.673076] LNet: Removed LNI 192.168.201.9@tcp [17914.675177] Key type .llcrypt unregistered [17914.677352] Key type ._llcrypt unregistered [17933.721799] Key type ._llcrypt registered [17933.724688] Key type .llcrypt registered [17935.798631] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 05:48:19 (1788169699) [17938.413734] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [17938.434087] alg: No test for adler32 (adler32-zlib) [17939.510802] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [17939.773122] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [17941.489057] Key type lgssc registered [17942.580691] Lustre: Echo OBD driver; http://www.lustre.org/ [17997.980350] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [18004.640880] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [18030.310856] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [18033.533641] Lustre: Mounted lustre-client [18038.768194] Lustre: lustre-MDT0001-mdc-ffff9e2e10b7e800: Connection to lustre-MDT0001 (at 192.168.201.109@tcp) was lost; in progress operations using this service will wait for recovery to complete [18044.711150] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [18047.197922] Lustre: Unmounted lustre-client [18068.460623] Key type lgssc unregistered [18068.870294] LNet: 346549:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [18068.875925] LNetError: 346549:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [18068.905363] LNet: Removed LNI 192.168.201.9@tcp [18069.886888] Key type .llcrypt unregistered [18069.889403] Key type ._llcrypt unregistered [18088.437542] Key type ._llcrypt registered [18088.439126] Key type .llcrypt registered [18090.371543] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 05:50:54 (1788169854) [18110.326965] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [18110.385148] alg: No test for adler32 (adler32-zlib) [18111.539202] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [18111.751426] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [18113.416384] Key type lgssc registered [18114.822617] Lustre: Echo OBD driver; http://www.lustre.org/ [18234.752541] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [18237.500104] Lustre: Mounted lustre-client [18282.479306] Lustre: lustre-MDT0001-mdc-ffff9e2e10730800: Connection to lustre-MDT0001 (at 192.168.201.109@tcp) was lost; in progress operations using this service will wait for recovery to complete [18294.143706] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [18304.546220] Lustre: Unmounted lustre-client [18331.431454] Key type lgssc unregistered [18331.729763] LNet: 352337:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [18331.740092] LNetError: 352337:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [18331.761112] LNet: Removed LNI 192.168.201.9@tcp [18332.820836] Key type .llcrypt unregistered [18332.823565] Key type ._llcrypt unregistered [18351.706227] Key type ._llcrypt registered [18351.707927] Key type .llcrypt registered [18353.290446] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 05:55:17 (1788170117) [18355.301510] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [18357.458271] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 05:55:22 (1788170122) [18359.744942] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [18361.614975] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 05:55:26 (1788170126) [18363.463618] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [18365.147338] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 05:55:30 (1788170130) [18367.180909] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [18368.939878] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 05:55:33 (1788170133) [18370.850711] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [18372.960272] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 05:55:37 (1788170137) [18382.566483] Key type .llcrypt unregistered [18382.569596] Key type ._llcrypt unregistered [18391.020229] Key type ._llcrypt registered [18391.024704] Key type .llcrypt registered [18391.925601] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [18391.951806] alg: No test for adler32 (adler32-zlib) [18393.522355] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [18394.217566] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [18396.105106] Key type lgssc registered [18397.846835] Lustre: Echo OBD driver; http://www.lustre.org/ [18484.732942] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [18491.166682] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [18514.816632] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [18516.765444] Lustre: Mounted lustre-client [18520.866106] Lustre: Unmounted lustre-client [18560.896161] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 05:58:45 (1788170325) [18600.712428] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [18606.586174] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [18641.328888] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [18647.130928] Lustre: Mounted lustre-client [18649.036190] Lustre: Unmounted lustre-client [18695.328528] LNet: 1 local NIs in recovery (showing 1): 192.168.201.9@tcp [18704.766753] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 06:01:09 (1788170469) [19368.246368] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [19375.922802] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [19415.642792] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [19531.247876] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 06:14:55 (1788171295) [19545.102385] Key type lgssc unregistered [19545.475642] LNet: 365885:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [19545.482584] LNetError: 365885:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [19545.499343] LNet: Removed LNI 192.168.201.9@tcp [19546.001462] Key type .llcrypt unregistered [19546.004607] Key type ._llcrypt unregistered [19665.866323] Key type ._llcrypt registered [19665.875102] Key type .llcrypt registered [19666.140920] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [19666.154944] alg: No test for adler32 (adler32-zlib) [19667.417300] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [19667.750428] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [19669.576811] Key type lgssc registered [19671.003134] Lustre: Echo OBD driver; http://www.lustre.org/ [19769.759292] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [19777.677906] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [19805.868801] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [19844.713325] Key type lgssc unregistered [19844.880664] LNet: 369589:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [19844.892088] LNetError: 369589:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [19844.914995] LNet: Removed LNI 192.168.201.9@tcp [19845.585790] Key type .llcrypt unregistered [19845.587314] Key type ._llcrypt unregistered [19880.440381] Key type ._llcrypt registered [19880.442586] Key type .llcrypt registered [19882.658362] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 06:20:46 (1788171646) [19910.791367] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [19910.828481] alg: No test for adler32 (adler32-zlib) [19912.091803] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [19912.577482] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [19914.331960] Key type lgssc registered [19916.065866] Lustre: Echo OBD driver; http://www.lustre.org/ [19983.889749] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 06:22:28 (1788171748) [19999.819607] Key type lgssc unregistered [20000.217973] LNet: 373166:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [20000.231224] LNetError: 373166:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [20000.253273] LNet: Removed LNI 192.168.201.9@tcp [20001.142937] Key type .llcrypt unregistered [20001.146687] Key type ._llcrypt unregistered [20014.199278] Key type ._llcrypt registered [20014.201117] Key type .llcrypt registered [20014.625376] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [20014.642316] alg: No test for adler32 (adler32-zlib) [20015.828800] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [20016.188218] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [20017.912702] Key type lgssc registered [20019.125561] Lustre: Echo OBD driver; http://www.lustre.org/ [20093.625674] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [20101.238186] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [20126.794729] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [20462.971942] LNet: Added LNI 192.168.201.9@tcp50 [8/256/0/180] [20464.093627] LNet: Removed LNI 192.168.201.9@tcp [20466.219091] Lustre: 377423:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff9e2e10734000: config has no valid NIDs, force dynamic NIDs [20467.426174] Lustre: Mounted lustre-client [20467.646820] Lustre: Unmounted lustre-client [20471.381976] Key type lgssc unregistered [20471.920961] LNet: 377681:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [20471.948093] LNetError: 377681:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [20471.982979] LNet: Removed LNI 192.168.201.9@tcp50 [20472.943726] Key type .llcrypt unregistered [20472.946263] Key type ._llcrypt unregistered [20493.728794] Key type ._llcrypt registered [20493.730368] Key type .llcrypt registered [20494.335522] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [20494.353798] alg: No test for adler32 (adler32-zlib) [20495.606060] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [20496.106945] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [20497.962701] Key type lgssc registered [20500.151257] Lustre: Echo OBD driver; http://www.lustre.org/ [20569.206754] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [20576.311769] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [20590.394488] LNet: Added LNI 192.168.201.9@tcp50 [8/256/0/180] [20592.064678] LNet: Removed LNI 192.168.201.9@tcp [20593.498573] Lustre: 380373:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff9e2e07019000: config has no valid NIDs, force dynamic NIDs [20594.699092] Lustre: Mounted lustre-client [20595.476219] Lustre: Unmounted lustre-client [20632.936448] Key type lgssc unregistered [20633.445879] LNet: 381243:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [20633.453766] LNetError: 381243:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [20633.478725] LNet: Removed LNI 192.168.201.9@tcp50 [20634.570195] Key type .llcrypt unregistered [20634.575470] Key type ._llcrypt unregistered [20646.571307] Key type ._llcrypt registered [20646.582441] Key type .llcrypt registered [20648.170614] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 06:33:32 (1788172412) [20668.354597] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [20668.397333] alg: No test for adler32 (adler32-zlib) [20669.752993] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [20670.071610] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [20671.755038] Key type lgssc registered [20673.485876] Lustre: Echo OBD driver; http://www.lustre.org/ [20764.228449] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [20770.494137] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [20797.888207] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [20834.594542] Key type lgssc unregistered [20834.856883] LNet: 385995:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [20834.865208] LNetError: 385995:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [20834.878042] LNet: Removed LNI 192.168.201.9@tcp [20835.678359] Key type .llcrypt unregistered [20835.684734] Key type ._llcrypt unregistered [20848.874496] Key type ._llcrypt registered [20848.876580] Key type .llcrypt registered [20850.531706] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 06:36:54 (1788172614) [20852.671446] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [20852.837617] alg: No test for adler32 (adler32-zlib) [20854.270931] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [20854.758972] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [20856.553770] Key type lgssc registered [20858.404891] Lustre: Echo OBD driver; http://www.lustre.org/ [20915.432485] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [20923.342103] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [20947.820417] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [20959.819173] Lustre: Mounted lustre-client [21020.330202] Lustre: Mounted fs15246-client [21036.426761] Lustre: Unmounted fs15246-client [21062.672936] Lustre: Unmounted lustre-client [21090.714994] Key type lgssc unregistered [21090.942726] LNet: 390862:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [21090.954063] LNetError: 390862:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [21090.972338] LNet: Removed LNI 192.168.201.9@tcp [21091.712626] Key type .llcrypt unregistered [21091.715229] Key type ._llcrypt unregistered [21101.653688] Key type ._llcrypt registered [21101.655318] Key type .llcrypt registered [21102.970658] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 06:41:07 (1788172867) [21105.071366] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [21105.097082] alg: No test for adler32 (adler32-zlib) [21106.263810] Lustre: Lustre: Build Version: 2.17.55_27_gdd19d92 [21106.564482] LNet: Added LNI 192.168.201.9@tcp [8/256/0/180] [21108.344716] Key type lgssc registered [21109.681733] Lustre: Echo OBD driver; http://www.lustre.org/ [21243.932776] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [21249.047863] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [21272.869148] Lustre: DEBUG MARKER: oleg109-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [21388.029663] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 21098 sec ========= 06:45:52 (1788173152) [21389.933552] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 06:45:54 (1788173154) === [21393.686224] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 06:45:58 (1788173158) === [21428.259264] Key type lgssc unregistered [21428.555677] LNet: 400125:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [21428.560649] LNetError: 400125:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [21428.589598] LNet: Removed LNI 192.168.201.9@tcp [21429.494793] Key type .llcrypt unregistered [21429.496234] Key type ._llcrypt unregistered