[ 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 619578941 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.001010] APIC: Switch to symmetric I/O mode setup [ 0.003073] x2apic enabled [ 0.004006] Switched APIC routing to physical x2apic. [ 0.005015] kvm-guest: setup PV IPIs [ 0.008632] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.009017] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010010] pid_max: default: 32768 minimum: 301 [ 0.011110] LSM: Security Framework initializing [ 0.012032] Yama: becoming mindful. [ 0.013026] SELinux: Initializing. [ 0.014051] *** VALIDATE selinux *** [ 0.022228] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026332] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.028072] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029079] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030080] *** VALIDATE tmpfs *** [ 0.032103] *** VALIDATE proc *** [ 0.033224] *** VALIDATE cgroup *** [ 0.034012] *** VALIDATE cgroup2 *** [ 0.035323] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.036190] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.037008] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.038025] Spectre V2 : User space: Vulnerable [ 0.039007] Speculative Store Bypass: Vulnerable [ 0.042315] debug: unmapping init [mem 0xffffffff85a59000-0xffffffff85a60fff] [ 0.044237] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.045677] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.046019] ... version: 2 [ 0.047009] ... bit width: 48 [ 0.048009] ... generic registers: 4 [ 0.049009] ... value mask: 0000ffffffffffff [ 0.050009] ... max period: 00007fffffffffff [ 0.051009] ... fixed-purpose events: 3 [ 0.052008] ... event mask: 000000070000000f [ 0.053279] rcu: Hierarchical SRCU implementation. [ 0.055410] smp: Bringing up secondary CPUs ... [ 0.056523] x86: Booting SMP configuration: [ 0.057019] .... node #0, CPUs: #1 #2 #3 [ 0.069170] smp: Brought up 1 node, 4 CPUs [ 0.071017] smpboot: Max logical packages: 1 [ 0.072023] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.126345] node 0 deferred pages initialised in 52ms [ 0.128081] devtmpfs: initialized [ 0.130038] x86/mm: Memory block size: 128MB [ 0.134100] gcov: version magic: 0x41383552 [ 0.137449] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.138070] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.139216] pinctrl core: initialized pinctrl subsystem [ 0.140184] [ 0.141008] ************************************************************* [ 0.142013] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.143012] ** ** [ 0.144034] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.145009] ** ** [ 0.146011] ** This means that this kernel is built to expose internal ** [ 0.147014] ** IOMMU data structures, which may compromise security on ** [ 0.148010] ** your system. ** [ 0.149022] ** ** [ 0.150013] ** If you see this message and you are not debugging the ** [ 0.151013] ** kernel, report this immediately to your vendor! ** [ 0.152010] ** ** [ 0.153011] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.154008] ************************************************************* [ 0.155748] NET: Registered protocol family 16 [ 0.156927] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.157064] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.158073] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.159410] cpuidle: using governor menu [ 0.160000] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.165653] PCI: Using configuration type 1 for base access [ 0.169129] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.185123] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.187067] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.193270] cryptd: max_cpu_qlen set to 1000 [ 0.194000] ACPI: Added _OSI(Module Device) [ 0.194014] ACPI: Added _OSI(Processor Device) [ 0.197017] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.201013] ACPI: Added _OSI(Processor Aggregator Device) [ 0.210289] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.219732] ACPI: Interpreter enabled [ 0.223073] ACPI: PM: (supports S0 S3 S4 S5) [ 0.225010] ACPI: Using IOAPIC for interrupt routing [ 0.230102] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.234375] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.247634] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.250035] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.253015] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.256065] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.261478] acpiphp: Slot [2] registered [ 0.262185] acpiphp: Slot [5] registered [ 0.264126] acpiphp: Slot [6] registered [ 0.265136] acpiphp: Slot [3] registered [ 0.266083] acpiphp: Slot [4] registered [ 0.267000] acpiphp: Slot [7] registered [ 0.268101] acpiphp: Slot [8] registered [ 0.269074] acpiphp: Slot [9] registered [ 0.271093] acpiphp: Slot [10] registered [ 0.273112] acpiphp: Slot [11] registered [ 0.276097] acpiphp: Slot [12] registered [ 0.277089] acpiphp: Slot [13] registered [ 0.278102] acpiphp: Slot [14] registered [ 0.279113] acpiphp: Slot [15] registered [ 0.282046] acpiphp: Slot [16] registered [ 0.283094] acpiphp: Slot [17] registered [ 0.285214] acpiphp: Slot [18] registered [ 0.286138] acpiphp: Slot [19] registered [ 0.288083] acpiphp: Slot [20] registered [ 0.289097] acpiphp: Slot [21] registered [ 0.291078] acpiphp: Slot [22] registered [ 0.292076] acpiphp: Slot [23] registered [ 0.294082] acpiphp: Slot [24] registered [ 0.295085] acpiphp: Slot [25] registered [ 0.297136] acpiphp: Slot [26] registered [ 0.300110] acpiphp: Slot [27] registered [ 0.309198] acpiphp: Slot [28] registered [ 0.314122] acpiphp: Slot [29] registered [ 0.315682] acpiphp: Slot [30] registered [ 0.318092] acpiphp: Slot [31] registered [ 0.319060] PCI host bridge to bus 0000:00 [ 0.321014] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.322019] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.323019] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.324000] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.324000] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.326022] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.329238] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.332063] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.335835] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.347445] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.351087] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.356019] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.358013] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.360013] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.364668] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.367882] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.371102] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.373917] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.376000] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.383940] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.389949] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.396587] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.404014] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.409013] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.438014] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.449010] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.458011] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.464014] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.487013] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.503838] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.506389] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.508320] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.510310] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.511233] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.514073] iommu: Default domain type: Passthrough [ 0.517326] SCSI subsystem initialized [ 0.518146] ACPI: bus type USB registered [ 0.520094] usbcore: registered new interface driver usbfs [ 0.522062] usbcore: registered new interface driver hub [ 0.523086] usbcore: registered new device driver usb [ 0.525162] pps_core: LinuxPPS API ver. 1 registered [ 0.527009] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.530049] PTP clock support registered [ 0.532165] EDAC MC: Ver: 3.0.0 [ 0.533396] PCI: Using ACPI for IRQ routing [ 0.534521] NetLabel: Initializing [ 0.536010] NetLabel: domain hash size = 128 [ 0.537014] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.539173] NetLabel: unlabeled traffic allowed by default [ 0.541294] vgaarb: loaded [ 0.543344] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.545013] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.551783] clocksource: Switched to clocksource kvm-clock [ 0.784806] VFS: Disk quotas dquot_6.6.0 [ 0.787320] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.789965] *** VALIDATE ramfs *** [ 0.791143] *** VALIDATE hugetlbfs *** [ 0.793625] pnp: PnP ACPI init [ 0.797771] pnp: PnP ACPI: found 6 devices [ 0.823084] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.827190] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.829371] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.832566] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.836063] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.838991] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.843043] NET: Registered protocol family 2 [ 0.847868] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.854476] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.859445] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.868538] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.873772] TCP: Hash tables configured (established 65536 bind 65536) [ 0.880963] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.885603] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.890476] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.895507] NET: Registered protocol family 1 [ 0.900822] RPC: Registered named UNIX socket transport module. [ 0.902649] RPC: Registered udp transport module. [ 0.908092] RPC: Registered tcp transport module. [ 0.911992] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.918173] NET: Registered protocol family 44 [ 0.921835] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.927027] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.931827] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.935283] PCI: CLS 0 bytes, default 64 [ 0.939503] Unpacking initramfs... [ 3.517964] debug: unmapping init [mem 0xffff8ebdfcc64000-0xffff8ebdfffcffff] [ 3.531459] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 3.536835] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 3.541504] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 4.139848] Initialise system trusted keyrings [ 4.141732] Key type blacklist registered [ 4.144448] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 4.162832] zbud: loaded [ 4.168552] *** VALIDATE nfs *** [ 4.169323] *** VALIDATE nfs4 *** [ 4.170539] pstore: using deflate compression [ 4.173341] Platform Keyring initialized [ 4.299065] NET: Registered protocol family 38 [ 4.303364] Key type asymmetric registered [ 4.304672] Asymmetric key parser 'x509' registered [ 4.306590] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 4.313507] io scheduler mq-deadline registered [ 4.317277] io scheduler kyber registered [ 4.318966] io scheduler bfq registered [ 4.320730] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 4.327207] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 4.331884] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 4.338944] ACPI: Power Button [PWRF] [ 4.346717] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 4.374459] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 4.392970] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 4.437775] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 4.474478] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 4.481112] Non-volatile memory driver v1.3 [ 4.483516] Linux agpgart interface v0.103 [ 4.526166] virtio_blk virtio1: [vda] 146208 512-byte logical blocks (74.9 MB/71.4 MiB) [ 4.534356] vda: detected capacity change from 0 to 74858496 [ 4.553408] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 4.556457] vdb: detected capacity change from 0 to 1073741824 [ 4.564246] libphy: Fixed MDIO Bus: probed [ 4.579750] usbcore: registered new interface driver usbserial_generic [ 4.583502] usbserial: USB Serial support registered for generic [ 4.587633] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 4.595614] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 4.599080] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 4.603568] mousedev: PS/2 mouse device common for all mice [ 4.636613] rtc_cmos 00:05: RTC can wake from S4 [ 4.644138] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 4.645986] rtc_cmos 00:05: registered as rtc0 [ 4.652163] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 4.654573] intel_pstate: CPU model not supported [ 4.670408] hid: raw HID events driver (C) Jiri Kosina [ 4.671039] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 4.682489] usbcore: registered new interface driver usbhid [ 4.682498] usbhid: USB HID core driver [ 4.682849] drop_monitor: Initializing network drop monitor service [ 4.682992] Initializing XFRM netlink socket [ 4.683437] NET: Registered protocol family 10 [ 4.693864] Segment Routing with IPv6 [ 4.720406] NET: Registered protocol family 17 [ 4.721464] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 4.727359] mpls_gso: MPLS GSO support [ 4.734672] RAS: Correctable Errors collector initialized. [ 4.736723] AVX version of gcm_enc/dec engaged. [ 4.738422] AES CTR mode by8 optimization enabled [ 4.850557] sched_clock: Marking stable (4850477135, 0)->(5969941161, -1119464026) [ 4.856952] registered taskstats version 1 [ 4.861253] Loading compiled-in X.509 certificates [ 4.865185] zswap: loaded using pool lzo/zbud [ 4.917200] Key type big_key registered [ 4.943635] Key type encrypted registered [ 4.946421] ima: No TPM chip found, activating TPM-bypass! [ 4.950443] ima: Allocated hash algorithm: sha1 [ 4.953175] ima: No architecture policies found [ 4.956778] evm: Initialising EVM extended attributes: [ 4.961820] evm: security.selinux [ 4.964430] evm: security.ima [ 4.967520] evm: security.capability [ 4.968657] evm: HMAC attrs: 0x1 [ 4.974114] rtc_cmos 00:05: setting system clock to 2026-08-24 05:13:26 UTC (1787548406) [ 4.985830] debug: unmapping init [mem 0xffffffff86a03000-0xffffffff86bfffff] [ 4.994481] debug: unmapping init [mem 0xffffffff85782000-0xffffffff85a58fff] [ 5.007060] Write protecting the kernel read-only data: 28672k [ 5.015511] debug: unmapping init [mem 0xffffffff83e03000-0xffffffff83ffffff] [ 5.022535] debug: unmapping init [mem 0xffffffff84714000-0xffffffff847fffff] [ 5.107965] 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) [ 5.125702] systemd[1]: Detected virtualization kvm. [ 5.129380] systemd[1]: Detected architecture x86-64. [ 5.131793] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 5.168944] systemd[1]: No hostname configured. [ 5.171076] systemd[1]: Set hostname to . [ 5.173446] random: systemd: uninitialized urandom read (16 bytes read) [ 5.176341] systemd[1]: Initializing machine ID from random generator. [ 5.553185] random: systemd: uninitialized urandom read (16 bytes read) [ 5.558377] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 5.580462] random: systemd: uninitialized urandom read (16 bytes read) [ 5.582751] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 5.601604] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Reached target Swap. [ OK ] Listening on udev Control Socket. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Timers. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket. Starting Journal Service... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Memstrack Anylazing Service. Starting Apply Kernel Variables... Starting Setup Virtual Console... 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 Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 7.451535] device-mapper: uevent: version 1.0.3 [ 7.460628] 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... [ 9.235750] virtio_net virtio0 ens2: renamed from eth0 [ 9.487171] scsi host0: ata_piix [ 9.548053] scsi host1: ata_piix [ 9.551813] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 9.559371] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 14.479793] random: crng init done [ 14.483768] random: 7 urandom warning(s) missed due to ratelimiting [ 17.467026] dracut-initqueue[591]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 18.869075] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Swap. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Local File Systems. Stopping udev Kernel Device Manager... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ 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 Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 20.662447] printk: systemd: 26 output lines suppressed due to ratelimiting [ 21.078244] SELinux: Disabled at runtime. [ 21.149114] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 21.161012] systemd[1]: Detected virtualization kvm. [ 21.162664] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 22.194909] systemd[1]: initrd-switch-root.service: Succeeded. [ 22.200295] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 22.212883] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 22.219381] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 22.226647] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 22.235313] systemd[1]: Starting Journal Service... Starting Journal Service... [ 22.256118] systemd[1]: Starting Create list of required static device nodes for the current kernel... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice User and Session Slice. [ OK ] Listening on udev Control Socket. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Created slice system-getty.slice. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Listening on Process Core Dump Socket. [ OK ] Reached target rpc_pipefs.target. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Mounting Huge Pages File System... [ OK ] Started Dispatch Password Requests to Console Directory Watch. Mounting POSIX Message Queue File System... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ 22.543078] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Stopped target Initrd File Systems. Mounting Kernel Debug File System... [ OK ] Reached target Slices. [ OK ] Created slice system-serial\x2dgetty.slice. Starting Apply Kernel Variables... Starting Remount Root and Kernel File Systems... [ OK ] Started Journal Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Apply Kernel Variables. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. Starting Configure read-only root support... [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... 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. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 23.409095] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 23.964950] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 23.979581] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 24.230807] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 24.283728] EDAC sbridge: Ver: 1.1.2 [ 26.149948] Key type dns_resolver registered [ 26.767237] NFS: Registering the id_resolver key type [ 26.769279] Key type id_resolver registered [ 26.771213] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Load/Save Random Seed... Starting Create Volatile Files and Directories... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started dnf makecache --timer. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Login Service... Starting Restore /run/initramfs on shutdown... [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg638-client login: [ 104.349149] hrtimer: interrupt took 5571708 ns [ 121.074818] libcfs: loading out-of-tree module taints kernel. [ 121.397159] Key type ._llcrypt registered [ 121.409571] Key type .llcrypt registered [ 122.709658] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 122.728695] alg: No test for adler32 (adler32-zlib) [ 125.902182] Lustre: Lustre: Build Version: 2.17.57_82_g53f9976 [ 129.134739] LNet: Added LNI 192.168.206.38@tcp [8/256/0/180] [ 131.353279] Key type lgssc registered [ 136.346767] Lustre: Echo OBD driver; http://www.lustre.org/ [ 296.256111] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 301.277939] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 311.766937] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing check_logdir /tmp/testlogs/ [ 316.627654] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing yml_node [ 321.237482] Lustre: DEBUG MARKER: Client: 2.17.57.82 [ 322.015772] Lustre: lustre-OST0000-osc-ffff8ebe4cf34000: disconnect after 23s idle [ 323.514583] Lustre: DEBUG MARKER: MDS: 2.17.57.82 [ 326.178379] Lustre: DEBUG MARKER: OSS: 2.17.57.82 [ 327.792102] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Mon Aug 24 01:18:48 EDT 2026 [ 347.747275] Lustre: DEBUG MARKER: excepting tests: 21e 32b 32c 32newtarball [ 349.466368] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 352.514823] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 362.862209] Lustre: Unmounted lustre-client [ 488.298341] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 495.199900] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 519.919144] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 559.555171] Lustre: DEBUG MARKER: == conf-sanity test 0: single mount setup ================ 01:22:39 (1787548959) [ 594.773417] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 601.465449] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 624.897180] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 625.726883] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 633.952913] Lustre: Unmounted lustre-client [ 662.448740] Key type lgssc unregistered [ 662.689076] LNet: 14672:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 662.695108] LNetError: 14672:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 662.724716] LNet: Removed LNI 192.168.206.38@tcp [ 663.442221] Key type .llcrypt unregistered [ 663.448703] Key type ._llcrypt unregistered [ 679.076284] Key type ._llcrypt registered [ 679.080265] Key type .llcrypt registered [ 680.252882] Lustre: DEBUG MARKER: == conf-sanity test 1: start up ost twice (should return errors) ========================================================== 01:24:40 (1787549080) [ 682.712087] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 682.815968] alg: No test for adler32 (adler32-zlib) [ 683.944236] Lustre: Lustre: Build Version: 2.17.57_82_g53f9976 [ 684.401988] LNet: Added LNI 192.168.206.38@tcp [8/256/0/180] [ 686.145608] Key type lgssc registered [ 687.480821] Lustre: Echo OBD driver; http://www.lustre.org/ [ 740.786656] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 746.057301] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 766.870770] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 778.145123] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 781.388191] Lustre: Unmounted lustre-client [ 809.882605] Key type lgssc unregistered [ 810.195190] LNet: 18344:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 810.206314] LNetError: 18344:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 810.230722] LNet: Removed LNI 192.168.206.38@tcp [ 811.327535] Key type .llcrypt unregistered [ 811.329913] Key type ._llcrypt unregistered [ 827.318034] Key type ._llcrypt registered [ 827.324039] Key type .llcrypt registered [ 828.787697] Lustre: DEBUG MARKER: == conf-sanity test 2: start up mds twice (should return err) ========================================================== 01:27:08 (1787549228) [ 832.035095] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 832.061501] alg: No test for adler32 (adler32-zlib) [ 833.171766] Lustre: Lustre: Build Version: 2.17.57_82_g53f9976 [ 833.474866] LNet: Added LNI 192.168.206.38@tcp [8/256/0/180] [ 835.135216] Key type lgssc registered [ 836.298243] Lustre: Echo OBD driver; http://www.lustre.org/ [ 886.882865] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 892.844407] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 922.733397] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 934.981137] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 937.761044] Lustre: Unmounted lustre-client [ 966.228183] Key type lgssc unregistered [ 966.510572] LNet: 22007:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 966.515342] LNetError: 22007:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 966.531054] LNet: Removed LNI 192.168.206.38@tcp [ 967.131449] Key type .llcrypt unregistered [ 967.133582] Key type ._llcrypt unregistered [ 982.342709] Key type ._llcrypt registered [ 982.349569] Key type .llcrypt registered [ 983.821935] Lustre: DEBUG MARKER: == conf-sanity test 3: mount client twice (should return err) ========================================================== 01:29:43 (1787549383) [ 987.231396] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 987.282414] alg: No test for adler32 (adler32-zlib) [ 988.486737] Lustre: Lustre: Build Version: 2.17.57_82_g53f9976 [ 988.725590] LNet: Added LNI 192.168.206.38@tcp [8/256/0/180] [ 990.439305] Key type lgssc registered [ 991.882139] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1040.422457] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1046.696973] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1069.531448] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1072.922580] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 1078.196162] Lustre: Unmounted lustre-client [ 1106.266230] Key type lgssc unregistered [ 1106.594584] LNet: 25624:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1106.602124] LNetError: 25624:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1106.611863] LNet: Removed LNI 192.168.206.38@tcp [ 1107.259459] Key type .llcrypt unregistered [ 1107.263490] Key type ._llcrypt unregistered [ 1119.540449] Key type ._llcrypt registered [ 1119.542546] Key type .llcrypt registered [ 1120.630918] Lustre: DEBUG MARKER: == conf-sanity test 4: force cleanup ost, then cleanup === 01:32:00 (1787549520) [ 1122.737679] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1122.754125] alg: No test for adler32 (adler32-zlib) [ 1123.799890] Lustre: Lustre: Build Version: 2.17.57_82_g53f9976 [ 1124.009950] LNet: Added LNI 192.168.206.38@tcp [8/256/0/180] [ 1125.672584] Key type lgssc registered [ 1126.617630] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1170.005345] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1176.079270] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1197.969111] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1200.542086] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 1205.737957] Lustre: lustre-OST0000-osc-ffff8ebe48f77800: Connection to lustre-OST0000 (at 192.168.206.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1213.796841] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1224.104965] Lustre: Unmounted lustre-client [ 1251.834362] Key type lgssc unregistered [ 1252.078244] LNet: 29248:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1252.094296] LNetError: 29248:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1252.120384] LNet: Removed LNI 192.168.206.38@tcp [ 1252.734619] Key type .llcrypt unregistered [ 1252.738547] Key type ._llcrypt unregistered [ 1267.016854] Key type ._llcrypt registered [ 1267.022947] Key type .llcrypt registered [ 1268.338307] Lustre: DEBUG MARKER: == conf-sanity test 5a: force cleanup mds, then cleanup == 01:34:28 (1787549668) [ 1270.723839] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1270.777331] alg: No test for adler32 (adler32-zlib) [ 1271.876498] Lustre: Lustre: Build Version: 2.17.57_82_g53f9976 [ 1272.094793] LNet: Added LNI 192.168.206.38@tcp [8/256/0/180] [ 1273.775942] Key type lgssc registered [ 1274.964434] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1326.161303] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1332.594936] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1354.256692] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1357.065321] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 1367.540892] Lustre: lustre-MDT0000-mdc-ffff8ebe46ed2800: Connection to lustre-MDT0000 (at 192.168.206.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1377.790561] Lustre: lustre-MDT0001-mdc-ffff8ebe46ed2800: Connection to lustre-MDT0001 (at 192.168.206.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1382.248213] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1389.023133] Lustre: 30207:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787549774/real 1787549774] req@0000000034e5b8ff x1874381685922048/t0(0) o400->MGC192.168.206.138@tcp@192.168.206.138@tcp:26/25 lens 224/224 e 0 to 1 dl 1787549790 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1389.045148] LustreError: MGC192.168.206.138@tcp: Connection to MGS (at 192.168.206.138@tcp) was lost; in progress operations using this service will fail [ 1392.639397] Lustre: Unmounted lustre-client [ 1411.024336] Key type lgssc unregistered [ 1411.256488] LNet: 32915:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1411.267235] LNetError: 32915:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1411.289254] LNet: Removed LNI 192.168.206.38@tcp [ 1411.824807] Key type .llcrypt unregistered [ 1411.832856] Key type ._llcrypt unregistered [ 1426.042427] Key type ._llcrypt registered [ 1426.044728] Key type .llcrypt registered [ 1427.178482] Lustre: DEBUG MARKER: == conf-sanity test 5b: Try to start a client with no MGS (should return errs) ========================================================== 01:37:07 (1787549827) [ 1429.368358] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1429.383876] alg: No test for adler32 (adler32-zlib) [ 1430.464457] Lustre: Lustre: Build Version: 2.17.57_82_g53f9976 [ 1430.665948] LNet: Added LNI 192.168.206.38@tcp [8/256/0/180] [ 1432.344159] Key type lgssc registered [ 1433.513034] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1514.916500] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1520.418772] LustreError: 35114:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.206.138@tcp: failed processing log, type 1: rc = -110 [ 1527.778151] LustreError: 35123:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 1679.328244] LustreError: MGC192.168.206.138@tcp: Confguration from log lustre-client failed from MGS -110. Communication error between node & MGS, a bad configuration, or other errors. See syslog for more info [ 1679.355100] Lustre: Unmounted lustre-client [ 1679.359261] LustreError: 35114:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 1691.631867] Key type lgssc unregistered [ 1691.853781] LNet: 35545:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1691.857377] LNetError: 35545:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1691.873270] LNet: Removed LNI 192.168.206.38@tcp [ 1692.515100] Key type .llcrypt unregistered [ 1692.517543] Key type ._llcrypt unregistered [ 1705.267979] Key type ._llcrypt registered [ 1705.269678] Key type .llcrypt registered [ 1706.373815] Lustre: DEBUG MARKER: == conf-sanity test 5c: cleanup after failed mount (bug 2712) (should return errs) ========================================================== 01:41:46 (1787550106) [ 1708.288511] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1708.317300] alg: No test for adler32 (adler32-zlib) [ 1709.509092] Lustre: Lustre: Build Version: 2.17.57_82_g53f9976 [ 1709.807904] LNet: Added LNI 192.168.206.38@tcp [8/256/0/180] [ 1711.519120] Key type lgssc registered [ 1712.540442] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1757.479662] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1763.659363] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1784.933438] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1785.360400] LustreError: 38634:0:(llite_lib.c:1555:ll_fill_super()) wrong.lustre: fsname longer than 8 characters: rc = -36 [ 1785.379561] Lustre: Unmounted wrong.lustre-client [ 1785.392630] LustreError: 38634:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -36 [ 1812.082428] Key type lgssc unregistered [ 1812.368032] LNet: 39053:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1812.378947] LNetError: 39053:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1812.399361] LNet: Removed LNI 192.168.206.38@tcp [ 1813.098944] Key type .llcrypt unregistered [ 1813.100807] Key type ._llcrypt unregistered [ 1828.975171] Key type ._llcrypt registered [ 1828.978104] Key type .llcrypt registered [ 1830.282641] Lustre: DEBUG MARKER: == conf-sanity test 5d: mount with ost down ============== 01:43:50 (1787550230) [ 1832.914424] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1832.955820] alg: No test for adler32 (adler32-zlib) [ 1834.064629] Lustre: Lustre: Build Version: 2.17.57_82_g53f9976 [ 1834.369092] LNet: Added LNI 192.168.206.38@tcp [8/256/0/180] [ 1836.063453] Key type lgssc registered [ 1837.245847] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1929.173168] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1962.882238] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1969.736659] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1982.869227] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 1985.077736] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1995.466644] Lustre: Unmounted lustre-client [ 2023.089166] Key type lgssc unregistered [ 2023.399792] LNet: 42632:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2023.404922] LNetError: 42632:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2023.420558] LNet: Removed LNI 192.168.206.38@tcp [ 2024.265853] Key type .llcrypt unregistered [ 2024.268205] Key type ._llcrypt unregistered [ 2039.395261] Key type ._llcrypt registered [ 2039.397322] Key type .llcrypt registered [ 2041.181372] Lustre: DEBUG MARKER: == conf-sanity test 5e: delayed connect, don't crash (bug 10268) ========================================================== 01:47:20 (1787550440) [ 2043.990099] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2044.087229] alg: No test for adler32 (adler32-zlib) [ 2045.280693] Lustre: Lustre: Build Version: 2.17.57_82_g53f9976 [ 2045.647058] LNet: Added LNI 192.168.206.38@tcp [8/256/0/180] [ 2047.399265] Key type lgssc registered [ 2049.193345] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2102.885765] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2108.960226] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2130.199771] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2130.673420] LustreError: 43590:0:(niobuf.c:1106:ptl_send_rpc()) cfs_fail_timeout id 506 sleeping for 10000ms [ 2135.776216] LustreError: 45712:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.206.138@tcp: failed processing log, type 1: rc = -110 [ 2140.687379] LustreError: 43590:0:(niobuf.c:1106:ptl_send_rpc()) cfs_fail_timeout id 506 awake [ 2142.024982] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 2144.382157] Lustre: Unmounted lustre-client [ 2173.409199] Key type lgssc unregistered [ 2173.690842] LNet: 46175:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2173.697340] LNetError: 46175:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2173.721441] LNet: Removed LNI 192.168.206.38@tcp [ 2175.099295] Key type .llcrypt unregistered [ 2175.102708] Key type ._llcrypt unregistered [ 2198.626197] Key type ._llcrypt registered [ 2198.629375] Key type .llcrypt registered [ 2200.295903] Lustre: DEBUG MARKER: == conf-sanity test 5f: mds down, cleanup after failed mount (bug 2712) ========================================================== 01:50:00 (1787550600) [ 2202.334153] Lustre: DEBUG MARKER: SKIP: conf-sanity test_5f needs separate mgs and mds [ 2204.393591] Lustre: DEBUG MARKER: == conf-sanity test 5g: handle missing debugfs =========== 01:50:04 (1787550604) [ 2205.799414] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2205.860911] alg: No test for adler32 (adler32-zlib) [ 2207.094214] Lustre: Lustre: Build Version: 2.17.57_82_g53f9976 [ 2207.386738] LNet: Added LNI 192.168.206.38@tcp [8/256/0/180] [ 2209.071352] Key type lgssc registered [ 2210.239798] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2233.915271] Lustre: DEBUG MARKER: == conf-sanity test 5h: start mdt failure at mdt_fs_setup() ========================================================== 01:50:33 (1787550633) [ 2271.734366] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2277.491186] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2306.929176] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2309.176712] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 2314.243613] Lustre: lustre-MDT0000-mdc-ffff8ebe44b94000: Connection to lustre-MDT0000 (at 192.168.206.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2334.623193] Lustre: 47398:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787550720/real 1787550720] req@000000002ed73be5 x1874382666338432/t0(0) o400->MGC192.168.206.138@tcp@192.168.206.138@tcp:26/25 lens 224/224 e 0 to 1 dl 1787550736 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2334.671966] LustreError: MGC192.168.206.138@tcp: Connection to MGS (at 192.168.206.138@tcp) was lost; in progress operations using this service will fail [ 2334.701956] Lustre: Evicted from MGS (at 192.168.206.138@tcp) after server handle changed from 0x912cd3436c240916 to 0x912cd3436c240b85 [ 2334.725848] Lustre: MGC192.168.206.138@tcp: Connection restored to 192.168.206.138@tcp (at 192.168.206.138@tcp) [ 2339.401270] Lustre: lustre-MDT0000-mdc-ffff8ebe44b94000: Connection restored to 192.168.206.138@tcp (at 192.168.206.138@tcp) [ 2347.871554] Lustre: Unmounted lustre-client [ 2376.378968] Key type lgssc unregistered [ 2376.790782] LNet: 51045:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2376.804144] LNetError: 51045:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2376.852723] LNet: Removed LNI 192.168.206.38@tcp [ 2378.049926] Key type .llcrypt unregistered [ 2378.058884] Key type ._llcrypt unregistered [ 2394.230984] Key type ._llcrypt registered [ 2394.234486] Key type .llcrypt registered [ 2395.842654] Lustre: DEBUG MARKER: == conf-sanity test 5i: start mdt failure at mdt_quota_init() ========================================================== 01:53:15 (1787550795) [ 2399.287368] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2399.314755] alg: No test for adler32 (adler32-zlib) [ 2400.562392] Lustre: Lustre: Build Version: 2.17.57_82_g53f9976 [ 2400.923303] LNet: Added LNI 192.168.206.38@tcp [8/256/0/180] [ 2402.727805] Key type lgssc registered [ 2404.939550] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2463.739657] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2472.033523] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2491.933757] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2493.658468] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 2504.197655] Lustre: lustre-MDT0000-mdc-ffff8ebe4516e000: Connection to lustre-MDT0000 (at 192.168.206.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2519.520217] Lustre: 52010:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787550905/real 1787550905] req@00000000f1aed36e x1874382869762176/t0(0) o400->MGC192.168.206.138@tcp@192.168.206.138@tcp:26/25 lens 224/224 e 0 to 1 dl 1787550921 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2519.547568] LustreError: MGC192.168.206.138@tcp: Connection to MGS (at 192.168.206.138@tcp) was lost; in progress operations using this service will fail [ 2519.590668] Lustre: Evicted from MGS (at 192.168.206.138@tcp) after server handle changed from 0x5faef93e5a869af2 to 0x5faef93e5a869cf1 [ 2519.603194] Lustre: MGC192.168.206.138@tcp: Connection restored to 192.168.206.138@tcp (at 192.168.206.138@tcp) [ 2523.668542] Lustre: lustre-MDT0000-mdc-ffff8ebe4516e000: Connection restored to 192.168.206.138@tcp (at 192.168.206.138@tcp) [ 2528.842744] Lustre: Unmounted lustre-client [ 2565.351431] Key type lgssc unregistered [ 2565.743502] LNet: 55018:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2565.749697] LNetError: 55018:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2565.766214] LNet: Removed LNI 192.168.206.38@tcp [ 2566.718427] Key type .llcrypt unregistered [ 2566.722390] Key type ._llcrypt unregistered [ 2585.631109] Key type ._llcrypt registered [ 2585.632819] Key type .llcrypt registered [ 2587.295070] Lustre: DEBUG MARKER: == conf-sanity test 5j: verify libcfs doesn't crash when setting debug flags ========================================================== 01:56:26 (1787550986) [ 2588.358426] Key type .llcrypt unregistered [ 2588.361235] Key type ._llcrypt unregistered [ 2595.361265] Key type ._llcrypt registered [ 2595.364782] Key type .llcrypt registered [ 2595.428761] Key type .llcrypt unregistered [ 2595.431846] Key type ._llcrypt unregistered [ 2601.605662] Key type ._llcrypt registered [ 2601.608807] Key type .llcrypt registered [ 2603.225703] Lustre: DEBUG MARKER: == conf-sanity test 6: manual umount, then mount again === 01:56:42 (1787551002) [ 2605.617580] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2605.668678] alg: No test for adler32 (adler32-zlib) [ 2606.742405] Lustre: Lustre: Build Version: 2.17.57_82_g53f9976 [ 2607.074490] LNet: Added LNI 192.168.206.38@tcp [8/256/0/180] [ 2608.863276] Key type lgssc registered [ 2610.227742] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2657.703436] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2663.859497] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2687.594828] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2690.273682] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 2693.078128] Lustre: Unmounted lustre-client [ 2693.650279] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 2696.052557] Lustre: Unmounted lustre-client [ 2727.208824] Key type lgssc unregistered [ 2727.531614] LNet: 59451:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2727.551982] LNetError: 59451:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2727.605539] LNet: Removed LNI 192.168.206.38@tcp [ 2728.381729] Key type .llcrypt unregistered [ 2728.387817] Key type ._llcrypt unregistered [ 2748.076716] Key type ._llcrypt registered [ 2748.084771] Key type .llcrypt registered [ 2749.821302] Lustre: DEBUG MARKER: == conf-sanity test 7: manual umount, then cleanup ======= 01:59:09 (1787551149) [ 2752.375647] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2752.421159] alg: No test for adler32 (adler32-zlib) [ 2753.452633] Lustre: Lustre: Build Version: 2.17.57_82_g53f9976 [ 2753.667289] LNet: Added LNI 192.168.206.38@tcp [8/256/0/180] [ 2755.327301] Key type lgssc registered [ 2756.417767] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2811.013174] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2816.709349] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2848.509424] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2851.437598] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 2853.963202] Lustre: Unmounted lustre-client [ 2883.963765] Key type lgssc unregistered [ 2884.441624] LNet: 63039:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2884.461626] LNetError: 63039:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2884.514380] LNet: Removed LNI 192.168.206.38@tcp [ 2885.571125] Key type .llcrypt unregistered [ 2885.579399] Key type ._llcrypt unregistered [ 2903.727301] Key type ._llcrypt registered [ 2903.729455] Key type .llcrypt registered [ 2905.262846] Lustre: DEBUG MARKER: == conf-sanity test 8: double mount setup ================ 02:01:45 (1787551305) [ 2908.992450] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2909.019260] alg: No test for adler32 (adler32-zlib) [ 2910.153371] Lustre: Lustre: Build Version: 2.17.57_82_g53f9976 [ 2910.491490] LNet: Added LNI 192.168.206.38@tcp [8/256/0/180] [ 2912.199254] Key type lgssc registered [ 2914.883537] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2966.650400] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2972.216970] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2992.579907] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2995.177986] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 2997.341960] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 3000.110067] Lustre: Unmounted lustre-client [ 3001.746338] Lustre: Unmounted lustre-client [ 3028.115833] Key type lgssc unregistered [ 3028.355808] LNet: 66698:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3028.361148] LNetError: 66698:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3028.382691] LNet: Removed LNI 192.168.206.38@tcp [ 3029.092162] Key type .llcrypt unregistered [ 3029.095583] Key type ._llcrypt unregistered [ 3043.121026] Key type ._llcrypt registered [ 3043.127709] Key type .llcrypt registered [ 3044.739463] Lustre: DEBUG MARKER: == conf-sanity test 9: test ptldebug and subsystem for mkfs ========================================================== 02:04:04 (1787551444) [ 3047.361352] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3047.386219] alg: No test for adler32 (adler32-zlib) [ 3048.360891] Lustre: Lustre: Build Version: 2.17.57_82_g53f9976 [ 3048.586199] LNet: Added LNI 192.168.206.38@tcp [8/256/0/180] [ 3050.247239] Key type lgssc registered [ 3051.770668] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3143.646833] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3158.416445] Lustre: DEBUG MARKER: == conf-sanity test 10a: find lctl param broken symlinks ========================================================== 02:05:58 (1787551558) [ 3197.049339] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3203.809542] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3228.967041] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3240.547379] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 3250.373798] Lustre: Unmounted lustre-client [ 3281.223552] Key type lgssc unregistered [ 3281.552335] LNet: 71475:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3281.564383] LNetError: 71475:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3281.605223] LNet: Removed LNI 192.168.206.38@tcp [ 3282.445610] Key type .llcrypt unregistered [ 3282.454748] Key type ._llcrypt unregistered [ 3300.558788] Key type ._llcrypt registered [ 3300.562699] Key type .llcrypt registered [ 3301.931311] Lustre: DEBUG MARKER: == conf-sanity test 11: Verify tool --version option works properly ========================================================== 02:08:21 (1787551701) [ 3321.189880] Lustre: DEBUG MARKER: == conf-sanity test 17: Verify failed mds_postsetup won't fail assertion (2936) (should return errs) ========================================================== 02:08:41 (1787551721) [ 3323.397547] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3323.444103] alg: No test for adler32 (adler32-zlib) [ 3324.452556] Lustre: Lustre: Build Version: 2.17.57_82_g53f9976 [ 3324.639278] LNet: Added LNI 192.168.206.38@tcp [8/256/0/180] [ 3326.367229] Key type lgssc registered [ 3327.700206] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3382.576537] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3390.042211] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3411.304203] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3414.277489] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 3420.471956] Lustre: Unmounted lustre-client [ 3452.591444] Key type lgssc unregistered [ 3453.060844] LNet: 75766:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3453.074207] LNetError: 75766:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3453.095985] LNet: Removed LNI 192.168.206.38@tcp [ 3454.272804] Key type .llcrypt unregistered [ 3454.276942] Key type ._llcrypt unregistered [ 3473.117788] Key type ._llcrypt registered [ 3473.126422] Key type .llcrypt registered [ 3473.978583] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3474.000302] alg: No test for adler32 (adler32-zlib) [ 3475.259871] Lustre: Lustre: Build Version: 2.17.57_82_g53f9976 [ 3475.545487] LNet: Added LNI 192.168.206.38@tcp [8/256/0/180] [ 3477.295969] Key type lgssc registered [ 3478.906969] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3574.005614] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3705.178455] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3712.408248] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3739.440416] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3783.213996] Lustre: DEBUG MARKER: == conf-sanity test 18: check mkfs creates large journals ========================================================== 02:16:22 (1787552182) [ 3787.270311] Lustre: DEBUG MARKER: use device /dev/mapper/mds1_flakey with MIN=2000000 [ 3887.173989] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3892.443285] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3916.494224] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3996.752513] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4002.329071] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4024.312326] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4026.087328] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 4030.922104] Lustre: DEBUG MARKER: Success: mkfs creates large journals. Size: 78M [ 4033.474890] Lustre: Unmounted lustre-client [ 4061.215863] Key type lgssc unregistered [ 4061.428955] LNet: 87467:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4061.436894] LNetError: 87467:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4061.454920] LNet: Removed LNI 192.168.206.38@tcp [ 4062.330606] Key type .llcrypt unregistered [ 4062.331936] Key type ._llcrypt unregistered [ 4089.042068] Key type ._llcrypt registered [ 4089.046621] Key type .llcrypt registered [ 4089.460468] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4089.495085] alg: No test for adler32 (adler32-zlib) [ 4090.558540] Lustre: Lustre: Build Version: 2.17.57_82_g53f9976 [ 4090.849640] LNet: Added LNI 192.168.206.38@tcp [8/256/0/180] [ 4092.535414] Key type lgssc registered [ 4093.970270] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4175.650942] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4183.637826] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4205.418698] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4261.145713] Lustre: DEBUG MARKER: == conf-sanity test 19a: start/stop MDS without OSTs ===== 02:24:21 (1787552661) [ 4299.951932] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4306.705545] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4327.037371] Lustre: DEBUG MARKER: == conf-sanity test 19b: start/stop OSTs without MDS ===== 02:25:27 (1787552727) [ 4393.153239] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4403.155439] Lustre: DEBUG MARKER: == conf-sanity test 20: remount ro,rw mounts work and doesn't break /etc/mtab ========================================================== 02:26:43 (1787552803) [ 4434.648739] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4439.656383] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4459.953869] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4461.807580] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 4462.664499] Lustre: Remounted lustre-client read-only [ 4465.535185] Lustre: Unmounted lustre-client [ 4503.230950] Lustre: DEBUG MARKER: == conf-sanity test 21a: start mds before ost, stop ost first ========================================================== 02:28:22 (1787552902) [ 4537.694380] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4544.671504] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4566.690256] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4616.534950] Lustre: DEBUG MARKER: == conf-sanity test 21b: start ost before mds, stop mds first ========================================================== 02:30:17 (1787553017) [ 4683.527963] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4717.989550] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4722.986235] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4766.786771] Lustre: DEBUG MARKER: == conf-sanity test 21c: start mds between two osts, stop mds last ========================================================== 02:32:47 (1787553167) [ 4833.157252] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4866.712405] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4872.113099] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4893.471343] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4963.757909] Lustre: DEBUG MARKER: == conf-sanity test 21d: start mgs then ost and then mds ========================================================== 02:36:03 (1787553363) [ 4965.322763] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21d need separate mgs device [ 4966.946579] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21e skipping excluded test 21e [ 4968.539167] Lustre: DEBUG MARKER: == conf-sanity test 22: start a client before osts (should return errs) ========================================================== 02:36:08 (1787553368) [ 4999.407476] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5005.542746] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5024.727170] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5043.549624] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 5045.669469] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5055.983375] Lustre: Unmounted lustre-client [ 5075.703380] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5076.460260] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 5091.005389] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8ebe4524e800.ost_server_uuid 50 [ 5092.966189] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8ebe4524e800.ost_server_uuid in FULL state after 0 sec [ 5095.767580] Lustre: Unmounted lustre-client [ 5138.444952] Key type lgssc unregistered [ 5138.890227] LNet: 107822:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5138.905309] LNetError: 107822:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5138.923572] LNet: Removed LNI 192.168.206.38@tcp [ 5139.866512] Key type .llcrypt unregistered [ 5139.868973] Key type ._llcrypt unregistered [ 5157.063900] Key type ._llcrypt registered [ 5157.068170] Key type .llcrypt registered [ 5158.575299] Lustre: DEBUG MARKER: == conf-sanity test 23a: interrupt client during recovery mount delay ========================================================== 02:39:18 (1787553558) [ 5161.689498] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5161.821894] alg: No test for adler32 (adler32-zlib) [ 5162.973965] Lustre: Lustre: Build Version: 2.17.57_82_g53f9976 [ 5163.353664] LNet: Added LNI 192.168.206.38@tcp [8/256/0/180] [ 5165.071211] Key type lgssc registered [ 5166.613826] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5214.592281] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5220.274687] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5241.612890] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5243.665167] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 5249.006135] Lustre: lustre-MDT0000-mdc-ffff8ebe44a91000: Connection to lustre-MDT0000 (at 192.168.206.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5251.394342] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5261.697665] LustreError: MGC192.168.206.138@tcp: Connection to MGS (at 192.168.206.138@tcp) was lost; in progress operations using this service will fail [ 5261.732198] Lustre: Unmounted lustre-client [ 5283.251342] LustreError: lustre-MDT0000-mdc-ffff8ebe44ae7800: operation mds_connect to node 192.168.206.138@tcp failed: rc = -16 [ 5288.208808] LustreError: 111211:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff8ebe44ae7800: can't stat MDS #0: rc = -16 [ 5288.286168] Lustre: Unmounted lustre-client [ 5288.300766] LustreError: 111211:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -16 [ 5316.709301] Key type lgssc unregistered [ 5316.982669] LNet: 111667:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5316.992469] LNetError: 111667:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5317.009619] LNet: Removed LNI 192.168.206.38@tcp [ 5317.797286] Key type .llcrypt unregistered [ 5317.802397] Key type ._llcrypt unregistered [ 5334.940174] Key type ._llcrypt registered [ 5334.946955] Key type .llcrypt registered [ 5336.220735] Lustre: DEBUG MARKER: == conf-sanity test 23b: Simulate -EINTR during mount ==== 02:42:16 (1787553736) [ 5338.929306] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5338.982247] alg: No test for adler32 (adler32-zlib) [ 5340.204953] Lustre: Lustre: Build Version: 2.17.57_82_g53f9976 [ 5340.477856] LNet: Added LNI 192.168.206.38@tcp [8/256/0/180] [ 5342.231217] Key type lgssc registered [ 5343.534435] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5391.334048] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5395.985214] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5416.045846] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5417.658175] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 5419.490882] Lustre: Unmounted lustre-client [ 5452.451923] Key type lgssc unregistered [ 5452.689409] LNet: 115201:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5452.704457] LNetError: 115201:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5452.754740] LNet: Removed LNI 192.168.206.38@tcp [ 5453.579705] Key type .llcrypt unregistered [ 5453.582056] Key type ._llcrypt unregistered [ 5466.266246] Key type ._llcrypt registered [ 5466.268841] Key type .llcrypt registered [ 5467.182878] Lustre: DEBUG MARKER: == conf-sanity test 24a: Multiple MDTs on a single node == 02:44:27 (1787553867) [ 5469.340372] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24a mixed loopback and real device not working [ 5470.821903] Lustre: DEBUG MARKER: == conf-sanity test 24b: Multiple MGSs on a single node (should return err) ========================================================== 02:44:31 (1787553871) [ 5472.858742] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24b mixed loopback and real device not working [ 5474.383603] Lustre: DEBUG MARKER: == conf-sanity test 25: Verify modules are referenced ==== 02:44:34 (1787553874) [ 5476.696184] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5476.830558] alg: No test for adler32 (adler32-zlib) [ 5478.051591] Lustre: Lustre: Build Version: 2.17.57_82_g53f9976 [ 5478.319973] LNet: Added LNI 192.168.206.38@tcp [8/256/0/180] [ 5479.994673] Key type lgssc registered [ 5481.893499] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5531.728458] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5538.573718] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5558.398362] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5560.010970] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 5567.471797] Lustre: Unmounted lustre-client [ 5600.590172] Key type lgssc unregistered [ 5600.846147] LNet: 119401:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5600.852226] LNetError: 119401:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5600.873249] LNet: Removed LNI 192.168.206.38@tcp [ 5601.729520] Key type .llcrypt unregistered [ 5601.733788] Key type ._llcrypt unregistered [ 5617.508320] Key type ._llcrypt registered [ 5617.514573] Key type .llcrypt registered [ 5619.069318] Lustre: DEBUG MARKER: == conf-sanity test 26: MDT startup failure cleans LOV (should return errs) ========================================================== 02:46:58 (1787554018) [ 5620.971825] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5621.011745] alg: No test for adler32 (adler32-zlib) [ 5622.315859] Lustre: Lustre: Build Version: 2.17.57_82_g53f9976 [ 5622.647151] LNet: Added LNI 192.168.206.38@tcp [8/256/0/180] [ 5624.476481] Key type lgssc registered [ 5626.014770] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5684.405734] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5690.634377] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5710.280783] Key type lgssc unregistered [ 5710.542660] LNet: 122483:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5710.555827] LNetError: 122483:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5710.577723] LNet: Removed LNI 192.168.206.38@tcp [ 5711.381872] Key type .llcrypt unregistered [ 5711.387482] Key type ._llcrypt unregistered [ 5725.542373] Key type ._llcrypt registered [ 5725.544859] Key type .llcrypt registered [ 5726.675920] Lustre: DEBUG MARKER: == conf-sanity test 27a: Reacquire MGS lock if OST started first ========================================================== 02:48:46 (1787554126) [ 5734.598316] Key type .llcrypt unregistered [ 5734.600399] Key type ._llcrypt unregistered [ 5741.830385] Key type ._llcrypt registered [ 5741.833349] Key type .llcrypt registered [ 5742.317597] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5742.338668] alg: No test for adler32 (adler32-zlib) [ 5743.439530] Lustre: Lustre: Build Version: 2.17.57_82_g53f9976 [ 5743.693838] LNet: Added LNI 192.168.206.38@tcp [8/256/0/180] [ 5745.351235] Key type lgssc registered [ 5746.347521] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5826.275504] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5855.103435] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5860.846814] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5913.549816] Key type lgssc unregistered [ 5913.785696] LNet: 126353:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5913.791842] LNetError: 126353:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5913.812095] LNet: Removed LNI 192.168.206.38@tcp [ 5914.520973] Key type .llcrypt unregistered [ 5914.527354] Key type ._llcrypt unregistered [ 5927.916892] Key type ._llcrypt registered [ 5927.919836] Key type .llcrypt registered [ 5929.068982] Lustre: DEBUG MARKER: == conf-sanity test 27b: Reacquire MGS lock after failover ========================================================== 02:52:09 (1787554329) [ 5931.373271] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5931.419264] alg: No test for adler32 (adler32-zlib) [ 5932.464805] Lustre: Lustre: Build Version: 2.17.57_82_g53f9976 [ 5932.702914] LNet: Added LNI 192.168.206.38@tcp [8/256/0/180] [ 5934.399280] Key type lgssc registered [ 5935.465907] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5974.238788] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5978.292260] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5995.022129] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6000.654958] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 6005.738350] Lustre: lustre-MDT0000-mdc-ffff8ebe480f0000: Connection to lustre-MDT0000 (at 192.168.206.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6021.108041] LustreError: MGC192.168.206.138@tcp: Connection to MGS (at 192.168.206.138@tcp) was lost; in progress operations using this service will fail [ 6021.138394] Lustre: Evicted from MGS (at 192.168.206.138@tcp) after server handle changed from 0xbcc38ed3ac073863 to 0xbcc38ed3ac073a4d [ 6021.146899] Lustre: MGC192.168.206.138@tcp: Connection restored to 192.168.206.138@tcp (at 192.168.206.138@tcp) [ 6026.310356] Lustre: lustre-MDT0000-mdc-ffff8ebe480f0000: Connection restored to 192.168.206.138@tcp (at 192.168.206.138@tcp) [ 6047.608940] Lustre: Unmounted lustre-client [ 6071.130345] Key type lgssc unregistered [ 6071.411909] LNet: 130354:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6071.426943] LNetError: 130354:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6071.439105] LNet: Removed LNI 192.168.206.38@tcp [ 6071.994851] Key type .llcrypt unregistered [ 6071.998420] Key type ._llcrypt unregistered [ 6085.729439] Key type ._llcrypt registered [ 6085.734211] Key type .llcrypt registered [ 6087.030963] Lustre: DEBUG MARKER: == conf-sanity test 28A: permanent parameter setting ===== 02:54:47 (1787554487) [ 6088.971249] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6089.109568] alg: No test for adler32 (adler32-zlib) [ 6090.133941] Lustre: Lustre: Build Version: 2.17.57_82_g53f9976 [ 6090.412035] LNet: Added LNI 192.168.206.38@tcp [8/256/0/180] [ 6092.088310] Key type lgssc registered [ 6093.098579] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6136.543665] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6142.311281] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6164.644572] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6167.328395] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 6183.363188] Lustre: Unmounted lustre-client [ 6183.843118] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 6195.482162] Lustre: Unmounted lustre-client [ 6221.813964] Key type lgssc unregistered [ 6222.055038] LNet: 134568:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6222.063831] LNetError: 134568:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6222.080442] LNet: Removed LNI 192.168.206.38@tcp [ 6222.613081] Key type .llcrypt unregistered [ 6222.615570] Key type ._llcrypt unregistered [ 6231.545228] Key type ._llcrypt registered [ 6231.548022] Key type .llcrypt registered [ 6232.737711] Lustre: DEBUG MARKER: == conf-sanity test 28a: set symlink parameters permanently with lctl ========================================================== 02:57:12 (1787554632) [ 6235.031279] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6235.188121] alg: No test for adler32 (adler32-zlib) [ 6236.293729] Lustre: Lustre: Build Version: 2.17.57_82_g53f9976 [ 6236.517885] LNet: Added LNI 192.168.206.38@tcp [8/256/0/180] [ 6238.191533] Key type lgssc registered [ 6239.290788] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6290.635911] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6296.911318] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6320.192758] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6322.973534] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 6373.146753] Lustre: Unmounted lustre-client [ 6402.981440] Key type lgssc unregistered [ 6403.286041] LNet: 137989:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6403.296875] LNetError: 137989:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6403.322844] LNet: Removed LNI 192.168.206.38@tcp [ 6404.097719] Key type .llcrypt unregistered [ 6404.101377] Key type ._llcrypt unregistered [ 6418.728430] Key type ._llcrypt registered [ 6418.731719] Key type .llcrypt registered [ 6420.218965] Lustre: DEBUG MARKER: == conf-sanity test 28b: verify client-side parameters are set upon mount ========================================================== 03:00:20 (1787554820) [ 6422.271275] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6422.322576] alg: No test for adler32 (adler32-zlib) [ 6423.347943] Lustre: Lustre: Build Version: 2.17.57_82_g53f9976 [ 6423.525730] LNet: Added LNI 192.168.206.38@tcp [8/256/0/180] [ 6425.191711] Key type lgssc registered [ 6426.300364] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6474.838726] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6480.378612] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6502.980914] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6505.860907] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 6508.019716] Lustre: Unmounted lustre-client [ 6508.495357] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 6518.377397] Lustre: Unmounted lustre-client [ 6546.736783] Key type lgssc unregistered [ 6547.059380] LNet: 142052:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6547.068164] LNetError: 142052:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6547.113562] LNet: Removed LNI 192.168.206.38@tcp [ 6547.827718] Key type .llcrypt unregistered [ 6547.830215] Key type ._llcrypt unregistered [ 6557.589299] Key type ._llcrypt registered [ 6557.593799] Key type .llcrypt registered [ 6559.434119] Lustre: DEBUG MARKER: == conf-sanity test 28c: verify filesystem parameters are set upon mount ========================================================== 03:02:38 (1787554958) [ 6561.556693] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6561.641407] alg: No test for adler32 (adler32-zlib) [ 6562.901169] Lustre: Lustre: Build Version: 2.17.57_82_g53f9976 [ 6563.121630] LNet: Added LNI 192.168.206.38@tcp [8/256/0/180] [ 6564.792055] Key type lgssc registered [ 6566.501601] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6618.571995] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6624.037722] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6645.229975] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6647.841302] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 6650.363286] Lustre: Unmounted lustre-client [ 6650.787288] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 6658.301324] Lustre: Unmounted lustre-client [ 6687.451546] Key type lgssc unregistered [ 6687.753105] LNet: 145674:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6687.765807] LNetError: 145674:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6687.793520] LNet: Removed LNI 192.168.206.38@tcp [ 6688.638245] Key type .llcrypt unregistered [ 6688.642504] Key type ._llcrypt unregistered [ 6700.464500] Key type ._llcrypt registered [ 6700.469980] Key type .llcrypt registered [ 6702.125766] Lustre: DEBUG MARKER: == conf-sanity test 29: permanently remove an OST ======== 03:05:01 (1787555101) [ 6705.150386] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6705.178599] alg: No test for adler32 (adler32-zlib) [ 6706.291683] Lustre: Lustre: Build Version: 2.17.57_82_g53f9976 [ 6706.503306] LNet: Added LNI 192.168.206.38@tcp [8/256/0/180] [ 6708.167663] Key type lgssc registered [ 6709.422834] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6759.681177] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6765.479272] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6788.763549] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6791.631364] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 6813.918516] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6815.597827] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6834.195029] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6838.391797] Lustre: Unmounted lustre-client [ 6838.859454] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6839.019573] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 6851.001857] Lustre: Unmounted lustre-client [ 6898.900475] Key type lgssc unregistered [ 6899.257196] LNet: 149723:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6899.268582] LNetError: 149723:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6899.296329] LNet: Removed LNI 192.168.206.38@tcp [ 6900.207264] Key type .llcrypt unregistered [ 6900.209458] Key type ._llcrypt unregistered [ 6930.921487] Key type ._llcrypt registered [ 6930.926852] Key type .llcrypt registered [ 6932.490108] Lustre: DEBUG MARKER: == conf-sanity test 30a: Big config llog and permanent parameter deletion ========================================================== 03:08:52 (1787555332) [ 6935.292461] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6935.309323] alg: No test for adler32 (adler32-zlib) [ 6936.491774] Lustre: Lustre: Build Version: 2.17.57_82_g53f9976 [ 6936.866437] LNet: Added LNI 192.168.206.38@tcp [8/256/0/180] [ 6938.552891] Key type lgssc registered [ 6939.964397] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6989.492218] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6995.869777] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7018.587976] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7021.250729] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 7182.012142] Lustre: Unmounted lustre-client [ 7182.401762] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 7186.260554] Lustre: Unmounted lustre-client [ 7186.720876] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 7189.691878] Lustre: Unmounted lustre-client [ 7226.344483] Key type lgssc unregistered [ 7226.764890] LNet: 154942:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7226.786899] LNetError: 154942:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7226.810579] LNet: Removed LNI 192.168.206.38@tcp [ 7227.713943] Key type .llcrypt unregistered [ 7227.715942] Key type ._llcrypt unregistered [ 7244.237177] Key type ._llcrypt registered [ 7244.238864] Key type .llcrypt registered [ 7245.539255] Lustre: DEBUG MARKER: == conf-sanity test 30b: Remove failover nids ============ 03:14:05 (1787555645) [ 7248.755487] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7248.836215] alg: No test for adler32 (adler32-zlib) [ 7249.918376] Lustre: Lustre: Build Version: 2.17.57_82_g53f9976 [ 7250.240303] LNet: Added LNI 192.168.206.38@tcp [8/256/0/180] [ 7251.887248] Key type lgssc registered [ 7253.302416] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7302.752707] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7308.801952] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7329.982852] Lustre: DEBUG MARKER: oleg638-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7332.722813] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 7343.248942] Lustre: Unmounted lustre-client [ 7343.671753] Lustre: Mounted lustre-client - version 2.17.57_82_g53f9976 [ 7345.621837] Lustre: Unmounted lustre-client [ 7378.852113] Key type lgssc unregistered [ 7379.143421] LNet: 158672:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7379.155468] LNetError: 158672:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7379.194506] LNet: Removed LNI 192.168.206.38@tcp [ 7380.120668] Key type .llcrypt unregistered [ 7380.122736] Key type ._llcrypt unregistered [ 7398.728881] Key type ._llcrypt registered [ 7398.736762] Key type .llcrypt registered [ 7400.266538] Lustre: DEBUG MARKER: == conf-sanity test 31: Connect to non-existent node (shouldn't crash) ========================================================== 03:16:40 (1787555800) [ 7401.809314] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7401.839251] alg: No test for adler32 (adler32-zlib) [ 7402.993656] Lustre: Lustre: Build Version: 2.17.57_82_g53f9976 [ 7403.191969] LNet: Added LNI 192.168.206.38@tcp [8/256/0/180] [ 7404.847533] Key type lgssc registered [ 7406.159325] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7427.808159] LustreError: 160367:0:(mgc_request.c:233:do_config_log_add()) MGC4.3.2.1@tcp: failed processing log, type 1: rc = -110 [ 7434.207544] LustreError: 160376:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 7482.079449] LNet: 1 local NIs in recovery (showing 1): 192.168.206.38@tcp [ 7554.783200] LNet: 1 local NIs in recovery (showing 1): 192.168.206.38@tcp [ 7585.759883] LustreError: MGC4.3.2.1@tcp: Confguration from log lustre-client failed from MGS -110. Communication error between node & MGS, a bad configuration, or other errors. See syslog for more info [ 7585.794296] Lustre: Unmounted lustre-client [ 7585.823890] LustreError: 160367:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 7600.628758] Key type lgssc unregistered [ 7609.120614] LNet: 160785:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7609.131531] LNetError: 160785:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7685.934897] LNet: Removed LNI 192.168.206.38@tcp [ 7686.998606] Key type .llcrypt unregistered [ 7687.008129] Key type ._llcrypt unregistered [ 7729.998208] Key type ._llcrypt registered [ 7730.001219] Key type .llcrypt registered [ 7730.660489] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7730.693991] alg: No test for adler32 (adler32-zlib) [ 7731.977068] Lustre: Lustre: Build Version: 2.17.57_82_g53f9976 [ 7732.267944] LNet: Added LNI 192.168.206.38@tcp [8/256/0/180] [ 7733.999249] Key type lgssc registered [ 7735.649105] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7783.681661] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 7454 sec ========== 03:23:03 (1787556183) [ 7785.084789] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 03:23:05 (1787556185) === [ 7787.879474] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 03:23:08 (1787556188) === [ 7819.417653] Key type lgssc unregistered [ 7819.657789] LNet: 165029:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7819.669574] LNetError: 165029:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7819.685752] LNet: Removed LNI 192.168.206.38@tcp [ 7820.415681] Key type .llcrypt unregistered [ 7820.420463] Key type ._llcrypt unregistered