[ 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 423847989 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2858424K/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.001011] APIC: Switch to symmetric I/O mode setup [ 0.003311] x2apic enabled [ 0.004007] Switched APIC routing to physical x2apic. [ 0.005012] kvm-guest: setup PV IPIs [ 0.008613] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.009023] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010014] pid_max: default: 32768 minimum: 301 [ 0.011134] LSM: Security Framework initializing [ 0.012052] Yama: becoming mindful. [ 0.013031] SELinux: Initializing. [ 0.014069] *** VALIDATE selinux *** [ 0.023175] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.027792] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.029030] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030113] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031147] *** VALIDATE tmpfs *** [ 0.033350] *** VALIDATE proc *** [ 0.035058] *** VALIDATE cgroup *** [ 0.036008] *** VALIDATE cgroup2 *** [ 0.037284] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.038164] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.039008] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.040028] Spectre V2 : User space: Vulnerable [ 0.041007] Speculative Store Bypass: Vulnerable [ 0.044413] debug: unmapping init [mem 0xffffffffb1459000-0xffffffffb1460fff] [ 0.046137] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.047686] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.048024] ... version: 2 [ 0.049014] ... bit width: 48 [ 0.050013] ... generic registers: 4 [ 0.051011] ... value mask: 0000ffffffffffff [ 0.052013] ... max period: 00007fffffffffff [ 0.053011] ... fixed-purpose events: 3 [ 0.054010] ... event mask: 000000070000000f [ 0.055324] rcu: Hierarchical SRCU implementation. [ 0.057494] smp: Bringing up secondary CPUs ... [ 0.058567] x86: Booting SMP configuration: [ 0.059021] .... node #0, CPUs: #1 #2 #3 [ 0.062453] smp: Brought up 1 node, 4 CPUs [ 0.064013] smpboot: Max logical packages: 1 [ 0.065019] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.139024] node 0 deferred pages initialised in 71ms [ 0.142098] devtmpfs: initialized [ 0.143260] x86/mm: Memory block size: 128MB [ 0.147072] gcov: version magic: 0x41383552 [ 0.149288] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.153102] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.155408] pinctrl core: initialized pinctrl subsystem [ 0.157150] [ 0.157686] ************************************************************* [ 0.160014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.163012] ** ** [ 0.165010] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.167009] ** ** [ 0.170013] ** This means that this kernel is built to expose internal ** [ 0.172008] ** IOMMU data structures, which may compromise security on ** [ 0.174012] ** your system. ** [ 0.177014] ** ** [ 0.179009] ** If you see this message and you are not debugging the ** [ 0.181009] ** kernel, report this immediately to your vendor! ** [ 0.183007] ** ** [ 0.186061] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.188008] ************************************************************* [ 0.190839] NET: Registered protocol family 16 [ 0.192457] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.195053] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.198058] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.200530] cpuidle: using governor menu [ 0.201514] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.204374] PCI: Using configuration type 1 for base access [ 0.206130] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.216115] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.217035] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.219129] cryptd: max_cpu_qlen set to 1000 [ 0.221238] ACPI: Added _OSI(Module Device) [ 0.222007] ACPI: Added _OSI(Processor Device) [ 0.223008] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.225028] ACPI: Added _OSI(Processor Aggregator Device) [ 0.230728] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.235378] ACPI: Interpreter enabled [ 0.236000] ACPI: PM: (supports S0 S3 S4 S5) [ 0.239012] ACPI: Using IOAPIC for interrupt routing [ 0.240101] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.244404] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.255282] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.257039] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.260027] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.264080] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.269439] acpiphp: Slot [2] registered [ 0.271106] acpiphp: Slot [5] registered [ 0.273112] acpiphp: Slot [6] registered [ 0.274102] acpiphp: Slot [3] registered [ 0.276121] acpiphp: Slot [4] registered [ 0.278122] acpiphp: Slot [7] registered [ 0.279139] acpiphp: Slot [8] registered [ 0.281111] acpiphp: Slot [9] registered [ 0.283128] acpiphp: Slot [10] registered [ 0.284101] acpiphp: Slot [11] registered [ 0.286108] acpiphp: Slot [12] registered [ 0.288100] acpiphp: Slot [13] registered [ 0.289070] acpiphp: Slot [14] registered [ 0.290030] acpiphp: Slot [15] registered [ 0.290924] acpiphp: Slot [16] registered [ 0.292049] acpiphp: Slot [17] registered [ 0.292910] acpiphp: Slot [18] registered [ 0.294078] acpiphp: Slot [19] registered [ 0.295098] acpiphp: Slot [20] registered [ 0.297089] acpiphp: Slot [21] registered [ 0.298083] acpiphp: Slot [22] registered [ 0.300058] acpiphp: Slot [23] registered [ 0.301087] acpiphp: Slot [24] registered [ 0.302095] acpiphp: Slot [25] registered [ 0.303066] acpiphp: Slot [26] registered [ 0.304033] acpiphp: Slot [27] registered [ 0.304953] acpiphp: Slot [28] registered [ 0.306064] acpiphp: Slot [29] registered [ 0.307095] acpiphp: Slot [30] registered [ 0.308076] acpiphp: Slot [31] registered [ 0.309041] PCI host bridge to bus 0000:00 [ 0.310024] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.312024] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.315028] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.316017] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.318014] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.320017] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.321133] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.322685] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.324900] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.331011] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.334009] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.335027] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.336010] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.339014] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.341483] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.343815] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.346040] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.350844] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.354025] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.361836] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.366014] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.370875] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.376017] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.382018] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.395013] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.404841] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.413017] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.422018] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.436017] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.448577] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.451367] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.454328] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.456272] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.458217] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.462147] iommu: Default domain type: Passthrough [ 0.465558] SCSI subsystem initialized [ 0.467106] ACPI: bus type USB registered [ 0.468125] usbcore: registered new interface driver usbfs [ 0.470075] usbcore: registered new interface driver hub [ 0.472072] usbcore: registered new device driver usb [ 0.474176] pps_core: LinuxPPS API ver. 1 registered [ 0.476011] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.479060] PTP clock support registered [ 0.481040] EDAC MC: Ver: 3.0.0 [ 0.483120] PCI: Using ACPI for IRQ routing [ 0.484713] NetLabel: Initializing [ 0.486009] NetLabel: domain hash size = 128 [ 0.488011] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.489096] NetLabel: unlabeled traffic allowed by default [ 0.491104] vgaarb: loaded [ 0.493169] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.494007] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.498536] clocksource: Switched to clocksource kvm-clock [ 0.598038] VFS: Disk quotas dquot_6.6.0 [ 0.599731] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.602351] *** VALIDATE ramfs *** [ 0.603638] *** VALIDATE hugetlbfs *** [ 0.605129] pnp: PnP ACPI init [ 0.607627] pnp: PnP ACPI: found 6 devices [ 0.624635] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.627036] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.628903] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.630271] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.631796] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.633426] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.635247] NET: Registered protocol family 2 [ 0.637142] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.640677] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.643471] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.648929] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.651976] TCP: Hash tables configured (established 65536 bind 65536) [ 0.654756] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.657929] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.660735] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.663829] NET: Registered protocol family 1 [ 0.666432] RPC: Registered named UNIX socket transport module. [ 0.668888] RPC: Registered udp transport module. [ 0.670719] RPC: Registered tcp transport module. [ 0.672479] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.675025] NET: Registered protocol family 44 [ 0.676635] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.677982] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.679300] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.681144] PCI: CLS 0 bytes, default 64 [ 0.683447] Unpacking initramfs... [ 2.052339] debug: unmapping init [mem 0xffff91167cc64000-0xffff91167ffcffff] [ 2.054771] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.056535] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.059314] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.541221] Initialise system trusted keyrings [ 2.542659] Key type blacklist registered [ 2.545310] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.554142] zbud: loaded [ 2.557302] *** VALIDATE nfs *** [ 2.558469] *** VALIDATE nfs4 *** [ 2.559976] pstore: using deflate compression [ 2.563397] Platform Keyring initialized [ 2.654731] NET: Registered protocol family 38 [ 2.655986] Key type asymmetric registered [ 2.656908] Asymmetric key parser 'x509' registered [ 2.658022] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.659890] io scheduler mq-deadline registered [ 2.660894] io scheduler kyber registered [ 2.661953] io scheduler bfq registered [ 2.663240] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.665057] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.667142] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.668917] ACPI: Power Button [PWRF] [ 2.672245] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.677225] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.684516] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.709099] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.735481] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.741246] Non-volatile memory driver v1.3 [ 2.743127] Linux agpgart interface v0.103 [ 2.778807] virtio_blk virtio1: [vda] 146168 512-byte logical blocks (74.8 MB/71.4 MiB) [ 2.781988] vda: detected capacity change from 0 to 74838016 [ 2.796692] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.799411] vdb: detected capacity change from 0 to 1073741824 [ 2.816979] libphy: Fixed MDIO Bus: probed [ 2.827644] usbcore: registered new interface driver usbserial_generic [ 2.830480] usbserial: USB Serial support registered for generic [ 2.832633] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.837105] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.838205] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.839915] mousedev: PS/2 mouse device common for all mice [ 2.842094] rtc_cmos 00:05: RTC can wake from S4 [ 2.844923] rtc_cmos 00:05: registered as rtc0 [ 2.845273] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.846515] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.852053] intel_pstate: CPU model not supported [ 2.856201] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.859752] hid: raw HID events driver (C) Jiri Kosina [ 2.862256] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.862262] usbcore: registered new interface driver usbhid [ 2.867012] usbhid: USB HID core driver [ 2.868558] drop_monitor: Initializing network drop monitor service [ 2.870945] Initializing XFRM netlink socket [ 2.872909] NET: Registered protocol family 10 [ 2.875664] Segment Routing with IPv6 [ 2.876681] NET: Registered protocol family 17 [ 2.878157] mpls_gso: MPLS GSO support [ 2.883727] RAS: Correctable Errors collector initialized. [ 2.885467] AVX version of gcm_enc/dec engaged. [ 2.886746] AES CTR mode by8 optimization enabled [ 2.966864] sched_clock: Marking stable (2966843644, 0)->(3829011312, -862167668) [ 2.970362] registered taskstats version 1 [ 2.972473] Loading compiled-in X.509 certificates [ 2.973925] zswap: loaded using pool lzo/zbud [ 3.003687] Key type big_key registered [ 3.019653] Key type encrypted registered [ 3.021360] ima: No TPM chip found, activating TPM-bypass! [ 3.023477] ima: Allocated hash algorithm: sha1 [ 3.025345] ima: No architecture policies found [ 3.027275] evm: Initialising EVM extended attributes: [ 3.029468] evm: security.selinux [ 3.030771] evm: security.ima [ 3.031911] evm: security.capability [ 3.033082] evm: HMAC attrs: 0x1 [ 3.035431] rtc_cmos 00:05: setting system clock to 2026-08-24 07:19:38 UTC (1787555978) [ 3.040939] debug: unmapping init [mem 0xffffffffb2403000-0xffffffffb25fffff] [ 3.043495] debug: unmapping init [mem 0xffffffffb1182000-0xffffffffb1458fff] [ 3.052136] Write protecting the kernel read-only data: 28672k [ 3.054886] debug: unmapping init [mem 0xffffffffaf803000-0xffffffffaf9fffff] [ 3.057050] debug: unmapping init [mem 0xffffffffb0114000-0xffffffffb01fffff] [ 3.086484] 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.095674] systemd[1]: Detected virtualization kvm. [ 3.097586] systemd[1]: Detected architecture x86-64. [ 3.099549] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.125590] systemd[1]: No hostname configured. [ 3.127057] systemd[1]: Set hostname to . [ 3.128590] random: systemd: uninitialized urandom read (16 bytes read) [ 3.130659] systemd[1]: Initializing machine ID from random generator. [ 3.181178] random: ln: uninitialized urandom read (6 bytes read) [ 3.278399] random: systemd: uninitialized urandom read (16 bytes read) [ 3.280135] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 3.283455] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ 3.288118] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ OK ] Listening on udev Control Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Swap. [ OK ] Listening on Journal Socket. [ OK ] Started Memstrack Anylazing Service. Starting Setup Virtual Console... [ OK ] Listening on Journal Socket (/dev/log). Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... Starting Journal Service... [ OK ] Reached target Paths. Starting Apply Kernel Variables... [ OK ] Reached target Sockets. [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 3.988123] device-mapper: uevent: version 1.0.3 [ 3.990411] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 4.844718] virtio_net virtio0 ens2: renamed from eth0 [ 4.924873] scsi host0: ata_piix [ 4.956771] scsi host1: ata_piix [ 4.958422] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.961208] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.690101] dracut-initqueue[588]: RTNETLINK answers: File exists [ 9.538189] random: crng init done [ 9.540918] 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.132990] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Apply Kernel Variables. Stopping udev Kernel Device Manager... [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped 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.330776] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.597690] SELinux: Disabled at runtime. [ 11.652870] 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.658948] systemd[1]: Detected virtualization kvm. [ 11.661427] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.129891] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.133469] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.136833] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.141265] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.143780] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.149936] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.156650] systemd[1]: Mounting Huge Pages File System... Mounting Huge Pages File System... [ OK ] Created slice system-getty.slice. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Listening on Process Core Dump Socket. Starting Remount Root and Kernel File Systems... Mounting POSIX Message Queue File System... [ OK ] Created slice system-sshd\x2dkeygen.slice. Mounting Kernel Debug File System... Activating swap /dev/disk/by-label/SWAP... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target rpc_pipefs.target. [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 slic[ 12.270742] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS e User and Session Slice. [ OK ] Reached target Slices. [ OK ] Listening on udev Kernel Socket. [ OK ] Stopped target Initrd Root File System. Starting Apply Kernel Variables... [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Started Journal Service. [ 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. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Kernel Debug File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Apply Kernel Variables. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 12.603465] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 12.859133] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 12.906658] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 12.978457] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 12.992283] EDAC sbridge: Ver: 1.1.2 [ 14.080176] Key type dns_resolver registered [ 14.404135] NFS: Registering the id_resolver key type [ 14.405747] Key type id_resolver registered [ 14.406991] 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 RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started irqbalance daemon. Starting Login Service... Starting Restore /run/initramfs on shutdown... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Reached target sshd-keygen.target. [ 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 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 OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS1. [ 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 Crash recovery kernel arming... Starting Notify NFS peers of a restart... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting 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 Authorization Manager. [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg223-client login: [ 57.223103] hrtimer: interrupt took 8611880 ns [ 58.181495] libcfs: loading out-of-tree module taints kernel. [ 58.285458] Key type ._llcrypt registered [ 58.288529] Key type .llcrypt registered [ 58.548698] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 58.558239] alg: No test for adler32 (adler32-zlib) [ 60.060333] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [ 60.811220] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [ 62.575538] Key type lgssc registered [ 64.637515] Lustre: Echo OBD driver; http://www.lustre.org/ [ 250.619261] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 255.553843] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 269.141567] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing check_logdir /tmp/testlogs/ [ 276.061812] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing yml_node [ 276.447219] Lustre: lustre-OST0000-osc-ffff9116cad7e800: disconnect after 23s idle [ 282.548865] Lustre: DEBUG MARKER: Client: 2.17.57.82 [ 285.977293] Lustre: DEBUG MARKER: MDS: 2.17.57.82 [ 289.306920] Lustre: DEBUG MARKER: OSS: 2.17.57.82 [ 290.902747] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Mon Aug 24 03:24:24 EDT 2026 [ 315.133851] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 316.619305] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 320.296788] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 330.703387] Lustre: Unmounted lustre-client [ 453.224436] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 460.426496] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 488.738979] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 539.843973] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 03:28:33 (1787556513) [ 542.991677] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 544.744840] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 03:28:38 (1787556518) [ 589.627581] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 597.178223] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 620.296956] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 626.262349] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 630.887792] Lustre: Unmounted lustre-client [ 673.639947] Key type lgssc unregistered [ 674.088716] LNet: 15055:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 674.098522] LNetError: 15055:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 674.150792] LNet: Removed LNI 192.168.202.23@tcp [ 675.816273] Key type .llcrypt unregistered [ 675.819252] Key type ._llcrypt unregistered [ 696.605406] Key type ._llcrypt registered [ 696.608349] Key type .llcrypt registered [ 697.745206] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 03:31:11 (1787556671) [ 700.071859] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 700.080239] alg: No test for adler32 (adler32-zlib) [ 701.194610] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [ 701.410834] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [ 703.120707] Key type lgssc registered [ 705.055363] Lustre: Echo OBD driver; http://www.lustre.org/ [ 795.585127] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 887.088853] Lustre: Unmounted lustre-client [ 914.343600] Key type lgssc unregistered [ 914.599843] LNet: 18858:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 914.608553] LNetError: 18858:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 914.630303] LNet: Removed LNI 192.168.202.23@tcp [ 915.653183] Key type .llcrypt unregistered [ 915.656594] Key type ._llcrypt unregistered [ 926.495394] Key type ._llcrypt registered [ 926.497125] Key type .llcrypt registered [ 928.171732] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 03:35:01 (1787556901) [ 930.866440] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 930.893392] alg: No test for adler32 (adler32-zlib) [ 932.008979] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [ 932.274935] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [ 933.975303] Key type lgssc registered [ 934.840735] Lustre: Echo OBD driver; http://www.lustre.org/ [ 991.846727] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 997.256243] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1023.570694] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1026.229503] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 1041.307863] Lustre: Unmounted lustre-client [ 1072.231674] Key type lgssc unregistered [ 1072.596174] LNet: 22470:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1072.612550] LNetError: 22470:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1072.647727] LNet: Removed LNI 192.168.202.23@tcp [ 1073.628654] Key type .llcrypt unregistered [ 1073.631200] Key type ._llcrypt unregistered [ 1085.406224] Key type ._llcrypt registered [ 1085.407596] Key type .llcrypt registered [ 1087.061488] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 03:37:40 (1787557060) [ 1090.011223] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1090.137498] alg: No test for adler32 (adler32-zlib) [ 1091.544527] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [ 1091.996379] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [ 1093.736704] Key type lgssc registered [ 1095.325452] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1153.244811] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1160.725803] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1187.041585] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1188.916593] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 1196.861188] Lustre: Unmounted lustre-client [ 1226.109285] Key type lgssc unregistered [ 1226.423458] LNet: 25667:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1226.432745] LNetError: 25667:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1226.453366] LNet: Removed LNI 192.168.202.23@tcp [ 1227.329630] Key type .llcrypt unregistered [ 1227.336106] Key type ._llcrypt unregistered [ 1244.429477] Key type ._llcrypt registered [ 1244.438726] Key type .llcrypt registered [ 1246.201620] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 03:40:19 (1787557219) [ 1249.168655] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1249.224404] alg: No test for adler32 (adler32-zlib) [ 1250.381715] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [ 1250.644360] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [ 1252.319262] Key type lgssc registered [ 1253.669885] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1308.736468] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1317.010769] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1338.263716] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1350.262149] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 1355.249595] Lustre: lustre-MDT0000-mdc-ffff9116cad7d000: Connection to lustre-MDT0000 (at 192.168.202.123@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1365.480271] Lustre: lustre-MDT0001-mdc-ffff9116cad7d000: Connection to lustre-MDT0001 (at 192.168.202.123@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1369.531641] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1375.712607] Lustre: 26626:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787557335/real 1787557335] req@0000000086b2e25c x1874389605816448/t0(0) o400->MGC192.168.202.123@tcp@192.168.202.123@tcp:26/25 lens 224/224 e 0 to 1 dl 1787557351 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1375.742930] LustreError: MGC192.168.202.123@tcp: Connection to MGS (at 192.168.202.123@tcp) was lost; in progress operations using this service will fail [ 1379.846502] Lustre: Unmounted lustre-client [ 1400.222064] Key type lgssc unregistered [ 1400.469224] LNet: 29332:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1400.475627] LNetError: 29332:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1400.485295] LNet: Removed LNI 192.168.202.23@tcp [ 1401.045225] Key type .llcrypt unregistered [ 1401.048647] Key type ._llcrypt unregistered [ 1416.474482] Key type ._llcrypt registered [ 1416.476242] Key type .llcrypt registered [ 1417.820089] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 03:43:11 (1787557391) [ 1419.911695] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1420.103472] alg: No test for adler32 (adler32-zlib) [ 1421.280596] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [ 1421.519313] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [ 1423.167564] Key type lgssc registered [ 1424.823994] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1490.909343] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1497.319316] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1519.116976] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1525.308609] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 1530.352793] Lustre: lustre-OST0000-osc-ffff9116c325d000: Connection to lustre-OST0000 (at 192.168.202.123@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1538.454436] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1548.848404] Lustre: Unmounted lustre-client [ 1581.905709] Key type lgssc unregistered [ 1582.406811] LNet: 32950:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1582.419696] LNetError: 32950:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1582.441556] LNet: Removed LNI 192.168.202.23@tcp [ 1583.525255] Key type .llcrypt unregistered [ 1583.540302] Key type ._llcrypt unregistered [ 1601.885632] Key type ._llcrypt registered [ 1601.888236] Key type .llcrypt registered [ 1603.327933] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 03:46:17 (1787557577) [ 1606.213365] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1606.297105] alg: No test for adler32 (adler32-zlib) [ 1607.292771] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [ 1607.627085] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [ 1609.351307] Key type lgssc registered [ 1611.153516] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1675.561852] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1683.311717] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1711.183814] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1714.342290] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 1718.653450] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1723.401518] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1735.214856] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-08-24 3h48m29s [ 1737.384354] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1739.748612] Lustre: lustre-MDT0000-mdc-ffff9116d019b000: Connection to lustre-MDT0000 (at 192.168.202.123@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1747.869504] LustreError: 36747:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff9116d019b000: can't stat MDS #0: rc = -19 [ 1749.759701] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1757.158579] LustreError: MGC192.168.202.123@tcp: Connection to MGS (at 192.168.202.123@tcp) was lost; in progress operations using this service will fail [ 1757.183335] Lustre: Evicted from MGS (at 192.168.202.123@tcp) after server handle changed from 0x9350e9cdda7781e0 to 0x9350e9cdda778487 [ 1757.193252] Lustre: MGC192.168.202.123@tcp: Connection restored to 192.168.202.123@tcp (at 192.168.202.123@tcp) [ 1758.258617] LustreError: lustre-MDT0000-mdc-ffff9116d019b000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1758.295526] Lustre: lustre-MDT0000-mdc-ffff9116d019b000: Connection restored to 192.168.202.123@tcp (at 192.168.202.123@tcp) [ 1765.737878] Lustre: DEBUG MARKER: Wait for df (36747) ... [ 1767.285978] Lustre: DEBUG MARKER: done [ 1770.088170] Lustre: Unmounted lustre-client [ 1800.441341] Key type lgssc unregistered [ 1800.890338] LNet: 37765:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1800.898972] LNetError: 37765:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1800.922833] LNet: Removed LNI 192.168.202.23@tcp [ 1801.726414] Key type .llcrypt unregistered [ 1801.729116] Key type ._llcrypt unregistered [ 1844.233273] Key type ._llcrypt registered [ 1844.239835] Key type .llcrypt registered [ 1845.885337] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 03:50:19 (1787557819) [ 1849.078236] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1849.133692] alg: No test for adler32 (adler32-zlib) [ 1850.519957] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [ 1851.116562] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [ 1852.959228] Key type lgssc registered [ 1853.996535] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1918.232839] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1925.111861] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1948.180150] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1951.093343] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 1955.864528] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-08-24 3h52m09s [ 1957.924112] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1967.462384] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1972.544738] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1992.671414] Lustre: 41909:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787557948/real 1787557948] req@00000000159966f1 x1874390234963200/t0(0) o101->lustre-MDT0000-mdc-ffff9116c4479000@192.168.202.123@tcp:12/10 lens 576/1152 e 0 to 1 dl 1787557968 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1992.706807] Lustre: lustre-MDT0000-mdc-ffff9116c4479000: Connection to lustre-MDT0000 (at 192.168.202.123@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1992.751123] Lustre: lustre-MDT0000-mdc-ffff9116c4479000: Connection restored to 192.168.202.123@tcp (at 192.168.202.123@tcp) [ 2014.823569] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 2018.786949] Lustre: DEBUG MARKER: done [ 2023.966936] Lustre: Unmounted lustre-client [ 2061.815327] Key type lgssc unregistered [ 2062.121521] LNet: 42800:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2062.130534] LNetError: 42800:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2062.168890] LNet: Removed LNI 192.168.202.23@tcp [ 2063.169518] Key type .llcrypt unregistered [ 2063.173677] Key type ._llcrypt unregistered [ 2101.248934] Key type ._llcrypt registered [ 2101.255219] Key type .llcrypt registered [ 2102.863522] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 03:54:36 (1787558076) [ 2104.636944] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 2106.209142] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 03:54:40 (1787558080) [ 2110.942081] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2110.958711] alg: No test for adler32 (adler32-zlib) [ 2112.223131] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [ 2112.658673] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [ 2114.416365] Key type lgssc registered [ 2116.000597] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2146.089720] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 03:55:20 (1787558120) [ 2184.137768] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2190.434484] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2213.681503] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2215.428915] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 2218.478731] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 2223.091653] Lustre: Unmounted lustre-client [ 2242.928843] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 2290.370440] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2297.658952] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2298.294209] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 2302.700273] Lustre: Unmounted lustre-client [ 2367.250521] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2375.634035] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2376.546552] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 2381.355742] Lustre: Unmounted lustre-client [ 2410.105496] Lustre: DEBUG MARKER: files compared the same [ 2437.554603] Key type lgssc unregistered [ 2437.965782] LNet: 50424:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2437.984858] LNetError: 50424:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2438.020537] LNet: Removed LNI 192.168.202.23@tcp [ 2439.157459] Key type .llcrypt unregistered [ 2439.158854] Key type ._llcrypt unregistered [ 2456.215599] Key type ._llcrypt registered [ 2456.217356] Key type .llcrypt registered [ 2457.719497] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 04:00:31 (1787558431) [ 2460.249782] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2460.299502] alg: No test for adler32 (adler32-zlib) [ 2461.347114] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [ 2461.581675] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [ 2463.304352] Key type lgssc registered [ 2464.452573] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2514.295970] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2521.942520] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2542.981400] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2544.659661] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 2551.942377] Lustre: Unmounted lustre-client [ 2587.961504] Key type lgssc unregistered [ 2588.216034] LNet: 54025:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2588.221602] LNetError: 54025:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2588.236317] LNet: Removed LNI 192.168.202.23@tcp [ 2588.879442] Key type .llcrypt unregistered [ 2588.882938] Key type ._llcrypt unregistered [ 2607.756863] Key type ._llcrypt registered [ 2607.760184] Key type .llcrypt registered [ 2609.655926] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 04:03:03 (1787558583) [ 2613.430104] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2613.491150] alg: No test for adler32 (adler32-zlib) [ 2614.953699] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [ 2615.519333] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [ 2617.311781] Key type lgssc registered [ 2619.623276] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2707.976184] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2747.884798] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2759.764178] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2790.650394] Key type lgssc unregistered [ 2790.888830] LNet: 57516:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2790.899863] LNetError: 57516:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2790.909384] LNet: Removed LNI 192.168.202.23@tcp [ 2791.712952] Key type .llcrypt unregistered [ 2791.718261] Key type ._llcrypt unregistered [ 2808.437279] Key type ._llcrypt registered [ 2808.442185] Key type .llcrypt registered [ 2809.877693] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 04:06:23 (1787558783) [ 2814.453585] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2814.574375] alg: No test for adler32 (adler32-zlib) [ 2815.860860] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [ 2816.247400] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [ 2817.959351] Key type lgssc registered [ 2819.376454] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2894.685617] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 2902.255279] Lustre: Unmounted lustre-client [ 2944.418606] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 04:08:38 (1787558918) [ 3075.151639] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3099.234168] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 3107.228196] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3107.238325] LustreError: 63993:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff9116d2e7c800: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 3107.256611] Lustre: 58502:0:(llite_lib.c:4342:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.202.123@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 3117.630376] Lustre: Unmounted lustre-client [ 3164.433357] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 04:12:18 (1787559138) [ 3176.618521] Key type lgssc unregistered [ 3176.874555] LNet: 65206:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3176.882759] LNetError: 65206:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3176.910771] LNet: Removed LNI 192.168.202.23@tcp [ 3177.705769] Key type .llcrypt unregistered [ 3177.709833] Key type ._llcrypt unregistered [ 3190.713672] Key type ._llcrypt registered [ 3190.726526] Key type .llcrypt registered [ 3191.506259] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3191.522493] alg: No test for adler32 (adler32-zlib) [ 3192.750051] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [ 3193.009746] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [ 3194.698731] Key type lgssc registered [ 3195.978988] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3251.067394] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3256.916332] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3282.079803] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3284.289986] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 3291.882609] Lustre: Unmounted lustre-client [ 3331.146727] Key type lgssc unregistered [ 3331.429670] LNet: 68225:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3331.433822] LNetError: 68225:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3331.451660] LNet: Removed LNI 192.168.202.23@tcp [ 3332.141122] Key type .llcrypt unregistered [ 3332.143773] Key type ._llcrypt unregistered [ 3344.689092] Key type ._llcrypt registered [ 3344.690533] Key type .llcrypt registered [ 3345.120783] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3345.151163] alg: No test for adler32 (adler32-zlib) [ 3346.394096] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [ 3346.828938] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [ 3348.625432] Key type lgssc registered [ 3350.523965] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3473.398187] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3479.381150] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3505.710347] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3507.621212] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 3511.563838] Lustre: Unmounted lustre-client [ 3539.519600] Key type lgssc unregistered [ 3539.844713] LNet: 71660:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3539.854647] LNetError: 71660:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3539.872323] LNet: Removed LNI 192.168.202.23@tcp [ 3540.812382] Key type .llcrypt unregistered [ 3540.814134] Key type ._llcrypt unregistered [ 3556.411854] Key type ._llcrypt registered [ 3556.414478] Key type .llcrypt registered [ 3557.862183] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 04:18:51 (1787559531) [ 3561.053554] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3561.118112] alg: No test for adler32 (adler32-zlib) [ 3562.362644] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [ 3562.656295] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [ 3564.343244] Key type lgssc registered [ 3565.793769] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3626.292342] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3632.835723] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3659.641655] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3666.507503] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 3672.173079] Lustre: Unmounted lustre-client [ 3672.702615] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 3675.766802] Lustre: Unmounted lustre-client [ 3711.307536] Key type lgssc unregistered [ 3711.611507] LNet: 75344:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3711.631778] LNetError: 75344:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3711.686567] LNet: Removed LNI 192.168.202.23@tcp [ 3712.407229] Key type .llcrypt unregistered [ 3712.412881] Key type ._llcrypt unregistered [ 3725.405736] Key type ._llcrypt registered [ 3725.416679] Key type .llcrypt registered [ 3725.708749] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3725.738622] alg: No test for adler32 (adler32-zlib) [ 3726.755847] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [ 3726.945871] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [ 3728.567223] Key type lgssc registered [ 3729.791518] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3787.082312] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3792.839364] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3813.875520] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3816.613274] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 3821.980660] Lustre: Unmounted lustre-client [ 3854.112733] Key type lgssc unregistered [ 3854.335949] LNet: 78383:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3854.341072] LNetError: 78383:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3855.421302] LNet: Removed LNI 192.168.202.23@tcp [ 3856.505492] Key type .llcrypt unregistered [ 3856.510921] Key type ._llcrypt unregistered [ 3873.628738] Key type ._llcrypt registered [ 3873.633444] Key type .llcrypt registered [ 3875.489754] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 04:24:08 (1787559848) [ 3880.013389] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3880.033433] alg: No test for adler32 (adler32-zlib) [ 3881.257749] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [ 3881.716973] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [ 3883.535434] Key type lgssc registered [ 3885.508913] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3936.456190] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3942.964441] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3970.057062] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3972.972639] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 3985.547843] Lustre: lustre: root_squash is set to 0:0 [ 3985.680250] Lustre: lustre: nosquash_nids is cleared [ 3992.068382] Lustre: lustre: root_squash is set to 500:500 [ 3998.643585] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.202.23@tcp 192.168.0.[2,10]@tcp [ 4008.438797] Lustre: lustre: nosquash_nids set to 192.168.202.23/24@tcp [ 4017.727296] Lustre: Unmounted lustre-client [ 4049.254885] Key type lgssc unregistered [ 4049.567482] LNet: 82328:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4049.580615] LNetError: 82328:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4049.605934] LNet: Removed LNI 192.168.202.23@tcp [ 4050.574478] Key type .llcrypt unregistered [ 4050.587196] Key type ._llcrypt unregistered [ 4072.477969] Key type ._llcrypt registered [ 4072.481917] Key type .llcrypt registered [ 4074.154835] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 04:27:27 (1787560047) [ 4076.808722] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 4078.616181] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 04:27:32 (1787560052) [ 4082.287136] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4082.306676] alg: No test for adler32 (adler32-zlib) [ 4083.407110] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [ 4083.625335] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [ 4085.359206] Key type lgssc registered [ 4087.361170] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4147.655755] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4154.613700] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4176.555684] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4177.611406] Lustre: lustre: root_squash is set to 500:500 [ 4177.645562] Lustre: lustre: nosquash_nids set to 192.168.202.23/24@tcp [ 4183.185129] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 4191.111580] Lustre: Unmounted lustre-client [ 4220.044960] Key type lgssc unregistered [ 4220.333975] LNet: 86288:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4220.341888] LNetError: 86288:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4220.353968] LNet: Removed LNI 192.168.202.23@tcp [ 4220.914960] Key type .llcrypt unregistered [ 4220.917377] Key type ._llcrypt unregistered [ 4234.789211] Key type ._llcrypt registered [ 4234.793850] Key type .llcrypt registered [ 4236.353378] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 4237.852149] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 04:30:12 (1787560212) [ 4261.680341] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4261.732901] alg: No test for adler32 (adler32-zlib) [ 4263.504424] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [ 4264.234944] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [ 4266.224624] Key type lgssc registered [ 4268.280552] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4372.152234] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4380.254974] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4409.236512] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4478.348376] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4484.934239] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4508.311593] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4520.509522] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 4560.263938] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9116cb32c000.ost_server_uuid 50 [ 4562.027107] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9116cb32c000.ost_server_uuid in IDLE state after 0 sec [ 4562.628120] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 4568.370440] Lustre: Unmounted lustre-client [ 4572.177572] Lustre: Unmounted lustre-client [ 4625.472327] Key type lgssc unregistered [ 4625.805660] LNet: 93972:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4625.812456] LNetError: 93972:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4625.822912] LNet: Removed LNI 192.168.202.23@tcp [ 4627.034128] Key type .llcrypt unregistered [ 4627.041799] Key type ._llcrypt unregistered [ 4662.286696] Key type ._llcrypt registered [ 4662.289613] Key type .llcrypt registered [ 4664.276494] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 04:37:17 (1787560637) [ 4685.361570] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4685.417670] alg: No test for adler32 (adler32-zlib) [ 4686.515351] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [ 4686.813440] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [ 4688.543225] Key type lgssc registered [ 4690.343469] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4785.571306] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4792.802325] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4821.075395] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4823.805384] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 4845.535197] Lustre: 95337:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787560804/real 1787560804] req@00000000f83f8589 x1874393208722688/t0(0) o400->lustre-OST0000-osc-ffff9116d0d16800@192.168.202.123@tcp:28/4 lens 224/224 e 0 to 1 dl 1787560820 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4845.563754] Lustre: lustre-OST0000-osc-ffff9116d0d16800: Connection to lustre-OST0000 (at 192.168.202.123@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4850.335311] Lustre: 95337:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787560809/real 1787560809] req@000000009cb7a752 x1874393208723200/t0(0) o400->lustre-OST0000-osc-ffff9116d0d16800@192.168.202.123@tcp:28/4 lens 224/224 e 0 to 1 dl 1787560825 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4854.751669] Lustre: 95338:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787560814/real 1787560814] req@00000000cc82f503 x1874393208723712/t0(0) o400->lustre-OST0000-osc-ffff9116d0d16800@192.168.202.123@tcp:28/4 lens 224/224 e 0 to 1 dl 1787560830 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4856.583834] Lustre: lustre-OST0000-osc-ffff9116d0d16800: Connection restored to 192.168.202.123@tcp (at 192.168.202.123@tcp) [ 4859.935188] Lustre: 95338:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787560819/real 1787560819] req@00000000fef2c131 x1874393208724224/t0(0) o400->lustre-OST0000-osc-ffff9116d0d16800@192.168.202.123@tcp:28/4 lens 224/224 e 0 to 1 dl 1787560835 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4860.924374] Lustre: lustre-MDT0000-mdc-ffff9116d0d16800: Connection to lustre-MDT0000 (at 192.168.202.123@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4877.280153] Lustre: 95337:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787560836/real 1787560836] req@00000000f61a1516 x1874393208726528/t0(0) o400->MGC192.168.202.123@tcp@192.168.202.123@tcp:26/25 lens 224/224 e 0 to 1 dl 1787560852 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4877.332330] LustreError: MGC192.168.202.123@tcp: Connection to MGS (at 192.168.202.123@tcp) was lost; in progress operations using this service will fail [ 4887.550896] Lustre: Evicted from MGS (at 192.168.202.123@tcp) after server handle changed from 0x92c262bdc46df203 to 0x92c262bdc46df495 [ 4887.569723] Lustre: MGC192.168.202.123@tcp: Connection restored to 192.168.202.123@tcp (at 192.168.202.123@tcp) [ 4891.557697] Lustre: lustre-MDT0000-mdc-ffff9116d0d16800: Connection restored to 192.168.202.123@tcp (at 192.168.202.123@tcp) [ 4897.619969] Lustre: Unmounted lustre-client [ 4935.825336] Key type lgssc unregistered [ 4936.233118] LNet: 99655:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4936.240884] LNetError: 99655:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4936.268700] LNet: Removed LNI 192.168.202.23@tcp [ 4937.270174] Key type .llcrypt unregistered [ 4937.271942] Key type ._llcrypt unregistered [ 4954.651094] Key type ._llcrypt registered [ 4954.656594] Key type .llcrypt registered [ 4956.059618] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 04:42:09 (1787560929) [ 4972.117299] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4972.141331] alg: No test for adler32 (adler32-zlib) [ 4973.360836] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [ 4973.713859] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [ 4975.367727] Key type lgssc registered [ 4976.539446] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5017.188962] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5125.292298] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5131.830470] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5155.967483] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5159.226766] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 5161.900799] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 5164.370771] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 04:45:37 (1787561137) [ 5418.073445] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5428.389397] Lustre: Unmounted lustre-client [ 5566.933120] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5574.416314] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5600.942085] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5644.825895] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 04:53:38 (1787561618) [ 5741.209495] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5747.860406] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5774.503827] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5775.301737] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 5780.130241] Lustre: Unmounted lustre-client [ 5811.509796] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 04:56:25 (1787561785) [ 5916.908382] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5924.021658] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5953.378323] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5954.363702] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 5958.367043] Lustre: Unmounted lustre-client [ 6001.248567] Key type lgssc unregistered [ 6001.724273] LNet: 122702:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6001.746404] LNetError: 122702:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6001.793992] LNet: Removed LNI 192.168.202.23@tcp [ 6003.136478] Key type .llcrypt unregistered [ 6003.139560] Key type ._llcrypt unregistered [ 6026.651889] Key type ._llcrypt registered [ 6026.654271] Key type .llcrypt registered [ 6028.145030] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 05:00:01 (1787562001) [ 6031.098815] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6031.109956] alg: No test for adler32 (adler32-zlib) [ 6032.137897] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [ 6032.448639] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [ 6034.183366] Key type lgssc registered [ 6036.151867] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6098.604343] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6104.965952] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6130.839994] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6136.878233] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 6150.967051] Lustre: Unmounted lustre-client [ 6177.990171] Key type lgssc unregistered [ 6178.275815] LNet: 126315:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6178.280728] LNetError: 126315:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6179.301896] LNet: Removed LNI 192.168.202.23@tcp [ 6180.262875] Key type .llcrypt unregistered [ 6180.269246] Key type ._llcrypt unregistered [ 6196.699137] Key type ._llcrypt registered [ 6196.700761] Key type .llcrypt registered [ 6198.271754] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 05:02:51 (1787562171) [ 6201.013361] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6201.044731] alg: No test for adler32 (adler32-zlib) [ 6202.201093] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [ 6202.504145] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [ 6204.215289] Key type lgssc registered [ 6205.802827] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6262.476855] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6268.813501] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6293.211180] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6295.274899] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 6305.794772] Lustre: lustre-OST0000-osc-ffff9116d726f000: Connection to lustre-OST0000 (at 192.168.202.123@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6319.371917] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff9116d726f000.ost_server_uuid 50 [ 6322.768428] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 6335.577447] Lustre: Unmounted lustre-client [ 6357.354615] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 05:05:30 (1787562330) [ 6394.134726] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6400.797800] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6428.349194] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6456.334483] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6457.137241] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 6462.465371] Lustre: lustre-OST0000-osc-ffff9116cb114000: Connection to lustre-OST0000 (at 192.168.202.123@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6490.882903] Lustre: Unmounted lustre-client [ 6550.349789] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 05:08:42 (1787562522) [ 6589.890391] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6595.908776] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6623.891422] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6648.324452] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6649.037223] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 6654.449076] Lustre: lustre-OST0000-osc-ffff9116d1e96800: Connection to lustre-OST0000 (at 192.168.202.123@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6670.448202] Lustre: Unmounted lustre-client [ 6735.789702] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 05:11:49 (1787562709) [ 6857.287802] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6865.153835] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6892.968878] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6983.722507] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6991.124428] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7016.324344] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7053.828256] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 7108.837205] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7110.866328] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7113.271179] Lustre: Unmounted lustre-client [ 7151.351759] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 05:18:43 (1787563123) [ 7195.900895] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7205.712569] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7235.221430] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7273.054377] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7309.037649] LustreError: 127273:0:(lmv_obd.c:211:lmv_notify()) activation of lustre-MDT0001_UUID failed: -22 [ 7309.139793] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 7357.033565] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7358.774969] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7379.977610] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7390.318918] Lustre: Unmounted lustre-client [ 7458.304785] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 05:23:52 (1787563432) [ 7498.025834] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7504.500391] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7529.459617] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7530.220471] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 7558.756737] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7560.762078] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7579.955643] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9116d084a000.ost_server_uuid 50 [ 7582.164084] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9116d084a000.ost_server_uuid in IDLE state after 0 sec [ 7586.650448] Lustre: Unmounted lustre-client [ 7587.118160] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 7587.131820] Lustre: Skipped 3 previous similar messages [ 7587.228369] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 7588.642986] LustreError: 149787:0:(sec.c:403:import_sec_validate_get()) import 00000000aa11c18f (NEW) with no sec [ 7652.635541] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 05:27:06 (1787563626) [ 7696.655420] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7704.199318] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7730.803494] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7754.545556] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7755.077769] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 7755.275090] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 7766.712629] Lustre: Unmounted lustre-client [ 7766.723705] Lustre: Skipped 1 previous similar message [ 7816.223075] Key type lgssc unregistered [ 7816.737111] LNet: 153192:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7816.755476] LNetError: 153192:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7816.795460] LNet: Removed LNI 192.168.202.23@tcp [ 7818.058665] Key type .llcrypt unregistered [ 7818.060093] Key type ._llcrypt unregistered [ 7841.458028] Key type ._llcrypt registered [ 7841.462435] Key type .llcrypt registered [ 7843.534471] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 05:30:16 (1787563816) [ 7844.914819] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7844.944976] alg: No test for adler32 (adler32-zlib) [ 7846.265053] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [ 7846.658182] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [ 7848.431133] Key type lgssc registered [ 7850.248425] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7908.852888] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7914.946200] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7939.152582] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7962.111231] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7963.773075] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7964.981839] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 7967.667668] LustreError: lustre-MDT0001-mdc-ffff9116c28a5800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 7967.697740] Lustre: lustre-MDT0001-mdc-ffff9116c28a5800: Connection restored to 192.168.202.123@tcp (at 192.168.202.123@tcp) [ 7974.138932] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7976.116571] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 8001.033087] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 8005.772710] Lustre: Unmounted lustre-client [ 8047.221111] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 05:33:40 (1787564020) [ 8157.208665] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8163.914850] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8188.700634] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8189.441564] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 8220.362473] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8222.388917] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 8235.600562] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 8245.935391] Lustre: Unmounted lustre-client [ 8281.323202] Key type lgssc unregistered [ 8281.549140] LNet: 162808:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8281.558940] LNetError: 162808:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8281.588886] LNet: Removed LNI 192.168.202.23@tcp [ 8282.632712] Key type .llcrypt unregistered [ 8282.635702] Key type ._llcrypt unregistered [ 8317.500027] Key type ._llcrypt registered [ 8317.501849] Key type .llcrypt registered [ 8319.081459] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 05:38:12 (1787564292) [ 8321.752034] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8321.784637] alg: No test for adler32 (adler32-zlib) [ 8323.006916] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [ 8323.474530] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [ 8325.303290] Key type lgssc registered [ 8326.833701] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8384.159671] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8390.497723] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8411.945634] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8413.575373] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 8460.427268] Lustre: Unmounted lustre-client [ 8505.701875] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8506.328864] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 8543.152942] Lustre: Unmounted lustre-client [ 8571.939880] Key type lgssc unregistered [ 8572.280769] LNet: 167259:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8572.287156] LNetError: 167259:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8572.318837] LNet: Removed LNI 192.168.202.23@tcp [ 8573.069924] Key type .llcrypt unregistered [ 8573.072503] Key type ._llcrypt unregistered [ 8588.916596] Key type ._llcrypt registered [ 8588.917938] Key type .llcrypt registered [ 8590.374205] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 05:42:44 (1787564564) [ 8592.937745] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8592.950323] alg: No test for adler32 (adler32-zlib) [ 8594.186400] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [ 8594.453045] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [ 8596.199284] Key type lgssc registered [ 8597.409923] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8644.343518] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8649.529215] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8673.627098] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8676.398693] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 8697.276558] Lustre: Unmounted lustre-client [ 8723.022748] Key type lgssc unregistered [ 8723.327171] LNet: 171017:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8723.342038] LNetError: 171017:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8723.358658] LNet: Removed LNI 192.168.202.23@tcp [ 8723.994821] Key type .llcrypt unregistered [ 8724.001943] Key type ._llcrypt unregistered [ 8738.081126] Key type ._llcrypt registered [ 8738.082869] Key type .llcrypt registered [ 8738.826266] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8738.880912] alg: No test for adler32 (adler32-zlib) [ 8739.959715] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [ 8740.170466] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [ 8741.839309] Key type lgssc registered [ 8742.927198] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8792.518811] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8798.593833] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8819.717987] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8821.614914] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 8833.373217] Lustre: Unmounted lustre-client [ 8862.696317] Key type lgssc unregistered [ 8863.067704] LNet: 174084:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8863.106184] LNetError: 174084:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8863.127171] LNet: Removed LNI 192.168.202.23@tcp [ 8864.230564] Key type .llcrypt unregistered [ 8864.234609] Key type ._llcrypt unregistered [ 8883.178458] Key type ._llcrypt registered [ 8883.181160] Key type .llcrypt registered [ 8884.842793] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 05:47:38 (1787564858) [ 8887.418501] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8887.541912] alg: No test for adler32 (adler32-zlib) [ 8888.654820] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [ 8888.899258] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [ 8890.591693] Key type lgssc registered [ 8892.103748] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8947.668095] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8954.444877] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8980.551842] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8987.771906] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 9007.871905] Lustre: Unmounted lustre-client [ 9035.729925] Key type lgssc unregistered [ 9036.070835] LNet: 177839:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9036.078485] LNetError: 177839:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9036.113572] LNet: Removed LNI 192.168.202.23@tcp [ 9037.190497] Key type .llcrypt unregistered [ 9037.194797] Key type ._llcrypt unregistered [ 9051.016317] Key type ._llcrypt registered [ 9051.018257] Key type .llcrypt registered [ 9051.441541] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9051.458515] alg: No test for adler32 (adler32-zlib) [ 9052.648817] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [ 9052.955721] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [ 9054.695313] Key type lgssc registered [ 9056.240207] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9108.816434] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9114.880892] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9139.403797] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9142.331311] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 9151.398455] Lustre: Unmounted lustre-client [ 9180.710367] Key type lgssc unregistered [ 9180.976125] LNet: 180905:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9180.985567] LNetError: 180905:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9182.055456] LNet: Removed LNI 192.168.202.23@tcp [ 9182.971985] Key type .llcrypt unregistered [ 9182.978889] Key type ._llcrypt unregistered [ 9201.231238] Key type ._llcrypt registered [ 9201.233754] Key type .llcrypt registered [ 9203.078777] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 05:52:56 (1787565176) [ 9229.212884] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9229.291790] alg: No test for adler32 (adler32-zlib) [ 9230.565473] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [ 9231.029549] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [ 9232.850219] Key type lgssc registered [ 9234.938967] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9331.803796] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9339.094914] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9365.399639] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9415.975592] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 05:56:29 (1787565389) [ 9461.730454] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9467.321906] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9491.973498] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9499.254508] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 9505.582972] Lustre: Unmounted lustre-client [ 9534.518880] Key type lgssc unregistered [ 9534.768147] LNet: 188020:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9534.781126] LNetError: 188020:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9534.803668] LNet: Removed LNI 192.168.202.23@tcp [ 9536.099220] Key type .llcrypt unregistered [ 9536.101711] Key type ._llcrypt unregistered [ 9557.555408] Key type ._llcrypt registered [ 9557.558539] Key type .llcrypt registered [ 9559.588596] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 9562.401284] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 05:58:55 (1787565535) [ 9582.100365] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9582.144910] alg: No test for adler32 (adler32-zlib) [ 9583.409495] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [ 9583.814818] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [ 9585.608150] Key type lgssc registered [ 9587.309746] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9697.837638] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9704.694765] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9729.774900] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9752.598676] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9755.677961] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [ 9780.803221] Lustre: Unmounted lustre-client [ 9907.832960] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9914.560658] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9939.282984] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9988.167877] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 06:06:00 (1787565960) [ 9990.752278] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 9993.456644] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 06:06:06 (1787565966) [10110.522722] Key type lgssc unregistered [10110.790409] LNet: 199480:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10110.798620] LNetError: 199480:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10110.821648] LNet: Removed LNI 192.168.202.23@tcp [10111.597406] Key type .llcrypt unregistered [10111.599993] Key type ._llcrypt unregistered [10128.403879] Key type ._llcrypt registered [10128.409354] Key type .llcrypt registered [10130.060223] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 06:08:23 (1787566103) [10176.385891] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10176.432859] alg: No test for adler32 (adler32-zlib) [10177.560700] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [10177.965436] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [10179.712912] Key type lgssc registered [10181.103562] Lustre: Echo OBD driver; http://www.lustre.org/ [10234.263279] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10241.961727] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10277.593637] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10308.594267] Key type lgssc unregistered [10308.874968] LNet: 203217:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10308.883434] LNetError: 203217:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10308.904511] LNet: Removed LNI 192.168.202.23@tcp [10309.601777] Key type .llcrypt unregistered [10309.605334] Key type ._llcrypt unregistered [10328.568954] Key type ._llcrypt registered [10328.570975] Key type .llcrypt registered [10330.139759] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 06:11:43 (1787566303) [10332.865583] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10333.011225] alg: No test for adler32 (adler32-zlib) [10334.179143] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [10334.394394] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [10336.047700] Key type lgssc registered [10337.707069] Lustre: Echo OBD driver; http://www.lustre.org/ [10394.986657] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10401.999907] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10428.084395] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10430.700883] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [10441.198817] Lustre: lustre-MDT0000-mdc-ffff9116ca44f800: Connection to lustre-MDT0000 (at 192.168.202.123@tcp) was lost; in progress operations using this service will wait for recovery to complete [10451.455370] Lustre: lustre-MDT0001-mdc-ffff9116ca44f800: Connection to lustre-MDT0001 (at 192.168.202.123@tcp) was lost; in progress operations using this service will wait for recovery to complete [10462.495290] Lustre: 204207:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787566421/real 1787566421] req@000000004b0b3007 x1874399130108032/t0(0) o400->MGC192.168.202.123@tcp@192.168.202.123@tcp:26/25 lens 224/224 e 0 to 1 dl 1787566437 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10462.535016] LustreError: MGC192.168.202.123@tcp: Connection to MGS (at 192.168.202.123@tcp) was lost; in progress operations using this service will fail [10488.173574] Lustre: Evicted from MGS (at 192.168.202.123@tcp) after server handle changed from 0x6a736061996c475d to 0x6a736061996c6253 [10488.183942] Lustre: MGC192.168.202.123@tcp: Connection restored to 192.168.202.123@tcp (at 192.168.202.123@tcp) [10516.998759] LustreError: lustre-MDT0000-mdc-ffff9116ca44f800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [10517.032992] Lustre: lustre-MDT0000-mdc-ffff9116ca44f800: Connection restored to 192.168.202.123@tcp (at 192.168.202.123@tcp) [10522.748180] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10524.834489] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10530.587866] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10532.821640] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [10536.692789] Lustre: Unmounted lustre-client [10576.796295] Key type lgssc unregistered [10577.290390] LNet: 208172:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10577.305420] LNetError: 208172:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10577.329148] LNet: Removed LNI 192.168.202.23@tcp [10578.601541] Key type .llcrypt unregistered [10578.603671] Key type ._llcrypt unregistered [10597.583368] Key type ._llcrypt registered [10597.589423] Key type .llcrypt registered [10599.281350] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 06:16:12 (1787566572) [10602.590010] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10602.639865] alg: No test for adler32 (adler32-zlib) [10603.799547] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [10604.264288] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [10605.983193] Key type lgssc registered [10607.533520] Lustre: Echo OBD driver; http://www.lustre.org/ [10669.087563] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10679.004614] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10751.118122] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10758.395636] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10800.338586] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10827.131563] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10878.963977] Key type lgssc unregistered [10879.304251] LNet: 213479:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10879.310747] LNetError: 213479:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10879.341867] LNet: Removed LNI 192.168.202.23@tcp [10880.338403] Key type .llcrypt unregistered [10880.343322] Key type ._llcrypt unregistered [10928.824501] Key type ._llcrypt registered [10928.825774] Key type .llcrypt registered [10930.124866] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 06:21:44 (1787566904) [10982.430751] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10982.529302] alg: No test for adler32 (adler32-zlib) [10983.649722] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [10984.049136] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [10985.767457] Key type lgssc registered [10987.639702] Lustre: Echo OBD driver; http://www.lustre.org/ [11086.534213] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11093.383890] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11121.197344] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11176.783314] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 06:25:50 (1787567150) [11188.366724] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 06:26:01 (1787567161) [11236.134883] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11243.512274] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11267.824268] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11278.958598] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [11285.626158] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [11288.530687] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [11290.631599] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [11292.696307] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [11299.304192] Lustre: lustre-MDT0000-mdc-ffff9116cbeb4000: Connection to lustre-MDT0000 (at 192.168.202.123@tcp) was lost; in progress operations using this service will wait for recovery to complete [11314.655390] Lustre: 215376:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787567274/real 1787567274] req@000000002e29b1f4 x1874399811611008/t0(0) o400->MGC192.168.202.123@tcp@192.168.202.123@tcp:26/25 lens 224/224 e 0 to 1 dl 1787567290 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [11314.692437] LustreError: MGC192.168.202.123@tcp: Connection to MGS (at 192.168.202.123@tcp) was lost; in progress operations using this service will fail [11324.921946] Lustre: Evicted from MGS (at 192.168.202.123@tcp) after server handle changed from 0x866e57fa7999ab16 to 0x866e57fa7999ac90 [11324.945330] Lustre: MGC192.168.202.123@tcp: Connection restored to 192.168.202.123@tcp (at 192.168.202.123@tcp) [11332.612246] Lustre: lustre-MDT0000-mdc-ffff9116cbeb4000: Connection restored to 192.168.202.123@tcp (at 192.168.202.123@tcp) [11347.914312] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11349.998569] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [11354.696715] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [11360.753849] Lustre: lustre-MDT0000-mdc-ffff9116cbeb4000: Connection to lustre-MDT0000 (at 192.168.202.123@tcp) was lost; in progress operations using this service will wait for recovery to complete [11377.183945] Lustre: 215376:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787567336/real 1787567336] req@0000000087afbf47 x1874399811620992/t0(0) o400->MGC192.168.202.123@tcp@192.168.202.123@tcp:26/25 lens 224/224 e 0 to 1 dl 1787567352 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [11377.225930] LustreError: MGC192.168.202.123@tcp: Connection to MGS (at 192.168.202.123@tcp) was lost; in progress operations using this service will fail [11386.867910] Lustre: Evicted from MGS (at 192.168.202.123@tcp) after server handle changed from 0x866e57fa7999ac90 to 0x866e57fa7999b09c [11386.894322] Lustre: MGC192.168.202.123@tcp: Connection restored to 192.168.202.123@tcp (at 192.168.202.123@tcp) [11390.414881] Lustre: lustre-MDT0000-mdc-ffff9116cbeb4000: Connection restored to 192.168.202.123@tcp (at 192.168.202.123@tcp) [11402.014675] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11404.231792] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [11408.947218] Lustre: Unmounted lustre-client [11481.333415] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11488.038402] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11512.121403] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11512.870532] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [11519.632650] Lustre: Unmounted lustre-client [11557.557851] Key type lgssc unregistered [11557.894438] LNet: 225974:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11557.914468] LNetError: 225974:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11557.943860] LNet: Removed LNI 192.168.202.23@tcp [11558.941818] Key type .llcrypt unregistered [11558.947899] Key type ._llcrypt unregistered [11578.492706] Key type ._llcrypt registered [11578.494430] Key type .llcrypt registered [11580.242615] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 06:32:33 (1787567553) [11585.661094] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11585.698553] alg: No test for adler32 (adler32-zlib) [11586.833542] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [11587.129294] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [11588.775280] Key type lgssc registered [11590.401744] Lustre: Echo OBD driver; http://www.lustre.org/ [11649.119411] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11655.851197] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11676.468474] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11679.068410] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [11688.620644] Lustre: Unmounted lustre-client [11758.553675] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11764.052681] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11789.603513] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11790.326115] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [11800.424284] Lustre: Unmounted lustre-client [11874.082030] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11880.679245] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11903.500262] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11904.268744] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [11906.590674] Lustre: Unmounted lustre-client [11935.669522] Key type lgssc unregistered [11935.960408] LNet: 233021:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11935.977177] LNetError: 233021:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11935.997256] LNet: Removed LNI 192.168.202.23@tcp [11936.816426] Key type .llcrypt unregistered [11936.818740] Key type ._llcrypt unregistered [11956.239019] Key type ._llcrypt registered [11956.240756] Key type .llcrypt registered [11958.063228] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 06:38:51 (1787567931) [11961.720247] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11961.762154] alg: No test for adler32 (adler32-zlib) [11962.995316] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [11963.247995] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [11964.975395] Key type lgssc registered [11966.524404] Lustre: Echo OBD driver; http://www.lustre.org/ [12015.137981] Key type lgssc unregistered [12015.378782] LNet: 235350:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12015.397175] LNetError: 235350:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12015.415365] LNet: Removed LNI 192.168.202.23@tcp [12015.816365] Key type .llcrypt unregistered [12015.820463] Key type ._llcrypt unregistered [12053.129668] Key type ._llcrypt registered [12053.132871] Key type .llcrypt registered [12053.720880] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12053.743621] alg: No test for adler32 (adler32-zlib) [12055.096731] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [12055.442894] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [12057.151235] Key type lgssc registered [12059.059328] Lustre: Echo OBD driver; http://www.lustre.org/ [12152.648853] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12158.781910] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12184.018263] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12238.170550] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [12240.066892] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 06:43:33 (1787568213) [12278.475493] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12284.992930] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12310.646750] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12338.587099] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12341.631094] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [12346.886823] Lustre: lustre-OST0001-osc-ffff9116d019a000: Connection to lustre-OST0001 (at 192.168.202.123@tcp) was lost; in progress operations using this service will wait for recovery to complete [12358.063431] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12368.455440] Lustre: Unmounted lustre-client [12407.237926] Key type lgssc unregistered [12407.681294] LNet: 242471:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12407.697265] LNetError: 242471:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12407.733982] LNet: Removed LNI 192.168.202.23@tcp [12409.169990] Key type .llcrypt unregistered [12409.172555] Key type ._llcrypt unregistered [12447.005790] Key type ._llcrypt registered [12447.008902] Key type .llcrypt registered [12448.599958] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 06:47:02 (1787568422) [12463.042512] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12463.070683] alg: No test for adler32 (adler32-zlib) [12464.067423] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [12464.292279] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [12465.952527] Key type lgssc registered [12468.170993] Lustre: Echo OBD driver; http://www.lustre.org/ [12519.931737] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12527.699710] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12561.649414] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 06:48:55 (1787568535) [12596.152952] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12602.246813] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12626.699716] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12629.390526] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [12635.201513] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [12648.351688] Lustre: Unmounted lustre-client [12765.685688] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12773.181457] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12827.552702] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12828.438809] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [12831.532244] Lustre: Unmounted lustre-client [12859.831904] Key type lgssc unregistered [12860.100232] LNet: 250176:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12860.111584] LNetError: 250176:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12861.181681] LNet: Removed LNI 192.168.202.23@tcp [12861.832906] Key type .llcrypt unregistered [12861.834918] Key type ._llcrypt unregistered [12897.099913] Key type ._llcrypt registered [12897.125858] Key type .llcrypt registered [12897.970902] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12897.983658] alg: No test for adler32 (adler32-zlib) [12899.408210] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [12899.948573] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [12901.783393] Key type lgssc registered [12903.501268] Lustre: Echo OBD driver; http://www.lustre.org/ [13001.441480] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13009.308683] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13038.492416] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13093.166045] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [13095.852782] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 06:57:49 (1787569069) [13133.094591] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13139.113705] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13163.800374] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13168.403693] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [13171.473644] Lustre: Unmounted lustre-client [13211.841151] Key type lgssc unregistered [13212.070277] LNet: 256823:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13212.076107] LNetError: 256823:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13212.096782] LNet: Removed LNI 192.168.202.23@tcp [13213.261172] Key type .llcrypt unregistered [13213.264701] Key type ._llcrypt unregistered [13234.829247] Key type ._llcrypt registered [13234.831978] Key type .llcrypt registered [13236.257223] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [13238.090163] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 07:00:12 (1787569212) [13248.192577] Key type .llcrypt unregistered [13248.196629] Key type ._llcrypt unregistered [13259.283760] Key type ._llcrypt registered [13259.287436] Key type .llcrypt registered [13259.908769] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13259.924509] alg: No test for adler32 (adler32-zlib) [13261.094190] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [13261.447908] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [13263.231248] Key type lgssc registered [13265.100055] Lustre: Echo OBD driver; http://www.lustre.org/ [13326.853731] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13345.275607] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [13348.666229] Lustre: Unmounted lustre-client [13385.092585] Key type lgssc unregistered [13385.465375] LNet: 260295:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13385.471333] LNetError: 260295:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13385.493533] LNet: Removed LNI 192.168.202.23@tcp [13386.297637] Key type .llcrypt unregistered [13386.299665] Key type ._llcrypt unregistered [13404.316811] Key type ._llcrypt registered [13404.318628] Key type .llcrypt registered [13405.733507] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 07:02:59 (1787569379) [13408.028509] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13408.214481] alg: No test for adler32 (adler32-zlib) [13409.399881] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [13409.628136] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [13411.328080] Key type lgssc registered [13413.007601] Lustre: Echo OBD driver; http://www.lustre.org/ [13503.443419] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13543.687989] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13551.970680] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13554.969781] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [13558.746484] Lustre: Unmounted lustre-client [13587.680821] Key type lgssc unregistered [13588.109163] LNet: 263843:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13588.121539] LNetError: 263843:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13588.156926] LNet: Removed LNI 192.168.202.23@tcp [13589.394343] Key type .llcrypt unregistered [13589.398372] Key type ._llcrypt unregistered [13608.057754] Key type ._llcrypt registered [13608.060168] Key type .llcrypt registered [13609.456638] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 07:06:23 (1787569583) [13612.321529] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13612.333829] alg: No test for adler32 (adler32-zlib) [13613.618349] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [13613.901332] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [13615.631192] Key type lgssc registered [13617.599458] Lustre: Echo OBD driver; http://www.lustre.org/ [13672.311918] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13678.901229] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13701.295431] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13703.128419] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [13708.273850] Lustre: lustre-MDT0000-mdc-ffff9116c33eb000: Connection to lustre-MDT0000 (at 192.168.202.123@tcp) was lost; in progress operations using this service will wait for recovery to complete [13714.303068] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [13714.347206] LustreError: 266997:0:(file.c:6167:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [13714.886101] LustreError: MGC192.168.202.123@tcp: Connection to MGS (at 192.168.202.123@tcp) was lost; in progress operations using this service will fail [13714.965409] Lustre: Unmounted lustre-client [13737.604359] Key type lgssc unregistered [13737.970465] LNet: 267414:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13737.984673] LNetError: 267414:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13738.009041] LNet: Removed LNI 192.168.202.23@tcp [13739.071194] Key type .llcrypt unregistered [13739.080838] Key type ._llcrypt unregistered [13757.915222] Key type ._llcrypt registered [13757.921719] Key type .llcrypt registered [13759.722696] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 07:08:53 (1787569733) [13762.528847] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13762.600288] alg: No test for adler32 (adler32-zlib) [13763.902608] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [13764.243375] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [13766.119409] Key type lgssc registered [13767.952492] Lustre: Echo OBD driver; http://www.lustre.org/ [13827.502909] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13834.208738] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13857.348788] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13859.149845] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [13864.434254] Lustre: lustre-MDT0001-mdc-ffff9116d083a000: Connection to lustre-MDT0001 (at 192.168.202.123@tcp) was lost; in progress operations using this service will wait for recovery to complete [13869.901061] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [13873.015669] Lustre: Unmounted lustre-client [13895.241811] Key type lgssc unregistered [13895.542229] LNet: 270997:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13895.553466] LNetError: 270997:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13895.575966] LNet: Removed LNI 192.168.202.23@tcp [13896.630641] Key type .llcrypt unregistered [13896.633619] Key type ._llcrypt unregistered [13915.183545] Key type ._llcrypt registered [13915.187839] Key type .llcrypt registered [13916.770391] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 07:11:30 (1787569890) [13936.618211] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13936.658432] alg: No test for adler32 (adler32-zlib) [13937.864687] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [13938.212898] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [13940.015494] Key type lgssc registered [13941.774137] Lustre: Echo OBD driver; http://www.lustre.org/ [14064.384943] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14067.266448] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [14112.762422] Lustre: lustre-MDT0001-mdc-ffff9116d019d000: Connection to lustre-MDT0001 (at 192.168.202.123@tcp) was lost; in progress operations using this service will wait for recovery to complete [14128.987150] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [14139.327676] Lustre: Unmounted lustre-client [14168.921296] Key type lgssc unregistered [14169.194822] LNet: 276787:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14169.205415] LNetError: 276787:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14169.227854] LNet: Removed LNI 192.168.202.23@tcp [14170.335085] Key type .llcrypt unregistered [14170.338122] Key type ._llcrypt unregistered [14190.400571] Key type ._llcrypt registered [14190.407258] Key type .llcrypt registered [14191.792666] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 07:16:05 (1787570165) [14193.692771] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [14195.659637] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 07:16:09 (1787570169) [14198.061920] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [14200.637892] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 07:16:14 (1787570174) [14203.227950] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [14205.568569] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 07:16:19 (1787570179) [14208.051524] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [14210.285525] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 07:16:23 (1787570183) [14213.087180] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [14215.724213] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 07:16:29 (1787570189) [14225.767631] Key type .llcrypt unregistered [14225.770216] Key type ._llcrypt unregistered [14235.830803] Key type ._llcrypt registered [14235.835172] Key type .llcrypt registered [14236.379121] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14236.429746] alg: No test for adler32 (adler32-zlib) [14237.747679] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [14238.215546] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [14239.959198] Key type lgssc registered [14242.295754] Lustre: Echo OBD driver; http://www.lustre.org/ [14331.909208] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14339.418985] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14367.376752] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14369.243744] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [14372.483088] Lustre: Unmounted lustre-client [14416.741634] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 07:19:50 (1787570390) [14462.468592] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14470.145227] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14510.573893] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14511.147956] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [14513.497526] Lustre: Unmounted lustre-client [14558.143801] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 07:22:11 (1787570531) [14565.920968] LNet: 1 local NIs in recovery (showing 1): 192.168.202.23@tcp [15216.454936] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15222.964577] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15258.613064] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15376.841665] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 07:35:50 (1787571350) [15390.487524] Key type lgssc unregistered [15390.902782] LNet: 290330:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15390.912227] LNetError: 290330:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15390.931218] LNet: Removed LNI 192.168.202.23@tcp [15392.080464] Key type .llcrypt unregistered [15392.082280] Key type ._llcrypt unregistered [15509.450813] Key type ._llcrypt registered [15509.457525] Key type .llcrypt registered [15510.282165] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15510.311049] alg: No test for adler32 (adler32-zlib) [15511.536453] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [15512.013626] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [15513.791838] Key type lgssc registered [15515.566661] Lustre: Echo OBD driver; http://www.lustre.org/ [15616.821146] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15623.319839] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15649.339837] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15688.881935] Key type lgssc unregistered [15689.237311] LNet: 294031:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15689.261833] LNetError: 294031:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15689.287174] LNet: Removed LNI 192.168.202.23@tcp [15690.465952] Key type .llcrypt unregistered [15690.470364] Key type ._llcrypt unregistered [15730.982193] Key type ._llcrypt registered [15730.984380] Key type .llcrypt registered [15732.642890] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 07:41:46 (1787571706) [15757.225856] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15757.257982] alg: No test for adler32 (adler32-zlib) [15758.498689] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [15758.952965] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [15760.808292] Key type lgssc registered [15762.950308] Lustre: Echo OBD driver; http://www.lustre.org/ [15827.134509] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 07:43:20 (1787571800) [15839.779978] Key type lgssc unregistered [15840.090145] LNet: 297592:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15840.097397] LNetError: 297592:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15840.112537] LNet: Removed LNI 192.168.202.23@tcp [15840.979972] Key type .llcrypt unregistered [15840.983365] Key type ._llcrypt unregistered [15853.926223] Key type ._llcrypt registered [15853.934404] Key type .llcrypt registered [15854.604980] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15854.618905] alg: No test for adler32 (adler32-zlib) [15855.787810] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [15856.119063] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [15857.807731] Key type lgssc registered [15859.292272] Lustre: Echo OBD driver; http://www.lustre.org/ [15923.941480] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15930.437341] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15956.697835] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16276.222537] LNet: Added LNI 192.168.202.23@tcp50 [8/256/0/180] [16278.395693] LNet: Removed LNI 192.168.202.23@tcp [16281.021446] Lustre: 302009:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff9116d083e800: config has no valid NIDs, force dynamic NIDs [16282.311505] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [16282.607484] Lustre: Unmounted lustre-client [16286.530963] Key type lgssc unregistered [16287.130062] LNet: 302272:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16287.144606] LNetError: 302272:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16287.169350] LNet: Removed LNI 192.168.202.23@tcp50 [16288.069421] Key type .llcrypt unregistered [16288.071736] Key type ._llcrypt unregistered [16307.190899] Key type ._llcrypt registered [16307.200967] Key type .llcrypt registered [16307.846921] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [16307.858420] alg: No test for adler32 (adler32-zlib) [16308.969656] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [16309.239824] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [16310.959239] Key type lgssc registered [16312.853099] Lustre: Echo OBD driver; http://www.lustre.org/ [16377.850468] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16384.702259] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16393.737335] LNet: Added LNI 192.168.202.23@tcp50 [8/256/0/180] [16394.543373] LNet: Removed LNI 192.168.202.23@tcp [16395.689233] Lustre: 304964:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff9116d2fba000: config has no valid NIDs, force dynamic NIDs [16396.968282] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [16397.868987] Lustre: Unmounted lustre-client [16439.904585] Key type lgssc unregistered [16440.343351] LNet: 305840:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16440.349330] LNetError: 305840:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16440.364368] LNet: Removed LNI 192.168.202.23@tcp50 [16440.806602] Key type .llcrypt unregistered [16440.808229] Key type ._llcrypt unregistered [16453.061471] Key type ._llcrypt registered [16453.062558] Key type .llcrypt registered [16454.421614] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 07:53:48 (1787572428) [16481.660056] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16481.694950] alg: No test for adler32 (adler32-zlib) [16482.728717] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [16482.979780] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [16484.633095] Key type lgssc registered [16485.817204] Lustre: Echo OBD driver; http://www.lustre.org/ [16575.069556] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16581.217360] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16609.051319] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16645.708511] Key type lgssc unregistered [16645.920066] LNet: 310587:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16645.931390] LNetError: 310587:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16645.956987] LNet: Removed LNI 192.168.202.23@tcp [16646.606667] Key type .llcrypt unregistered [16646.609678] Key type ._llcrypt unregistered [16660.619719] Key type ._llcrypt registered [16660.623321] Key type .llcrypt registered [16663.049952] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 07:57:15 (1787572635) [16666.128993] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16666.184576] alg: No test for adler32 (adler32-zlib) [16667.499784] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [16667.999958] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [16669.839280] Key type lgssc registered [16671.416819] Lustre: Echo OBD driver; http://www.lustre.org/ [16727.343563] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16734.060703] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16758.442526] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16761.306815] Lustre: Mounted lustre-client - version 2.17.57_82_g4029ffa [16831.170155] Lustre: Mounted fs15246-client - version 2.17.57_82_g4029ffa [16848.530213] Lustre: Unmounted fs15246-client [16875.284060] Lustre: Unmounted lustre-client [16904.888358] Key type lgssc unregistered [16905.195038] LNet: 315464:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16905.204793] LNetError: 315464:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16905.220463] LNet: Removed LNI 192.168.202.23@tcp [16906.201797] Key type .llcrypt unregistered [16906.208400] Key type ._llcrypt unregistered [16920.276565] Key type ._llcrypt registered [16920.278968] Key type .llcrypt registered [16922.182841] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 08:01:35 (1787572895) [16925.219254] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16925.252202] alg: No test for adler32 (adler32-zlib) [16926.395883] Lustre: Lustre: Build Version: 2.17.57_82_g4029ffa [16926.801211] LNet: Added LNI 192.168.202.23@tcp [8/256/0/180] [16928.655221] Key type lgssc registered [16930.309513] Lustre: Echo OBD driver; http://www.lustre.org/ [17074.385731] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [17081.421712] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [17106.539680] Lustre: DEBUG MARKER: oleg223-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [17221.967296] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 16928 sec ========= 08:06:35 (1787573195) [17224.160844] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 08:06:37 (1787573197) === [17228.782579] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 08:06:42 (1787573202) === [17269.738927] Key type lgssc unregistered [17269.883253] LNet: 324738:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [17269.889647] LNetError: 324738:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [17269.901655] LNet: Removed LNI 192.168.202.23@tcp [17270.583537] Key type .llcrypt unregistered [17270.584695] Key type ._llcrypt unregistered