[ 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 461236066 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524584K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001012] APIC: Switch to symmetric I/O mode setup [ 0.003187] x2apic enabled [ 0.005004] Switched APIC routing to physical x2apic. [ 0.006014] kvm-guest: setup PV IPIs [ 0.009430] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.010000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.010021] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.011011] pid_max: default: 32768 minimum: 301 [ 0.012142] LSM: Security Framework initializing [ 0.013052] Yama: becoming mindful. [ 0.014039] SELinux: Initializing. [ 0.016069] *** VALIDATE selinux *** [ 0.024343] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.029194] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.030155] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031107] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.033110] *** VALIDATE tmpfs *** [ 0.035127] *** VALIDATE proc *** [ 0.036216] *** VALIDATE cgroup *** [ 0.037007] *** VALIDATE cgroup2 *** [ 0.038235] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.039146] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.040009] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.041030] Spectre V2 : User space: Vulnerable [ 0.042010] Speculative Store Bypass: Vulnerable [ 0.045111] debug: unmapping init [mem 0xffffffffa4e59000-0xffffffffa4e60fff] [ 0.047183] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.048596] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.049024] ... version: 2 [ 0.050014] ... bit width: 48 [ 0.051014] ... generic registers: 4 [ 0.052016] ... value mask: 0000ffffffffffff [ 0.053017] ... max period: 00007fffffffffff [ 0.054016] ... fixed-purpose events: 3 [ 0.055016] ... event mask: 000000070000000f [ 0.056285] rcu: Hierarchical SRCU implementation. [ 0.058412] smp: Bringing up secondary CPUs ... [ 0.059635] x86: Booting SMP configuration: [ 0.060027] .... node #0, CPUs: #1 #2 #3 [ 0.067619] smp: Brought up 1 node, 4 CPUs [ 0.069012] smpboot: Max logical packages: 1 [ 0.070012] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.209017] node 0 deferred pages initialised in 137ms [ 0.212093] devtmpfs: initialized [ 0.213000] x86/mm: Memory block size: 128MB [ 0.216373] gcov: version magic: 0x41383552 [ 0.219375] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.222094] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.224335] pinctrl core: initialized pinctrl subsystem [ 0.225188] [ 0.225636] ************************************************************* [ 0.228019] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.230010] ** ** [ 0.232011] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.233011] ** ** [ 0.235011] ** This means that this kernel is built to expose internal ** [ 0.237011] ** IOMMU data structures, which may compromise security on ** [ 0.238010] ** your system. ** [ 0.240011] ** ** [ 0.242010] ** If you see this message and you are not debugging the ** [ 0.244011] ** kernel, report this immediately to your vendor! ** [ 0.246015] ** ** [ 0.248011] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.249010] ************************************************************* [ 0.251779] NET: Registered protocol family 16 [ 0.253476] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.255064] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.257068] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.260159] cpuidle: using governor menu [ 0.264050] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.265642] PCI: Using configuration type 1 for base access [ 0.267334] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.276062] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.278024] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.281149] cryptd: max_cpu_qlen set to 1000 [ 0.284077] ACPI: Added _OSI(Module Device) [ 0.285013] ACPI: Added _OSI(Processor Device) [ 0.287011] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.288012] ACPI: Added _OSI(Processor Aggregator Device) [ 0.293181] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.298447] ACPI: Interpreter enabled [ 0.300053] ACPI: PM: (supports S0 S3 S4 S5) [ 0.301014] ACPI: Using IOAPIC for interrupt routing [ 0.303192] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.306756] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.316000] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.319099] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.322018] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.326084] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.330771] acpiphp: Slot [2] registered [ 0.331000] acpiphp: Slot [5] registered [ 0.331113] acpiphp: Slot [6] registered [ 0.332000] acpiphp: Slot [3] registered [ 0.333103] acpiphp: Slot [4] registered [ 0.335120] acpiphp: Slot [7] registered [ 0.336070] acpiphp: Slot [8] registered [ 0.337091] acpiphp: Slot [9] registered [ 0.338077] acpiphp: Slot [10] registered [ 0.339140] acpiphp: Slot [11] registered [ 0.342104] acpiphp: Slot [12] registered [ 0.343082] acpiphp: Slot [13] registered [ 0.344075] acpiphp: Slot [14] registered [ 0.345096] acpiphp: Slot [15] registered [ 0.347096] acpiphp: Slot [16] registered [ 0.348086] acpiphp: Slot [17] registered [ 0.349169] acpiphp: Slot [18] registered [ 0.351077] acpiphp: Slot [19] registered [ 0.352069] acpiphp: Slot [20] registered [ 0.353120] acpiphp: Slot [21] registered [ 0.354074] acpiphp: Slot [22] registered [ 0.356080] acpiphp: Slot [23] registered [ 0.357091] acpiphp: Slot [24] registered [ 0.358080] acpiphp: Slot [25] registered [ 0.359093] acpiphp: Slot [26] registered [ 0.360111] acpiphp: Slot [27] registered [ 0.362077] acpiphp: Slot [28] registered [ 0.363086] acpiphp: Slot [29] registered [ 0.364117] acpiphp: Slot [30] registered [ 0.366184] acpiphp: Slot [31] registered [ 0.372109] PCI host bridge to bus 0000:00 [ 0.374047] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.376042] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.378025] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.380027] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.381029] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.384026] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.385166] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.388000] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.388000] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.395016] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.399037] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.402026] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.405038] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.408035] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.412000] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.414845] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.418316] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.422140] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.427014] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.439022] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.445788] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.451278] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.464017] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.470016] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.484017] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.504962] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.512019] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.518016] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.536016] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.551604] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.554497] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.558421] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.559416] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.562292] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.566154] iommu: Default domain type: Passthrough [ 0.568537] SCSI subsystem initialized [ 0.571176] ACPI: bus type USB registered [ 0.573135] usbcore: registered new interface driver usbfs [ 0.574103] usbcore: registered new interface driver hub [ 0.576085] usbcore: registered new device driver usb [ 0.578161] pps_core: LinuxPPS API ver. 1 registered [ 0.580013] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.583063] PTP clock support registered [ 0.585194] EDAC MC: Ver: 3.0.0 [ 0.588024] PCI: Using ACPI for IRQ routing [ 0.589581] NetLabel: Initializing [ 0.593027] NetLabel: domain hash size = 128 [ 0.595013] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.597086] NetLabel: unlabeled traffic allowed by default [ 0.600145] vgaarb: loaded [ 0.601265] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.603018] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.610807] clocksource: Switched to clocksource kvm-clock [ 0.725744] VFS: Disk quotas dquot_6.6.0 [ 0.727136] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.729903] *** VALIDATE ramfs *** [ 0.731460] *** VALIDATE hugetlbfs *** [ 0.732970] pnp: PnP ACPI init [ 0.736416] pnp: PnP ACPI: found 6 devices [ 0.761240] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.764946] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.766970] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.768930] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.770961] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.773100] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.776277] NET: Registered protocol family 2 [ 0.778955] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.783721] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.787108] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.792740] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.796211] TCP: Hash tables configured (established 65536 bind 65536) [ 0.800460] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.804611] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.807918] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.811451] NET: Registered protocol family 1 [ 0.814716] RPC: Registered named UNIX socket transport module. [ 0.817020] RPC: Registered udp transport module. [ 0.819027] RPC: Registered tcp transport module. [ 0.820629] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.822866] NET: Registered protocol family 44 [ 0.824737] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.827084] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.829504] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.832071] PCI: CLS 0 bytes, default 64 [ 0.834053] Unpacking initramfs... [ 2.313631] debug: unmapping init [mem 0xffff8b59bcc64000-0xffff8b59bffcffff] [ 2.321048] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.323774] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.327292] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.858767] Initialise system trusted keyrings [ 2.860520] Key type blacklist registered [ 2.862638] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.871807] zbud: loaded [ 2.875283] *** VALIDATE nfs *** [ 2.876852] *** VALIDATE nfs4 *** [ 2.878391] pstore: using deflate compression [ 2.881802] Platform Keyring initialized [ 3.036888] NET: Registered protocol family 38 [ 3.038824] Key type asymmetric registered [ 3.040684] Asymmetric key parser 'x509' registered [ 3.042677] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.046521] io scheduler mq-deadline registered [ 3.047871] io scheduler kyber registered [ 3.049275] io scheduler bfq registered [ 3.051128] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.055905] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.058886] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.062819] ACPI: Power Button [PWRF] [ 3.070477] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.078091] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.098754] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.126696] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.157305] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.163131] Non-volatile memory driver v1.3 [ 3.165436] Linux agpgart interface v0.103 [ 3.214986] virtio_blk virtio1: [vda] 146624 512-byte logical blocks (75.1 MB/71.6 MiB) [ 3.217980] vda: detected capacity change from 0 to 75071488 [ 3.231716] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.235082] vdb: detected capacity change from 0 to 1073741824 [ 3.240207] libphy: Fixed MDIO Bus: probed [ 3.246406] usbcore: registered new interface driver usbserial_generic [ 3.248452] usbserial: USB Serial support registered for generic [ 3.250186] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.253337] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.254738] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.256817] mousedev: PS/2 mouse device common for all mice [ 3.259992] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.261553] rtc_cmos 00:05: RTC can wake from S4 [ 3.270613] rtc_cmos 00:05: registered as rtc0 [ 3.272972] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.276558] intel_pstate: CPU model not supported [ 3.277336] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.284663] hid: raw HID events driver (C) Jiri Kosina [ 3.284801] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.288237] usbcore: registered new interface driver usbhid [ 3.294588] usbhid: USB HID core driver [ 3.295922] drop_monitor: Initializing network drop monitor service [ 3.298240] Initializing XFRM netlink socket [ 3.299954] NET: Registered protocol family 10 [ 3.303225] Segment Routing with IPv6 [ 3.305783] NET: Registered protocol family 17 [ 3.308087] mpls_gso: MPLS GSO support [ 3.314493] RAS: Correctable Errors collector initialized. [ 3.316875] AVX version of gcm_enc/dec engaged. [ 3.319041] AES CTR mode by8 optimization enabled [ 3.406495] sched_clock: Marking stable (3406467320, 0)->(4352272595, -945805275) [ 3.409572] registered taskstats version 1 [ 3.411463] Loading compiled-in X.509 certificates [ 3.413579] zswap: loaded using pool lzo/zbud [ 3.441993] Key type big_key registered [ 3.455109] Key type encrypted registered [ 3.456906] ima: No TPM chip found, activating TPM-bypass! [ 3.459623] ima: Allocated hash algorithm: sha1 [ 3.461719] ima: No architecture policies found [ 3.463753] evm: Initialising EVM extended attributes: [ 3.466234] evm: security.selinux [ 3.468157] evm: security.ima [ 3.469300] evm: security.capability [ 3.471137] evm: HMAC attrs: 0x1 [ 3.474145] rtc_cmos 00:05: setting system clock to 2026-09-04 01:20:57 UTC (1788484857) [ 3.481382] debug: unmapping init [mem 0xffffffffa5e03000-0xffffffffa5ffffff] [ 3.484868] debug: unmapping init [mem 0xffffffffa4b82000-0xffffffffa4e58fff] [ 3.494249] Write protecting the kernel read-only data: 28672k [ 3.498283] debug: unmapping init [mem 0xffffffffa3203000-0xffffffffa33fffff] [ 3.501497] debug: unmapping init [mem 0xffffffffa3b14000-0xffffffffa3bfffff] [ 3.538192] 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.548382] systemd[1]: Detected virtualization kvm. [ 3.550380] systemd[1]: Detected architecture x86-64. [ 3.552309] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.578570] systemd[1]: No hostname configured. [ 3.580675] systemd[1]: Set hostname to . [ 3.583236] random: systemd: uninitialized urandom read (16 bytes read) [ 3.586069] systemd[1]: Initializing machine ID from random generator. [ 3.647374] random: ln: uninitialized urandom read (6 bytes read) [ 3.731487] random: systemd: uninitialized urandom read (16 bytes read) [ 3.734710] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 3.742171] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 3.747302] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Timers. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on Journal Socket. Starting Create list of required st…ce nodes for the current kernel... Starting Apply Kernel Variables... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Slices. Starting Journal Service... [ OK ] Listening on udev Control Socket. Starting Setup Virtual Console... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Reached target Sockets. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. [ OK ] Started Create Volatile Files and Directories. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.391092] device-mapper: uevent: version 1.0.3 [ 4.393389] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 5.080706] virtio_net virtio0 ens2: renamed from eth0 [ 5.091827] random: fast init done [ 5.156381] scsi host0: ata_piix [ 5.169301] scsi host1: ata_piix [ 5.171133] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.175945] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.155047] dracut-initqueue[581]: RTNETLINK answers: File exists [ 9.981303] random: crng init done [ 9.984586] 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). [ 11.554943] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Reached target Remote File Systems. [ 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. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped Apply Kernel Variables. [ 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 Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. Stopping udev Kernel Device Manager... [ OK ] Stopped target Swap. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 14.223410] printk: systemd: 25 output lines suppressed due to ratelimiting [ 14.926340] SELinux: Disabled at runtime. [ 15.048187] 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) [ 15.070502] systemd[1]: Detected virtualization kvm. [ 15.077200] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 16.377577] systemd[1]: initrd-switch-root.service: Succeeded. [ 16.383820] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 16.397923] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 16.422106] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 16.435752] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 16.445661] systemd[1]: Starting Journal Service... Starting Journal Service... [ 16.477327] systemd[1]: proc-sys-fs-binfmt_misc.automount: Refusing to start, unit to trigger not loaded. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Reached target rpc_pipefs.target. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Listening on udev Control Socket. Mounting Huge Pages File System... [ OK ] Stopped target Switch Root. Activating swap /dev/disk/by-label/SWAP... [ OK ] Created slice system-serial\x2dgetty.slice. [ 16.678235] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Listening on Process Core Dump Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Listening on initctl Compatibility Named Pipe. Mounting POSIX Message Queue File System... [ OK ] Reached target Local Encrypted Volumes. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice User and Session Slice. [ OK ] Listening on udev Kernel Socket. [ OK ] Stopped target Initrd Root File System. Starting Remount Root and Kernel File Systems... [ OK ] Reached target Slices. [ OK ] Stopped target Initrd File Systems. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. Starting udev Coldplug all Devices... Mounting Kernel Debug File System... Starting Apply Kernel Variables... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Created slice system-getty.slice. [ OK ] Started Journal Service. [ OK ] Mounted Huge Pages File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted POSIX Message Queue 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 Kernel Debug File System. [ OK ] Started Apply Kernel Variables. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started udev Coldplug all Devices. [ 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 /home/green/git/lustre-release... Mounting /mnt... [ OK ] Mounted /mnt. [ 17.962133] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 18.743415] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 18.868461] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 19.105414] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 19.154264] EDAC sbridge: Ver: 1.1.2 [ 22.172197] Key type dns_resolver registered [ 22.622422] NFS: Registering the id_resolver key type [ 22.624769] Key type id_resolver registered [ 22.626672] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... Starting Rebuild Dynamic Linker Cache... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started D-Bus System Message Bus. [ OK ] Started irqbalance daemon. [ OK ] Reached target sshd-keygen.target. Starting Network Manager... Starting Login Service... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Login Service. [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ 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 System Logging Service... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg452-client login: [ 78.867670] hrtimer: interrupt took 17300075 ns [ 92.251685] libcfs: loading out-of-tree module taints kernel. [ 92.374720] Key type ._llcrypt registered [ 92.377039] Key type .llcrypt registered [ 93.142817] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 93.163697] alg: No test for adler32 (adler32-zlib) [ 94.836634] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 96.032399] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [ 97.959178] Key type lgssc registered [ 100.076744] Lustre: Echo OBD driver; http://www.lustre.org/ [ 273.733830] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 280.751536] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 299.488929] Lustre: lustre-OST0000-osc-ffff8b5a0a21a000: disconnect after 23s idle [ 300.958598] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing check_logdir /tmp/testlogs/ [ 306.973753] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing yml_node [ 311.929576] Lustre: DEBUG MARKER: Client: 2.17.57.84 [ 314.092519] Lustre: DEBUG MARKER: MDS: 2.17.57.84 [ 316.825261] Lustre: DEBUG MARKER: OSS: 2.17.57.84 [ 318.458321] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Thu Sep 3 21:26:11 EDT 2026 [ 337.298892] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 339.269288] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 342.638661] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 352.997028] Lustre: Unmounted lustre-client [ 476.863437] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 483.431336] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 510.029046] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 549.308641] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 21:30:01 (1788485401) [ 551.769503] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 553.928125] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 21:30:06 (1788485406) [ 586.976404] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 592.153468] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 609.868291] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 610.519402] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 614.533254] Lustre: Unmounted lustre-client [ 641.294604] Key type lgssc unregistered [ 641.606311] LNet: 15061:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 641.616664] LNetError: 15061:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 641.644528] LNet: Removed LNI 192.168.204.52@tcp [ 642.511687] Key type .llcrypt unregistered [ 642.515408] Key type ._llcrypt unregistered [ 657.837837] Key type ._llcrypt registered [ 657.845326] Key type .llcrypt registered [ 659.324186] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 21:31:51 (1788485511) [ 660.968832] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 661.004141] alg: No test for adler32 (adler32-zlib) [ 662.082786] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 662.347583] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [ 664.000201] Key type lgssc registered [ 665.354351] Lustre: Echo OBD driver; http://www.lustre.org/ [ 749.103131] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 838.872984] Lustre: Unmounted lustre-client [ 867.126461] Key type lgssc unregistered [ 867.465618] LNet: 18917:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 867.474414] LNetError: 18917:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 867.492120] LNet: Removed LNI 192.168.204.52@tcp [ 868.171459] Key type .llcrypt unregistered [ 868.174929] Key type ._llcrypt unregistered [ 878.778707] Key type ._llcrypt registered [ 878.789411] Key type .llcrypt registered [ 880.141139] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 21:35:32 (1788485732) [ 882.544523] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 882.783426] alg: No test for adler32 (adler32-zlib) [ 883.849554] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 884.070931] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [ 885.791191] Key type lgssc registered [ 886.854125] Lustre: Echo OBD driver; http://www.lustre.org/ [ 932.581153] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 938.169054] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 959.569337] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 962.184402] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 972.099162] Lustre: Unmounted lustre-client [ 1008.295555] Key type lgssc unregistered [ 1008.538255] LNet: 22517:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1008.547470] LNetError: 22517:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1008.564807] LNet: Removed LNI 192.168.204.52@tcp [ 1009.264744] Key type .llcrypt unregistered [ 1009.267452] Key type ._llcrypt unregistered [ 1019.473845] Key type ._llcrypt registered [ 1019.475466] Key type .llcrypt registered [ 1020.728455] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 21:37:53 (1788485873) [ 1022.428778] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1022.497797] alg: No test for adler32 (adler32-zlib) [ 1023.522506] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 1023.776102] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [ 1025.463298] Key type lgssc registered [ 1026.985679] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1078.355923] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1084.557401] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1105.292302] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1107.802592] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 1116.956372] Lustre: Unmounted lustre-client [ 1146.296381] Key type lgssc unregistered [ 1146.650553] LNet: 25712:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1146.657868] LNetError: 25712:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1146.685730] LNet: Removed LNI 192.168.204.52@tcp [ 1147.489779] Key type .llcrypt unregistered [ 1147.494832] Key type ._llcrypt unregistered [ 1163.647763] Key type ._llcrypt registered [ 1163.649706] Key type .llcrypt registered [ 1164.723301] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 21:40:17 (1788486017) [ 1166.513629] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1166.635650] alg: No test for adler32 (adler32-zlib) [ 1167.651439] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 1167.833110] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [ 1169.479193] Key type lgssc registered [ 1170.688204] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1222.478668] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1228.629497] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1249.697487] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1251.309322] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 1261.548505] Lustre: lustre-MDT0000-mdc-ffff8b5a09a55800: Connection to lustre-MDT0000 (at 192.168.204.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1271.783912] Lustre: lustre-MDT0001-mdc-ffff8b5a09a55800: Connection to lustre-MDT0001 (at 192.168.204.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1277.396193] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1282.032829] Lustre: 26667:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788486120/real 1788486120] req@0000000089df2472 x1875363517962240/t0(0) o400->MGC192.168.204.152@tcp@192.168.204.152@tcp:26/25 lens 224/224 e 0 to 1 dl 1788486136 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1282.061690] LustreError: MGC192.168.204.152@tcp: Connection to MGS (at 192.168.204.152@tcp) was lost; in progress operations using this service will fail [ 1287.721762] Lustre: Unmounted lustre-client [ 1300.426415] Key type lgssc unregistered [ 1300.675933] LNet: 29360:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1300.687128] LNetError: 29360:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1300.704546] LNet: Removed LNI 192.168.204.52@tcp [ 1301.499243] Key type .llcrypt unregistered [ 1301.506053] Key type ._llcrypt unregistered [ 1316.463511] Key type ._llcrypt registered [ 1316.466225] Key type .llcrypt registered [ 1317.792864] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 21:42:50 (1788486170) [ 1320.274817] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1320.340178] alg: No test for adler32 (adler32-zlib) [ 1321.347146] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 1321.600430] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [ 1323.279583] Key type lgssc registered [ 1324.462261] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1372.903695] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1378.411809] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1401.742968] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1404.246370] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 1409.523098] Lustre: lustre-OST0000-osc-ffff8b5a10791800: Connection to lustre-OST0000 (at 192.168.204.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1416.177037] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1426.548156] Lustre: Unmounted lustre-client [ 1460.450583] Key type lgssc unregistered [ 1460.705336] LNet: 32967:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1460.712545] LNetError: 32967:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1460.731793] LNet: Removed LNI 192.168.204.52@tcp [ 1461.496487] Key type .llcrypt unregistered [ 1461.498706] Key type ._llcrypt unregistered [ 1476.029769] Key type ._llcrypt registered [ 1476.034736] Key type .llcrypt registered [ 1477.167700] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 21:45:29 (1788486329) [ 1479.231481] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1479.269039] alg: No test for adler32 (adler32-zlib) [ 1480.302782] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 1480.527368] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [ 1482.183225] Key type lgssc registered [ 1483.420154] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1530.275321] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1537.070315] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1556.877728] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1559.482783] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 1566.577727] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1569.501152] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1580.848630] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-09-0321h47m13s [ 1582.481478] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1585.128541] Lustre: lustre-MDT0000-mdc-ffff8b5a0bac0000: Connection to lustre-MDT0000 (at 192.168.204.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1592.112268] LustreError: 36750:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff8b5a0bac0000: can't stat MDS #0: rc = -19 [ 1593.687921] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1602.556905] LustreError: MGC192.168.204.152@tcp: Connection to MGS (at 192.168.204.152@tcp) was lost; in progress operations using this service will fail [ 1602.608977] Lustre: Evicted from MGS (at 192.168.204.152@tcp) after server handle changed from 0xfe0276699f3e11ea to 0xfe0276699f3e14b4 [ 1602.631778] Lustre: MGC192.168.204.152@tcp: Connection restored to 192.168.204.152@tcp (at 192.168.204.152@tcp) [ 1603.629861] LustreError: lustre-MDT0000-mdc-ffff8b5a0bac0000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1603.660718] Lustre: lustre-MDT0000-mdc-ffff8b5a0bac0000: Connection restored to 192.168.204.152@tcp (at 192.168.204.152@tcp) [ 1610.056774] Lustre: DEBUG MARKER: Wait for df (36750) ... [ 1611.267169] Lustre: DEBUG MARKER: done [ 1613.651862] Lustre: Unmounted lustre-client [ 1638.705491] Key type lgssc unregistered [ 1638.959836] LNet: 37763:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1638.974045] LNetError: 37763:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1639.003110] LNet: Removed LNI 192.168.204.52@tcp [ 1639.630241] Key type .llcrypt unregistered [ 1639.633970] Key type ._llcrypt unregistered [ 1669.234447] Key type ._llcrypt registered [ 1669.236537] Key type .llcrypt registered [ 1670.537371] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 21:48:43 (1788486523) [ 1672.715581] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1672.819339] alg: No test for adler32 (adler32-zlib) [ 1673.902803] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 1674.198448] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [ 1675.847284] Key type lgssc registered [ 1676.903957] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1729.014144] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1736.319413] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1760.261423] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1763.131186] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 1767.410174] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-09-0321h50m20s [ 1768.756859] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1775.428961] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1777.743634] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1798.111170] Lustre: 41908:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788486631/real 1788486631] req@00000000f8d86389 x1875364049590656/t0(0) o101->lustre-MDT0000-mdc-ffff8b5a0b184000@192.168.204.152@tcp:12/10 lens 576/1152 e 0 to 1 dl 1788486651 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1798.139061] Lustre: lustre-MDT0000-mdc-ffff8b5a0b184000: Connection to lustre-MDT0000 (at 192.168.204.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1798.185640] Lustre: lustre-MDT0000-mdc-ffff8b5a0b184000: Connection restored to 192.168.204.152@tcp (at 192.168.204.152@tcp) [ 1819.345957] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1822.163970] Lustre: DEBUG MARKER: done [ 1825.526888] Lustre: Unmounted lustre-client [ 1860.353332] Key type lgssc unregistered [ 1860.618593] LNet: 42786:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1860.641606] LNetError: 42786:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1860.674231] LNet: Removed LNI 192.168.204.52@tcp [ 1861.248567] Key type .llcrypt unregistered [ 1861.250516] Key type ._llcrypt unregistered [ 1895.482532] Key type ._llcrypt registered [ 1895.486423] Key type .llcrypt registered [ 1896.990414] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 21:52:29 (1788486749) [ 1898.746249] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1900.997726] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 21:52:33 (1788486753) [ 1905.807574] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1905.888894] alg: No test for adler32 (adler32-zlib) [ 1907.037520] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 1907.348499] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [ 1909.071349] Key type lgssc registered [ 1910.227244] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1938.997679] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 21:53:11 (1788486791) [ 1968.553886] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1973.855399] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1991.666290] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1994.408849] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 1998.001980] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 2000.481686] Lustre: Unmounted lustre-client [ 2011.791244] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 2059.267984] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2065.215746] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2065.934661] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 2069.839866] Lustre: Unmounted lustre-client [ 2140.708127] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2146.933321] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2147.522943] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 2152.184684] Lustre: Unmounted lustre-client [ 2176.235656] Lustre: DEBUG MARKER: files compared the same [ 2200.734930] Key type lgssc unregistered [ 2201.069298] LNet: 50419:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2201.078193] LNetError: 50419:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2201.095484] LNet: Removed LNI 192.168.204.52@tcp [ 2202.017320] Key type .llcrypt unregistered [ 2202.024941] Key type ._llcrypt unregistered [ 2218.248570] Key type ._llcrypt registered [ 2218.250943] Key type .llcrypt registered [ 2219.742485] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 21:57:52 (1788487072) [ 2222.449405] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2222.492777] alg: No test for adler32 (adler32-zlib) [ 2223.768967] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 2224.141118] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [ 2225.943224] Key type lgssc registered [ 2227.532376] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2279.514226] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2285.352798] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2305.054689] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2307.533532] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 2316.761541] Lustre: Unmounted lustre-client [ 2349.535217] Key type lgssc unregistered [ 2349.841272] LNet: 54005:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2349.857584] LNetError: 54005:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2349.897757] LNet: Removed LNI 192.168.204.52@tcp [ 2350.634854] Key type .llcrypt unregistered [ 2350.645643] Key type ._llcrypt unregistered [ 2365.251955] Key type ._llcrypt registered [ 2365.254395] Key type .llcrypt registered [ 2366.969367] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 22:00:18 (1788487218) [ 2369.168706] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2369.355713] alg: No test for adler32 (adler32-zlib) [ 2370.436357] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 2370.735668] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [ 2372.399190] Key type lgssc registered [ 2373.740494] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2457.706682] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2490.604676] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2495.760615] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2530.961249] Key type lgssc unregistered [ 2531.252725] LNet: 57500:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2531.259988] LNetError: 57500:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2531.290243] LNet: Removed LNI 192.168.204.52@tcp [ 2532.011295] Key type .llcrypt unregistered [ 2532.013821] Key type ._llcrypt unregistered [ 2545.230735] Key type ._llcrypt registered [ 2545.233012] Key type .llcrypt registered [ 2546.369629] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 22:03:19 (1788487399) [ 2549.112139] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2549.124626] alg: No test for adler32 (adler32-zlib) [ 2550.189856] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 2550.387361] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [ 2552.031722] Key type lgssc registered [ 2553.033912] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2617.152763] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 2624.270649] Lustre: Unmounted lustre-client [ 2663.094661] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 22:05:15 (1788487515) [ 2779.498274] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2796.872138] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 2803.366357] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2803.376444] LustreError: 63974:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff8b5a0b181000: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2803.390484] Lustre: 58480:0:(llite_lib.c:4342:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.204.152@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2813.812092] Lustre: Unmounted lustre-client [ 2876.831378] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 22:08:49 (1788487729) [ 2889.827741] Key type lgssc unregistered [ 2890.140217] LNet: 65190:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2890.157518] LNetError: 65190:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2890.189518] LNet: Removed LNI 192.168.204.52@tcp [ 2890.908337] Key type .llcrypt unregistered [ 2890.910140] Key type ._llcrypt unregistered [ 2904.156364] Key type ._llcrypt registered [ 2904.160931] Key type .llcrypt registered [ 2904.574988] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2904.593404] alg: No test for adler32 (adler32-zlib) [ 2905.679684] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 2905.948700] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [ 2907.665552] Key type lgssc registered [ 2909.033440] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2966.301540] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2972.973438] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2997.746795] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3008.607922] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 3013.083923] Lustre: Unmounted lustre-client [ 3041.405715] Key type lgssc unregistered [ 3041.665305] LNet: 68203:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3041.678558] LNetError: 68203:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3041.716624] LNet: Removed LNI 192.168.204.52@tcp [ 3042.694365] Key type .llcrypt unregistered [ 3042.699344] Key type ._llcrypt unregistered [ 3055.268916] Key type ._llcrypt registered [ 3055.283796] Key type .llcrypt registered [ 3055.514538] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3055.530986] alg: No test for adler32 (adler32-zlib) [ 3056.614719] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 3056.886849] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [ 3058.663183] Key type lgssc registered [ 3060.077274] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3168.510531] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3174.458067] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3197.083326] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3198.881428] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 3206.637898] Lustre: Unmounted lustre-client [ 3243.466239] Key type lgssc unregistered [ 3243.764524] LNet: 71631:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3243.777544] LNetError: 71631:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3243.795252] LNet: Removed LNI 192.168.204.52@tcp [ 3244.546461] Key type .llcrypt unregistered [ 3244.549676] Key type ._llcrypt unregistered [ 3261.498762] Key type ._llcrypt registered [ 3261.506530] Key type .llcrypt registered [ 3262.691744] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 22:15:15 (1788488115) [ 3265.291624] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3265.328120] alg: No test for adler32 (adler32-zlib) [ 3266.570667] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 3266.767669] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [ 3268.407178] Key type lgssc registered [ 3269.760903] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3333.693750] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3339.898915] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3361.587745] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3373.632436] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 3379.808777] Lustre: Unmounted lustre-client [ 3380.374426] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 3383.190290] Lustre: Unmounted lustre-client [ 3411.076812] Key type lgssc unregistered [ 3411.292305] LNet: 75319:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3411.302818] LNetError: 75319:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3411.330771] LNet: Removed LNI 192.168.204.52@tcp [ 3412.038353] Key type .llcrypt unregistered [ 3412.040056] Key type ._llcrypt unregistered [ 3425.299081] Key type ._llcrypt registered [ 3425.313468] Key type .llcrypt registered [ 3426.350344] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3426.363710] alg: No test for adler32 (adler32-zlib) [ 3427.631526] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 3427.902900] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [ 3429.599626] Key type lgssc registered [ 3431.113609] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3490.561732] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3495.836851] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3516.817452] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3518.457757] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 3525.763153] Lustre: Unmounted lustre-client [ 3554.106574] Key type lgssc unregistered [ 3554.470546] LNet: 78354:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3554.485632] LNetError: 78354:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3554.512168] LNet: Removed LNI 192.168.204.52@tcp [ 3555.443299] Key type .llcrypt unregistered [ 3555.452621] Key type ._llcrypt unregistered [ 3572.683646] Key type ._llcrypt registered [ 3572.690794] Key type .llcrypt registered [ 3574.124967] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 22:20:26 (1788488426) [ 3577.054093] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3577.186413] alg: No test for adler32 (adler32-zlib) [ 3578.405242] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 3578.736146] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [ 3580.463188] Key type lgssc registered [ 3582.081630] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3641.467726] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3647.499413] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3670.242779] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3672.987701] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 3680.717377] Lustre: lustre: root_squash is set to 0:0 [ 3686.988186] Lustre: lustre: nosquash_nids is cleared [ 3695.128972] Lustre: lustre: root_squash is set to 500:500 [ 3703.802150] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.204.52@tcp 192.168.0.[2,10]@tcp [ 3721.785437] Lustre: lustre: nosquash_nids set to 192.168.204.52/24@tcp [ 3724.479740] Lustre: Unmounted lustre-client [ 3757.711789] Key type lgssc unregistered [ 3758.063939] LNet: 82343:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3758.076249] LNetError: 82343:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3758.113752] LNet: Removed LNI 192.168.204.52@tcp [ 3759.292083] Key type .llcrypt unregistered [ 3759.294738] Key type ._llcrypt unregistered [ 3776.109292] Key type ._llcrypt registered [ 3776.111119] Key type .llcrypt registered [ 3777.620744] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 22:23:50 (1788488630) [ 3780.364276] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3781.918561] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 22:23:54 (1788488634) [ 3784.797852] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3784.814435] alg: No test for adler32 (adler32-zlib) [ 3785.950815] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 3786.270689] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [ 3787.983204] Key type lgssc registered [ 3789.265072] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3840.252132] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3846.744759] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3867.922825] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3869.540732] Lustre: lustre: root_squash is set to 500:500 [ 3869.562362] Lustre: lustre: nosquash_nids set to 192.168.204.52/24@tcp [ 3870.702249] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 3880.034803] Lustre: Unmounted lustre-client [ 3909.401773] Key type lgssc unregistered [ 3909.646167] LNet: 86302:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3909.653958] LNetError: 86302:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3909.675408] LNet: Removed LNI 192.168.204.52@tcp [ 3910.445809] Key type .llcrypt unregistered [ 3910.451625] Key type ._llcrypt unregistered [ 3926.014948] Key type ._llcrypt registered [ 3926.017305] Key type .llcrypt registered [ 3927.368894] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 3929.557303] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 22:26:21 (1788488781) [ 3949.508220] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3949.543250] alg: No test for adler32 (adler32-zlib) [ 3950.725621] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 3951.069518] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [ 3952.743733] Key type lgssc registered [ 3954.081613] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4042.749501] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4048.546827] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4076.100734] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4152.890222] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4159.627576] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4182.303973] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4196.641497] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 4234.112327] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8b5a089b9000.ost_server_uuid 50 [ 4235.894225] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8b5a089b9000.ost_server_uuid in IDLE state after 0 sec [ 4236.547937] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 4240.775847] Lustre: Unmounted lustre-client [ 4243.637164] Lustre: Unmounted lustre-client [ 4293.973221] Key type lgssc unregistered [ 4294.288480] LNet: 93972:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4294.322899] LNetError: 93972:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4294.367751] LNet: Removed LNI 192.168.204.52@tcp [ 4295.353043] Key type .llcrypt unregistered [ 4295.361979] Key type ._llcrypt unregistered [ 4333.072997] Key type ._llcrypt registered [ 4333.078664] Key type .llcrypt registered [ 4334.758307] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 22:33:06 (1788489186) [ 4356.826448] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4356.840576] alg: No test for adler32 (adler32-zlib) [ 4358.091322] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 4358.555745] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [ 4360.303596] Key type lgssc registered [ 4361.694683] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4454.306831] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4460.120248] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4484.377957] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4485.816524] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 4507.615179] Lustre: 95333:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788489345/real 1788489345] req@00000000688690d1 x1875366863967360/t0(0) o400->lustre-OST0000-osc-ffff8b5a043b3000@192.168.204.152@tcp:28/4 lens 224/224 e 0 to 1 dl 1788489361 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4507.671721] Lustre: lustre-OST0000-osc-ffff8b5a043b3000: Connection to lustre-OST0000 (at 192.168.204.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4512.480238] Lustre: 95334:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788489350/real 1788489350] req@000000002071b4a8 x1875366863967872/t0(0) o400->lustre-OST0000-osc-ffff8b5a043b3000@192.168.204.152@tcp:28/4 lens 224/224 e 0 to 1 dl 1788489366 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4517.855264] Lustre: 95333:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788489355/real 1788489355] req@0000000056011922 x1875366863968384/t0(0) o400->lustre-OST0000-osc-ffff8b5a043b3000@192.168.204.152@tcp:28/4 lens 224/224 e 0 to 1 dl 1788489371 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4517.935152] Lustre: lustre-OST0000-osc-ffff8b5a043b3000: Connection restored to 192.168.204.152@tcp (at 192.168.204.152@tcp) [ 4522.080969] Lustre: 95333:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788489360/real 1788489360] req@00000000a51d7d70 x1875366863968896/t0(0) o400->lustre-OST0000-osc-ffff8b5a043b3000@192.168.204.152@tcp:28/4 lens 224/224 e 0 to 1 dl 1788489376 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4522.992154] Lustre: lustre-MDT0000-mdc-ffff8b5a043b3000: Connection to lustre-MDT0000 (at 192.168.204.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4539.362118] Lustre: 95334:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788489377/real 1788489377] req@000000003822d058 x1875366863971200/t0(0) o400->MGC192.168.204.152@tcp@192.168.204.152@tcp:26/25 lens 224/224 e 0 to 1 dl 1788489393 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4539.420382] LustreError: MGC192.168.204.152@tcp: Connection to MGS (at 192.168.204.152@tcp) was lost; in progress operations using this service will fail [ 4549.619178] Lustre: Evicted from MGS (at 192.168.204.152@tcp) after server handle changed from 0x2b7cb2d56cc76454 to 0x2b7cb2d56cc765ea [ 4549.643343] Lustre: MGC192.168.204.152@tcp: Connection restored to 192.168.204.152@tcp (at 192.168.204.152@tcp) [ 4558.583699] Lustre: lustre-MDT0000-mdc-ffff8b5a043b3000: Connection restored to 192.168.204.152@tcp (at 192.168.204.152@tcp) [ 4566.170938] Lustre: Unmounted lustre-client [ 4596.769128] Key type lgssc unregistered [ 4597.084227] LNet: 99639:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4597.095407] LNetError: 99639:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4597.109199] LNet: Removed LNI 192.168.204.52@tcp [ 4597.833515] Key type .llcrypt unregistered [ 4597.836818] Key type ._llcrypt unregistered [ 4615.314868] Key type ._llcrypt registered [ 4615.316516] Key type .llcrypt registered [ 4616.734438] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 22:37:49 (1788489469) [ 4631.631722] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4631.783958] alg: No test for adler32 (adler32-zlib) [ 4632.870628] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 4633.079773] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [ 4634.743562] Key type lgssc registered [ 4636.158440] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4674.951711] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4778.747459] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4784.761545] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4810.062644] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4812.878713] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 4814.808336] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 4816.679448] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 22:41:09 (1788489669) [ 5041.954730] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5052.340688] Lustre: Unmounted lustre-client [ 5180.828956] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5187.552496] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5215.684488] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5260.236983] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 22:48:33 (1788490113) [ 5351.960357] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5357.909960] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5381.437437] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5382.180871] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 5386.460183] Lustre: Unmounted lustre-client [ 5419.968842] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 22:51:11 (1788490271) [ 5524.932701] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5530.537430] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5556.777473] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5557.414461] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 5561.353129] Lustre: Unmounted lustre-client [ 5588.333566] Key type lgssc unregistered [ 5588.616657] LNet: 122671:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5588.628157] LNetError: 122671:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5588.650818] LNet: Removed LNI 192.168.204.52@tcp [ 5589.460125] Key type .llcrypt unregistered [ 5589.462874] Key type ._llcrypt unregistered [ 5605.390527] Key type ._llcrypt registered [ 5605.396994] Key type .llcrypt registered [ 5607.191947] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 22:54:19 (1788490459) [ 5609.610926] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5609.673458] alg: No test for adler32 (adler32-zlib) [ 5610.892448] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 5611.242576] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [ 5612.943271] Key type lgssc registered [ 5614.361550] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5672.007353] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5677.463966] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5697.755418] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5700.550899] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 5717.863079] Lustre: Unmounted lustre-client [ 5745.377302] Key type lgssc unregistered [ 5745.730884] LNet: 126264:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5745.736571] LNetError: 126264:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5745.759726] LNet: Removed LNI 192.168.204.52@tcp [ 5746.774442] Key type .llcrypt unregistered [ 5746.778799] Key type ._llcrypt unregistered [ 5765.608486] Key type ._llcrypt registered [ 5765.613951] Key type .llcrypt registered [ 5767.122625] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 22:56:59 (1788490619) [ 5769.630177] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5769.705284] alg: No test for adler32 (adler32-zlib) [ 5770.921919] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 5771.244059] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [ 5773.031814] Key type lgssc registered [ 5774.420597] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5833.126918] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5839.770315] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5864.205533] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5866.168810] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 5871.593519] Lustre: lustre-OST0000-osc-ffff8b5a0d073000: Connection to lustre-OST0000 (at 192.168.204.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5886.940450] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff8b5a0d073000.ost_server_uuid 50 [ 5889.402683] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 5901.910381] Lustre: Unmounted lustre-client [ 5928.223527] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 22:59:40 (1788490780) [ 5969.635069] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5977.160238] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6002.203921] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6028.007421] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6028.772075] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 6033.897656] Lustre: lustre-OST0000-osc-ffff8b5a0b184000: Connection to lustre-OST0000 (at 192.168.204.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6062.473337] Lustre: Unmounted lustre-client [ 6110.472595] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 23:02:43 (1788490963) [ 6148.784619] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6154.802166] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6179.322218] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6203.556590] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6204.350566] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 6209.523822] Lustre: lustre-OST0000-osc-ffff8b5a0a20f800: Connection to lustre-OST0000 (at 192.168.204.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6227.225509] Lustre: Unmounted lustre-client [ 6276.551229] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 23:05:29 (1788491129) [ 6372.367096] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6378.778900] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6402.420508] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6486.952434] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6493.105803] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6516.254666] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6547.617354] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 6591.371910] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6595.467430] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 2 sec [ 6598.220510] Lustre: Unmounted lustre-client [ 6630.896893] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 23:11:23 (1788491483) [ 6667.790563] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6673.913993] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6697.601540] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6735.557615] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6768.605950] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 6814.965170] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6816.795175] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6829.261797] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6839.567777] Lustre: Unmounted lustre-client [ 6907.724747] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 23:16:00 (1788491760) [ 6941.121674] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6947.981506] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6970.220897] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6970.960766] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 7000.723451] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7002.325900] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7013.656066] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8b5a05273800.ost_server_uuid 50 [ 7015.096444] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8b5a05273800.ost_server_uuid in IDLE state after 0 sec [ 7018.105929] Lustre: Unmounted lustre-client [ 7018.332110] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 7018.338656] Lustre: Skipped 3 previous similar messages [ 7018.420634] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 7019.447851] LustreError: 149742:0:(sec.c:403:import_sec_validate_get()) import 000000001e59680b (NEW) with no sec [ 7079.631489] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 23:18:52 (1788491932) [ 7111.595889] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7116.899228] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7142.011728] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7167.926665] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7168.635153] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 7168.735992] LustreError: 127229:0:(lmv_obd.c:211:lmv_notify()) activation of lustre-MDT0001_UUID failed: -22 [ 7168.806773] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 7184.507177] Lustre: Unmounted lustre-client [ 7184.515434] Lustre: Skipped 1 previous similar message [ 7233.442885] Key type lgssc unregistered [ 7233.798699] LNet: 153164:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7233.807084] LNetError: 153164:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7233.832063] LNet: Removed LNI 192.168.204.52@tcp [ 7234.512688] Key type .llcrypt unregistered [ 7234.514435] Key type ._llcrypt unregistered [ 7252.962429] Key type ._llcrypt registered [ 7252.965294] Key type .llcrypt registered [ 7254.413740] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 23:21:46 (1788492106) [ 7255.955727] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7256.047512] alg: No test for adler32 (adler32-zlib) [ 7257.179902] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 7257.467870] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [ 7259.135231] Key type lgssc registered [ 7260.413234] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7314.994452] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7321.388222] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7342.716757] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7367.207960] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7368.677493] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7369.845222] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 7377.476286] LustreError: lustre-MDT0001-mdc-ffff8b5a0998e000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 7377.504355] Lustre: lustre-MDT0001-mdc-ffff8b5a0998e000: Connection restored to 192.168.204.152@tcp (at 192.168.204.152@tcp) [ 7384.457611] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7387.150869] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7410.191684] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7414.617166] Lustre: Unmounted lustre-client [ 7440.580784] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 23:24:53 (1788492293) [ 7538.421971] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7544.345475] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7566.892925] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7567.706188] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 7594.645716] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7596.329149] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7607.485719] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7617.841721] Lustre: Unmounted lustre-client [ 7654.135655] Key type lgssc unregistered [ 7654.482955] LNet: 162791:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7654.490768] LNetError: 162791:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7654.514058] LNet: Removed LNI 192.168.204.52@tcp [ 7655.666815] Key type .llcrypt unregistered [ 7655.668955] Key type ._llcrypt unregistered [ 7694.733880] Key type ._llcrypt registered [ 7694.737439] Key type .llcrypt registered [ 7696.175442] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 23:29:08 (1788492548) [ 7698.895710] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7698.986906] alg: No test for adler32 (adler32-zlib) [ 7700.146592] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 7700.498771] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [ 7702.207328] Key type lgssc registered [ 7703.465819] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7756.788074] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7763.834494] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7789.639741] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7791.440074] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 7838.710861] Lustre: Unmounted lustre-client [ 7880.268650] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7880.805043] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 7916.981706] Lustre: Unmounted lustre-client [ 7947.688635] Key type lgssc unregistered [ 7947.954792] LNet: 167244:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7947.961547] LNetError: 167244:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7948.011101] LNet: Removed LNI 192.168.204.52@tcp [ 7948.931656] Key type .llcrypt unregistered [ 7948.933713] Key type ._llcrypt unregistered [ 7970.081736] Key type ._llcrypt registered [ 7970.083993] Key type .llcrypt registered [ 7971.770988] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 23:33:43 (1788492823) [ 7975.206733] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7975.223472] alg: No test for adler32 (adler32-zlib) [ 7976.508386] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 7976.849138] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [ 7978.639234] Key type lgssc registered [ 7980.322466] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8036.624533] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8042.429935] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8063.959432] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8066.883732] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 8090.454497] Lustre: Unmounted lustre-client [ 8127.772760] Key type lgssc unregistered [ 8128.024166] LNet: 171010:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8128.031554] LNetError: 171010:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8128.061170] LNet: Removed LNI 192.168.204.52@tcp [ 8128.805861] Key type .llcrypt unregistered [ 8128.808892] Key type ._llcrypt unregistered [ 8140.469471] Key type ._llcrypt registered [ 8140.471283] Key type .llcrypt registered [ 8140.939313] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8140.969122] alg: No test for adler32 (adler32-zlib) [ 8142.263582] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 8142.526949] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [ 8144.175632] Key type lgssc registered [ 8146.381341] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8207.106977] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8213.316641] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8237.508292] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8239.480640] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 8247.540028] Lustre: Unmounted lustre-client [ 8284.882883] Key type lgssc unregistered [ 8285.187186] LNet: 174074:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8285.209465] LNetError: 174074:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8285.233256] LNet: Removed LNI 192.168.204.52@tcp [ 8285.989464] Key type .llcrypt unregistered [ 8285.991089] Key type ._llcrypt unregistered [ 8303.263946] Key type ._llcrypt registered [ 8303.268637] Key type .llcrypt registered [ 8304.945436] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 23:39:17 (1788493157) [ 8307.526448] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8307.557638] alg: No test for adler32 (adler32-zlib) [ 8308.713142] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 8308.914070] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [ 8310.585131] Key type lgssc registered [ 8311.926432] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8368.498831] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8375.265952] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8399.501240] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8402.275981] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 8424.718623] Lustre: Unmounted lustre-client [ 8453.396502] Key type lgssc unregistered [ 8453.695685] LNet: 177826:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8453.710540] LNetError: 177826:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8453.739277] LNet: Removed LNI 192.168.204.52@tcp [ 8454.485692] Key type .llcrypt unregistered [ 8454.488075] Key type ._llcrypt unregistered [ 8466.918539] Key type ._llcrypt registered [ 8466.920293] Key type .llcrypt registered [ 8467.285073] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8467.299203] alg: No test for adler32 (adler32-zlib) [ 8468.514912] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 8468.970488] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [ 8470.727708] Key type lgssc registered [ 8472.198373] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8525.885928] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8532.249611] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8554.942828] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8556.642195] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 8568.192438] Lustre: Unmounted lustre-client [ 8596.484869] Key type lgssc unregistered [ 8596.721517] LNet: 180891:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8596.735531] LNetError: 180891:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8596.771973] LNet: Removed LNI 192.168.204.52@tcp [ 8597.743494] Key type .llcrypt unregistered [ 8597.754666] Key type ._llcrypt unregistered [ 8614.063472] Key type ._llcrypt registered [ 8614.068587] Key type .llcrypt registered [ 8615.392662] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 23:44:27 (1788493467) [ 8639.824620] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8639.865889] alg: No test for adler32 (adler32-zlib) [ 8641.074758] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 8641.400904] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [ 8643.143197] Key type lgssc registered [ 8644.213190] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8734.326700] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8740.489223] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8767.137174] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8819.166407] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 23:47:51 (1788493671) [ 8852.960241] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8858.151843] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8882.447507] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8884.206178] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 8888.512554] Lustre: Unmounted lustre-client [ 8917.578639] Key type lgssc unregistered [ 8917.893178] LNet: 187992:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8917.909661] LNetError: 187992:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8917.937784] LNet: Removed LNI 192.168.204.52@tcp [ 8918.753964] Key type .llcrypt unregistered [ 8918.755984] Key type ._llcrypt unregistered [ 8938.397217] Key type ._llcrypt registered [ 8938.403405] Key type .llcrypt registered [ 8939.954062] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 8942.242821] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 23:49:54 (1788493794) [ 8963.293478] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8963.371502] alg: No test for adler32 (adler32-zlib) [ 8964.582329] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 8964.938058] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [ 8966.671241] Key type lgssc registered [ 8968.105845] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9068.027315] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9074.313476] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9100.324545] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9122.762056] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9125.581495] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 9153.164408] Lustre: Unmounted lustre-client [ 9281.362239] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9289.418172] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9314.280652] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9359.231686] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 23:56:51 (1788494211) [ 9361.416955] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 9363.467964] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 23:56:55 (1788494215) [ 9477.879399] Key type lgssc unregistered [ 9478.242023] LNet: 199442:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9478.251548] LNetError: 199442:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9478.276848] LNet: Removed LNI 192.168.204.52@tcp [ 9479.074089] Key type .llcrypt unregistered [ 9479.079465] Key type ._llcrypt unregistered [ 9498.346913] Key type ._llcrypt registered [ 9498.348328] Key type .llcrypt registered [ 9500.070873] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 23:59:12 (1788494352) [ 9548.205639] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9548.292685] alg: No test for adler32 (adler32-zlib) [ 9549.497835] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 9549.778757] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [ 9551.447191] Key type lgssc registered [ 9552.581391] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9603.897957] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9609.752413] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9646.839741] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9676.049127] Key type lgssc unregistered [ 9676.358636] LNet: 203188:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9676.375487] LNetError: 203188:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9676.392402] LNet: Removed LNI 192.168.204.52@tcp [ 9677.136737] Key type .llcrypt unregistered [ 9677.139367] Key type ._llcrypt unregistered [ 9694.363488] Key type ._llcrypt registered [ 9694.367549] Key type .llcrypt registered [ 9695.766897] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 00:02:28 (1788494548) [ 9698.546580] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9698.679221] alg: No test for adler32 (adler32-zlib) [ 9699.796465] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 9700.157740] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [ 9701.856956] Key type lgssc registered [ 9703.024287] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9758.426885] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9764.697857] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9788.000569] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9790.817037] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 9801.193906] Lustre: lustre-MDT0000-mdc-ffff8b5a10795800: Connection to lustre-MDT0000 (at 192.168.204.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9811.441499] Lustre: lustre-MDT0001-mdc-ffff8b5a10795800: Connection to lustre-MDT0001 (at 192.168.204.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9822.626361] Lustre: 204175:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788494660/real 1788494660] req@000000002508e1b5 x1875372465537280/t0(0) o400->MGC192.168.204.152@tcp@192.168.204.152@tcp:26/25 lens 224/224 e 0 to 1 dl 1788494676 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9822.706265] LustreError: MGC192.168.204.152@tcp: Connection to MGS (at 192.168.204.152@tcp) was lost; in progress operations using this service will fail [ 9833.022155] Lustre: Evicted from MGS (at 192.168.204.152@tcp) after server handle changed from 0x3c2e31352fabc8cc to 0x3c2e31352fabe4cc [ 9833.039319] Lustre: MGC192.168.204.152@tcp: Connection restored to 192.168.204.152@tcp (at 192.168.204.152@tcp) [ 9856.495712] LustreError: lustre-MDT0000-mdc-ffff8b5a10795800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 9856.561708] Lustre: lustre-MDT0000-mdc-ffff8b5a10795800: Connection restored to 192.168.204.152@tcp (at 192.168.204.152@tcp) [ 9861.269870] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9863.427880] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9870.778800] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9872.727694] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9875.729375] Lustre: Unmounted lustre-client [ 9906.794291] Key type lgssc unregistered [ 9907.087660] LNet: 208127:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9907.099358] LNetError: 208127:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9907.129214] LNet: Removed LNI 192.168.204.52@tcp [ 9908.093953] Key type .llcrypt unregistered [ 9908.095667] Key type ._llcrypt unregistered [ 9922.424700] Key type ._llcrypt registered [ 9922.430803] Key type .llcrypt registered [ 9923.806874] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 00:06:16 (1788494776) [ 9925.341846] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9925.398077] alg: No test for adler32 (adler32-zlib) [ 9926.556577] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 9926.832096] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [ 9928.567165] Key type lgssc registered [ 9929.702875] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9977.034517] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9981.926439] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10035.599834] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10041.380549] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10073.554729] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10098.327624] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10135.853918] Key type lgssc unregistered [10136.165047] LNet: 213415:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10136.178901] LNetError: 213415:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10136.191075] LNet: Removed LNI 192.168.204.52@tcp [10137.029585] Key type .llcrypt unregistered [10137.032887] Key type ._llcrypt unregistered [10181.121948] Key type ._llcrypt registered [10181.126115] Key type .llcrypt registered [10182.349321] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 00:10:35 (1788495035) [10222.540792] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10222.608092] alg: No test for adler32 (adler32-zlib) [10223.743571] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [10224.034696] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [10225.711454] Key type lgssc registered [10227.298878] Lustre: Echo OBD driver; http://www.lustre.org/ [10313.293598] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10318.559889] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10339.445867] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10393.902220] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 00:14:06 (1788495246) [10400.421228] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 00:14:13 (1788495253) [10431.919358] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10437.487705] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10459.794754] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10462.543876] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [10468.172374] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [10470.045781] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [10471.663680] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [10473.610742] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [10478.054189] Lustre: lustre-MDT0000-mdc-ffff8b5a0a2c3000: Connection to lustre-MDT0000 (at 192.168.204.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [10494.431204] Lustre: 215305:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788495332/real 1788495332] req@000000008ec4ea47 x1875373014918912/t0(0) o400->MGC192.168.204.152@tcp@192.168.204.152@tcp:26/25 lens 224/224 e 0 to 1 dl 1788495348 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10494.473703] LustreError: MGC192.168.204.152@tcp: Connection to MGS (at 192.168.204.152@tcp) was lost; in progress operations using this service will fail [10504.686147] Lustre: Evicted from MGS (at 192.168.204.152@tcp) after server handle changed from 0xcf3a72e4be1c812f to 0xcf3a72e4be1c8351 [10504.702129] Lustre: MGC192.168.204.152@tcp: Connection restored to 192.168.204.152@tcp (at 192.168.204.152@tcp) [10510.067552] Lustre: lustre-MDT0000-mdc-ffff8b5a0a2c3000: Connection restored to 192.168.204.152@tcp (at 192.168.204.152@tcp) [10519.777103] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10521.350940] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10524.857091] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [10530.289959] Lustre: lustre-MDT0000-mdc-ffff8b5a0a2c3000: Connection to lustre-MDT0000 (at 192.168.204.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [10546.655167] Lustre: 215306:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788495384/real 1788495384] req@0000000061cd1803 x1875373014928128/t0(0) o400->MGC192.168.204.152@tcp@192.168.204.152@tcp:26/25 lens 224/224 e 0 to 1 dl 1788495400 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10546.680568] LustreError: MGC192.168.204.152@tcp: Connection to MGS (at 192.168.204.152@tcp) was lost; in progress operations using this service will fail [10556.911075] Lustre: Evicted from MGS (at 192.168.204.152@tcp) after server handle changed from 0xcf3a72e4be1c8351 to 0xcf3a72e4be1c85b2 [10556.932982] Lustre: MGC192.168.204.152@tcp: Connection restored to 192.168.204.152@tcp (at 192.168.204.152@tcp) [10562.849870] Lustre: lustre-MDT0000-mdc-ffff8b5a0a2c3000: Connection restored to 192.168.204.152@tcp (at 192.168.204.152@tcp) [10570.035478] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10571.470651] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10575.597823] Lustre: Unmounted lustre-client [10651.370176] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10657.531378] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10680.751303] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10681.483687] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [10689.554460] Lustre: Unmounted lustre-client [10726.132459] Key type lgssc unregistered [10726.431124] LNet: 225887:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10726.440373] LNetError: 225887:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10726.455487] LNet: Removed LNI 192.168.204.52@tcp [10727.311611] Key type .llcrypt unregistered [10727.316757] Key type ._llcrypt unregistered [10744.881765] Key type ._llcrypt registered [10744.884858] Key type .llcrypt registered [10746.154448] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 00:19:58 (1788495598) [10751.046984] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10751.085034] alg: No test for adler32 (adler32-zlib) [10752.147983] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [10752.380543] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [10754.073144] Key type lgssc registered [10755.470874] Lustre: Echo OBD driver; http://www.lustre.org/ [10809.024777] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10814.446501] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10836.967084] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10839.543959] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [10846.019272] Lustre: Unmounted lustre-client [10905.703482] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10911.795288] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10936.568162] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10937.133459] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [10946.269375] Lustre: Unmounted lustre-client [11018.851256] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11025.135501] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11046.319712] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11046.868490] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [11049.670988] Lustre: Unmounted lustre-client [11086.213719] Key type lgssc unregistered [11086.527238] LNet: 232927:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11086.535707] LNetError: 232927:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11086.552122] LNet: Removed LNI 192.168.204.52@tcp [11087.236270] Key type .llcrypt unregistered [11087.239177] Key type ._llcrypt unregistered [11104.274382] Key type ._llcrypt registered [11104.277862] Key type .llcrypt registered [11105.827478] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 00:25:58 (1788495958) [11109.444557] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11109.520044] alg: No test for adler32 (adler32-zlib) [11110.608676] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [11110.904046] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [11112.655201] Key type lgssc registered [11114.093638] Lustre: Echo OBD driver; http://www.lustre.org/ [11160.773235] Key type lgssc unregistered [11161.088534] LNet: 235244:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11161.094279] LNetError: 235244:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11161.111777] LNet: Removed LNI 192.168.204.52@tcp [11161.838646] Key type .llcrypt unregistered [11161.840979] Key type ._llcrypt unregistered [11191.358192] Key type ._llcrypt registered [11191.364027] Key type .llcrypt registered [11191.955679] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11191.977767] alg: No test for adler32 (adler32-zlib) [11193.140754] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [11193.394957] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [11195.127211] Key type lgssc registered [11196.816206] Lustre: Echo OBD driver; http://www.lustre.org/ [11288.290651] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11294.402524] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11319.693784] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11364.570524] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [11366.237276] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 00:30:18 (1788496218) [11400.979174] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11406.778951] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11429.436374] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11453.284845] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11454.946640] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [11460.074207] Lustre: lustre-OST0001-osc-ffff8b5a0a208800: Connection to lustre-OST0001 (at 192.168.204.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [11468.940171] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11479.380561] Lustre: Unmounted lustre-client [11515.379305] Key type lgssc unregistered [11515.843969] LNet: 242356:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11515.853826] LNetError: 242356:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11515.890959] LNet: Removed LNI 192.168.204.52@tcp [11516.818206] Key type .llcrypt unregistered [11516.825985] Key type ._llcrypt unregistered [11551.035402] Key type ._llcrypt registered [11551.039585] Key type .llcrypt registered [11552.223680] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 00:33:24 (1788496404) [11567.256393] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11567.395604] alg: No test for adler32 (adler32-zlib) [11568.540931] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [11568.850268] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [11570.600657] Key type lgssc registered [11572.169895] Lustre: Echo OBD driver; http://www.lustre.org/ [11632.324759] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11638.549964] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11662.839116] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 00:35:15 (1788496515) [11698.650494] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11704.493352] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11727.356556] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11729.966434] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [11740.676125] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [11746.761181] Lustre: Unmounted lustre-client [11857.337571] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11864.052754] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11919.851969] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11920.619111] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [11923.707609] Lustre: Unmounted lustre-client [11949.393640] Key type lgssc unregistered [11949.848814] LNet: 250053:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11949.859439] LNetError: 250053:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11949.903552] LNet: Removed LNI 192.168.204.52@tcp [11950.789810] Key type .llcrypt unregistered [11950.795706] Key type ._llcrypt unregistered [11984.184789] Key type ._llcrypt registered [11984.245173] Key type .llcrypt registered [11984.658552] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11984.688573] alg: No test for adler32 (adler32-zlib) [11985.868504] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [11986.170868] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [11987.943198] Key type lgssc registered [11989.594921] Lustre: Echo OBD driver; http://www.lustre.org/ [12080.378197] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12087.386068] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12112.592853] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12155.424216] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [12156.996665] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 00:43:29 (1788497009) [12192.956866] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12199.350586] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12222.194937] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12228.152778] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [12233.346385] Lustre: Unmounted lustre-client [12262.148975] Key type lgssc unregistered [12262.561350] LNet: 256687:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12262.581400] LNetError: 256687:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12262.604818] LNet: Removed LNI 192.168.204.52@tcp [12263.529371] Key type .llcrypt unregistered [12263.530992] Key type ._llcrypt unregistered [12282.498305] Key type ._llcrypt registered [12282.500698] Key type .llcrypt registered [12283.519190] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [12285.559542] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 00:45:37 (1788497137) [12295.921672] Key type .llcrypt unregistered [12295.932382] Key type ._llcrypt unregistered [12306.896473] Key type ._llcrypt registered [12306.903960] Key type .llcrypt registered [12307.489892] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12307.509392] alg: No test for adler32 (adler32-zlib) [12308.540491] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [12308.949967] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [12310.697888] Key type lgssc registered [12312.183345] Lustre: Echo OBD driver; http://www.lustre.org/ [12370.773904] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12388.411241] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [12392.213680] Lustre: Unmounted lustre-client [12420.779887] Key type lgssc unregistered [12421.154431] LNet: 260136:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12421.165821] LNetError: 260136:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12421.198806] LNet: Removed LNI 192.168.204.52@tcp [12422.105892] Key type .llcrypt unregistered [12422.108417] Key type ._llcrypt unregistered [12438.169577] Key type ._llcrypt registered [12438.174297] Key type .llcrypt registered [12439.833938] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 00:48:11 (1788497291) [12442.817203] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12442.886461] alg: No test for adler32 (adler32-zlib) [12443.947733] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [12444.555389] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [12446.575550] Key type lgssc registered [12449.738349] Lustre: Echo OBD driver; http://www.lustre.org/ [12539.496689] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12574.071525] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12579.502355] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12581.340208] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [12584.621571] Lustre: Unmounted lustre-client [12604.277590] Key type lgssc unregistered [12604.555258] LNet: 263674:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12604.560692] LNetError: 263674:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12604.595490] LNet: Removed LNI 192.168.204.52@tcp [12605.472789] Key type .llcrypt unregistered [12605.477871] Key type ._llcrypt unregistered [12622.413174] Key type ._llcrypt registered [12622.414590] Key type .llcrypt registered [12623.971592] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 00:51:16 (1788497476) [12626.288324] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12626.390090] alg: No test for adler32 (adler32-zlib) [12627.399910] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [12627.675520] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [12629.367181] Key type lgssc registered [12630.343448] Lustre: Echo OBD driver; http://www.lustre.org/ [12680.202740] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12686.047358] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12706.923886] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12709.796372] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [12714.996402] Lustre: lustre-MDT0000-mdc-ffff8b5a26566000: Connection to lustre-MDT0000 (at 192.168.204.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [12720.096932] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12720.124162] LustreError: 266825:0:(file.c:6167:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [12720.566509] LustreError: MGC192.168.204.152@tcp: Connection to MGS (at 192.168.204.152@tcp) was lost; in progress operations using this service will fail [12720.628478] Lustre: Unmounted lustre-client [12741.568915] Key type lgssc unregistered [12741.824087] LNet: 267238:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12741.837347] LNetError: 267238:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12741.861615] LNet: Removed LNI 192.168.204.52@tcp [12742.470782] Key type .llcrypt unregistered [12742.473045] Key type ._llcrypt unregistered [12759.690472] Key type ._llcrypt registered [12759.703886] Key type .llcrypt registered [12761.731091] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 00:53:33 (1788497613) [12764.715996] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12764.738516] alg: No test for adler32 (adler32-zlib) [12766.043580] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [12766.614175] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [12768.455360] Key type lgssc registered [12770.183557] Lustre: Echo OBD driver; http://www.lustre.org/ [12822.157515] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12827.859488] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12848.592278] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12850.256338] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [12855.294965] Lustre: lustre-MDT0001-mdc-ffff8b5a0b180800: Connection to lustre-MDT0001 (at 192.168.204.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [12860.354293] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [12862.753028] Lustre: Unmounted lustre-client [12882.433674] Key type lgssc unregistered [12882.740969] LNet: 270813:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12882.749455] LNetError: 270813:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12882.771225] LNet: Removed LNI 192.168.204.52@tcp [12883.413205] Key type .llcrypt unregistered [12883.417269] Key type ._llcrypt unregistered [12899.753099] Key type ._llcrypt registered [12899.754903] Key type .llcrypt registered [12900.818676] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 00:55:53 (1788497753) [12918.017495] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12918.046597] alg: No test for adler32 (adler32-zlib) [12919.022361] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [12919.234868] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [12920.953375] Key type lgssc registered [12922.321916] Lustre: Echo OBD driver; http://www.lustre.org/ [13037.946319] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13040.606180] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [13085.670450] Lustre: lustre-MDT0001-mdc-ffff8b5a0ba30000: Connection to lustre-MDT0001 (at 192.168.204.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [13102.241720] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [13112.666300] Lustre: Unmounted lustre-client [13140.926164] Key type lgssc unregistered [13141.150292] LNet: 276593:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13141.156882] LNetError: 276593:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13141.175454] LNet: Removed LNI 192.168.204.52@tcp [13141.872986] Key type .llcrypt unregistered [13141.877517] Key type ._llcrypt unregistered [13157.080483] Key type ._llcrypt registered [13157.084772] Key type .llcrypt registered [13158.722041] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 01:00:10 (1788498010) [13160.406887] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [13162.309556] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 01:00:14 (1788498014) [13164.156652] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [13166.297807] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 01:00:18 (1788498018) [13168.232835] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [13170.006448] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 01:00:22 (1788498022) [13171.772441] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [13173.701263] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 01:00:26 (1788498026) [13175.575230] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [13177.198247] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 01:00:29 (1788498029) [13187.963868] Key type .llcrypt unregistered [13187.967676] Key type ._llcrypt unregistered [13198.193780] Key type ._llcrypt registered [13198.198901] Key type .llcrypt registered [13199.003921] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13199.027739] alg: No test for adler32 (adler32-zlib) [13200.229494] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [13200.531657] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [13202.215387] Key type lgssc registered [13203.807160] Lustre: Echo OBD driver; http://www.lustre.org/ [13292.500322] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13298.403688] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13327.823540] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13329.546502] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [13332.528319] Lustre: Unmounted lustre-client [13364.519891] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 01:03:36 (1788498216) [13402.476330] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13408.292357] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13444.099968] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13450.325217] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [13451.776503] Lustre: Unmounted lustre-client [13497.516879] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 01:05:50 (1788498350) [13501.664334] LNet: 1 local NIs in recovery (showing 1): 192.168.204.52@tcp [14157.039974] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14164.055208] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14205.084926] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14331.211395] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 01:19:43 (1788499183) [14346.701244] Key type lgssc unregistered [14347.020838] LNet: 290145:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14347.032724] LNetError: 290145:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14348.076146] LNet: Removed LNI 192.168.204.52@tcp [14348.780387] Key type .llcrypt unregistered [14348.782176] Key type ._llcrypt unregistered [14449.651788] Key type ._llcrypt registered [14449.656025] Key type .llcrypt registered [14450.202202] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14450.220521] alg: No test for adler32 (adler32-zlib) [14451.462781] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [14451.758287] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [14453.535197] Key type lgssc registered [14454.550549] Lustre: Echo OBD driver; http://www.lustre.org/ [14551.926835] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14559.027568] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14584.644533] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14628.645860] Key type lgssc unregistered [14628.832042] LNet: 293841:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14628.841335] LNetError: 293841:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14628.856148] LNet: Removed LNI 192.168.204.52@tcp [14629.563069] Key type .llcrypt unregistered [14629.567683] Key type ._llcrypt unregistered [14656.735887] Key type ._llcrypt registered [14656.739879] Key type .llcrypt registered [14658.669913] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 01:25:10 (1788499510) [14683.147759] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14683.171354] alg: No test for adler32 (adler32-zlib) [14684.355946] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [14684.705662] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [14686.407231] Key type lgssc registered [14687.851921] Lustre: Echo OBD driver; http://www.lustre.org/ [14741.315963] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 01:26:33 (1788499593) [14754.567843] Key type lgssc unregistered [14754.928561] LNet: 297396:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14754.942880] LNetError: 297396:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14754.961502] LNet: Removed LNI 192.168.204.52@tcp [14755.840169] Key type .llcrypt unregistered [14755.842205] Key type ._llcrypt unregistered [14766.556743] Key type ._llcrypt registered [14766.564373] Key type .llcrypt registered [14766.955212] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14766.973874] alg: No test for adler32 (adler32-zlib) [14768.068535] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [14768.322576] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [14770.103175] Key type lgssc registered [14771.376551] Lustre: Echo OBD driver; http://www.lustre.org/ [14831.064674] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14837.852930] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14863.166247] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15192.105942] LNet: Added LNI 192.168.204.52@tcp50 [8/256/0/180] [15195.311136] LNet: Removed LNI 192.168.204.52@tcp [15197.645371] Lustre: 301814:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff8b5a076f6000: config has no valid NIDs, force dynamic NIDs [15199.028317] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [15199.335729] Lustre: Unmounted lustre-client [15202.660302] Key type lgssc unregistered [15203.163963] LNet: 302072:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15203.168336] LNetError: 302072:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15203.190192] LNet: Removed LNI 192.168.204.52@tcp50 [15204.073222] Key type .llcrypt unregistered [15204.075477] Key type ._llcrypt unregistered [15223.527089] Key type ._llcrypt registered [15223.532488] Key type .llcrypt registered [15224.198481] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15224.225895] alg: No test for adler32 (adler32-zlib) [15225.479946] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [15225.780809] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [15227.495186] Key type lgssc registered [15229.120609] Lustre: Echo OBD driver; http://www.lustre.org/ [15293.900877] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15301.151625] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15310.289229] LNet: Added LNI 192.168.204.52@tcp50 [8/256/0/180] [15311.108466] LNet: Removed LNI 192.168.204.52@tcp [15312.315085] Lustre: 304763:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff8b5a0ba31000: config has no valid NIDs, force dynamic NIDs [15313.505123] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [15314.230698] Lustre: Unmounted lustre-client [15361.645495] Key type lgssc unregistered [15361.996480] LNet: 305635:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15362.001352] LNetError: 305635:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15362.020758] LNet: Removed LNI 192.168.204.52@tcp50 [15362.958887] Key type .llcrypt unregistered [15362.962522] Key type ._llcrypt unregistered [15377.845277] Key type ._llcrypt registered [15377.849427] Key type .llcrypt registered [15379.639260] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 01:37:11 (1788500231) [15403.875224] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15404.068381] alg: No test for adler32 (adler32-zlib) [15405.345240] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [15405.836623] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [15407.680086] Key type lgssc registered [15409.391438] Lustre: Echo OBD driver; http://www.lustre.org/ [15505.515572] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15512.039623] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15540.548633] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15577.224453] Key type lgssc unregistered [15577.483033] LNet: 310385:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15577.491596] LNetError: 310385:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15577.517147] LNet: Removed LNI 192.168.204.52@tcp [15578.371125] Key type .llcrypt unregistered [15578.373766] Key type ._llcrypt unregistered [15589.420713] Key type ._llcrypt registered [15589.424739] Key type .llcrypt registered [15590.646428] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 01:40:43 (1788500443) [15592.475853] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15592.591108] alg: No test for adler32 (adler32-zlib) [15593.679383] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [15594.006890] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [15595.783158] Key type lgssc registered [15597.425213] Lustre: Echo OBD driver; http://www.lustre.org/ [15658.166213] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15664.721940] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15690.542419] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15698.030499] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [15765.362960] Lustre: Mounted fs15246-client - version 2.17.57_84_g5b8c505 [15781.175570] Lustre: Unmounted fs15246-client [15814.333397] Lustre: Unmounted lustre-client [15853.961580] Key type lgssc unregistered [15854.248782] LNet: 315253:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15854.261162] LNetError: 315253:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15854.283930] LNet: Removed LNI 192.168.204.52@tcp [15855.154154] Key type .llcrypt unregistered [15855.156087] Key type ._llcrypt unregistered [15868.700129] Key type ._llcrypt registered [15868.703513] Key type .llcrypt registered [15870.309967] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 01:45:22 (1788500722) [15872.800079] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15872.814112] alg: No test for adler32 (adler32-zlib) [15873.973304] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [15874.313560] LNet: Added LNI 192.168.204.52@tcp [8/256/0/180] [15876.071246] Key type lgssc registered [15877.133413] Lustre: Echo OBD driver; http://www.lustre.org/ [16036.652894] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16043.477026] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16072.329906] Lustre: DEBUG MARKER: oleg452-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16201.175789] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 15881 sec ========= 01:50:53 (1788501053) [16203.131677] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 01:50:55 (1788501055) === [16206.872233] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 01:50:59 (1788501059) === [16243.432430] Key type lgssc unregistered [16243.711809] LNet: 324526:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16243.728081] LNetError: 324526:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16243.771691] LNet: Removed LNI 192.168.204.52@tcp [16244.704921] Key type .llcrypt unregistered [16244.706475] Key type ._llcrypt unregistered