[ 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 463519405 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.003108] x2apic enabled [ 0.004008] Switched APIC routing to physical x2apic. [ 0.005015] kvm-guest: setup PV IPIs [ 0.008000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008022] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010002] pid_max: default: 32768 minimum: 301 [ 0.012124] LSM: Security Framework initializing [ 0.013060] Yama: becoming mindful. [ 0.014056] SELinux: Initializing. [ 0.015103] *** VALIDATE selinux *** [ 0.024293] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.028647] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.029146] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030129] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031111] *** VALIDATE tmpfs *** [ 0.032437] *** VALIDATE proc *** [ 0.034209] *** VALIDATE cgroup *** [ 0.035012] *** VALIDATE cgroup2 *** [ 0.036234] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.037134] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.038010] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.039030] Spectre V2 : User space: Vulnerable [ 0.040009] Speculative Store Bypass: Vulnerable [ 0.042817] debug: unmapping init [mem 0xffffffff99859000-0xffffffff99860fff] [ 0.044188] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.045717] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.046027] ... version: 2 [ 0.047011] ... bit width: 48 [ 0.048013] ... generic registers: 4 [ 0.049014] ... value mask: 0000ffffffffffff [ 0.050014] ... max period: 00007fffffffffff [ 0.051014] ... fixed-purpose events: 3 [ 0.052010] ... event mask: 000000070000000f [ 0.053277] rcu: Hierarchical SRCU implementation. [ 0.055399] smp: Bringing up secondary CPUs ... [ 0.056566] x86: Booting SMP configuration: [ 0.057023] .... node #0, CPUs: #1 #2 #3 [ 0.060245] smp: Brought up 1 node, 4 CPUs [ 0.062013] smpboot: Max logical packages: 1 [ 0.063019] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.150352] node 0 deferred pages initialised in 85ms [ 0.154382] devtmpfs: initialized [ 0.156393] x86/mm: Memory block size: 128MB [ 0.159803] gcov: version magic: 0x41383552 [ 0.163290] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.164076] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.165263] pinctrl core: initialized pinctrl subsystem [ 0.166206] [ 0.167011] ************************************************************* [ 0.169016] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.172014] ** ** [ 0.174011] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.177014] ** ** [ 0.179011] ** This means that this kernel is built to expose internal ** [ 0.181012] ** IOMMU data structures, which may compromise security on ** [ 0.184013] ** your system. ** [ 0.186010] ** ** [ 0.188012] ** If you see this message and you are not debugging the ** [ 0.191013] ** kernel, report this immediately to your vendor! ** [ 0.194014] ** ** [ 0.196021] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.198016] ************************************************************* [ 0.202185] NET: Registered protocol family 16 [ 0.204547] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.207093] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.209095] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.213032] cpuidle: using governor menu [ 0.214479] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.219031] PCI: Using configuration type 1 for base access [ 0.220168] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.229106] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.232031] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.237083] cryptd: max_cpu_qlen set to 1000 [ 0.241326] ACPI: Added _OSI(Module Device) [ 0.243019] ACPI: Added _OSI(Processor Device) [ 0.244023] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.246019] ACPI: Added _OSI(Processor Aggregator Device) [ 0.251285] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.259512] ACPI: Interpreter enabled [ 0.261087] ACPI: PM: (supports S0 S3 S4 S5) [ 0.263020] ACPI: Using IOAPIC for interrupt routing [ 0.265308] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.269523] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.280507] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.284062] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.287031] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.289130] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.294719] acpiphp: Slot [2] registered [ 0.296151] acpiphp: Slot [5] registered [ 0.297221] acpiphp: Slot [6] registered [ 0.298197] acpiphp: Slot [3] registered [ 0.300117] acpiphp: Slot [4] registered [ 0.301120] acpiphp: Slot [7] registered [ 0.302150] acpiphp: Slot [8] registered [ 0.304124] acpiphp: Slot [9] registered [ 0.305104] acpiphp: Slot [10] registered [ 0.306125] acpiphp: Slot [11] registered [ 0.308145] acpiphp: Slot [12] registered [ 0.309123] acpiphp: Slot [13] registered [ 0.310168] acpiphp: Slot [14] registered [ 0.312104] acpiphp: Slot [15] registered [ 0.313105] acpiphp: Slot [16] registered [ 0.314126] acpiphp: Slot [17] registered [ 0.316147] acpiphp: Slot [18] registered [ 0.317119] acpiphp: Slot [19] registered [ 0.319171] acpiphp: Slot [20] registered [ 0.321155] acpiphp: Slot [21] registered [ 0.322135] acpiphp: Slot [22] registered [ 0.324153] acpiphp: Slot [23] registered [ 0.325177] acpiphp: Slot [24] registered [ 0.327136] acpiphp: Slot [25] registered [ 0.329143] acpiphp: Slot [26] registered [ 0.330133] acpiphp: Slot [27] registered [ 0.332135] acpiphp: Slot [28] registered [ 0.334149] acpiphp: Slot [29] registered [ 0.335152] acpiphp: Slot [30] registered [ 0.337138] acpiphp: Slot [31] registered [ 0.338087] PCI host bridge to bus 0000:00 [ 0.340028] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.342033] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.345033] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.348045] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.350045] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.353042] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.355189] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.358047] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.361446] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.368535] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.371913] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.375026] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.377025] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.379023] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.381444] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.385875] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.388055] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.391887] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.396928] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.405929] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.411021] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.416705] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.425018] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.433019] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.449027] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.459000] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.466021] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.473017] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.484019] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.494116] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.496469] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.499409] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.501430] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.503281] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.509067] iommu: Default domain type: Passthrough [ 0.511582] SCSI subsystem initialized [ 0.513211] ACPI: bus type USB registered [ 0.515249] usbcore: registered new interface driver usbfs [ 0.517118] usbcore: registered new interface driver hub [ 0.519130] usbcore: registered new device driver usb [ 0.522239] pps_core: LinuxPPS API ver. 1 registered [ 0.524015] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.527101] PTP clock support registered [ 0.530126] EDAC MC: Ver: 3.0.0 [ 0.532156] PCI: Using ACPI for IRQ routing [ 0.534034] NetLabel: Initializing [ 0.536018] NetLabel: domain hash size = 128 [ 0.537015] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.540110] NetLabel: unlabeled traffic allowed by default [ 0.542380] vgaarb: loaded [ 0.544402] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.547029] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.553606] clocksource: Switched to clocksource kvm-clock [ 0.656755] VFS: Disk quotas dquot_6.6.0 [ 0.658317] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.661552] *** VALIDATE ramfs *** [ 0.662955] *** VALIDATE hugetlbfs *** [ 0.664830] pnp: PnP ACPI init [ 0.668528] pnp: PnP ACPI: found 6 devices [ 0.687358] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.690144] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.692321] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.694582] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.696978] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.698814] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.701512] NET: Registered protocol family 2 [ 0.704163] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.709424] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.713373] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.718580] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.721876] TCP: Hash tables configured (established 65536 bind 65536) [ 0.724212] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.727302] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.730484] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.733292] NET: Registered protocol family 1 [ 0.737282] RPC: Registered named UNIX socket transport module. [ 0.739437] RPC: Registered udp transport module. [ 0.744073] RPC: Registered tcp transport module. [ 0.746838] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.749285] NET: Registered protocol family 44 [ 0.750943] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.752478] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.753724] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.755277] PCI: CLS 0 bytes, default 64 [ 0.756412] Unpacking initramfs... [ 2.172513] debug: unmapping init [mem 0xffff9e7c7cc64000-0xffff9e7c7ffcffff] [ 2.178040] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.180455] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.183427] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.733478] Initialise system trusted keyrings [ 2.735599] Key type blacklist registered [ 2.739605] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.749437] zbud: loaded [ 2.752805] *** VALIDATE nfs *** [ 2.754617] *** VALIDATE nfs4 *** [ 2.756794] pstore: using deflate compression [ 2.763306] Platform Keyring initialized [ 2.894283] NET: Registered protocol family 38 [ 2.896750] Key type asymmetric registered [ 2.901127] Asymmetric key parser 'x509' registered [ 2.903473] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.907320] io scheduler mq-deadline registered [ 2.909352] io scheduler kyber registered [ 2.911402] io scheduler bfq registered [ 2.913810] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.917667] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.921299] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.924951] ACPI: Power Button [PWRF] [ 2.932355] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.940752] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.957045] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.985258] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.015211] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.020900] Non-volatile memory driver v1.3 [ 3.023216] Linux agpgart interface v0.103 [ 3.055506] virtio_blk virtio1: [vda] 146624 512-byte logical blocks (75.1 MB/71.6 MiB) [ 3.060416] vda: detected capacity change from 0 to 75071488 [ 3.077438] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.081099] vdb: detected capacity change from 0 to 1073741824 [ 3.089075] libphy: Fixed MDIO Bus: probed [ 3.097637] usbcore: registered new interface driver usbserial_generic [ 3.101270] usbserial: USB Serial support registered for generic [ 3.104283] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.108848] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.111027] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.113906] mousedev: PS/2 mouse device common for all mice [ 3.116516] rtc_cmos 00:05: RTC can wake from S4 [ 3.119455] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.123128] rtc_cmos 00:05: registered as rtc0 [ 3.125505] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.126373] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.132286] intel_pstate: CPU model not supported [ 3.132477] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.143395] hid: raw HID events driver (C) Jiri Kosina [ 3.146791] usbcore: registered new interface driver usbhid [ 3.148551] usbhid: USB HID core driver [ 3.149908] drop_monitor: Initializing network drop monitor service [ 3.151901] Initializing XFRM netlink socket [ 3.153561] NET: Registered protocol family 10 [ 3.156414] Segment Routing with IPv6 [ 3.157849] NET: Registered protocol family 17 [ 3.160480] mpls_gso: MPLS GSO support [ 3.166464] RAS: Correctable Errors collector initialized. [ 3.169420] AVX version of gcm_enc/dec engaged. [ 3.171346] AES CTR mode by8 optimization enabled [ 3.276234] sched_clock: Marking stable (3276024354, 0)->(4178873951, -902849597) [ 3.280585] registered taskstats version 1 [ 3.282960] Loading compiled-in X.509 certificates [ 3.285275] zswap: loaded using pool lzo/zbud [ 3.315569] Key type big_key registered [ 3.328912] Key type encrypted registered [ 3.330466] ima: No TPM chip found, activating TPM-bypass! [ 3.332633] ima: Allocated hash algorithm: sha1 [ 3.334223] ima: No architecture policies found [ 3.335942] evm: Initialising EVM extended attributes: [ 3.337854] evm: security.selinux [ 3.338984] evm: security.ima [ 3.340319] evm: security.capability [ 3.341802] evm: HMAC attrs: 0x1 [ 3.344157] rtc_cmos 00:05: setting system clock to 2026-09-04 01:21:03 UTC (1788484863) [ 3.351276] debug: unmapping init [mem 0xffffffff9a803000-0xffffffff9a9fffff] [ 3.354388] debug: unmapping init [mem 0xffffffff99582000-0xffffffff99858fff] [ 3.363099] Write protecting the kernel read-only data: 28672k [ 3.367117] debug: unmapping init [mem 0xffffffff97c03000-0xffffffff97dfffff] [ 3.369862] debug: unmapping init [mem 0xffffffff98514000-0xffffffff985fffff] [ 3.406791] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 3.415525] systemd[1]: Detected virtualization kvm. [ 3.417735] systemd[1]: Detected architecture x86-64. [ 3.419830] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.446187] systemd[1]: No hostname configured. [ 3.448235] systemd[1]: Set hostname to . [ 3.450285] random: systemd: uninitialized urandom read (16 bytes read) [ 3.452123] systemd[1]: Initializing machine ID from random generator. [ 3.612266] random: systemd: uninitialized urandom read (16 bytes read) [ 3.616343] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 3.622485] random: systemd: uninitialized urandom read (16 bytes read) [ 3.631057] systemd[1]: Starting Create list of required static device nodes for the current kernel... Starting Create list of required st…ce nodes for the current kernel... [ 3.642973] systemd[1]: Started Memstrack Anylazing Service. [ OK ] Started Memstrack Anylazing Service. [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Timers. [ OK ] Reached target Swap. [ OK ] Reached target Slices. Starting Setup Virtual Console... [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. Starting Apply Kernel Variables... [ OK ] Reached target Paths. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.313315] device-mapper: uevent: version 1.0.3 [ 4.315906] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] [ 5.050728] random: fast init done Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 5.153437] virtio_net virtio0 ens2: renamed from eth0 [ 5.174297] scsi host0: ata_piix [ 5.230307] scsi host1: ata_piix [ 5.231934] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.233866] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.976062] dracut-initqueue[578]: RTNETLINK answers: File exists [ 9.848258] random: crng init done [ 9.849824] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 10.362580] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target System Initialization. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped Apply Kernel Variables. Stopping udev Kernel Device Manager... [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped target Swap. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Slices. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.572063] printk: systemd: 25 output lines suppressed due to ratelimiting [ 11.824511] SELinux: Disabled at runtime. [ 11.885874] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 11.894446] systemd[1]: Detected virtualization kvm. [ 11.896184] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.374056] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.377615] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.383337] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.386763] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.389646] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.405963] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.413286] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. Activating swap /dev/disk/by-label/SWAP... [ OK ] Started Forward Password Requests to Wall Directory Watch. Mounting Kernel Debug File System... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ 12.456810] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Starting Create list of required st…ce nodes for the current kernel... Mounting Huge Pages File System... [ OK ] Created slice system-getty.slice. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Created slice system-sshd\x2dkeygen.slice. Starting Remount Root and Kernel File Systems... Starting Apply Kernel Variables... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Stopped target Initrd File Systems. [ OK ] Listening on Process Core Dump Socket. [ OK ] Reached target rpc_pipefs.target. [ OK ] Listening on initctl Compatibility Named Pipe. Mounting POSIX Message Queue File System... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Started Journal Service. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ 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 ] Mounted POSIX Message Queue 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 Create Static Device Nodes in /dev. [ OK ] Started Flush Journal to Persistent Storage. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... [ OK ] Mounted /mnt. [ 12.862671] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Coldplug all Devices. [ OK ] Started udev Kernel Device Manager. [ 13.197712] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.335563] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.361265] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.391890] EDAC sbridge: Ver: 1.1.2 [ 14.564597] Key type dns_resolver registered [ 14.880644] NFS: Registering the id_resolver key type [ 14.883614] Key type id_resolver registered [ 14.885789] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... Starting Rebuild Dynamic Linker Cache... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ 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 D-Bus System Message Bus. Starting Network Manager... Starting Restore /run/initramfs on shutdown... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Login Service... [ OK ] Started irqbalance daemon. [ OK ] Reached target sshd-keygen.target. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... 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 Getty on tty1. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg337-client login: [ 43.465855] libcfs: loading out-of-tree module taints kernel. [ 43.505364] Key type ._llcrypt registered [ 43.508462] Key type .llcrypt registered [ 43.844619] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 43.853875] alg: No test for adler32 (adler32-zlib) [ 44.907194] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 45.613187] LNet: Added LNI 192.168.203.37@tcp [8/256/0/180] [ 47.527601] Key type lgssc registered [ 50.053520] Lustre: Echo OBD driver; http://www.lustre.org/ [ 101.599110] hrtimer: interrupt took 7101587 ns [ 224.744359] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 229.773590] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 247.408614] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing check_logdir /tmp/testlogs/ [ 250.335391] Lustre: lustre-OST0000-osc-ffff9e7cc71a9800: disconnect after 23s idle [ 253.764796] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing yml_node [ 259.947770] Lustre: DEBUG MARKER: Client: 2.17.57.84 [ 263.130418] Lustre: DEBUG MARKER: MDS: 2.17.57.84 [ 266.450282] Lustre: DEBUG MARKER: OSS: 2.17.57.84 [ 268.694230] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Thu Sep 3 21:25:26 EDT 2026 [ 285.293464] Lustre: DEBUG MARKER: excepting tests: 21e 32b 32c 32newtarball [ 286.881931] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 290.347505] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 300.721454] Lustre: Unmounted lustre-client [ 415.272719] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 420.495585] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 442.579170] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 495.341593] Lustre: DEBUG MARKER: == conf-sanity test 0: single mount setup ================ 21:29:14 (1788485354) [ 525.749718] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 531.608624] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 549.216925] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 549.663105] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 553.523321] Lustre: Unmounted lustre-client [ 580.055755] Key type lgssc unregistered [ 580.383794] LNet: 14689:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 580.388591] LNetError: 14689:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 580.407520] LNet: Removed LNI 192.168.203.37@tcp [ 581.155930] Key type .llcrypt unregistered [ 581.160407] Key type ._llcrypt unregistered [ 594.152526] Key type ._llcrypt registered [ 594.155694] Key type .llcrypt registered [ 595.312168] Lustre: DEBUG MARKER: == conf-sanity test 1: start up ost twice (should return errors) ========================================================== 21:30:54 (1788485454) [ 597.271096] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 597.345573] alg: No test for adler32 (adler32-zlib) [ 598.424193] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 598.705951] LNet: Added LNI 192.168.203.37@tcp [8/256/0/180] [ 600.418838] Key type lgssc registered [ 601.575738] Lustre: Echo OBD driver; http://www.lustre.org/ [ 647.130967] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 652.125670] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 669.678276] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 679.381727] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 681.938140] Lustre: Unmounted lustre-client [ 703.311363] Key type lgssc unregistered [ 703.540807] LNet: 18356:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 703.547444] LNetError: 18356:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 703.560729] LNet: Removed LNI 192.168.203.37@tcp [ 704.199839] Key type .llcrypt unregistered [ 704.201051] Key type ._llcrypt unregistered [ 717.984664] Key type ._llcrypt registered [ 717.991420] Key type .llcrypt registered [ 719.090611] Lustre: DEBUG MARKER: == conf-sanity test 2: start up mds twice (should return err) ========================================================== 21:32:58 (1788485578) [ 721.092731] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 721.103226] alg: No test for adler32 (adler32-zlib) [ 722.209240] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 722.518932] LNet: Added LNI 192.168.203.37@tcp [8/256/0/180] [ 724.159658] Key type lgssc registered [ 725.151926] Lustre: Echo OBD driver; http://www.lustre.org/ [ 771.000966] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 776.775434] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 803.000893] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 804.760421] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 806.539593] Lustre: Unmounted lustre-client [ 827.893380] Key type lgssc unregistered [ 828.071058] LNet: 22002:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 828.081346] LNetError: 22002:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 828.104574] LNet: Removed LNI 192.168.203.37@tcp [ 828.735920] Key type .llcrypt unregistered [ 828.741719] Key type ._llcrypt unregistered [ 842.353254] Key type ._llcrypt registered [ 842.356660] Key type .llcrypt registered [ 843.534692] Lustre: DEBUG MARKER: == conf-sanity test 3: mount client twice (should return err) ========================================================== 21:35:02 (1788485702) [ 845.763580] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 845.790072] alg: No test for adler32 (adler32-zlib) [ 846.868484] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 847.163552] LNet: Added LNI 192.168.203.37@tcp [8/256/0/180] [ 848.847290] Key type lgssc registered [ 849.748548] Lustre: Echo OBD driver; http://www.lustre.org/ [ 896.690501] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 902.184318] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 922.446419] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 924.022955] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 928.677522] Lustre: Unmounted lustre-client [ 962.988079] Key type lgssc unregistered [ 963.211555] LNet: 25608:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 963.218604] LNetError: 25608:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 963.230820] LNet: Removed LNI 192.168.203.37@tcp [ 963.882239] Key type .llcrypt unregistered [ 963.885827] Key type ._llcrypt unregistered [ 977.807956] Key type ._llcrypt registered [ 977.811197] Key type .llcrypt registered [ 978.963517] Lustre: DEBUG MARKER: == conf-sanity test 4: force cleanup ost, then cleanup === 21:37:17 (1788485837) [ 981.159252] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 981.226783] alg: No test for adler32 (adler32-zlib) [ 982.227936] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 982.423560] LNet: Added LNI 192.168.203.37@tcp [8/256/0/180] [ 984.111936] Key type lgssc registered [ 985.069584] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1034.038923] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1041.435431] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1065.510213] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1068.160205] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 1073.129802] Lustre: lustre-OST0000-osc-ffff9e7cd0418800: Connection to lustre-OST0000 (at 192.168.203.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1083.019448] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1093.372938] Lustre: Unmounted lustre-client [ 1119.248633] Key type lgssc unregistered [ 1119.643217] LNet: 29221:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1119.663939] LNetError: 29221:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1119.685590] LNet: Removed LNI 192.168.203.37@tcp [ 1120.380420] Key type .llcrypt unregistered [ 1120.383106] Key type ._llcrypt unregistered [ 1133.546987] Key type ._llcrypt registered [ 1133.550767] Key type .llcrypt registered [ 1134.820522] Lustre: DEBUG MARKER: == conf-sanity test 5a: force cleanup mds, then cleanup == 21:39:53 (1788485993) [ 1137.048119] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1137.115626] alg: No test for adler32 (adler32-zlib) [ 1138.259837] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 1138.497726] LNet: Added LNI 192.168.203.37@tcp [8/256/0/180] [ 1140.191308] Key type lgssc registered [ 1141.585392] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1190.863114] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1198.189528] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1219.991558] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1222.772150] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 1232.872938] Lustre: lustre-MDT0000-mdc-ffff9e7cc65aa000: Connection to lustre-MDT0000 (at 192.168.203.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1243.121872] Lustre: lustre-MDT0001-mdc-ffff9e7cc65aa000: Connection to lustre-MDT0001 (at 192.168.203.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1248.466260] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1254.367516] Lustre: 30177:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788486098/real 1788486098] req@000000006cae56a6 x1875363493845248/t0(0) o400->MGC192.168.203.137@tcp@192.168.203.137@tcp:26/25 lens 224/224 e 0 to 1 dl 1788486114 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1254.407809] LustreError: MGC192.168.203.137@tcp: Connection to MGS (at 192.168.203.137@tcp) was lost; in progress operations using this service will fail [ 1258.780524] Lustre: Unmounted lustre-client [ 1272.211318] Key type lgssc unregistered [ 1272.491828] LNet: 32867:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1272.499692] LNetError: 32867:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1272.521223] LNet: Removed LNI 192.168.203.37@tcp [ 1273.264755] Key type .llcrypt unregistered [ 1273.266700] Key type ._llcrypt unregistered [ 1291.089539] Key type ._llcrypt registered [ 1291.092574] Key type .llcrypt registered [ 1292.496871] Lustre: DEBUG MARKER: == conf-sanity test 5b: Try to start a client with no MGS (should return errs) ========================================================== 21:42:31 (1788486151) [ 1295.045318] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1295.112257] alg: No test for adler32 (adler32-zlib) [ 1296.250687] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 1296.636221] LNet: Added LNI 192.168.203.37@tcp [8/256/0/180] [ 1298.415248] Key type lgssc registered [ 1299.800578] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1384.498657] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1390.175892] LustreError: 35064:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.203.137@tcp: failed processing log, type 1: rc = -110 [ 1396.706651] LustreError: 35073:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 1548.258964] LustreError: MGC192.168.203.137@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 [ 1548.300034] Lustre: Unmounted lustre-client [ 1548.316058] LustreError: 35064:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 1562.996653] Key type lgssc unregistered [ 1563.348869] LNet: 35490:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1563.356528] LNetError: 35490:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1563.381070] LNet: Removed LNI 192.168.203.37@tcp [ 1564.158644] Key type .llcrypt unregistered [ 1564.160923] Key type ._llcrypt unregistered [ 1579.686914] Key type ._llcrypt registered [ 1579.688922] Key type .llcrypt registered [ 1580.944206] Lustre: DEBUG MARKER: == conf-sanity test 5c: cleanup after failed mount (bug 2712) (should return errs) ========================================================== 21:47:19 (1788486439) [ 1582.963478] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1583.016080] alg: No test for adler32 (adler32-zlib) [ 1584.111729] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 1584.369551] LNet: Added LNI 192.168.203.37@tcp [8/256/0/180] [ 1586.047185] Key type lgssc registered [ 1587.173589] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1636.382526] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1642.147502] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1662.757947] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1663.492477] LustreError: 38578:0:(llite_lib.c:1555:ll_fill_super()) wrong.lustre: fsname longer than 8 characters: rc = -36 [ 1663.502800] Lustre: Unmounted wrong.lustre-client [ 1663.520846] LustreError: 38578:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -36 [ 1692.663891] Key type lgssc unregistered [ 1692.939537] LNet: 38992:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1692.949556] LNetError: 38992:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1692.968088] LNet: Removed LNI 192.168.203.37@tcp [ 1693.813770] Key type .llcrypt unregistered [ 1693.819596] Key type ._llcrypt unregistered [ 1710.940541] Key type ._llcrypt registered [ 1710.943207] Key type .llcrypt registered [ 1712.333268] Lustre: DEBUG MARKER: == conf-sanity test 5d: mount with ost down ============== 21:49:30 (1788486570) [ 1714.127068] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1714.227831] alg: No test for adler32 (adler32-zlib) [ 1715.346929] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 1715.595615] LNet: Added LNI 192.168.203.37@tcp [8/256/0/180] [ 1717.319313] Key type lgssc registered [ 1718.396992] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1801.008576] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1832.881649] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1837.839952] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1849.697587] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 1851.961236] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1862.320983] Lustre: Unmounted lustre-client [ 1888.910143] Key type lgssc unregistered [ 1889.255772] LNet: 42559:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1889.270936] LNetError: 42559:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1889.302373] LNet: Removed LNI 192.168.203.37@tcp [ 1890.180739] Key type .llcrypt unregistered [ 1890.185760] Key type ._llcrypt unregistered [ 1906.716352] Key type ._llcrypt registered [ 1906.718310] Key type .llcrypt registered [ 1908.055897] Lustre: DEBUG MARKER: == conf-sanity test 5e: delayed connect, don't crash (bug 10268) ========================================================== 21:52:46 (1788486766) [ 1910.303820] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1910.320812] alg: No test for adler32 (adler32-zlib) [ 1911.472658] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 1911.743738] LNet: Added LNI 192.168.203.37@tcp [8/256/0/180] [ 1913.393981] Key type lgssc registered [ 1914.825971] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1959.838760] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1964.781968] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1983.922096] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1984.497920] LustreError: 43516:0:(niobuf.c:1106:ptl_send_rpc()) cfs_fail_timeout id 506 sleeping for 10000ms [ 1989.600530] LustreError: 45639:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.203.137@tcp: failed processing log, type 1: rc = -110 [ 1994.543496] LustreError: 43516:0:(niobuf.c:1106:ptl_send_rpc()) cfs_fail_timeout id 506 awake [ 1995.848968] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 1997.770786] Lustre: Unmounted lustre-client [ 2023.400661] Key type lgssc unregistered [ 2023.666774] LNet: 46094:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2023.676953] LNetError: 46094:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2023.693594] LNet: Removed LNI 192.168.203.37@tcp [ 2024.359020] Key type .llcrypt unregistered [ 2024.361727] Key type ._llcrypt unregistered [ 2040.380679] Key type ._llcrypt registered [ 2040.383965] Key type .llcrypt registered [ 2041.733239] Lustre: DEBUG MARKER: == conf-sanity test 5f: mds down, cleanup after failed mount (bug 2712) ========================================================== 21:55:00 (1788486900) [ 2043.305322] Lustre: DEBUG MARKER: SKIP: conf-sanity test_5f needs separate mgs and mds [ 2045.293379] Lustre: DEBUG MARKER: == conf-sanity test 5g: handle missing debugfs =========== 21:55:03 (1788486903) [ 2046.264826] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2046.409089] alg: No test for adler32 (adler32-zlib) [ 2047.545212] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 2047.747868] LNet: Added LNI 192.168.203.37@tcp [8/256/0/180] [ 2049.399534] Key type lgssc registered [ 2050.493918] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2072.684396] Lustre: DEBUG MARKER: == conf-sanity test 5h: start mdt failure at mdt_fs_setup() ========================================================== 21:55:31 (1788486931) [ 2106.390863] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2111.438836] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2129.843861] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2131.538729] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 2136.556738] Lustre: lustre-MDT0000-mdc-ffff9e7cc5500800: Connection to lustre-MDT0000 (at 192.168.203.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2151.931086] LustreError: MGC192.168.203.137@tcp: Connection to MGS (at 192.168.203.137@tcp) was lost; in progress operations using this service will fail [ 2151.960052] Lustre: Evicted from MGS (at 192.168.203.137@tcp) after server handle changed from 0x93d0960b46f77acf to 0x93d0960b46f77d45 [ 2151.965721] Lustre: MGC192.168.203.137@tcp: Connection restored to 192.168.203.137@tcp (at 192.168.203.137@tcp) [ 2156.127930] Lustre: lustre-MDT0000-mdc-ffff9e7cc5500800: Connection restored to 192.168.203.137@tcp (at 192.168.203.137@tcp) [ 2159.667963] Lustre: Unmounted lustre-client [ 2191.800808] Key type lgssc unregistered [ 2192.000669] LNet: 50945:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2192.017900] LNetError: 50945:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2192.039034] LNet: Removed LNI 192.168.203.37@tcp [ 2192.790397] Key type .llcrypt unregistered [ 2192.798334] Key type ._llcrypt unregistered [ 2206.295158] Key type ._llcrypt registered [ 2206.299073] Key type .llcrypt registered [ 2207.205360] Lustre: DEBUG MARKER: == conf-sanity test 5i: start mdt failure at mdt_quota_init() ========================================================== 21:57:46 (1788487066) [ 2209.081510] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2209.203882] alg: No test for adler32 (adler32-zlib) [ 2210.361520] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 2210.601508] LNet: Added LNI 192.168.203.37@tcp [8/256/0/180] [ 2212.271249] Key type lgssc registered [ 2213.507533] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2258.913575] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2264.102501] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2285.773897] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2287.619746] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 2292.716719] Lustre: lustre-MDT0000-mdc-ffff9e7cc8e0e800: Connection to lustre-MDT0000 (at 192.168.203.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2307.873338] Lustre: 51904:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788487152/real 1788487152] req@00000000ede86c02 x1875364617915904/t0(0) o400->MGC192.168.203.137@tcp@192.168.203.137@tcp:26/25 lens 224/224 e 0 to 1 dl 1788487168 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2307.964081] LustreError: MGC192.168.203.137@tcp: Connection to MGS (at 192.168.203.137@tcp) was lost; in progress operations using this service will fail [ 2318.323542] Lustre: Evicted from MGS (at 192.168.203.137@tcp) after server handle changed from 0xb124d035e17bf003 to 0xb124d035e17bf287 [ 2318.344049] Lustre: MGC192.168.203.137@tcp: Connection restored to 192.168.203.137@tcp (at 192.168.203.137@tcp) [ 2319.993614] Lustre: lustre-MDT0000-mdc-ffff9e7cc8e0e800: Connection restored to 192.168.203.137@tcp (at 192.168.203.137@tcp) [ 2331.194198] Lustre: Unmounted lustre-client [ 2359.964486] Key type lgssc unregistered [ 2360.271941] LNet: 54907:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2360.282474] LNetError: 54907:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2361.320157] LNet: Removed LNI 192.168.203.37@tcp [ 2361.917351] Key type .llcrypt unregistered [ 2361.920232] Key type ._llcrypt unregistered [ 2375.802642] Key type ._llcrypt registered [ 2375.805265] Key type .llcrypt registered [ 2376.916696] Lustre: DEBUG MARKER: == conf-sanity test 5j: verify libcfs doesn't crash when setting debug flags ========================================================== 22:00:35 (1788487235) [ 2377.490382] Key type .llcrypt unregistered [ 2377.501177] Key type ._llcrypt unregistered [ 2383.412903] Key type ._llcrypt registered [ 2383.416548] Key type .llcrypt registered [ 2383.454148] Key type .llcrypt unregistered [ 2383.457359] Key type ._llcrypt unregistered [ 2387.871627] Key type ._llcrypt registered [ 2387.876659] Key type .llcrypt registered [ 2389.136309] Lustre: DEBUG MARKER: == conf-sanity test 6: manual umount, then mount again === 22:00:47 (1788487247) [ 2391.565102] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2391.579901] alg: No test for adler32 (adler32-zlib) [ 2392.705819] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 2392.957573] LNet: Added LNI 192.168.203.37@tcp [8/256/0/180] [ 2394.647186] Key type lgssc registered [ 2395.802205] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2439.699711] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2444.852958] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2467.049882] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2468.785280] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 2470.943681] Lustre: Unmounted lustre-client [ 2471.441914] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 2473.649272] Lustre: Unmounted lustre-client [ 2502.151664] Key type lgssc unregistered [ 2502.415943] LNet: 59333:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2502.423151] LNetError: 59333:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2502.444837] LNet: Removed LNI 192.168.203.37@tcp [ 2503.108349] Key type .llcrypt unregistered [ 2503.110123] Key type ._llcrypt unregistered [ 2516.830690] Key type ._llcrypt registered [ 2516.832485] Key type .llcrypt registered [ 2518.113239] Lustre: DEBUG MARKER: == conf-sanity test 7: manual umount, then cleanup ======= 22:02:56 (1788487376) [ 2520.450072] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2520.577660] alg: No test for adler32 (adler32-zlib) [ 2521.616653] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 2521.886458] LNet: Added LNI 192.168.203.37@tcp [8/256/0/180] [ 2523.631752] Key type lgssc registered [ 2524.700608] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2569.663085] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2575.134311] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2596.820787] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2598.577720] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 2600.734455] Lustre: Unmounted lustre-client [ 2628.399296] Key type lgssc unregistered [ 2628.679900] LNet: 62897:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2628.692090] LNetError: 62897:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2628.713263] LNet: Removed LNI 192.168.203.37@tcp [ 2629.494294] Key type .llcrypt unregistered [ 2629.497251] Key type ._llcrypt unregistered [ 2645.578519] Key type ._llcrypt registered [ 2645.580342] Key type .llcrypt registered [ 2647.253682] Lustre: DEBUG MARKER: == conf-sanity test 8: double mount setup ================ 22:05:05 (1788487505) [ 2650.240287] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2650.255696] alg: No test for adler32 (adler32-zlib) [ 2651.311351] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 2651.638476] LNet: Added LNI 192.168.203.37@tcp [8/256/0/180] [ 2653.361478] Key type lgssc registered [ 2654.692056] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2704.829585] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2710.950866] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2732.804057] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2734.446588] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 2736.833859] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 2739.867905] Lustre: Unmounted lustre-client [ 2742.017497] Lustre: Unmounted lustre-client [ 2769.214278] Key type lgssc unregistered [ 2769.497940] LNet: 66555:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2769.518848] LNetError: 66555:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2769.537853] LNet: Removed LNI 192.168.203.37@tcp [ 2770.228466] Key type .llcrypt unregistered [ 2770.230630] Key type ._llcrypt unregistered [ 2786.605898] Key type ._llcrypt registered [ 2786.609722] Key type .llcrypt registered [ 2788.125944] Lustre: DEBUG MARKER: == conf-sanity test 9: test ptldebug and subsystem for mkfs ========================================================== 22:07:26 (1788487646) [ 2790.243810] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2790.383970] alg: No test for adler32 (adler32-zlib) [ 2791.484953] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 2791.767482] LNet: Added LNI 192.168.203.37@tcp [8/256/0/180] [ 2793.464490] Key type lgssc registered [ 2795.151255] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2879.254901] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2892.921790] Lustre: DEBUG MARKER: == conf-sanity test 10a: find lctl param broken symlinks ========================================================== 22:09:11 (1788487751) [ 2923.917058] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2928.841501] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2949.821375] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2952.596091] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 2958.897757] Lustre: Unmounted lustre-client [ 2985.402946] Key type lgssc unregistered [ 2985.788474] LNet: 71320:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2985.802175] LNetError: 71320:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2985.833363] LNet: Removed LNI 192.168.203.37@tcp [ 2986.618710] Key type .llcrypt unregistered [ 2986.622511] Key type ._llcrypt unregistered [ 3000.159778] Key type ._llcrypt registered [ 3000.164213] Key type .llcrypt registered [ 3001.353979] Lustre: DEBUG MARKER: == conf-sanity test 11: Verify tool --version option works properly ========================================================== 22:11:00 (1788487860) [ 3018.022529] Lustre: DEBUG MARKER: == conf-sanity test 17: Verify failed mds_postsetup won't fail assertion (2936) (should return errs) ========================================================== 22:11:16 (1788487876) [ 3020.370212] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3020.442550] alg: No test for adler32 (adler32-zlib) [ 3021.666649] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 3021.958235] LNet: Added LNI 192.168.203.37@tcp [8/256/0/180] [ 3023.639196] Key type lgssc registered [ 3024.933096] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3074.960333] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3081.133716] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3101.703169] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3103.341108] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 3110.064588] Lustre: Unmounted lustre-client [ 3135.036458] Key type lgssc unregistered [ 3135.307068] LNet: 75608:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3135.311386] LNetError: 75608:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3135.333667] LNet: Removed LNI 192.168.203.37@tcp [ 3136.028875] Key type .llcrypt unregistered [ 3136.031766] Key type ._llcrypt unregistered [ 3148.047842] Key type ._llcrypt registered [ 3148.055029] Key type .llcrypt registered [ 3148.632060] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3148.673378] alg: No test for adler32 (adler32-zlib) [ 3149.669640] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 3149.976707] LNet: Added LNI 192.168.203.37@tcp [8/256/0/180] [ 3151.695219] Key type lgssc registered [ 3152.944783] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3236.187953] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3361.402819] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3366.905851] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3390.003900] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3436.440531] Lustre: DEBUG MARKER: == conf-sanity test 18: check mkfs creates large journals ========================================================== 22:18:15 (1788488295) [ 3439.036665] Lustre: DEBUG MARKER: use device /dev/mapper/mds1_flakey with MIN=2000000 [ 3525.558288] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3531.286767] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3551.548275] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3629.838434] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3635.328485] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3655.634491] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3658.392397] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 3663.141847] Lustre: DEBUG MARKER: Success: mkfs creates large journals. Size: 78M [ 3665.346275] Lustre: Unmounted lustre-client [ 3691.732472] Key type lgssc unregistered [ 3691.990794] LNet: 87294:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3692.003727] LNetError: 87294:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3692.029079] LNet: Removed LNI 192.168.203.37@tcp [ 3692.700685] Key type .llcrypt unregistered [ 3692.710478] Key type ._llcrypt unregistered [ 3720.888180] Key type ._llcrypt registered [ 3720.893409] Key type .llcrypt registered [ 3721.067063] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3721.092576] alg: No test for adler32 (adler32-zlib) [ 3722.251864] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 3722.509520] LNet: Added LNI 192.168.203.37@tcp [8/256/0/180] [ 3724.217062] Key type lgssc registered [ 3725.399678] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3808.198132] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3814.629848] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3840.797759] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3889.751578] Lustre: DEBUG MARKER: == conf-sanity test 19a: start/stop MDS without OSTs ===== 22:25:48 (1788488748) [ 3919.686098] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3925.640844] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3946.663675] Lustre: DEBUG MARKER: == conf-sanity test 19b: start/stop OSTs without MDS ===== 22:26:45 (1788488805) [ 4013.983193] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4024.993539] Lustre: DEBUG MARKER: == conf-sanity test 20: remount ro,rw mounts work and doesn't break /etc/mtab ========================================================== 22:28:03 (1788488883) [ 4058.359847] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4063.885825] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4086.493649] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4088.203640] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 4093.896480] Lustre: Remounted lustre-client read-only [ 4097.021314] Lustre: Unmounted lustre-client [ 4142.097988] Lustre: DEBUG MARKER: == conf-sanity test 21a: start mds before ost, stop ost first ========================================================== 22:30:00 (1788489000) [ 4176.745743] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4183.541678] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4204.654981] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4244.155347] Lustre: DEBUG MARKER: == conf-sanity test 21b: start ost before mds, stop mds first ========================================================== 22:31:42 (1788489102) [ 4310.229714] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4345.189128] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4351.161600] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4396.732400] Lustre: DEBUG MARKER: == conf-sanity test 21c: start mds between two osts, stop mds last ========================================================== 22:34:15 (1788489255) [ 4461.610989] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4491.682154] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4499.947216] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4522.263681] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4580.929646] Lustre: DEBUG MARKER: == conf-sanity test 21d: start mgs then ost and then mds ========================================================== 22:37:19 (1788489439) [ 4582.142711] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21d need separate mgs device [ 4583.442877] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21e skipping excluded test 21e [ 4584.599571] Lustre: DEBUG MARKER: == conf-sanity test 22: start a client before osts (should return errs) ========================================================== 22:37:23 (1788489443) [ 4616.013982] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4620.812694] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4641.009787] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4659.379903] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 4661.461501] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4671.754070] Lustre: Unmounted lustre-client [ 4689.839839] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4690.606737] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 4705.735333] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9e7cc586a000.ost_server_uuid 50 [ 4707.663495] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9e7cc586a000.ost_server_uuid in FULL state after 0 sec [ 4711.166770] Lustre: Unmounted lustre-client [ 4744.457697] Key type lgssc unregistered [ 4744.799421] LNet: 107660:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4744.810877] LNetError: 107660:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4744.853819] LNet: Removed LNI 192.168.203.37@tcp [ 4745.702550] Key type .llcrypt unregistered [ 4745.705076] Key type ._llcrypt unregistered [ 4761.007123] Key type ._llcrypt registered [ 4761.010677] Key type .llcrypt registered [ 4762.420233] Lustre: DEBUG MARKER: == conf-sanity test 23a: interrupt client during recovery mount delay ========================================================== 22:40:20 (1788489620) [ 4764.548595] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4764.572687] alg: No test for adler32 (adler32-zlib) [ 4765.701425] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 4765.981627] LNet: Added LNI 192.168.203.37@tcp [8/256/0/180] [ 4767.687316] Key type lgssc registered [ 4768.820904] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4812.819241] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4818.859610] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4837.903396] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4840.740722] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 4846.057187] Lustre: lustre-MDT0000-mdc-ffff9e7cc5fba000: Connection to lustre-MDT0000 (at 192.168.203.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4847.225311] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4857.538705] LustreError: MGC192.168.203.137@tcp: Connection to MGS (at 192.168.203.137@tcp) was lost; in progress operations using this service will fail [ 4857.584900] Lustre: Unmounted lustre-client [ 4879.329933] LustreError: lustre-MDT0000-mdc-ffff9e7cd0f19000: operation mds_connect to node 192.168.203.137@tcp failed: rc = -16 [ 4884.460264] LustreError: 111038:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff9e7cd0f19000: can't stat MDS #0: rc = -16 [ 4884.524624] Lustre: Unmounted lustre-client [ 4884.544672] LustreError: 111038:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -16 [ 4908.849653] Key type lgssc unregistered [ 4909.171838] LNet: 111487:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4909.195695] LNetError: 111487:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4909.210445] LNet: Removed LNI 192.168.203.37@tcp [ 4909.999859] Key type .llcrypt unregistered [ 4910.004265] Key type ._llcrypt unregistered [ 4923.823350] Key type ._llcrypt registered [ 4923.827093] Key type .llcrypt registered [ 4924.767613] Lustre: DEBUG MARKER: == conf-sanity test 23b: Simulate -EINTR during mount ==== 22:43:03 (1788489783) [ 4926.521666] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4926.540614] alg: No test for adler32 (adler32-zlib) [ 4927.492796] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 4927.758780] LNet: Added LNI 192.168.203.37@tcp [8/256/0/180] [ 4929.487499] Key type lgssc registered [ 4930.406937] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4973.853763] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4978.503775] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4996.468850] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4998.007050] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 4999.898588] Lustre: Unmounted lustre-client [ 5024.777463] Key type lgssc unregistered [ 5025.007137] LNet: 115014:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5025.020059] LNetError: 115014:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5025.041173] LNet: Removed LNI 192.168.203.37@tcp [ 5025.767366] Key type .llcrypt unregistered [ 5025.770206] Key type ._llcrypt unregistered [ 5038.385634] Key type ._llcrypt registered [ 5038.387178] Key type .llcrypt registered [ 5039.417624] Lustre: DEBUG MARKER: == conf-sanity test 24a: Multiple MDTs on a single node == 22:44:58 (1788489898) [ 5041.968662] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24a mixed loopback and real device not working [ 5044.123353] Lustre: DEBUG MARKER: == conf-sanity test 24b: Multiple MGSs on a single node (should return err) ========================================================== 22:45:02 (1788489902) [ 5047.404490] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24b mixed loopback and real device not working [ 5049.949462] Lustre: DEBUG MARKER: == conf-sanity test 25: Verify modules are referenced ==== 22:45:07 (1788489907) [ 5052.940232] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5052.994743] alg: No test for adler32 (adler32-zlib) [ 5054.418161] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 5055.177876] LNet: Added LNI 192.168.203.37@tcp [8/256/0/180] [ 5056.903187] Key type lgssc registered [ 5058.325602] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5105.166949] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5111.600854] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5131.470869] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5134.404100] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 5142.486524] Lustre: Unmounted lustre-client [ 5172.386684] Key type lgssc unregistered [ 5172.736836] LNet: 119196:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5172.743609] LNetError: 119196:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5172.761145] LNet: Removed LNI 192.168.203.37@tcp [ 5173.413406] Key type .llcrypt unregistered [ 5173.415495] Key type ._llcrypt unregistered [ 5188.327360] Key type ._llcrypt registered [ 5188.331061] Key type .llcrypt registered [ 5189.677537] Lustre: DEBUG MARKER: == conf-sanity test 26: MDT startup failure cleans LOV (should return errs) ========================================================== 22:47:28 (1788490048) [ 5191.404598] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5191.678099] alg: No test for adler32 (adler32-zlib) [ 5192.805523] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 5193.233957] LNet: Added LNI 192.168.203.37@tcp [8/256/0/180] [ 5194.975674] Key type lgssc registered [ 5196.374213] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5254.097278] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5259.629633] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5278.847801] Key type lgssc unregistered [ 5279.216509] LNet: 122272:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5279.221801] LNetError: 122272:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5279.242792] LNet: Removed LNI 192.168.203.37@tcp [ 5279.969760] Key type .llcrypt unregistered [ 5279.973537] Key type ._llcrypt unregistered [ 5293.277649] Key type ._llcrypt registered [ 5293.284332] Key type .llcrypt registered [ 5294.711153] Lustre: DEBUG MARKER: == conf-sanity test 27a: Reacquire MGS lock if OST started first ========================================================== 22:49:13 (1788490153) [ 5302.245340] Key type .llcrypt unregistered [ 5302.248169] Key type ._llcrypt unregistered [ 5308.922781] Key type ._llcrypt registered [ 5308.927686] Key type .llcrypt registered [ 5309.292965] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5309.317858] alg: No test for adler32 (adler32-zlib) [ 5310.377230] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 5310.639536] LNet: Added LNI 192.168.203.37@tcp [8/256/0/180] [ 5312.328098] Key type lgssc registered [ 5313.488627] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5392.953351] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5428.635271] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5434.279943] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5474.443533] Key type lgssc unregistered [ 5474.657739] LNet: 126128:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5474.665746] LNetError: 126128:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5474.707675] LNet: Removed LNI 192.168.203.37@tcp [ 5475.532960] Key type .llcrypt unregistered [ 5475.538515] Key type ._llcrypt unregistered [ 5489.242199] Key type ._llcrypt registered [ 5489.246492] Key type .llcrypt registered [ 5490.291806] Lustre: DEBUG MARKER: == conf-sanity test 27b: Reacquire MGS lock after failover ========================================================== 22:52:29 (1788490349) [ 5492.265058] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5492.329947] alg: No test for adler32 (adler32-zlib) [ 5493.315370] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 5493.501077] LNet: Added LNI 192.168.203.37@tcp [8/256/0/180] [ 5495.143759] Key type lgssc registered [ 5496.250219] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5542.701619] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5548.716736] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5567.432414] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5570.105074] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 5575.141895] Lustre: lustre-MDT0000-mdc-ffff9e7cd0f1c000: Connection to lustre-MDT0000 (at 192.168.203.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5591.519442] Lustre: 127097:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788490435/real 1788490435] req@00000000dbde0859 x1875368060390784/t0(0) o400->MGC192.168.203.137@tcp@192.168.203.137@tcp:26/25 lens 224/224 e 0 to 1 dl 1788490451 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5591.562644] LustreError: MGC192.168.203.137@tcp: Connection to MGS (at 192.168.203.137@tcp) was lost; in progress operations using this service will fail [ 5601.795397] Lustre: Evicted from MGS (at 192.168.203.137@tcp) after server handle changed from 0x72d805f550390376 to 0x72d805f5503904d4 [ 5601.808076] Lustre: MGC192.168.203.137@tcp: Connection restored to 192.168.203.137@tcp (at 192.168.203.137@tcp) [ 5606.004133] Lustre: lustre-MDT0000-mdc-ffff9e7cd0f1c000: Connection restored to 192.168.203.137@tcp (at 192.168.203.137@tcp) [ 5624.508597] Lustre: Unmounted lustre-client [ 5655.139906] Key type lgssc unregistered [ 5655.397280] LNet: 130095:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5655.426680] LNetError: 130095:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5655.449183] LNet: Removed LNI 192.168.203.37@tcp [ 5656.204515] Key type .llcrypt unregistered [ 5656.210466] Key type ._llcrypt unregistered [ 5671.930806] Key type ._llcrypt registered [ 5671.933368] Key type .llcrypt registered [ 5673.197240] Lustre: DEBUG MARKER: == conf-sanity test 28A: permanent parameter setting ===== 22:55:31 (1788490531) [ 5675.510066] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5675.519132] alg: No test for adler32 (adler32-zlib) [ 5676.531353] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 5676.757314] LNet: Added LNI 192.168.203.37@tcp [8/256/0/180] [ 5678.463175] Key type lgssc registered [ 5679.612291] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5725.984498] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5731.300438] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5750.982836] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5753.806555] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 5766.433753] Lustre: Unmounted lustre-client [ 5766.750285] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 5775.469135] Lustre: Unmounted lustre-client [ 5809.163539] Key type lgssc unregistered [ 5809.375444] LNet: 134280:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5809.388260] LNetError: 134280:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5809.408536] LNet: Removed LNI 192.168.203.37@tcp [ 5810.025900] Key type .llcrypt unregistered [ 5810.028760] Key type ._llcrypt unregistered [ 5819.475324] Key type ._llcrypt registered [ 5819.477881] Key type .llcrypt registered [ 5820.660698] Lustre: DEBUG MARKER: == conf-sanity test 28a: set symlink parameters permanently with lctl ========================================================== 22:57:59 (1788490679) [ 5822.711297] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5822.739760] alg: No test for adler32 (adler32-zlib) [ 5823.840420] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 5824.112389] LNet: Added LNI 192.168.203.37@tcp [8/256/0/180] [ 5825.791276] Key type lgssc registered [ 5826.969529] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5868.250721] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5875.999439] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5894.187900] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5895.898966] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 5933.658771] Lustre: Unmounted lustre-client [ 5958.454204] Key type lgssc unregistered [ 5958.678704] LNet: 137661:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5958.687346] LNetError: 137661:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5958.714277] LNet: Removed LNI 192.168.203.37@tcp [ 5959.344602] Key type .llcrypt unregistered [ 5959.346343] Key type ._llcrypt unregistered [ 5973.372779] Key type ._llcrypt registered [ 5973.374820] Key type .llcrypt registered [ 5974.661858] Lustre: DEBUG MARKER: == conf-sanity test 28b: verify client-side parameters are set upon mount ========================================================== 23:00:33 (1788490833) [ 5976.755579] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5976.784771] alg: No test for adler32 (adler32-zlib) [ 5977.801211] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 5977.985568] LNet: Added LNI 192.168.203.37@tcp [8/256/0/180] [ 5979.647168] Key type lgssc registered [ 5980.642501] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6019.741716] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6023.738276] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6040.763232] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6047.799993] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 6050.341636] Lustre: Unmounted lustre-client [ 6050.795511] LustreError: 138629:0:(lmv_obd.c:211:lmv_notify()) activation of lustre-MDT0001_UUID failed: -22 [ 6050.882928] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 6058.875063] Lustre: Unmounted lustre-client [ 6085.211572] Key type lgssc unregistered [ 6085.435277] LNet: 141709:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6085.442983] LNetError: 141709:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6085.456154] LNet: Removed LNI 192.168.203.37@tcp [ 6086.287736] Key type .llcrypt unregistered [ 6086.291927] Key type ._llcrypt unregistered [ 6096.421435] Key type ._llcrypt registered [ 6096.423939] Key type .llcrypt registered [ 6097.793630] Lustre: DEBUG MARKER: == conf-sanity test 28c: verify filesystem parameters are set upon mount ========================================================== 23:02:36 (1788490956) [ 6099.817440] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6099.927541] alg: No test for adler32 (adler32-zlib) [ 6100.970595] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 6101.154375] LNet: Added LNI 192.168.203.37@tcp [8/256/0/180] [ 6102.839204] Key type lgssc registered [ 6103.942782] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6152.449256] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6159.239802] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6180.028994] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6182.870047] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 6185.341855] Lustre: Unmounted lustre-client [ 6185.791616] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 6192.951754] Lustre: Unmounted lustre-client [ 6219.574674] Key type lgssc unregistered [ 6219.819729] LNet: 145324:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6219.826864] LNetError: 145324:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6219.845221] LNet: Removed LNI 192.168.203.37@tcp [ 6220.416793] Key type .llcrypt unregistered [ 6220.419512] Key type ._llcrypt unregistered [ 6229.984367] Key type ._llcrypt registered [ 6229.986755] Key type .llcrypt registered [ 6231.167212] Lustre: DEBUG MARKER: == conf-sanity test 29: permanently remove an OST ======== 23:04:50 (1788491090) [ 6233.378382] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6233.440512] alg: No test for adler32 (adler32-zlib) [ 6234.539614] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 6234.760294] LNet: Added LNI 192.168.203.37@tcp [8/256/0/180] [ 6236.447177] Key type lgssc registered [ 6237.585751] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6284.955324] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6290.999591] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6311.278296] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6313.296810] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 6336.560148] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6337.928589] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6355.464063] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6366.386706] Lustre: Unmounted lustre-client [ 6366.735350] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6366.832626] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 6373.955269] Lustre: Unmounted lustre-client [ 6405.830142] Key type lgssc unregistered [ 6406.083291] LNet: 149358:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6406.095412] LNetError: 149358:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6406.114591] LNet: Removed LNI 192.168.203.37@tcp [ 6406.710586] Key type .llcrypt unregistered [ 6406.714706] Key type ._llcrypt unregistered [ 6435.899392] Key type ._llcrypt registered [ 6435.902611] Key type .llcrypt registered [ 6437.172622] Lustre: DEBUG MARKER: == conf-sanity test 30a: Big config llog and permanent parameter deletion ========================================================== 23:08:15 (1788491295) [ 6439.069462] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6439.091326] alg: No test for adler32 (adler32-zlib) [ 6440.284864] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 6440.556221] LNet: Added LNI 192.168.203.37@tcp [8/256/0/180] [ 6442.239296] Key type lgssc registered [ 6443.636890] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6486.850407] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6492.572593] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6511.124810] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6513.672462] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 6670.053074] Lustre: Unmounted lustre-client [ 6670.540558] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 6673.590057] Lustre: Unmounted lustre-client [ 6673.928201] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 6675.689846] Lustre: Unmounted lustre-client [ 6711.039472] Key type lgssc unregistered [ 6711.312676] LNet: 154583:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6711.326582] LNetError: 154583:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6711.351842] LNet: Removed LNI 192.168.203.37@tcp [ 6712.016524] Key type .llcrypt unregistered [ 6712.018592] Key type ._llcrypt unregistered [ 6728.729485] Key type ._llcrypt registered [ 6728.731828] Key type .llcrypt registered [ 6729.930856] Lustre: DEBUG MARKER: == conf-sanity test 30b: Remove failover nids ============ 23:13:08 (1788491588) [ 6731.879111] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6731.931168] alg: No test for adler32 (adler32-zlib) [ 6733.053629] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 6733.255255] LNet: Added LNI 192.168.203.37@tcp [8/256/0/180] [ 6734.919506] Key type lgssc registered [ 6736.008651] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6779.482809] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6785.008145] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6803.850361] Lustre: DEBUG MARKER: oleg337-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6806.377202] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 6818.387817] Lustre: Unmounted lustre-client [ 6818.907503] Lustre: Mounted lustre-client - version 2.17.57_84_g5b8c505 [ 6821.139333] Lustre: Unmounted lustre-client [ 6849.284685] Key type lgssc unregistered [ 6849.618350] LNet: 158311:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6849.634187] LNetError: 158311:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6849.654268] LNet: Removed LNI 192.168.203.37@tcp [ 6850.345255] Key type .llcrypt unregistered [ 6850.347854] Key type ._llcrypt unregistered [ 6865.972571] Key type ._llcrypt registered [ 6865.974079] Key type .llcrypt registered [ 6867.274555] Lustre: DEBUG MARKER: == conf-sanity test 31: Connect to non-existent node (shouldn't crash) ========================================================== 23:15:25 (1788491725) [ 6868.083814] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6868.194634] alg: No test for adler32 (adler32-zlib) [ 6869.309814] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 6869.509721] LNet: Added LNI 192.168.203.37@tcp [8/256/0/180] [ 6871.135197] Key type lgssc registered [ 6872.304588] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6894.306070] LustreError: 160005:0:(mgc_request.c:233:do_config_log_add()) MGC4.3.2.1@tcp: failed processing log, type 1: rc = -110 [ 6900.898484] LustreError: 160014:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 6947.423829] LNet: 1 local NIs in recovery (showing 1): 192.168.203.37@tcp [ 7022.175295] LNet: 1 local NIs in recovery (showing 1): 192.168.203.37@tcp [ 7053.279716] 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 [ 7053.293354] Lustre: Unmounted lustre-client [ 7053.302384] LustreError: 160005:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 7064.905249] Key type lgssc unregistered [ 7075.487548] LNet: 160420:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7075.496992] LNetError: 160420:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7153.405795] LNet: Removed LNI 192.168.203.37@tcp [ 7154.541377] Key type .llcrypt unregistered [ 7154.544266] Key type ._llcrypt unregistered [ 7191.356088] Key type ._llcrypt registered [ 7191.360819] Key type .llcrypt registered [ 7191.746590] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7191.758262] alg: No test for adler32 (adler32-zlib) [ 7193.010355] Lustre: Lustre: Build Version: 2.17.57_84_g5b8c505 [ 7193.348089] LNet: Added LNI 192.168.203.37@tcp [8/256/0/180] [ 7195.119370] Key type lgssc registered [ 7196.369900] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7238.058358] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 6967 sec ========== 23:21:36 (1788492096) [ 7239.492494] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 23:21:38 (1788492098) === [ 7242.710086] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 23:21:41 (1788492101) === [ 7276.934412] Key type lgssc unregistered [ 7277.213924] LNet: 164655:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7277.217329] LNetError: 164655:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7277.228280] LNet: Removed LNI 192.168.203.37@tcp [ 7277.846632] Key type .llcrypt unregistered [ 7277.849450] Key type ._llcrypt unregistered