[ 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 450407157 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001011] APIC: Switch to symmetric I/O mode setup [ 0.003334] x2apic enabled [ 0.004009] Switched APIC routing to physical x2apic. [ 0.005015] kvm-guest: setup PV IPIs [ 0.008318] ..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.009016] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010012] pid_max: default: 32768 minimum: 301 [ 0.012083] LSM: Security Framework initializing [ 0.013053] Yama: becoming mindful. [ 0.015036] SELinux: Initializing. [ 0.016073] *** VALIDATE selinux *** [ 0.024800] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.029122] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.030139] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031094] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.032108] *** VALIDATE tmpfs *** [ 0.034263] *** VALIDATE proc *** [ 0.035208] *** VALIDATE cgroup *** [ 0.036009] *** VALIDATE cgroup2 *** [ 0.037254] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.038159] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.039008] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.040021] Spectre V2 : User space: Vulnerable [ 0.041005] Speculative Store Bypass: Vulnerable [ 0.044491] debug: unmapping init [mem 0xffffffffbd059000-0xffffffffbd060fff] [ 0.046180] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.047692] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.048033] ... version: 2 [ 0.049012] ... bit width: 48 [ 0.050012] ... generic registers: 4 [ 0.051013] ... value mask: 0000ffffffffffff [ 0.052014] ... max period: 00007fffffffffff [ 0.053013] ... fixed-purpose events: 3 [ 0.054012] ... event mask: 000000070000000f [ 0.055307] rcu: Hierarchical SRCU implementation. [ 0.057450] smp: Bringing up secondary CPUs ... [ 0.058586] x86: Booting SMP configuration: [ 0.059025] .... node #0, CPUs: #1 #2 #3 [ 0.062427] smp: Brought up 1 node, 4 CPUs [ 0.064012] smpboot: Max logical packages: 1 [ 0.065016] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.129711] node 0 deferred pages initialised in 61ms [ 0.132439] devtmpfs: initialized [ 0.133226] x86/mm: Memory block size: 128MB [ 0.135806] gcov: version magic: 0x41383552 [ 0.137368] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.141135] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.145307] pinctrl core: initialized pinctrl subsystem [ 0.147178] [ 0.147863] ************************************************************* [ 0.150013] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.152012] ** ** [ 0.155011] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.158016] ** ** [ 0.161013] ** This means that this kernel is built to expose internal ** [ 0.164011] ** IOMMU data structures, which may compromise security on ** [ 0.166009] ** your system. ** [ 0.169012] ** ** [ 0.173012] ** If you see this message and you are not debugging the ** [ 0.175008] ** kernel, report this immediately to your vendor! ** [ 0.178012] ** ** [ 0.180011] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.182012] ************************************************************* [ 0.185673] NET: Registered protocol family 16 [ 0.187438] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.190053] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.193057] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.196092] cpuidle: using governor menu [ 0.197902] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.198413] PCI: Using configuration type 1 for base access [ 0.199123] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.209089] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.210016] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.212331] cryptd: max_cpu_qlen set to 1000 [ 0.216857] ACPI: Added _OSI(Module Device) [ 0.221094] ACPI: Added _OSI(Processor Device) [ 0.222011] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.224021] ACPI: Added _OSI(Processor Aggregator Device) [ 0.229892] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.235579] ACPI: Interpreter enabled [ 0.237052] ACPI: PM: (supports S0 S3 S4 S5) [ 0.238008] ACPI: Using IOAPIC for interrupt routing [ 0.239093] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.242342] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.252686] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.254036] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.257018] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.259197] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.264314] acpiphp: Slot [2] registered [ 0.265098] acpiphp: Slot [5] registered [ 0.267109] acpiphp: Slot [6] registered [ 0.268101] acpiphp: Slot [3] registered [ 0.269152] acpiphp: Slot [4] registered [ 0.271097] acpiphp: Slot [7] registered [ 0.272085] acpiphp: Slot [8] registered [ 0.273099] acpiphp: Slot [9] registered [ 0.275090] acpiphp: Slot [10] registered [ 0.276102] acpiphp: Slot [11] registered [ 0.278071] acpiphp: Slot [12] registered [ 0.279098] acpiphp: Slot [13] registered [ 0.281103] acpiphp: Slot [14] registered [ 0.282100] acpiphp: Slot [15] registered [ 0.284092] acpiphp: Slot [16] registered [ 0.285107] acpiphp: Slot [17] registered [ 0.287116] acpiphp: Slot [18] registered [ 0.289103] acpiphp: Slot [19] registered [ 0.290090] acpiphp: Slot [20] registered [ 0.292112] acpiphp: Slot [21] registered [ 0.293089] acpiphp: Slot [22] registered [ 0.295088] acpiphp: Slot [23] registered [ 0.296172] acpiphp: Slot [24] registered [ 0.298100] acpiphp: Slot [25] registered [ 0.299102] acpiphp: Slot [26] registered [ 0.301150] acpiphp: Slot [27] registered [ 0.302092] acpiphp: Slot [28] registered [ 0.304093] acpiphp: Slot [29] registered [ 0.305091] acpiphp: Slot [30] registered [ 0.306118] acpiphp: Slot [31] registered [ 0.307000] PCI host bridge to bus 0000:00 [ 0.307000] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.308021] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.310028] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.312020] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.314051] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.317026] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.319224] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.323090] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.326404] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.334013] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.340068] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.346026] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.352130] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.355018] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.358030] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.360919] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.365044] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.369093] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.373013] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.382013] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.387854] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.395155] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.407015] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.422020] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.455014] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.467428] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.477015] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.490015] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.511014] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.526587] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.529401] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.532364] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.536780] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.538245] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.544039] iommu: Default domain type: Passthrough [ 0.548522] SCSI subsystem initialized [ 0.551177] ACPI: bus type USB registered [ 0.555197] usbcore: registered new interface driver usbfs [ 0.561093] usbcore: registered new interface driver hub [ 0.566088] usbcore: registered new device driver usb [ 0.572198] pps_core: LinuxPPS API ver. 1 registered [ 0.574009] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.580054] PTP clock support registered [ 0.586314] EDAC MC: Ver: 3.0.0 [ 0.588202] PCI: Using ACPI for IRQ routing [ 0.590183] NetLabel: Initializing [ 0.592009] NetLabel: domain hash size = 128 [ 0.596012] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.599088] NetLabel: unlabeled traffic allowed by default [ 0.602093] vgaarb: loaded [ 0.604000] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.606012] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.613632] clocksource: Switched to clocksource kvm-clock [ 0.847032] VFS: Disk quotas dquot_6.6.0 [ 0.849041] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.856707] *** VALIDATE ramfs *** [ 0.862657] *** VALIDATE hugetlbfs *** [ 0.865742] pnp: PnP ACPI init [ 0.869771] pnp: PnP ACPI: found 6 devices [ 0.906382] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.911651] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.915476] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.919577] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.929608] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.935424] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.946963] NET: Registered protocol family 2 [ 0.949453] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.962194] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.969603] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.987720] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 1.000554] TCP: Hash tables configured (established 65536 bind 65536) [ 1.008616] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 1.019607] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 1.023409] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 1.027424] NET: Registered protocol family 1 [ 1.031908] RPC: Registered named UNIX socket transport module. [ 1.033741] RPC: Registered udp transport module. [ 1.036047] RPC: Registered tcp transport module. [ 1.041538] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 1.047720] NET: Registered protocol family 44 [ 1.051350] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 1.058892] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 1.062918] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 1.073423] PCI: CLS 0 bytes, default 64 [ 1.076807] Unpacking initramfs... [ 4.041493] debug: unmapping init [mem 0xffff9a663cc64000-0xffff9a663ffcffff] [ 4.047894] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 4.051860] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 4.056514] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 4.959833] Initialise system trusted keyrings [ 4.963467] Key type blacklist registered [ 4.970293] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 4.987838] zbud: loaded [ 4.992184] *** VALIDATE nfs *** [ 4.993489] *** VALIDATE nfs4 *** [ 4.996759] pstore: using deflate compression [ 5.001821] Platform Keyring initialized [ 5.130210] NET: Registered protocol family 38 [ 5.132151] Key type asymmetric registered [ 5.134317] Asymmetric key parser 'x509' registered [ 5.136917] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 5.143297] io scheduler mq-deadline registered [ 5.147268] io scheduler kyber registered [ 5.150241] io scheduler bfq registered [ 5.153467] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 5.161890] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 5.166624] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 5.173619] ACPI: Power Button [PWRF] [ 5.183662] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 5.201420] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 5.222030] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 5.261908] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 5.304120] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 5.314476] Non-volatile memory driver v1.3 [ 5.318718] Linux agpgart interface v0.103 [ 5.413678] virtio_blk virtio1: [vda] 149816 512-byte logical blocks (76.7 MB/73.2 MiB) [ 5.422440] vda: detected capacity change from 0 to 76705792 [ 5.479091] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 5.491738] vdb: detected capacity change from 0 to 1073741824 [ 5.516771] libphy: Fixed MDIO Bus: probed [ 5.525389] usbcore: registered new interface driver usbserial_generic [ 5.529262] usbserial: USB Serial support registered for generic [ 5.532335] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 5.540929] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 5.543263] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 5.547585] mousedev: PS/2 mouse device common for all mice [ 5.551896] rtc_cmos 00:05: RTC can wake from S4 [ 5.554401] rtc_cmos 00:05: registered as rtc0 [ 5.556245] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 5.558172] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 5.563733] intel_pstate: CPU model not supported [ 5.564951] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 5.571457] hid: raw HID events driver (C) Jiri Kosina [ 5.573221] usbcore: registered new interface driver usbhid [ 5.574409] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 5.577589] usbhid: USB HID core driver [ 5.577744] drop_monitor: Initializing network drop monitor service [ 5.577862] Initializing XFRM netlink socket [ 5.579362] NET: Registered protocol family 10 [ 5.591456] Segment Routing with IPv6 [ 5.592978] NET: Registered protocol family 17 [ 5.595249] mpls_gso: MPLS GSO support [ 5.602338] RAS: Correctable Errors collector initialized. [ 5.604794] AVX version of gcm_enc/dec engaged. [ 5.607031] AES CTR mode by8 optimization enabled [ 5.704605] sched_clock: Marking stable (5704505914, 0)->(6609962248, -905456334) [ 5.715653] registered taskstats version 1 [ 5.719506] Loading compiled-in X.509 certificates [ 5.722184] zswap: loaded using pool lzo/zbud [ 5.827883] Key type big_key registered [ 5.877612] Key type encrypted registered [ 5.879561] ima: No TPM chip found, activating TPM-bypass! [ 5.882574] ima: Allocated hash algorithm: sha1 [ 5.887778] ima: No architecture policies found [ 5.893450] evm: Initialising EVM extended attributes: [ 5.894932] evm: security.selinux [ 5.896334] evm: security.ima [ 5.897370] evm: security.capability [ 5.898657] evm: HMAC attrs: 0x1 [ 5.903059] rtc_cmos 00:05: setting system clock to 2026-09-05 07:33:10 UTC (1788593590) [ 5.913678] debug: unmapping init [mem 0xffffffffbe003000-0xffffffffbe1fffff] [ 5.920341] debug: unmapping init [mem 0xffffffffbcd82000-0xffffffffbd058fff] [ 5.930146] Write protecting the kernel read-only data: 28672k [ 5.940222] debug: unmapping init [mem 0xffffffffbb403000-0xffffffffbb5fffff] [ 5.943880] debug: unmapping init [mem 0xffffffffbbd14000-0xffffffffbbdfffff] [ 6.025181] 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) [ 6.047462] systemd[1]: Detected virtualization kvm. [ 6.050792] systemd[1]: Detected architecture x86-64. [ 6.053816] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 6.091516] systemd[1]: No hostname configured. [ 6.094451] systemd[1]: Set hostname to . [ 6.096279] random: systemd: uninitialized urandom read (16 bytes read) [ 6.098454] systemd[1]: Initializing machine ID from random generator. [ 6.156863] random: ln: uninitialized urandom read (6 bytes read) [ 6.305582] random: systemd: uninitialized urandom read (16 bytes read) [ 6.309687] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 6.315461] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 6.323632] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket. Starting Create Volatile Files and Directories... Starting Apply Kernel Variables... Starting Journal Service... [ OK ] Reached target Slices. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. Starting Create list of required st…ce nodes for the current kernel... Starting Setup Virtual Console... [ OK ] Reached target Timers. [ OK ] Started Memstrack Anylazing Service. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Initrd Root Device. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 7.462992] device-mapper: uevent: version 1.0.3 [ 7.467235] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK [ 8.579564] random: fast init done ] Reached target Basic System. [ 8.588884] virtio_net virtio0 ens2: renamed from eth0 [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 8.713319] scsi host0: ata_piix [ 8.742804] scsi host1: ata_piix [ 8.744412] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 8.747815] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 13.345037] dracut-initqueue[586]: RTNETLINK answers: File exists [ 13.415803] random: crng init done [ 13.418928] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ 15.522385] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Reached target Remote File Systems. [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 17.711983] printk: systemd: 25 output lines suppressed due to ratelimiting [ 18.761085] SELinux: Disabled at runtime. [ 18.899589] 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) [ 18.924634] systemd[1]: Detected virtualization kvm. [ 18.931405] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 20.093251] systemd[1]: initrd-switch-root.service: Succeeded. [ 20.098539] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 20.111560] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 20.118582] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 20.122351] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 20.148467] systemd[1]: Starting Journal Service... Starting Journal Service... [ 20.174351] systemd[1]: Listening on RPCbind Server Activation Socket. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. Activating swap /dev/disk/by-label/SWAP... [ OK ] Reached target RPC Port Mapper. [ OK ] Created slice system-getty.slice. Mounting Huge Pages File System... [ OK ] Listening on initctl Compatibility Named Pipe. [ 20.285856] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Starting Remount Root and Kernel File Systems... Starting Apply Kernel Variables... [ OK ] Stopped target Initrd File Systems. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-sshd\x2dkeygen.slice. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Dispatch Password Requests to Console Directory Watch. Mounting POSIX Message Queue File System... [ OK ] Listening on Process Core Dump Socket. [ OK ] Listening on udev Kernel Socket. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Reached target rpc_pipefs.target. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. Mounting Kernel Debug File System... [ OK ] Listening on udev Control Socket. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. Starting udev Coldplug all Devices... [ OK ] Started Journal Service. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Huge Pages File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Kernel Debug File System. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started udev Coldplug all Devices. [ 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. [ 21.889339] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 22.858323] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 22.907262] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 23.040386] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 23.109916] EDAC sbridge: Ver: 1.1.2 [ 26.744726] Key type dns_resolver registered [ 27.315496] NFS: Registering the id_resolver key type [ 27.318243] Key type id_resolver registered [ 27.320505] Key type id_legacy registered [* ] A start job is running for Configur…-only root support (7s / no limit) [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Load/Save Random Seed. 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 ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Basic System. [ OK ] Started irqbalance daemon. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Login Service... [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... Starting Restore /run/initramfs on shutdown... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... [ OK ] Started Login Service. Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Started OpenSSH server 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 Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg628-client login: [ 89.983718] libcfs: loading out-of-tree module taints kernel. [ 90.275661] Key type ._llcrypt registered [ 90.279621] Key type .llcrypt registered [ 91.000262] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 91.013475] alg: No test for adler32 (adler32-zlib) [ 92.496363] Lustre: Lustre: Build Version: 2.17.58_2_gae7f7f4 [ 93.624562] LNet: Added LNI 192.168.206.28@tcp [8/256/0/180] [ 95.447154] Key type lgssc registered [ 97.778834] Lustre: Echo OBD driver; http://www.lustre.org/ [ 258.547244] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 263.050337] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 280.246518] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing check_logdir /tmp/testlogs/ [ 284.127138] Lustre: lustre-OST0000-osc-ffff9a669239d000: disconnect after 23s idle [ 284.733873] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing yml_node [ 288.780592] Lustre: DEBUG MARKER: Client: 2.17.58.2 [ 291.311787] Lustre: DEBUG MARKER: MDS: 2.17.58.2 [ 293.784443] Lustre: DEBUG MARKER: OSS: 2.17.58.2 [ 295.253966] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Sat Sep 5 03:37:58 EDT 2026 [ 311.288316] Lustre: DEBUG MARKER: excepting tests: 21e 32b 32c 32newtarball [ 313.329194] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 316.111953] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 326.422227] Lustre: Unmounted lustre-client [ 433.857774] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 438.720107] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 460.457292] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 504.396046] Lustre: DEBUG MARKER: == conf-sanity test 0: single mount setup ================ 03:41:27 (1788594087) [ 535.103219] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 540.054981] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 558.660305] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 559.269680] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 563.374624] Lustre: Unmounted lustre-client [ 588.554487] Key type lgssc unregistered [ 588.857348] LNet: 14699:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 588.871094] LNetError: 14699:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 588.898556] LNet: Removed LNI 192.168.206.28@tcp [ 589.509421] Key type .llcrypt unregistered [ 589.512746] Key type ._llcrypt unregistered [ 602.896156] Key type ._llcrypt registered [ 602.899184] Key type .llcrypt registered [ 603.357005] hrtimer: interrupt took 7241681 ns [ 604.022547] Lustre: DEBUG MARKER: == conf-sanity test 1: start up ost twice (should return errors) ========================================================== 03:43:07 (1788594187) [ 606.095832] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 606.116376] alg: No test for adler32 (adler32-zlib) [ 607.124797] Lustre: Lustre: Build Version: 2.17.58_2_gae7f7f4 [ 607.343284] LNet: Added LNI 192.168.206.28@tcp [8/256/0/180] [ 609.015147] Key type lgssc registered [ 610.240401] Lustre: Echo OBD driver; http://www.lustre.org/ [ 656.661558] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 663.340428] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 684.379713] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 695.300978] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 697.878128] Lustre: Unmounted lustre-client [ 720.495942] Key type lgssc unregistered [ 720.774314] LNet: 18366:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 720.783623] LNetError: 18366:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 720.803971] LNet: Removed LNI 192.168.206.28@tcp [ 721.523399] Key type .llcrypt unregistered [ 721.525237] Key type ._llcrypt unregistered [ 735.967249] Key type ._llcrypt registered [ 735.969883] Key type .llcrypt registered [ 737.403782] Lustre: DEBUG MARKER: == conf-sanity test 2: start up mds twice (should return err) ========================================================== 03:45:20 (1788594320) [ 739.633535] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 739.687168] alg: No test for adler32 (adler32-zlib) [ 740.791981] Lustre: Lustre: Build Version: 2.17.58_2_gae7f7f4 [ 741.153706] LNet: Added LNI 192.168.206.28@tcp [8/256/0/180] [ 742.919471] Key type lgssc registered [ 743.993483] Lustre: Echo OBD driver; http://www.lustre.org/ [ 790.742593] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 795.385721] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 822.293905] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 823.777231] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 831.263292] Lustre: Unmounted lustre-client [ 856.665598] Key type lgssc unregistered [ 856.964174] LNet: 22015:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 856.979278] LNetError: 22015:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 856.999071] LNet: Removed LNI 192.168.206.28@tcp [ 857.808113] Key type .llcrypt unregistered [ 857.812353] Key type ._llcrypt unregistered [ 873.800162] Key type ._llcrypt registered [ 873.801669] Key type .llcrypt registered [ 874.944663] Lustre: DEBUG MARKER: == conf-sanity test 3: mount client twice (should return err) ========================================================== 03:47:38 (1788594458) [ 877.597832] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 877.675089] alg: No test for adler32 (adler32-zlib) [ 878.839724] Lustre: Lustre: Build Version: 2.17.58_2_gae7f7f4 [ 879.182331] LNet: Added LNI 192.168.206.28@tcp [8/256/0/180] [ 880.951161] Key type lgssc registered [ 882.294036] Lustre: Echo OBD driver; http://www.lustre.org/ [ 928.060423] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 932.681390] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 953.184506] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 955.792456] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 959.588056] Lustre: Unmounted lustre-client [ 986.689652] Key type lgssc unregistered [ 986.958927] LNet: 25626:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 986.975228] LNetError: 25626:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 986.993557] LNet: Removed LNI 192.168.206.28@tcp [ 987.958459] Key type .llcrypt unregistered [ 987.961073] Key type ._llcrypt unregistered [ 1003.799510] Key type ._llcrypt registered [ 1003.803557] Key type .llcrypt registered [ 1005.091957] Lustre: DEBUG MARKER: == conf-sanity test 4: force cleanup ost, then cleanup === 03:49:48 (1788594588) [ 1007.206319] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1007.317483] alg: No test for adler32 (adler32-zlib) [ 1008.481723] Lustre: Lustre: Build Version: 2.17.58_2_gae7f7f4 [ 1008.786832] LNet: Added LNI 192.168.206.28@tcp [8/256/0/180] [ 1010.441734] Key type lgssc registered [ 1011.504321] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1056.453248] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1062.585616] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1079.154745] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1081.687107] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 1086.951463] Lustre: lustre-OST0000-osc-ffff9a668943f000: Connection to lustre-OST0000 (at 192.168.206.128@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1102.961708] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1113.322267] Lustre: Unmounted lustre-client [ 1148.213857] Key type lgssc unregistered [ 1148.493729] LNet: 29242:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1148.499888] LNetError: 29242:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1148.522096] LNet: Removed LNI 192.168.206.28@tcp [ 1149.357099] Key type .llcrypt unregistered [ 1149.360493] Key type ._llcrypt unregistered [ 1163.507265] Key type ._llcrypt registered [ 1163.508916] Key type .llcrypt registered [ 1164.703329] Lustre: DEBUG MARKER: == conf-sanity test 5a: force cleanup mds, then cleanup == 03:52:27 (1788594747) [ 1166.491323] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1166.586598] alg: No test for adler32 (adler32-zlib) [ 1167.697966] Lustre: Lustre: Build Version: 2.17.58_2_gae7f7f4 [ 1168.004564] LNet: Added LNI 192.168.206.28@tcp [8/256/0/180] [ 1169.703460] Key type lgssc registered [ 1170.845286] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1218.022276] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1223.416383] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1243.185385] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1245.896588] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 1251.308937] Lustre: lustre-MDT0000-mdc-ffff9a66870a0000: Connection to lustre-MDT0000 (at 192.168.206.128@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1256.429932] Lustre: lustre-MDT0001-mdc-ffff9a66870a0000: Connection to lustre-MDT0001 (at 192.168.206.128@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1263.747792] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1272.799298] Lustre: 30198:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788594841/real 1788594841] req@00000000bd379c99 x1875477530678912/t0(0) o400->MGC192.168.206.128@tcp@192.168.206.128@tcp:26/25 lens 224/224 e 0 to 1 dl 1788594857 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1272.848542] LustreError: MGC192.168.206.128@tcp: Connection to MGS (at 192.168.206.128@tcp) was lost; in progress operations using this service will fail [ 1274.097527] Lustre: Unmounted lustre-client [ 1291.752261] Key type lgssc unregistered [ 1292.119171] LNet: 32890:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1292.132107] LNetError: 32890:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1292.162590] LNet: Removed LNI 192.168.206.28@tcp [ 1292.985735] Key type .llcrypt unregistered [ 1292.987701] Key type ._llcrypt unregistered [ 1310.816638] Key type ._llcrypt registered [ 1310.818691] Key type .llcrypt registered [ 1312.502899] Lustre: DEBUG MARKER: == conf-sanity test 5b: Try to start a client with no MGS (should return errs) ========================================================== 03:54:55 (1788594895) [ 1315.401331] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1315.413182] alg: No test for adler32 (adler32-zlib) [ 1316.513741] Lustre: Lustre: Build Version: 2.17.58_2_gae7f7f4 [ 1316.852548] LNet: Added LNI 192.168.206.28@tcp [8/256/0/180] [ 1318.543248] Key type lgssc registered [ 1319.790153] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1401.393303] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1407.007603] LustreError: 35088:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.206.128@tcp: failed processing log, type 1: rc = -110 [ 1412.577669] LustreError: 35097:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 1564.704586] LustreError: MGC192.168.206.128@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 [ 1564.716913] Lustre: Unmounted lustre-client [ 1564.733207] LustreError: 35088:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 1575.116645] Key type lgssc unregistered [ 1575.365175] LNet: 35513:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1575.374808] LNetError: 35513:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1575.403914] LNet: Removed LNI 192.168.206.28@tcp [ 1576.042161] Key type .llcrypt unregistered [ 1576.047206] Key type ._llcrypt unregistered [ 1589.865592] Key type ._llcrypt registered [ 1589.867777] Key type .llcrypt registered [ 1590.959768] Lustre: DEBUG MARKER: == conf-sanity test 5c: cleanup after failed mount (bug 2712) (should return errs) ========================================================== 03:59:34 (1788595174) [ 1592.782136] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1592.822378] alg: No test for adler32 (adler32-zlib) [ 1593.896071] Lustre: Lustre: Build Version: 2.17.58_2_gae7f7f4 [ 1594.164299] LNet: Added LNI 192.168.206.28@tcp [8/256/0/180] [ 1595.831187] Key type lgssc registered [ 1596.990387] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1644.304170] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1649.459907] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1670.080643] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1670.626806] LustreError: 38604:0:(llite_lib.c:1555:ll_fill_super()) wrong.lustre: fsname longer than 8 characters: rc = -36 [ 1670.646534] Lustre: Unmounted wrong.lustre-client [ 1670.659666] LustreError: 38604:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -36 [ 1695.577563] Key type lgssc unregistered [ 1695.791719] LNet: 39032:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1695.802765] LNetError: 39032:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1696.869958] LNet: Removed LNI 192.168.206.28@tcp [ 1697.512833] Key type .llcrypt unregistered [ 1697.515258] Key type ._llcrypt unregistered [ 1710.711902] Key type ._llcrypt registered [ 1710.714816] Key type .llcrypt registered [ 1711.938850] Lustre: DEBUG MARKER: == conf-sanity test 5d: mount with ost down ============== 04:01:35 (1788595295) [ 1713.600978] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1713.627114] alg: No test for adler32 (adler32-zlib) [ 1714.695468] Lustre: Lustre: Build Version: 2.17.58_2_gae7f7f4 [ 1714.975464] LNet: Added LNI 192.168.206.28@tcp [8/256/0/180] [ 1716.623191] Key type lgssc registered [ 1717.861902] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1801.855352] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1832.373918] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1838.327710] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1850.841797] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 1852.679429] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1863.010775] Lustre: Unmounted lustre-client [ 1888.418527] Key type lgssc unregistered [ 1888.757889] LNet: 42604:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1888.775830] LNetError: 42604:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1889.831427] LNet: Removed LNI 192.168.206.28@tcp [ 1890.508577] Key type .llcrypt unregistered [ 1890.510636] Key type ._llcrypt unregistered [ 1903.923792] Key type ._llcrypt registered [ 1903.925935] Key type .llcrypt registered [ 1905.037498] Lustre: DEBUG MARKER: == conf-sanity test 5e: delayed connect, don't crash (bug 10268) ========================================================== 04:04:48 (1788595488) [ 1906.860994] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1907.009696] alg: No test for adler32 (adler32-zlib) [ 1908.076359] Lustre: Lustre: Build Version: 2.17.58_2_gae7f7f4 [ 1908.328082] LNet: Added LNI 192.168.206.28@tcp [8/256/0/180] [ 1910.007904] Key type lgssc registered [ 1911.061261] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1955.338137] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1960.569939] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1980.495862] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1981.104858] LustreError: 43559:0:(niobuf.c:1106:ptl_send_rpc()) cfs_fail_timeout id 506 sleeping for 10000ms [ 1986.208092] LustreError: 45683:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.206.128@tcp: failed processing log, type 1: rc = -110 [ 1991.111115] LustreError: 43559:0:(niobuf.c:1106:ptl_send_rpc()) cfs_fail_timeout id 506 awake [ 1992.431717] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 1994.162443] Lustre: Unmounted lustre-client [ 2027.143503] Key type lgssc unregistered [ 2027.444227] LNet: 46142:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2027.449115] LNetError: 46142:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2027.467539] LNet: Removed LNI 192.168.206.28@tcp [ 2028.390777] Key type .llcrypt unregistered [ 2028.392828] Key type ._llcrypt unregistered [ 2045.474943] Key type ._llcrypt registered [ 2045.477330] Key type .llcrypt registered [ 2046.940881] Lustre: DEBUG MARKER: == conf-sanity test 5f: mds down, cleanup after failed mount (bug 2712) ========================================================== 04:07:09 (1788595629) [ 2048.460948] Lustre: DEBUG MARKER: SKIP: conf-sanity test_5f needs separate mgs and mds [ 2050.192849] Lustre: DEBUG MARKER: == conf-sanity test 5g: handle missing debugfs =========== 04:07:13 (1788595633) [ 2051.143841] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2051.215674] alg: No test for adler32 (adler32-zlib) [ 2052.302646] Lustre: Lustre: Build Version: 2.17.58_2_gae7f7f4 [ 2052.646572] LNet: Added LNI 192.168.206.28@tcp [8/256/0/180] [ 2054.439226] Key type lgssc registered [ 2055.589459] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2080.639844] Lustre: DEBUG MARKER: == conf-sanity test 5h: start mdt failure at mdt_fs_setup() ========================================================== 04:07:44 (1788595664) [ 2116.096244] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2121.780465] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2140.382088] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2143.019678] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 2153.454027] Lustre: lustre-MDT0000-mdc-ffff9a6692795800: Connection to lustre-MDT0000 (at 192.168.206.128@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2168.829849] LustreError: MGC192.168.206.128@tcp: Connection to MGS (at 192.168.206.128@tcp) was lost; in progress operations using this service will fail [ 2168.860228] Lustre: Evicted from MGS (at 192.168.206.128@tcp) after server handle changed from 0x497b7929544db284 to 0x497b7929544db4fa [ 2168.877907] Lustre: MGC192.168.206.128@tcp: Connection restored to 192.168.206.128@tcp (at 192.168.206.128@tcp) [ 2172.920263] Lustre: lustre-MDT0000-mdc-ffff9a6692795800: Connection restored to 192.168.206.128@tcp (at 192.168.206.128@tcp) [ 2175.884953] Lustre: Unmounted lustre-client [ 2198.776332] Key type lgssc unregistered [ 2199.081507] LNet: 50993:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2199.092203] LNetError: 50993:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2199.124097] LNet: Removed LNI 192.168.206.28@tcp [ 2199.976458] Key type .llcrypt unregistered [ 2199.980139] Key type ._llcrypt unregistered [ 2216.866349] Key type ._llcrypt registered [ 2216.870600] Key type .llcrypt registered [ 2218.363470] Lustre: DEBUG MARKER: == conf-sanity test 5i: start mdt failure at mdt_quota_init() ========================================================== 04:10:01 (1788595801) [ 2220.392753] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2220.491500] alg: No test for adler32 (adler32-zlib) [ 2221.685857] Lustre: Lustre: Build Version: 2.17.58_2_gae7f7f4 [ 2221.957154] LNet: Added LNI 192.168.206.28@tcp [8/256/0/180] [ 2223.624309] Key type lgssc registered [ 2224.844660] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2273.634131] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2279.719443] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2300.393875] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2301.927690] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 2312.201204] Lustre: lustre-MDT0000-mdc-ffff9a668452a800: Connection to lustre-MDT0000 (at 192.168.206.128@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2327.520995] Lustre: 51955:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788595896/real 1788595896] req@00000000d3d33870 x1875478635876480/t0(0) o400->MGC192.168.206.128@tcp@192.168.206.128@tcp:26/25 lens 224/224 e 0 to 1 dl 1788595912 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2327.544317] LustreError: MGC192.168.206.128@tcp: Connection to MGS (at 192.168.206.128@tcp) was lost; in progress operations using this service will fail [ 2327.610611] Lustre: Evicted from MGS (at 192.168.206.128@tcp) after server handle changed from 0x986583c98cbba229 to 0x986583c98cbba3db [ 2327.628104] Lustre: MGC192.168.206.128@tcp: Connection restored to 192.168.206.128@tcp (at 192.168.206.128@tcp) [ 2333.168779] Lustre: lustre-MDT0000-mdc-ffff9a668452a800: Connection restored to 192.168.206.128@tcp (at 192.168.206.128@tcp) [ 2337.993299] Lustre: Unmounted lustre-client [ 2370.855454] Key type lgssc unregistered [ 2371.103248] LNet: 54958:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2371.108368] LNetError: 54958:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2371.130587] LNet: Removed LNI 192.168.206.28@tcp [ 2371.693125] Key type .llcrypt unregistered [ 2371.694949] Key type ._llcrypt unregistered [ 2386.396828] Key type ._llcrypt registered [ 2386.401558] Key type .llcrypt registered [ 2388.326896] Lustre: DEBUG MARKER: == conf-sanity test 5j: verify libcfs doesn't crash when setting debug flags ========================================================== 04:12:50 (1788595970) [ 2388.793313] Key type .llcrypt unregistered [ 2388.797384] Key type ._llcrypt unregistered [ 2395.637489] Key type ._llcrypt registered [ 2395.639751] Key type .llcrypt registered [ 2395.682687] Key type .llcrypt unregistered [ 2395.684277] Key type ._llcrypt unregistered [ 2401.070671] Key type ._llcrypt registered [ 2401.072643] Key type .llcrypt registered [ 2402.295980] Lustre: DEBUG MARKER: == conf-sanity test 6: manual umount, then mount again === 04:13:05 (1788595985) [ 2404.779821] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2404.813785] alg: No test for adler32 (adler32-zlib) [ 2405.884285] Lustre: Lustre: Build Version: 2.17.58_2_gae7f7f4 [ 2406.086595] LNet: Added LNI 192.168.206.28@tcp [8/256/0/180] [ 2407.831384] Key type lgssc registered [ 2409.027726] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2456.048568] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2461.777851] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2482.706559] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2485.389046] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 2487.585361] Lustre: Unmounted lustre-client [ 2488.011974] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 2490.194918] Lustre: Unmounted lustre-client [ 2519.185298] Key type lgssc unregistered [ 2519.564132] LNet: 59371:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2519.579097] LNetError: 59371:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2519.613958] LNet: Removed LNI 192.168.206.28@tcp [ 2521.066814] Key type .llcrypt unregistered [ 2521.070322] Key type ._llcrypt unregistered [ 2535.952399] Key type ._llcrypt registered [ 2535.953947] Key type .llcrypt registered [ 2536.871983] Lustre: DEBUG MARKER: == conf-sanity test 7: manual umount, then cleanup ======= 04:15:20 (1788596120) [ 2538.173704] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2538.224195] alg: No test for adler32 (adler32-zlib) [ 2539.259702] Lustre: Lustre: Build Version: 2.17.58_2_gae7f7f4 [ 2539.448289] LNet: Added LNI 192.168.206.28@tcp [8/256/0/180] [ 2541.111188] Key type lgssc registered [ 2542.396543] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2588.174791] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2593.293710] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2612.761401] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2614.408736] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 2616.523870] Lustre: Unmounted lustre-client [ 2651.016636] Key type lgssc unregistered [ 2651.213981] LNet: 62962:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2651.220457] LNetError: 62962:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2651.241129] LNet: Removed LNI 192.168.206.28@tcp [ 2651.826425] Key type .llcrypt unregistered [ 2651.829612] Key type ._llcrypt unregistered [ 2664.927464] Key type ._llcrypt registered [ 2664.929499] Key type .llcrypt registered [ 2665.884475] Lustre: DEBUG MARKER: == conf-sanity test 8: double mount setup ================ 04:17:29 (1788596249) [ 2667.805964] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2667.838935] alg: No test for adler32 (adler32-zlib) [ 2668.844303] Lustre: Lustre: Build Version: 2.17.58_2_gae7f7f4 [ 2669.176541] LNet: Added LNI 192.168.206.28@tcp [8/256/0/180] [ 2670.871260] Key type lgssc registered [ 2672.115440] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2719.367892] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2725.135827] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2743.763430] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2745.258673] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 2747.411234] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 2750.112631] Lustre: Unmounted lustre-client [ 2751.737501] Lustre: Unmounted lustre-client [ 2776.973835] Key type lgssc unregistered [ 2777.141581] LNet: 66614:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2777.145104] LNetError: 66614:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2777.157886] LNet: Removed LNI 192.168.206.28@tcp [ 2777.634639] Key type .llcrypt unregistered [ 2777.639386] Key type ._llcrypt unregistered [ 2792.137547] Key type ._llcrypt registered [ 2792.139991] Key type .llcrypt registered [ 2793.618417] Lustre: DEBUG MARKER: == conf-sanity test 9: test ptldebug and subsystem for mkfs ========================================================== 04:19:36 (1788596376) [ 2796.203496] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2796.227470] alg: No test for adler32 (adler32-zlib) [ 2797.411628] Lustre: Lustre: Build Version: 2.17.58_2_gae7f7f4 [ 2797.697208] LNet: Added LNI 192.168.206.28@tcp [8/256/0/180] [ 2799.431231] Key type lgssc registered [ 2800.799540] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2883.506619] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2895.523444] Lustre: DEBUG MARKER: == conf-sanity test 10a: find lctl param broken symlinks ========================================================== 04:21:18 (1788596478) [ 2921.612726] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2926.460335] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2945.899051] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2951.729646] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 2956.797473] Lustre: Unmounted lustre-client [ 2981.783961] Key type lgssc unregistered [ 2981.995784] LNet: 71375:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2982.003635] LNetError: 71375:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2982.020189] LNet: Removed LNI 192.168.206.28@tcp [ 2982.679972] Key type .llcrypt unregistered [ 2982.681774] Key type ._llcrypt unregistered [ 2996.284444] Key type ._llcrypt registered [ 2996.286544] Key type .llcrypt registered [ 2997.469359] Lustre: DEBUG MARKER: == conf-sanity test 11: Verify tool --version option works properly ========================================================== 04:23:00 (1788596580) [ 3012.599621] Lustre: DEBUG MARKER: == conf-sanity test 17: Verify failed mds_postsetup won't fail assertion (2936) (should return errs) ========================================================== 04:23:16 (1788596596) [ 3014.718929] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3014.743889] alg: No test for adler32 (adler32-zlib) [ 3015.811688] Lustre: Lustre: Build Version: 2.17.58_2_gae7f7f4 [ 3016.023558] LNet: Added LNI 192.168.206.28@tcp [8/256/0/180] [ 3017.679171] Key type lgssc registered [ 3018.745670] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3060.071488] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3064.956055] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3082.386422] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3088.413727] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 3092.720360] Lustre: Unmounted lustre-client [ 3119.506361] Key type lgssc unregistered [ 3119.787085] LNet: 75657:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3119.799546] LNetError: 75657:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3119.820307] LNet: Removed LNI 192.168.206.28@tcp [ 3120.506167] Key type .llcrypt unregistered [ 3120.508306] Key type ._llcrypt unregistered [ 3133.549750] Key type ._llcrypt registered [ 3133.552033] Key type .llcrypt registered [ 3133.911570] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3133.921873] alg: No test for adler32 (adler32-zlib) [ 3134.994214] Lustre: Lustre: Build Version: 2.17.58_2_gae7f7f4 [ 3135.186694] LNet: Added LNI 192.168.206.28@tcp [8/256/0/180] [ 3136.863279] Key type lgssc registered [ 3137.789923] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3217.790877] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3343.589497] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3348.333852] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3368.747384] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3416.355532] Lustre: DEBUG MARKER: == conf-sanity test 18: check mkfs creates large journals ========================================================== 04:29:59 (1788596999) [ 3419.065505] Lustre: DEBUG MARKER: use device /dev/mapper/mds1_flakey with MIN=2000000 [ 3503.755241] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3509.390694] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3531.418775] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3596.526761] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3601.446523] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3621.037287] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3622.967923] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 3627.040548] Lustre: DEBUG MARKER: Success: mkfs creates large journals. Size: 78M [ 3629.218513] Lustre: Unmounted lustre-client [ 3656.075137] Key type lgssc unregistered [ 3656.288724] LNet: 87336:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3656.300744] LNetError: 87336:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3656.326095] LNet: Removed LNI 192.168.206.28@tcp [ 3656.875870] Key type .llcrypt unregistered [ 3656.879536] Key type ._llcrypt unregistered [ 3680.883764] Key type ._llcrypt registered [ 3680.886647] Key type .llcrypt registered [ 3681.232504] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3681.247924] alg: No test for adler32 (adler32-zlib) [ 3682.269863] Lustre: Lustre: Build Version: 2.17.58_2_gae7f7f4 [ 3682.431034] LNet: Added LNI 192.168.206.28@tcp [8/256/0/180] [ 3684.079218] Key type lgssc registered [ 3685.192111] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3753.242541] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3758.194595] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3778.723335] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3824.454748] Lustre: DEBUG MARKER: == conf-sanity test 19a: start/stop MDS without OSTs ===== 04:36:47 (1788597407) [ 3855.898495] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3860.647762] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3888.824891] Lustre: DEBUG MARKER: == conf-sanity test 19b: start/stop OSTs without MDS ===== 04:37:52 (1788597472) [ 3955.473425] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3964.807140] Lustre: DEBUG MARKER: == conf-sanity test 20: remount ro,rw mounts work and doesn't break /etc/mtab ========================================================== 04:39:08 (1788597548) [ 3993.950704] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3998.936334] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4016.172586] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4017.774689] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 4018.375737] Lustre: Remounted lustre-client read-only [ 4021.373411] Lustre: Unmounted lustre-client [ 4054.502506] Lustre: DEBUG MARKER: == conf-sanity test 21a: start mds before ost, stop ost first ========================================================== 04:40:37 (1788597637) [ 4078.598524] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4082.140679] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4096.651657] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4137.279222] Lustre: DEBUG MARKER: == conf-sanity test 21b: start ost before mds, stop mds first ========================================================== 04:42:00 (1788597720) [ 4201.557168] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4233.632617] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4239.168731] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4281.094967] Lustre: DEBUG MARKER: == conf-sanity test 21c: start mds between two osts, stop mds last ========================================================== 04:44:24 (1788597864) [ 4347.249989] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4377.857956] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4382.826367] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4402.904390] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4477.583884] Lustre: DEBUG MARKER: == conf-sanity test 21d: start mgs then ost and then mds ========================================================== 04:47:40 (1788598060) [ 4479.023424] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21d need separate mgs device [ 4480.393833] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21e skipping excluded test 21e [ 4482.136786] Lustre: DEBUG MARKER: == conf-sanity test 22: start a client before osts (should return errs) ========================================================== 04:47:45 (1788598065) [ 4510.994331] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4515.330270] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4530.782211] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4548.706599] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 4551.231454] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4561.587749] Lustre: Unmounted lustre-client [ 4581.934950] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4582.498860] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 4595.773836] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9a6698116800.ost_server_uuid 50 [ 4597.066843] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9a6698116800.ost_server_uuid in FULL state after 0 sec [ 4599.661417] Lustre: Unmounted lustre-client [ 4632.583411] Key type lgssc unregistered [ 4632.824409] LNet: 107708:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4632.833030] LNetError: 107708:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4632.856414] LNet: Removed LNI 192.168.206.28@tcp [ 4633.591479] Key type .llcrypt unregistered [ 4633.594589] Key type ._llcrypt unregistered [ 4648.564538] Key type ._llcrypt registered [ 4648.567742] Key type .llcrypt registered [ 4649.851561] Lustre: DEBUG MARKER: == conf-sanity test 23a: interrupt client during recovery mount delay ========================================================== 04:50:33 (1788598233) [ 4651.809878] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4651.831769] alg: No test for adler32 (adler32-zlib) [ 4652.838895] Lustre: Lustre: Build Version: 2.17.58_2_gae7f7f4 [ 4653.052378] LNet: Added LNI 192.168.206.28@tcp [8/256/0/180] [ 4654.736044] Key type lgssc registered [ 4655.733660] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4703.334064] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4708.541910] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4727.310936] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4728.846987] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 4733.933863] Lustre: lustre-MDT0000-mdc-ffff9a6684c8e800: Connection to lustre-MDT0000 (at 192.168.206.128@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4734.549234] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4744.835079] LustreError: MGC192.168.206.128@tcp: Connection to MGS (at 192.168.206.128@tcp) was lost; in progress operations using this service will fail [ 4744.849291] Lustre: Unmounted lustre-client [ 4757.031649] LustreError: lustre-MDT0000-mdc-ffff9a6698117000: operation mds_connect to node 192.168.206.128@tcp failed: rc = -16 [ 4762.146462] LustreError: lustre-MDT0000-mdc-ffff9a6698117000: operation mds_connect to node 192.168.206.128@tcp failed: rc = -16 [ 4762.234195] LustreError: 111090:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff9a6698117000: can't stat MDS #0: rc = -16 [ 4762.326230] Lustre: Unmounted lustre-client [ 4762.336553] LustreError: 111090:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -16 [ 4784.213671] Key type lgssc unregistered [ 4784.511210] LNet: 111541:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4784.518255] LNetError: 111541:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4784.547755] LNet: Removed LNI 192.168.206.28@tcp [ 4785.254283] Key type .llcrypt unregistered [ 4785.263223] Key type ._llcrypt unregistered [ 4800.887267] Key type ._llcrypt registered [ 4800.892378] Key type .llcrypt registered [ 4802.123365] Lustre: DEBUG MARKER: == conf-sanity test 23b: Simulate -EINTR during mount ==== 04:53:05 (1788598385) [ 4804.280867] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4804.301555] alg: No test for adler32 (adler32-zlib) [ 4805.345899] Lustre: Lustre: Build Version: 2.17.58_2_gae7f7f4 [ 4805.559632] LNet: Added LNI 192.168.206.28@tcp [8/256/0/180] [ 4807.209747] Key type lgssc registered [ 4808.129360] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4855.831082] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4861.613362] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4879.042772] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4881.696056] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 4883.074882] Lustre: Unmounted lustre-client [ 4907.995550] Key type lgssc unregistered [ 4908.220888] LNet: 115065:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4908.235852] LNetError: 115065:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4908.257239] LNet: Removed LNI 192.168.206.28@tcp [ 4908.884218] Key type .llcrypt unregistered [ 4908.890491] Key type ._llcrypt unregistered [ 4925.745866] Key type ._llcrypt registered [ 4925.752175] Key type .llcrypt registered [ 4927.746434] Lustre: DEBUG MARKER: == conf-sanity test 24a: Multiple MDTs on a single node == 04:55:10 (1788598510) [ 4930.251585] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24a mixed loopback and real device not working [ 4932.586884] Lustre: DEBUG MARKER: == conf-sanity test 24b: Multiple MGSs on a single node (should return err) ========================================================== 04:55:15 (1788598515) [ 4935.304532] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24b mixed loopback and real device not working [ 4937.061568] Lustre: DEBUG MARKER: == conf-sanity test 25: Verify modules are referenced ==== 04:55:20 (1788598520) [ 4939.367597] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4939.407046] alg: No test for adler32 (adler32-zlib) [ 4940.554459] Lustre: Lustre: Build Version: 2.17.58_2_gae7f7f4 [ 4940.694280] LNet: Added LNI 192.168.206.28@tcp [8/256/0/180] [ 4942.327164] Key type lgssc registered [ 4943.183185] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4996.055191] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5001.688612] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5022.825591] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5024.463825] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 5032.789640] Lustre: Unmounted lustre-client [ 5060.269865] Key type lgssc unregistered [ 5060.604670] LNet: 119258:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5060.637360] LNetError: 119258:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5060.648343] LNet: Removed LNI 192.168.206.28@tcp [ 5061.469093] Key type .llcrypt unregistered [ 5061.470119] Key type ._llcrypt unregistered [ 5077.441626] Key type ._llcrypt registered [ 5077.444254] Key type .llcrypt registered [ 5078.957407] Lustre: DEBUG MARKER: == conf-sanity test 26: MDT startup failure cleans LOV (should return errs) ========================================================== 04:57:41 (1788598661) [ 5079.581589] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5079.606916] alg: No test for adler32 (adler32-zlib) [ 5080.678812] Lustre: Lustre: Build Version: 2.17.58_2_gae7f7f4 [ 5080.935348] LNet: Added LNI 192.168.206.28@tcp [8/256/0/180] [ 5082.623238] Key type lgssc registered [ 5083.836685] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5137.012775] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5142.396903] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5167.497510] Key type lgssc unregistered [ 5167.768965] LNet: 122337:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5167.781242] LNetError: 122337:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5167.804881] LNet: Removed LNI 192.168.206.28@tcp [ 5168.436846] Key type .llcrypt unregistered [ 5168.438329] Key type ._llcrypt unregistered [ 5183.377861] Key type ._llcrypt registered [ 5183.381564] Key type .llcrypt registered [ 5184.805093] Lustre: DEBUG MARKER: == conf-sanity test 27a: Reacquire MGS lock if OST started first ========================================================== 04:59:27 (1788598767) [ 5192.831324] Key type .llcrypt unregistered [ 5192.834839] Key type ._llcrypt unregistered [ 5200.822880] Key type ._llcrypt registered [ 5200.824845] Key type .llcrypt registered [ 5201.088915] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5201.114469] alg: No test for adler32 (adler32-zlib) [ 5202.264116] Lustre: Lustre: Build Version: 2.17.58_2_gae7f7f4 [ 5202.587957] LNet: Added LNI 192.168.206.28@tcp [8/256/0/180] [ 5204.256389] Key type lgssc registered [ 5205.422684] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5286.047564] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5319.276457] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5324.155486] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5361.859876] Key type lgssc unregistered [ 5362.045564] LNet: 126208:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5362.049834] LNetError: 126208:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5362.062283] LNet: Removed LNI 192.168.206.28@tcp [ 5362.523737] Key type .llcrypt unregistered [ 5362.525364] Key type ._llcrypt unregistered [ 5375.321379] Key type ._llcrypt registered [ 5375.323661] Key type .llcrypt registered [ 5376.684946] Lustre: DEBUG MARKER: == conf-sanity test 27b: Reacquire MGS lock after failover ========================================================== 05:02:39 (1788598959) [ 5379.176223] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5379.193354] alg: No test for adler32 (adler32-zlib) [ 5380.192199] Lustre: Lustre: Build Version: 2.17.58_2_gae7f7f4 [ 5380.353709] LNet: Added LNI 192.168.206.28@tcp [8/256/0/180] [ 5381.999657] Key type lgssc registered [ 5383.294837] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5425.103867] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5431.929127] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5454.105619] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5455.567829] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 5460.969539] Lustre: lustre-MDT0000-mdc-ffff9a6684c8f000: Connection to lustre-MDT0000 (at 192.168.206.128@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5476.324962] LustreError: MGC192.168.206.128@tcp: Connection to MGS (at 192.168.206.128@tcp) was lost; in progress operations using this service will fail [ 5476.353185] Lustre: Evicted from MGS (at 192.168.206.128@tcp) after server handle changed from 0xd8f4bee8e81dd318 to 0xd8f4bee8e81dd46f [ 5476.374533] Lustre: MGC192.168.206.128@tcp: Connection restored to 192.168.206.128@tcp (at 192.168.206.128@tcp) [ 5480.930078] Lustre: lustre-MDT0000-mdc-ffff9a6684c8f000: Connection restored to 192.168.206.128@tcp (at 192.168.206.128@tcp) [ 5493.786340] Lustre: Unmounted lustre-client [ 5523.053728] Key type lgssc unregistered [ 5523.267880] LNet: 130167:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5523.272971] LNetError: 130167:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5523.291627] LNet: Removed LNI 192.168.206.28@tcp [ 5523.830760] Key type .llcrypt unregistered [ 5523.835974] Key type ._llcrypt unregistered [ 5538.040985] Key type ._llcrypt registered [ 5538.042975] Key type .llcrypt registered [ 5539.251970] Lustre: DEBUG MARKER: == conf-sanity test 28A: permanent parameter setting ===== 05:05:22 (1788599122) [ 5541.698583] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5541.711728] alg: No test for adler32 (adler32-zlib) [ 5542.840927] Lustre: Lustre: Build Version: 2.17.58_2_gae7f7f4 [ 5543.132146] LNet: Added LNI 192.168.206.28@tcp [8/256/0/180] [ 5544.831306] Key type lgssc registered [ 5546.076120] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5593.645112] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5599.253900] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5617.388361] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5619.046163] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 5635.676419] Lustre: Unmounted lustre-client [ 5636.132548] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 5646.292841] Lustre: Unmounted lustre-client [ 5671.794474] Key type lgssc unregistered [ 5672.048244] LNet: 134386:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5672.058212] LNetError: 134386:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5672.080059] LNet: Removed LNI 192.168.206.28@tcp [ 5672.753890] Key type .llcrypt unregistered [ 5672.757363] Key type ._llcrypt unregistered [ 5682.146873] Key type ._llcrypt registered [ 5682.152094] Key type .llcrypt registered [ 5683.376448] Lustre: DEBUG MARKER: == conf-sanity test 28a: set symlink parameters permanently with lctl ========================================================== 05:07:46 (1788599266) [ 5685.381671] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5685.413539] alg: No test for adler32 (adler32-zlib) [ 5686.475331] Lustre: Lustre: Build Version: 2.17.58_2_gae7f7f4 [ 5686.704394] LNet: Added LNI 192.168.206.28@tcp [8/256/0/180] [ 5688.423173] Key type lgssc registered [ 5689.743576] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5736.458835] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5741.856708] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5759.675907] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5761.240867] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 5794.318922] Lustre: Unmounted lustre-client [ 5828.234316] Key type lgssc unregistered [ 5828.512217] LNet: 137758:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5828.522285] LNetError: 137758:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5828.539387] LNet: Removed LNI 192.168.206.28@tcp [ 5829.258820] Key type .llcrypt unregistered [ 5829.263463] Key type ._llcrypt unregistered [ 5844.445727] Key type ._llcrypt registered [ 5844.449431] Key type .llcrypt registered [ 5845.782872] Lustre: DEBUG MARKER: == conf-sanity test 28b: verify client-side parameters are set upon mount ========================================================== 05:10:28 (1788599428) [ 5848.135859] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5848.184524] alg: No test for adler32 (adler32-zlib) [ 5849.243938] Lustre: Lustre: Build Version: 2.17.58_2_gae7f7f4 [ 5849.520206] LNet: Added LNI 192.168.206.28@tcp [8/256/0/180] [ 5851.183239] Key type lgssc registered [ 5852.274206] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5900.314706] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5907.246911] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5926.630276] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5929.424101] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 5931.357978] Lustre: Unmounted lustre-client [ 5931.809615] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 5937.729428] Lustre: Unmounted lustre-client [ 5967.619914] Key type lgssc unregistered [ 5967.912739] LNet: 141799:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5967.925864] LNetError: 141799:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5967.951074] LNet: Removed LNI 192.168.206.28@tcp [ 5968.884693] Key type .llcrypt unregistered [ 5968.890712] Key type ._llcrypt unregistered [ 5979.028646] Key type ._llcrypt registered [ 5979.030566] Key type .llcrypt registered [ 5980.606932] Lustre: DEBUG MARKER: == conf-sanity test 28c: verify filesystem parameters are set upon mount ========================================================== 05:12:43 (1788599563) [ 5983.056726] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5983.088796] alg: No test for adler32 (adler32-zlib) [ 5984.275448] Lustre: Lustre: Build Version: 2.17.58_2_gae7f7f4 [ 5984.502320] LNet: Added LNI 192.168.206.28@tcp [8/256/0/180] [ 5986.176208] Key type lgssc registered [ 5987.518367] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6036.869425] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6041.993943] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6060.795673] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6063.436971] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 6065.142248] Lustre: Unmounted lustre-client [ 6065.569545] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 6071.021410] Lustre: Unmounted lustre-client [ 6104.413782] Key type lgssc unregistered [ 6104.659823] LNet: 145422:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6104.665030] LNetError: 145422:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6104.697706] LNet: Removed LNI 192.168.206.28@tcp [ 6105.407446] Key type .llcrypt unregistered [ 6105.410573] Key type ._llcrypt unregistered [ 6113.652907] Key type ._llcrypt registered [ 6113.654850] Key type .llcrypt registered [ 6115.052528] Lustre: DEBUG MARKER: == conf-sanity test 29: permanently remove an OST ======== 05:14:58 (1788599698) [ 6116.684702] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6116.725852] alg: No test for adler32 (adler32-zlib) [ 6117.778185] Lustre: Lustre: Build Version: 2.17.58_2_gae7f7f4 [ 6118.076652] LNet: Added LNI 192.168.206.28@tcp [8/256/0/180] [ 6119.743398] Key type lgssc registered [ 6120.806357] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6164.997148] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6170.213772] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6188.983876] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6190.600497] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 6209.603537] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6211.032708] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6230.526275] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6235.067506] Lustre: Unmounted lustre-client [ 6235.335297] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6235.402387] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 6246.760211] Lustre: Unmounted lustre-client [ 6282.278587] Key type lgssc unregistered [ 6282.636575] LNet: 149473:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6282.646981] LNetError: 149473:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6282.676981] LNet: Removed LNI 192.168.206.28@tcp [ 6283.774568] Key type .llcrypt unregistered [ 6283.781445] Key type ._llcrypt unregistered [ 6314.872938] Key type ._llcrypt registered [ 6314.874891] Key type .llcrypt registered [ 6316.125810] Lustre: DEBUG MARKER: == conf-sanity test 30a: Big config llog and permanent parameter deletion ========================================================== 05:18:19 (1788599899) [ 6318.577683] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6318.619098] alg: No test for adler32 (adler32-zlib) [ 6319.730247] Lustre: Lustre: Build Version: 2.17.58_2_gae7f7f4 [ 6319.957813] LNet: Added LNI 192.168.206.28@tcp [8/256/0/180] [ 6321.647203] Key type lgssc registered [ 6322.817079] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6369.127287] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6374.918471] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6396.044298] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6397.874922] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 6566.446474] Lustre: Unmounted lustre-client [ 6566.866450] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 6570.297974] Lustre: Unmounted lustre-client [ 6570.677921] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 6572.750689] Lustre: Unmounted lustre-client [ 6607.750925] Key type lgssc unregistered [ 6608.000701] LNet: 154784:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6608.012059] LNetError: 154784:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6608.029430] LNet: Removed LNI 192.168.206.28@tcp [ 6608.745685] Key type .llcrypt unregistered [ 6608.749842] Key type ._llcrypt unregistered [ 6623.460542] Key type ._llcrypt registered [ 6623.462953] Key type .llcrypt registered [ 6624.733849] Lustre: DEBUG MARKER: == conf-sanity test 30b: Remove failover nids ============ 05:23:27 (1788600207) [ 6626.705342] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6626.782229] alg: No test for adler32 (adler32-zlib) [ 6627.870182] Lustre: Lustre: Build Version: 2.17.58_2_gae7f7f4 [ 6628.087595] LNet: Added LNI 192.168.206.28@tcp [8/256/0/180] [ 6629.767194] Key type lgssc registered [ 6630.834692] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6679.287599] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6685.776953] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6706.911284] Lustre: DEBUG MARKER: oleg628-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6709.509277] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 6719.431975] Lustre: Unmounted lustre-client [ 6719.768139] Lustre: Mounted lustre-client - version 2.17.58_2_gae7f7f4 [ 6722.107513] Lustre: Unmounted lustre-client [ 6742.276152] Key type lgssc unregistered [ 6742.463054] LNet: 158508:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6742.470986] LNetError: 158508:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6742.492509] LNet: Removed LNI 192.168.206.28@tcp [ 6742.988425] Key type .llcrypt unregistered [ 6742.991603] Key type ._llcrypt unregistered [ 6756.000729] Key type ._llcrypt registered [ 6756.003981] Key type .llcrypt registered [ 6757.533348] Lustre: DEBUG MARKER: == conf-sanity test 31: Connect to non-existent node (shouldn't crash) ========================================================== 05:25:40 (1788600340) [ 6758.627155] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6758.643342] alg: No test for adler32 (adler32-zlib) [ 6759.753038] Lustre: Lustre: Build Version: 2.17.58_2_gae7f7f4 [ 6760.022540] LNet: Added LNI 192.168.206.28@tcp [8/256/0/180] [ 6761.703146] Key type lgssc registered [ 6762.786276] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6786.403176] LustreError: 160198:0:(mgc_request.c:233:do_config_log_add()) MGC4.3.2.1@tcp: failed processing log, type 1: rc = -110 [ 6793.184210] LustreError: 160207:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 6838.879860] LNet: 1 local NIs in recovery (showing 1): 192.168.206.28@tcp [ 6911.583859] LNet: 1 local NIs in recovery (showing 1): 192.168.206.28@tcp [ 6942.688318] 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 [ 6942.713454] Lustre: Unmounted lustre-client [ 6942.721150] LustreError: 160198:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 6952.243875] Key type lgssc unregistered [ 6966.945486] LNet: 160612:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6966.959321] LNetError: 160612:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7042.790382] LNet: Removed LNI 192.168.206.28@tcp [ 7043.497963] Key type .llcrypt unregistered [ 7043.500794] Key type ._llcrypt unregistered [ 7076.791319] Key type ._llcrypt registered [ 7076.796746] Key type .llcrypt registered [ 7077.217313] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7077.232319] alg: No test for adler32 (adler32-zlib) [ 7078.371343] Lustre: Lustre: Build Version: 2.17.58_2_gae7f7f4 [ 7078.701919] LNet: Added LNI 192.168.206.28@tcp [8/256/0/180] [ 7080.455200] Key type lgssc registered [ 7081.633826] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7120.023588] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 6823 sec ========== 05:31:43 (1788600703) [ 7121.392307] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 05:31:44 (1788600704) === [ 7124.484748] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 05:31:47 (1788600707) === [ 7154.022953] Key type lgssc unregistered [ 7154.250891] LNet: 164850:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7154.258981] LNetError: 164850:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7154.286512] LNet: Removed LNI 192.168.206.28@tcp [ 7154.961731] Key type .llcrypt unregistered [ 7154.964651] Key type ._llcrypt unregistered