[ 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 465869175 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: 2895288K/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.003148] x2apic enabled [ 0.004025] Switched APIC routing to physical x2apic. [ 0.005016] kvm-guest: setup PV IPIs [ 0.008619] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.009028] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010013] pid_max: default: 32768 minimum: 301 [ 0.011155] LSM: Security Framework initializing [ 0.013037] Yama: becoming mindful. [ 0.014095] SELinux: Initializing. [ 0.015085] *** VALIDATE selinux *** [ 0.022633] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.027354] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.028152] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030108] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031120] *** VALIDATE tmpfs *** [ 0.032475] *** VALIDATE proc *** [ 0.034196] *** VALIDATE cgroup *** [ 0.035010] *** VALIDATE cgroup2 *** [ 0.036289] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.037174] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.038009] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.039032] Spectre V2 : User space: Vulnerable [ 0.041007] Speculative Store Bypass: Vulnerable [ 0.044561] debug: unmapping init [mem 0xffffffff8c859000-0xffffffff8c860fff] [ 0.046166] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.047732] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.048027] ... version: 2 [ 0.049016] ... bit width: 48 [ 0.050015] ... generic registers: 4 [ 0.051015] ... value mask: 0000ffffffffffff [ 0.052015] ... max period: 00007fffffffffff [ 0.053013] ... fixed-purpose events: 3 [ 0.054010] ... event mask: 000000070000000f [ 0.055315] rcu: Hierarchical SRCU implementation. [ 0.057486] smp: Bringing up secondary CPUs ... [ 0.058594] x86: Booting SMP configuration: [ 0.059024] .... node #0, CPUs: #1 #2 #3 [ 0.063017] smp: Brought up 1 node, 4 CPUs [ 0.065013] smpboot: Max logical packages: 1 [ 0.066018] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.139712] node 0 deferred pages initialised in 71ms [ 0.143337] devtmpfs: initialized [ 0.144326] x86/mm: Memory block size: 128MB [ 0.147116] gcov: version magic: 0x41383552 [ 0.149412] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.150104] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.151327] pinctrl core: initialized pinctrl subsystem [ 0.152255] [ 0.152977] ************************************************************* [ 0.153019] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.154021] ** ** [ 0.155016] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.156015] ** ** [ 0.157014] ** This means that this kernel is built to expose internal ** [ 0.158011] ** IOMMU data structures, which may compromise security on ** [ 0.159019] ** your system. ** [ 0.160023] ** ** [ 0.161012] ** If you see this message and you are not debugging the ** [ 0.162017] ** kernel, report this immediately to your vendor! ** [ 0.163015] ** ** [ 0.164014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.165012] ************************************************************* [ 0.166665] NET: Registered protocol family 16 [ 0.167483] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.168072] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.169070] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.170440] cpuidle: using governor menu [ 0.172917] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.175659] PCI: Using configuration type 1 for base access [ 0.178125] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.186093] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.187025] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.189135] cryptd: max_cpu_qlen set to 1000 [ 0.193258] ACPI: Added _OSI(Module Device) [ 0.194012] ACPI: Added _OSI(Processor Device) [ 0.195008] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.196010] ACPI: Added _OSI(Processor Aggregator Device) [ 0.200203] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.204020] ACPI: Interpreter enabled [ 0.205064] ACPI: PM: (supports S0 S3 S4 S5) [ 0.206013] ACPI: Using IOAPIC for interrupt routing [ 0.207123] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.208416] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.215000] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.218043] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.221020] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.225130] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.230380] acpiphp: Slot [2] registered [ 0.233157] acpiphp: Slot [5] registered [ 0.234111] acpiphp: Slot [6] registered [ 0.236095] acpiphp: Slot [3] registered [ 0.237097] acpiphp: Slot [4] registered [ 0.238085] acpiphp: Slot [7] registered [ 0.240102] acpiphp: Slot [8] registered [ 0.241084] acpiphp: Slot [9] registered [ 0.242118] acpiphp: Slot [10] registered [ 0.244158] acpiphp: Slot [11] registered [ 0.246100] acpiphp: Slot [12] registered [ 0.248116] acpiphp: Slot [13] registered [ 0.249112] acpiphp: Slot [14] registered [ 0.251118] acpiphp: Slot [15] registered [ 0.253132] acpiphp: Slot [16] registered [ 0.255206] acpiphp: Slot [17] registered [ 0.258139] acpiphp: Slot [18] registered [ 0.259117] acpiphp: Slot [19] registered [ 0.261115] acpiphp: Slot [20] registered [ 0.263108] acpiphp: Slot [21] registered [ 0.264134] acpiphp: Slot [22] registered [ 0.266117] acpiphp: Slot [23] registered [ 0.267112] acpiphp: Slot [24] registered [ 0.269104] acpiphp: Slot [25] registered [ 0.271127] acpiphp: Slot [26] registered [ 0.272091] acpiphp: Slot [27] registered [ 0.274115] acpiphp: Slot [28] registered [ 0.275214] acpiphp: Slot [29] registered [ 0.277087] acpiphp: Slot [30] registered [ 0.278167] acpiphp: Slot [31] registered [ 0.280059] PCI host bridge to bus 0000:00 [ 0.282020] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.284020] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.286035] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.289022] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.291022] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.294032] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.296170] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.299168] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.302298] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.310016] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.314057] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.318021] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.320024] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.323017] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.325758] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.330428] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.333043] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.335839] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.341015] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.349721] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.355014] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.360443] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.367975] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.379015] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.407023] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.425329] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.437015] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.453016] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.480014] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.496505] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.499437] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.501431] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.505463] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.508318] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.514040] iommu: Default domain type: Passthrough [ 0.516524] SCSI subsystem initialized [ 0.518156] ACPI: bus type USB registered [ 0.520155] usbcore: registered new interface driver usbfs [ 0.522121] usbcore: registered new interface driver hub [ 0.524094] usbcore: registered new device driver usb [ 0.526269] pps_core: LinuxPPS API ver. 1 registered [ 0.528011] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.531060] PTP clock support registered [ 0.533100] EDAC MC: Ver: 3.0.0 [ 0.535182] PCI: Using ACPI for IRQ routing [ 0.536774] NetLabel: Initializing [ 0.537012] NetLabel: domain hash size = 128 [ 0.538012] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.539152] NetLabel: unlabeled traffic allowed by default [ 0.541139] vgaarb: loaded [ 0.543266] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.545051] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.552840] clocksource: Switched to clocksource kvm-clock [ 0.658759] VFS: Disk quotas dquot_6.6.0 [ 0.660683] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.663634] *** VALIDATE ramfs *** [ 0.665123] *** VALIDATE hugetlbfs *** [ 0.666817] pnp: PnP ACPI init [ 0.669428] pnp: PnP ACPI: found 6 devices [ 0.695952] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.699673] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.702236] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.705040] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.708140] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.711159] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.714391] NET: Registered protocol family 2 [ 0.717218] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.721864] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.725780] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.731455] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.735197] TCP: Hash tables configured (established 65536 bind 65536) [ 0.738203] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.741516] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.745182] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.749323] NET: Registered protocol family 1 [ 0.754777] RPC: Registered named UNIX socket transport module. [ 0.757423] RPC: Registered udp transport module. [ 0.759610] RPC: Registered tcp transport module. [ 0.761885] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.766819] NET: Registered protocol family 44 [ 0.770100] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.774421] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.778089] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.779750] PCI: CLS 0 bytes, default 64 [ 0.781081] Unpacking initramfs... [ 2.201893] debug: unmapping init [mem 0xffff94f1fcc64000-0xffff94f1fffcffff] [ 2.207856] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.210702] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.214255] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.716163] Initialise system trusted keyrings [ 2.718249] Key type blacklist registered [ 2.720146] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.729888] zbud: loaded [ 2.732919] *** VALIDATE nfs *** [ 2.735035] *** VALIDATE nfs4 *** [ 2.736889] pstore: using deflate compression [ 2.740429] Platform Keyring initialized [ 2.854874] NET: Registered protocol family 38 [ 2.857170] Key type asymmetric registered [ 2.859153] Asymmetric key parser 'x509' registered [ 2.861569] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.865103] io scheduler mq-deadline registered [ 2.867455] io scheduler kyber registered [ 2.869250] io scheduler bfq registered [ 2.871526] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.874473] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.878146] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.881332] ACPI: Power Button [PWRF] [ 2.886581] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.892891] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.904959] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.932822] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.961725] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.967197] Non-volatile memory driver v1.3 [ 2.969066] Linux agpgart interface v0.103 [ 3.004385] virtio_blk virtio1: [vda] 150616 512-byte logical blocks (77.1 MB/73.5 MiB) [ 3.007884] vda: detected capacity change from 0 to 77115392 [ 3.022703] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.025776] vdb: detected capacity change from 0 to 1073741824 [ 3.034251] libphy: Fixed MDIO Bus: probed [ 3.039801] usbcore: registered new interface driver usbserial_generic [ 3.042813] usbserial: USB Serial support registered for generic [ 3.045830] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.050367] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.052083] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.054652] mousedev: PS/2 mouse device common for all mice [ 3.057184] rtc_cmos 00:05: RTC can wake from S4 [ 3.060113] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.060286] rtc_cmos 00:05: registered as rtc0 [ 3.065140] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.068159] intel_pstate: CPU model not supported [ 3.071311] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.074253] hid: raw HID events driver (C) Jiri Kosina [ 3.077785] usbcore: registered new interface driver usbhid [ 3.079833] usbhid: USB HID core driver [ 3.081376] drop_monitor: Initializing network drop monitor service [ 3.084038] Initializing XFRM netlink socket [ 3.086025] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.086272] NET: Registered protocol family 10 [ 3.092425] Segment Routing with IPv6 [ 3.094045] NET: Registered protocol family 17 [ 3.097433] mpls_gso: MPLS GSO support [ 3.103865] RAS: Correctable Errors collector initialized. [ 3.106818] AVX version of gcm_enc/dec engaged. [ 3.108790] AES CTR mode by8 optimization enabled [ 3.195954] sched_clock: Marking stable (3195934135, 0)->(4158470423, -962536288) [ 3.199589] registered taskstats version 1 [ 3.203714] Loading compiled-in X.509 certificates [ 3.205575] zswap: loaded using pool lzo/zbud [ 3.231710] Key type big_key registered [ 3.244283] Key type encrypted registered [ 3.246015] ima: No TPM chip found, activating TPM-bypass! [ 3.248030] ima: Allocated hash algorithm: sha1 [ 3.249822] ima: No architecture policies found [ 3.251713] evm: Initialising EVM extended attributes: [ 3.253653] evm: security.selinux [ 3.254837] evm: security.ima [ 3.255900] evm: security.capability [ 3.257287] evm: HMAC attrs: 0x1 [ 3.259546] rtc_cmos 00:05: setting system clock to 2026-09-30 19:08:24 UTC (1790795304) [ 3.265881] debug: unmapping init [mem 0xffffffff8d803000-0xffffffff8d9fffff] [ 3.269412] debug: unmapping init [mem 0xffffffff8c582000-0xffffffff8c858fff] [ 3.278166] Write protecting the kernel read-only data: 28672k [ 3.280734] debug: unmapping init [mem 0xffffffff8ac03000-0xffffffff8adfffff] [ 3.283082] debug: unmapping init [mem 0xffffffff8b514000-0xffffffff8b5fffff] [ 3.311937] 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.320525] systemd[1]: Detected virtualization kvm. [ 3.322756] systemd[1]: Detected architecture x86-64. [ 3.324549] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.352936] systemd[1]: No hostname configured. [ 3.354752] systemd[1]: Set hostname to . [ 3.356745] random: systemd: uninitialized urandom read (16 bytes read) [ 3.359252] systemd[1]: Initializing machine ID from random generator. [ 3.400514] random: ln: uninitialized urandom read (6 bytes read) [ 3.487223] random: systemd: uninitialized urandom read (16 bytes read) [ 3.490316] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 3.495251] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 3.500292] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. Starting Apply Kernel Variables... [ OK ] Reached target Local File Systems. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Timers. [ OK ] Reached target Slices. [ OK ] Started Memstrack Anylazing Service. [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... Starting Setup Virtual Console... [ OK ] Reached target Sockets. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Swap. Starting Create Volatile Files and Directories... [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. [ 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.090530] device-mapper: uevent: version 1.0.3 [ 4.092954] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK [ 4.779218] random: fast init done ] Started Hardware RNG Entropy Gatherer Daemon. [ 4.814976] virtio_net virtio0 ens2: renamed from eth0 [ 4.922814] scsi host0: ata_piix [ 4.936607] scsi host1: ata_piix [ 4.938286] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.940439] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.676304] dracut-initqueue[581]: RTNETLINK answers: File exists [ 9.761627] random: crng init done [ 9.763054] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 10.228061] 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 dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Timers. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. Stopping udev Kernel Device Manager... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.400599] printk: systemd: 25 output lines suppressed due to ratelimiting [ 11.687866] SELinux: Disabled at runtime. [ 11.749885] 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.761790] systemd[1]: Detected virtualization kvm. [ 11.764836] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.288032] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.291377] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.295326] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.299474] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.302659] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.310825] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.321368] systemd[1]: Activating swap /dev/disk/by-label/SWAP... Activating swap /dev/disk/by-label/SWAP... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on udev Kernel Socket. [ OK ] Stopped target Switch Root. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ 12.354198] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Listening on Process Core Dump Socket. [ OK ] Created slice system-getty.slice. Mounting POSIX Message Queue File System... [ OK ] Listening on RPCbind Server Activation Socket. Starting Apply Kernel Variables... [ OK ] Listening on initctl Compatibility Named Pipe. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Stopped target Initrd File Systems. Mounting Huge Pages File System... [ OK ] Started Forward Password Requests to Wall Directory Watch. Starting Remount Root and Kernel File Systems... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Created slice User and Session Slice. Mounting Kernel Debug File System... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Reached target RPC Port Mapper. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Reached target rpc_pipefs.target. [ OK ] Reached target Slices. [ OK ] Stopped target Initrd Root File System. [ OK ] Started Journal Service. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Apply Kernel Variables. [ 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 ] Mounted Kernel Debug File System. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 12.758269] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 13.029356] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.125403] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.237321] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.254368] EDAC sbridge: Ver: 1.1.2 [ 14.256589] Key type dns_resolver registered [ 14.546764] NFS: Registering the id_resolver key type [ 14.548652] Key type id_resolver registered [ 14.550077] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Load/Save Random Seed... Starting Create Volatile Files and Directories... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started dnf makecache --timer. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. Starting Login Service... Starting Network Manager... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting Network Manager Wait Online... 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 Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting System Logging Service... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Authorization Manager. [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg415-client login: [ 43.421603] libcfs: loading out-of-tree module taints kernel. [ 43.496174] Key type ._llcrypt registered [ 43.499831] Key type .llcrypt registered [ 43.736235] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 43.742976] alg: No test for adler32 (adler32-zlib) [ 44.773800] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [ 45.155660] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [ 46.799248] Key type lgssc registered [ 47.591762] Lustre: Echo OBD driver; http://www.lustre.org/ [ 106.614015] hrtimer: interrupt took 4459880 ns [ 241.302501] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [ 246.965778] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 262.620495] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing check_logdir /tmp/testlogs/ [ 266.719383] Lustre: lustre-OST0000-osc-ffff94f247b9f000: disconnect after 22s idle [ 268.683827] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing yml_node [ 272.759897] Lustre: DEBUG MARKER: Client: 2.17.59.2 [ 275.564534] Lustre: DEBUG MARKER: MDS: 2.17.59.2 [ 278.287221] Lustre: DEBUG MARKER: OSS: 2.17.59.2 [ 280.351920] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Wed Sep 30 15:12:59 EDT 2026 [ 302.695256] Lustre: DEBUG MARKER: only running tests: 121-999 [ 304.649846] Lustre: DEBUG MARKER: excepting tests: 32newtarball [ 306.717455] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 310.395246] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 320.733890] Lustre: Unmounted lustre-client [ 447.513805] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 455.140508] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 489.640986] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 548.198533] Lustre: DEBUG MARKER: == conf-sanity test 121: failover MGS ==================== 15:17:27 (1790795847) [ 601.634584] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 608.082364] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 655.040104] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid 1475 0 [ 713.468843] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 720.815502] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 761.249689] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid 1475 0 [ 787.482534] Lustre: DEBUG MARKER: == conf-sanity test 122a: Check OST sequence update ====== 15:21:27 (1790796087) [ 920.620577] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 927.889343] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 960.551472] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 961.623684] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [ 987.581074] Lustre: Unmounted lustre-client [ 1019.842479] Key type lgssc unregistered [ 1020.222356] LNet: 22006:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1020.233077] LNetError: 22006:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1020.254181] LNet: Removed LNI 192.168.204.15@tcp [ 1021.365234] Key type .llcrypt unregistered [ 1021.370990] Key type ._llcrypt unregistered [ 1039.424701] Key type ._llcrypt registered [ 1039.431032] Key type .llcrypt registered [ 1042.301460] Lustre: DEBUG MARKER: == conf-sanity test 123aa: llog_print works with FIDs and simple names ========================================================== 15:25:40 (1790796340) [ 1045.792389] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1046.025277] alg: No test for adler32 (adler32-zlib) [ 1047.667453] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [ 1047.951894] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [ 1049.650480] Key type lgssc registered [ 1050.893326] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1119.607826] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1126.796268] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1153.065782] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1159.290919] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [ 1172.094860] Lustre: DEBUG MARKER: == conf-sanity test 123ab: llog_print params output values from set_param -P ========================================================== 15:27:51 (1790796471) [ 1186.003704] Lustre: DEBUG MARKER: == conf-sanity test 123ac: llog_print with --start and --end ========================================================== 15:28:06 (1790796486) [ 1200.375780] Lustre: DEBUG MARKER: == conf-sanity test 123ad: llog_print shows all records == 15:28:19 (1790796499) [ 1214.767121] Lustre: DEBUG MARKER: == conf-sanity test 123ae: llog_cancel can cancel requested record ========================================================== 15:28:34 (1790796514) [ 1242.108658] Lustre: DEBUG MARKER: == conf-sanity test 123af: llog_catlist can show all config files correctly ========================================================== 15:29:01 (1790796541) [ 1260.411777] Lustre: DEBUG MARKER: == conf-sanity test 123ag: llog_print skips values deleted by set_param -P -d ========================================================== 15:29:19 (1790796559) [ 1277.838759] Lustre: DEBUG MARKER: == conf-sanity test 123ah: del_ost cancels config log entries correctly ========================================================== 15:29:37 (1790796577) [ 1289.868496] Lustre: Unmounted lustre-client [ 1290.502211] LustreError: 22976:0:(lmv_obd.c:211:lmv_notify()) activation of lustre-MDT0001_UUID failed: -22 [ 1290.567336] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [ 1293.037223] Lustre: Unmounted lustre-client [ 1324.519642] Key type lgssc unregistered [ 1324.778807] LNet: 30395:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1324.794193] LNetError: 30395:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1324.823417] LNet: Removed LNI 192.168.204.15@tcp [ 1325.523430] Key type .llcrypt unregistered [ 1325.526326] Key type ._llcrypt unregistered [ 1357.166954] Key type ._llcrypt registered [ 1357.169161] Key type .llcrypt registered [ 1357.665532] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1357.688888] alg: No test for adler32 (adler32-zlib) [ 1358.823842] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [ 1359.066700] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [ 1360.735344] Key type lgssc registered [ 1362.218308] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1451.328197] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1457.444956] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1484.122589] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1537.891537] Lustre: DEBUG MARKER: == conf-sanity test 123ai: llog_print display all non skipped records ========================================================== 15:33:57 (1790796837) [ 1575.912649] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1582.551471] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1605.022479] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1606.833545] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [ 1750.154939] Lustre: DEBUG MARKER: == conf-sanity test 123aj: check permanent TBF rules ===== 15:37:29 (1790797049) [ 1799.707892] Lustre: DEBUG MARKER: == conf-sanity test 123F: clear and reset all parameters using set_param -F ========================================================== 15:38:19 (1790797099) [ 1812.813905] Lustre: Unmounted lustre-client [ 1906.703426] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1912.541644] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1935.360089] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1936.195165] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [ 1942.699941] Lustre: Unmounted lustre-client [ 1974.895799] Key type lgssc unregistered [ 1975.257064] LNet: 41736:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1975.278412] LNetError: 41736:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1975.301032] LNet: Removed LNI 192.168.204.15@tcp [ 1976.199450] Key type .llcrypt unregistered [ 1976.206229] Key type ._llcrypt unregistered [ 1995.210642] Key type ._llcrypt registered [ 1995.215719] Key type .llcrypt registered [ 1996.934250] Lustre: DEBUG MARKER: == conf-sanity test 123G: clear and reset all parameters using apply_yaml ========================================================== 15:41:36 (1790797296) [ 2000.017710] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2000.037761] alg: No test for adler32 (adler32-zlib) [ 2001.197990] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [ 2001.540910] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [ 2003.271486] Key type lgssc registered [ 2004.586426] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2060.496319] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2067.727933] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2093.289471] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2095.136495] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [ 2108.724537] Lustre: Unmounted lustre-client [ 2198.050477] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2205.742883] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2231.465413] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2232.169986] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [ 2238.602690] Lustre: Unmounted lustre-client [ 2268.898438] Key type lgssc unregistered [ 2269.239914] LNet: 47289:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2269.247761] LNetError: 47289:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2269.265251] LNet: Removed LNI 192.168.204.15@tcp [ 2270.106300] Key type .llcrypt unregistered [ 2270.108430] Key type ._llcrypt unregistered [ 2291.263336] Key type ._llcrypt registered [ 2291.264986] Key type .llcrypt registered [ 2292.935387] Lustre: DEBUG MARKER: == conf-sanity test 123H: check concurent accesses with 'lctl llog_print ========================================================== 15:46:32 (1790797592) [ 2296.411140] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2296.442724] alg: No test for adler32 (adler32-zlib) [ 2297.718907] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [ 2298.129346] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [ 2299.913191] Key type lgssc registered [ 2301.726290] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2362.031100] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2369.074234] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2398.145244] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2405.459955] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [ 2536.642416] Lustre: DEBUG MARKER: == conf-sanity test 124: check failover after replace_nids ========================================================== 15:50:36 (1790797836) [ 2538.715421] Lustre: DEBUG MARKER: SKIP: conf-sanity test_124 needs MDT failover setup [ 2540.696320] Lustre: DEBUG MARKER: == conf-sanity test 126: mount in parallel shouldn't cause a crash ========================================================== 15:50:40 (1790797840) [ 2543.316807] Lustre: Unmounted lustre-client [ 2580.659222] Key type lgssc unregistered [ 2580.970718] LNet: 52652:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2580.992502] LNetError: 52652:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2581.027830] LNet: Removed LNI 192.168.204.15@tcp [ 2581.781211] Key type .llcrypt unregistered [ 2581.782984] Key type ._llcrypt unregistered [ 2611.481080] Key type ._llcrypt registered [ 2611.491211] Key type .llcrypt registered [ 2612.214306] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2612.229342] alg: No test for adler32 (adler32-zlib) [ 2613.347857] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [ 2613.629065] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [ 2615.313421] Key type lgssc registered [ 2616.747451] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2653.121277] Lustre: DEBUG MARKER: == conf-sanity test 127: direct io overwrite on full ost ========================================================== 15:52:32 (1790797952) [ 2671.617738] Key type lgssc unregistered [ 2672.087925] LNet: 55076:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2672.111145] LNetError: 55076:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2672.141990] LNet: Removed LNI 192.168.204.15@tcp [ 2672.885164] Key type .llcrypt unregistered [ 2672.890772] Key type ._llcrypt unregistered [ 2689.912718] Key type ._llcrypt registered [ 2689.929276] Key type .llcrypt registered [ 2690.400498] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2690.414491] alg: No test for adler32 (adler32-zlib) [ 2691.751726] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [ 2692.285835] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [ 2694.127467] Key type lgssc registered [ 2695.834736] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2757.380178] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2764.308294] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2791.001122] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2797.662213] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [ 2852.003295] Lustre: DEBUG MARKER: == conf-sanity test 128: Force using remote logs with --nolocallogs ========================================================== 15:55:51 (1790798151) [ 2854.409725] Lustre: DEBUG MARKER: SKIP: conf-sanity test_128 need separate mgs device [ 2856.978273] Lustre: DEBUG MARKER: == conf-sanity test 129: attempt to connect an OST with the same index should fail ========================================================== 15:55:56 (1790798156) [ 2860.597884] Lustre: Unmounted lustre-client [ 2930.358757] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2938.039704] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3025.431229] Lustre: DEBUG MARKER: == conf-sanity test 130: re-register an MDT after writeconf ========================================================== 15:58:45 (1790798325) [ 3114.953964] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [ 3120.707827] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3140.576436] Lustre: lustre-OST0000-osc-ffff94f247b9b000: disconnect after 23s idle [ 3140.590638] Lustre: lustre-MDT0001-mdc-ffff94f247b9b000: Connection to lustre-MDT0001 (at 192.168.204.115@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3159.825601] LustreError: 63818:0:(obd_config.c:2102:class_config_llog_handler()) MGC192.168.204.115@tcp: cfg command failed: rc = -17 [ 3159.878084] Lustre: cmd=cf001 0:lustre-MDT0001-mdc 1:mdc 2:lustre-clilmv_UUID [ 3159.878084] [ 3159.901803] LustreError: 63053:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [ 3179.716706] Lustre: DEBUG MARKER: == conf-sanity test 131: MDT backup restore with project ID and inheritance flag ========================================================== 16:01:17 (1790798477) [ 3251.142227] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3341.506127] Lustre: Unmounted lustre-client [ 3535.427922] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [ 3541.624805] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3612.267047] Lustre: DEBUG MARKER: == conf-sanity test 132: hsm_actions processed after failover ========================================================== 16:08:31 (1790798911) [ 3614.319945] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3614.828764] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 3614.832458] Lustre: Skipped 2 previous similar messages [ 3633.912855] Lustre: Unmounted lustre-client [ 3785.078954] Lustre: DEBUG MARKER: == conf-sanity test 133: stripe QOS: free space balance in a pool ========================================================== 16:11:24 (1790799084) [ 3786.551965] Lustre: DEBUG MARKER: SKIP: conf-sanity test_133 needs >= 4 OSTs [ 3788.519131] Lustre: DEBUG MARKER: == conf-sanity test 134: check_iam works without faults == 16:11:28 (1790799088) [ 3891.957116] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [ 3899.143442] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 4686.503534] Lustre: DEBUG MARKER: == conf-sanity test 135: check the behavior when changelog is wrapped around ========================================================== 16:26:26 (1790799986) [ 4688.867832] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4699.207714] Lustre: Unmounted lustre-client [ 4837.935935] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4844.476721] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4871.507733] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4872.343769] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [ 7119.850838] Lustre: 95566:0:(llog_cat.c:310:llog_cat_process_or_fork()) lustre-MDT0000-mdc-ffff94f246c91000: catlog [0xa:0x5:0x0] crosses index zero [ 7148.474879] Lustre: Unmounted lustre-client [ 7186.672228] Key type lgssc unregistered [ 7187.276716] LNet: 103830:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7187.288057] LNetError: 103830:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7187.331956] LNet: Removed LNI 192.168.204.15@tcp [ 7188.321316] Key type .llcrypt unregistered [ 7188.325824] Key type ._llcrypt unregistered [ 7202.749927] Key type ._llcrypt registered [ 7202.753236] Key type .llcrypt registered [ 7205.073609] Lustre: DEBUG MARKER: == conf-sanity test 136: don't panic with bad obdecho setup ========================================================== 17:08:23 (1790802503) [ 7227.994723] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7228.205613] alg: No test for adler32 (adler32-zlib) [ 7229.473682] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [ 7229.930416] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [ 7231.922533] Key type lgssc registered [ 7234.677613] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7335.307725] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7343.235776] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7368.738627] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7371.701455] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [ 7392.509206] Lustre: Unmounted lustre-client [ 7555.566860] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7563.544299] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7593.133098] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7635.532931] Lustre: DEBUG MARKER: == conf-sanity test 137: a new MDT should inherit pools, parameters and failnode ========================================================== 17:15:34 (1790802934) [ 7637.408252] Lustre: DEBUG MARKER: SKIP: conf-sanity test_137 needs >= 3 MDTs [ 7640.398962] Lustre: DEBUG MARKER: == conf-sanity test 138: MDT mount failure must not LBUG destroying recovery exports ========================================================== 17:15:39 (1790802939) [ 7694.057162] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7701.175692] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7727.996687] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7739.517892] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [ 7749.619363] Lustre: lustre-MDT0000-mdc-ffff94f269aee000: Connection to lustre-MDT0000 (at 192.168.204.115@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7764.959214] Lustre: 104599:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1790803050/real 1790803050] req@0000000048b0b4f3 x1877792554756864/t0(0) o400->MGC192.168.204.115@tcp@192.168.204.115@tcp:26/25 lens 224/224 e 0 to 1 dl 1790803066 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 7765.012893] LustreError: MGC192.168.204.115@tcp: Connection to MGS (at 192.168.204.115@tcp) was lost; in progress operations using this service will fail [ 7765.046301] Lustre: Evicted from MGS (at 192.168.204.115@tcp) after server handle changed from 0x58b205fd31ea688f to 0x58b205fd31ea68f8 [ 7765.062662] Lustre: MGC192.168.204.115@tcp: Connection restored to 192.168.204.115@tcp (at 192.168.204.115@tcp) [ 7770.079190] Lustre: 104599:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1790803055/real 1790803055] req@000000006f2405f1 x1877792554757760/t0(0) o400->lustre-MDT0001-mdc-ffff94f269aee000@192.168.204.115@tcp:12/10 lens 224/224 e 0 to 1 dl 1790803071 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 7770.123209] Lustre: lustre-MDT0001-mdc-ffff94f269aee000: Connection to lustre-MDT0001 (at 192.168.204.115@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7773.682426] Lustre: 114185:0:(mgc_request.c:1900:mgc_process_log()) MGC192.168.204.115@tcp: IR log lustre-cliir failed, not fatal: rc = -2 [ 7776.224936] Lustre: 104599:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1790803061/real 1790803061] req@000000002750358d x1877792554758144/t0(0) o400->lustre-MDT0001-mdc-ffff94f269aee000@192.168.204.115@tcp:12/10 lens 224/224 e 0 to 1 dl 1790803077 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 7781.215126] Lustre: 104600:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1790803066/real 1790803066] req@000000004525fab8 x1877792554758912/t0(0) o400->lustre-MDT0001-mdc-ffff94f269aee000@192.168.204.115@tcp:12/10 lens 224/224 e 0 to 1 dl 1790803082 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 7785.311503] Lustre: 104602:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1790803070/real 1790803070] req@00000000d4a87a02 x1877792554759168/t0(0) o400->lustre-MDT0001-mdc-ffff94f269aee000@192.168.204.115@tcp:12/10 lens 224/224 e 0 to 1 dl 1790803086 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 7785.357656] Lustre: 104602:0:(client.c:2504:ptlrpc_expire_one_request()) Skipped 1 previous similar message [ 7789.557725] LustreError: MGC192.168.204.115@tcp: Connection to MGS (at 192.168.204.115@tcp) was lost; in progress operations using this service will fail [ 7789.574837] Lustre: 114185:0:(mgc_request.c:1900:mgc_process_log()) MGC192.168.204.115@tcp: IR log lustre-cliir failed, not fatal: rc = -5 [ 7789.589191] Lustre: Evicted from MGS (at 192.168.204.115@tcp) after server handle changed from 0x58b205fd31ea68f8 to 0x58b205fd31ea6ad4 [ 7789.607864] Lustre: MGC192.168.204.115@tcp: Connection restored to 192.168.204.115@tcp (at 192.168.204.115@tcp) [ 7807.814036] Lustre: lustre-MDT0001-mdc-ffff94f269aee000: Connection restored to 192.168.204.115@tcp (at 192.168.204.115@tcp) [ 7808.956979] LustreError: lustre-MDT0000-mdc-ffff94f269aee000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 7815.842315] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7817.790673] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7824.447772] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7826.581261] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7838.332252] Lustre: Unmounted lustre-client [ 7880.314302] Key type lgssc unregistered [ 7880.666953] LNet: 116557:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7880.684686] LNetError: 116557:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7880.708845] LNet: Removed LNI 192.168.204.15@tcp [ 7881.768599] Key type .llcrypt unregistered [ 7881.774975] Key type ._llcrypt unregistered [ 7894.416155] Key type ._llcrypt registered [ 7894.422668] Key type .llcrypt registered [ 7895.871706] Lustre: DEBUG MARKER: == conf-sanity test 140: remove_updatelog script actions ========================================================== 17:19:55 (1790803195) [ 7901.060900] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7901.108156] alg: No test for adler32 (adler32-zlib) [ 7902.426405] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [ 7902.729950] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [ 7904.463263] Key type lgssc registered [ 7906.171779] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7964.085052] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7970.811933] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7994.859739] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7997.635066] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [ 8008.180524] Lustre: lustre-MDT0000-mdc-ffff94f246668000: Connection to lustre-MDT0000 (at 192.168.204.115@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8013.298161] Lustre: lustre-MDT0001-mdc-ffff94f246668000: Connection to lustre-MDT0001 (at 192.168.204.115@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8024.543315] Lustre: 117133:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1790803309/real 1790803309] req@00000000f06ce45f x1877793259459840/t0(0) o400->MGC192.168.204.115@tcp@192.168.204.115@tcp:26/25 lens 224/224 e 0 to 1 dl 1790803325 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 8024.568866] LustreError: MGC192.168.204.115@tcp: Connection to MGS (at 192.168.204.115@tcp) was lost; in progress operations using this service will fail [ 8160.755831] LustreError: lustre-MDT0000-mdc-ffff94f246668000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 8160.788394] Lustre: lustre-MDT0000-mdc-ffff94f246668000: Connection restored to 192.168.204.115@tcp (at 192.168.204.115@tcp) [ 8165.881410] LustreError: lustre-MDT0001-mdc-ffff94f246668000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 8165.898875] Lustre: lustre-MDT0001-mdc-ffff94f246668000: Connection restored to 192.168.204.115@tcp (at 192.168.204.115@tcp) [ 8170.040295] Lustre: Evicted from MGS (at 192.168.204.115@tcp) after server handle changed from 0x7df28fd128d80f43 to 0x7df28fd128d8286b [ 8170.095512] Lustre: MGC192.168.204.115@tcp: Connection restored to 192.168.204.115@tcp (at 192.168.204.115@tcp) [ 8172.496136] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8174.270685] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 8181.061975] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8182.911437] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 8200.379351] Lustre: Unmounted lustre-client [ 8337.326450] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8344.737213] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8372.025784] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8420.331919] Lustre: DEBUG MARKER: == conf-sanity test 150: test setting max_cached_mb to a % ========================================================== 17:28:39 (1790803719) [ 8463.558770] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8471.120893] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8495.793255] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8501.801432] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [ 8504.161864] LustreError: 126612:0:(lproc_llite.c:618:ll_max_cached_mb_seq_write()) lustre: cannot set max_cached_mb=0 MB more than 3693 MB: rc = -34 [ 8512.461796] Lustre: DEBUG MARKER: == conf-sanity test 151a: damaged local config doesn't prevent mounting ========================================================== 17:30:12 (1790803812) [ 8515.230443] Lustre: Unmounted lustre-client [ 8546.621748] Key type lgssc unregistered [ 8546.983680] LNet: 127616:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8546.990740] LNetError: 127616:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8547.020325] LNet: Removed LNI 192.168.204.15@tcp [ 8548.051110] Key type .llcrypt unregistered [ 8548.055166] Key type ._llcrypt unregistered [ 8565.205475] Key type ._llcrypt registered [ 8565.209468] Key type .llcrypt registered [ 8565.713577] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8565.733903] alg: No test for adler32 (adler32-zlib) [ 8566.892565] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [ 8567.215546] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [ 8568.911800] Key type lgssc registered [ 8570.312657] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8663.107843] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8669.512521] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8697.439336] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8841.906848] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8848.272421] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8874.758264] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8930.092083] Lustre: DEBUG MARKER: == conf-sanity test 151b: -ENOSPC doesn't affect mount === 17:37:09 (1790804229) [ 8943.737902] Key type lgssc unregistered [ 8944.116066] LNet: 134846:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8944.131392] LNetError: 134846:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8944.160499] LNet: Removed LNI 192.168.204.15@tcp [ 8945.286105] Key type .llcrypt unregistered [ 8945.291979] Key type ._llcrypt unregistered [ 8960.226159] Key type ._llcrypt registered [ 8960.230408] Key type .llcrypt registered [ 8960.729707] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8960.758472] alg: No test for adler32 (adler32-zlib) [ 8961.788363] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [ 8962.131617] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [ 8963.984582] Key type lgssc registered [ 8965.980468] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9027.794740] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9034.771642] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9056.204827] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9174.923217] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9180.843149] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9202.603941] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9254.972050] Lustre: DEBUG MARKER: == conf-sanity test 152: seq allocation error in OSP ===== 17:42:34 (1790804554) [ 9344.708095] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [ 9350.679839] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9357.893512] Lustre: DEBUG MARKER: ADD OST3 [ 9370.595462] Lustre: lustre-OST0000-osc-ffff94f251bae000: disconnect after 23s idle [ 9371.052682] Lustre: DEBUG MARKER: STOP OST3 [ 9383.926031] Lustre: lustre-OST0003-osc-ffff94f251bae000: Connection to lustre-OST0003 (at 192.168.204.115@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9393.618943] Lustre: DEBUG MARKER: START OST3 again [ 9404.105845] Lustre: lustre-OST0003-osc-ffff94f251bae000: Connection restored to 192.168.204.115@tcp (at 192.168.204.115@tcp) [ 9434.659920] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 9434.687825] LustreError: 145655:0:(file.c:252:ll_close_inode_openhandle()) lustre-clilmv-ffff94f251bae000: inode [0x240000bd0:0x1:0x0] mdc close failed: rc = -108 [ 9445.105781] Lustre: Unmounted lustre-client [ 9568.351444] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9574.998846] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9599.476695] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9647.973529] Lustre: DEBUG MARKER: == conf-sanity test 153a: bypass invalid NIDs quickly ==== 17:49:07 (1790804947) [ 9756.353991] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9762.313304] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9789.894764] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9864.138049] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9870.541606] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9893.108573] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9895.390862] LNetError: 135265:0:(lib-move.c:2151:lnet_handle_find_routed_path()) peer 192.168.252.112@tcp2 has no available nets [ 9895.663072] LustreError: 135266:0:(lmv_obd.c:211:lmv_notify()) activation of lustre-MDT0001_UUID failed: -22 [ 9906.275683] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [ 9911.443076] Lustre: Unmounted lustre-client [ 9948.255412] LNet: 1 local NIs in recovery (showing 1): 192.168.204.15@tcp [ 9949.729522] Key type lgssc unregistered [ 9955.103736] LNet: 154637:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9955.120274] LNetError: 154637:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10024.807307] LNet: Removed LNI 192.168.204.15@tcp [10026.266444] Key type .llcrypt unregistered [10026.269420] Key type ._llcrypt unregistered [10048.771567] Key type ._llcrypt registered [10048.773628] Key type .llcrypt registered [10050.855976] Lustre: DEBUG MARKER: == conf-sanity test 153b: added IPv6 NID support ========= 17:55:49 (1790805349) [10074.749386] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10074.865596] alg: No test for adler32 (adler32-zlib) [10075.978988] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [10076.293293] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [10078.095309] Key type lgssc registered [10080.238796] Lustre: Echo OBD driver; http://www.lustre.org/ [10176.401882] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10183.521243] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10208.820533] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10348.620274] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [10354.990651] Lustre: DEBUG MARKER: Using TIMEOUT=20 [10363.144408] Lustre: Unmounted lustre-client [10363.287775] LNetError: 155826:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::547c:25ff:fead:a5f3/1023: rc = -22 [10363.297427] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [10363.838338] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [10364.172278] Lustre: Unmounted lustre-client [10365.147142] LNetError: 155827:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::547c:25ff:fead:a5f3/1023: rc = -22 [10365.183212] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [10365.529762] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [10365.534881] Lustre: Skipped 1 previous similar message [10365.818182] Lustre: Unmounted lustre-client [10365.819896] Lustre: Skipped 1 previous similar message [10367.637180] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [10367.641557] Lustre: Skipped 2 previous similar messages [10367.870528] Lustre: Unmounted lustre-client [10367.884572] Lustre: Skipped 2 previous similar messages [10368.009268] LNetError: 155827:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::547c:25ff:fead:a5f3/1023: rc = -22 [10368.017607] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [10369.565819] Lustre: 161766:0:(obd_mount.c:175:lustre_add_mgc_failnodes()) MGC6699:7654::1234:1234:d84@tcp: can't add failover NID 5031:db8:85a3:8d3:1319:8a2e:370:7348@tcp, rc = -75 [10370.141250] Lustre: 161798:0:(obd_mount.c:175:lustre_add_mgc_failnodes()) MGC193.168.0.240@tcp: can't add failover NID 5031:db8:85a3:8d3:1319:8a2e:370:7348@tcp, rc = -75 [10370.154782] LNetError: 155824:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::547c:25ff:fead:a5f3/1023: rc = -22 [10370.163703] LNetError: 155824:0:(lib-socket.c:308:lnet_sock_create()) Skipped 1 previous similar message [10370.179347] LNetError: Unexpected error -22 connecting to 2001:db8:85a3::8a2e:370:7334@tcp at host [2001:db8:85a3::8a2e:370:7334]:988 [10370.192276] LNetError: Skipped 1 previous similar message [10377.413767] Lustre: DEBUG MARKER: == conf-sanity test 153c: don't stuck on unreached NID === 18:01:17 (1790805677) [10428.575866] LNet: 1 local NIs in recovery (showing 1): 192.168.204.15@tcp [10512.278680] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10518.196722] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10546.210352] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10615.108746] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10621.913729] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10645.191553] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10650.911771] LustreError: 167392:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.252.112@tcp: failed processing log, type 1: rc = -110 [10701.983575] LNet: 1 local NIs in recovery (showing 1): 192.168.204.15@tcp [10777.759184] LNet: 1 local NIs in recovery (showing 1): 192.168.204.15@tcp [10788.832991] LustreError: MGC192.168.252.112@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 [10788.861619] Lustre: Unmounted lustre-client [10788.870203] Lustre: Skipped 4 previous similar messages [10788.885420] LustreError: 167392:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -110 [10817.286758] Key type lgssc unregistered [10834.981121] LNet: 167849:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10835.004239] LNetError: 167849:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10908.774380] LNet: Removed LNI 192.168.204.15@tcp [10909.838165] Key type .llcrypt unregistered [10909.842813] Key type ._llcrypt unregistered [10932.688609] Key type ._llcrypt registered [10932.691176] Key type .llcrypt registered [10934.019308] Lustre: DEBUG MARKER: == conf-sanity test 154: expand .. on rename after MDT backup restore ========================================================== 18:10:33 (1790806233) [10956.847099] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10956.883694] alg: No test for adler32 (adler32-zlib) [10958.214769] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [10958.611873] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [10960.335497] Key type lgssc registered [10961.646294] Lustre: Echo OBD driver; http://www.lustre.org/ [11122.438338] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [11128.621744] Lustre: DEBUG MARKER: Using TIMEOUT=20 [11148.047173] Lustre: Unmounted lustre-client [11326.728065] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [11334.097269] Lustre: DEBUG MARKER: Using TIMEOUT=20 [11342.300704] Lustre: Unmounted lustre-client [11388.430979] Lustre: DEBUG MARKER: == conf-sanity test 155: gap in seq allocation from ofd after restarting ========================================================== 18:18:07 (1790806687) [11500.578836] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11505.938624] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11534.668696] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11667.296538] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [11673.277752] Lustre: DEBUG MARKER: Using TIMEOUT=20 [11689.990305] Lustre: Unmounted lustre-client [11804.210607] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [11809.650809] Lustre: DEBUG MARKER: Using TIMEOUT=20 [11829.728717] Lustre: lustre-OST0001-osc-ffff94f246c94000: disconnect after 23s idle [11831.913851] Lustre: DEBUG MARKER: == conf-sanity test 156: root_fid on export consistent with client mount ========================================================== 18:25:31 (1790807131) [11834.259541] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11834.269320] LustreError: 186285:0:(file.c:252:ll_close_inode_openhandle()) lustre-clilmv-ffff94f246c94000: inode [0x200000bd1:0x1:0x0] mdc close failed: rc = -108 [11844.585474] Lustre: Unmounted lustre-client [12028.335785] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [12033.705512] Lustre: DEBUG MARKER: Using TIMEOUT=20 [12041.648109] Lustre: Unmounted lustre-client [12042.270718] LustreError: 169055:0:(lmv_obd.c:211:lmv_notify()) activation of lustre-MDT0001_UUID failed: -22 [12042.322988] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [12051.054514] Lustre: Unmounted lustre-client [12052.934467] Lustre: DEBUG MARKER: == conf-sanity test 157a: test allow_register for MDT registration and MGS restart ========================================================== 18:29:12 (1790807352) [12129.136512] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12135.886844] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12160.634587] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12161.297157] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [12251.633328] LustreError: lustre-OST0001-osc-ffff94f2504d3000: operation ost_connect to node 192.168.204.115@tcp failed: rc = -19 [12256.763847] Lustre: lustre-MDT0000-mdc-ffff94f2504d3000: Connection to lustre-MDT0000 (at 192.168.204.115@tcp) was lost; in progress operations using this service will wait for recovery to complete [12272.113693] LustreError: MGC192.168.204.115@tcp: Connection to MGS (at 192.168.204.115@tcp) was lost; in progress operations using this service will fail [12272.136794] Lustre: Evicted from MGS (at 192.168.204.115@tcp) after server handle changed from 0xae18c0e7704bde4d to 0xae18c0e7704be068 [12272.145447] Lustre: MGC192.168.204.115@tcp: Connection restored to 192.168.204.115@tcp (at 192.168.204.115@tcp) [12273.454250] LustreError: lustre-MDT0000-mdc-ffff94f2504d3000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [12273.485935] Lustre: lustre-MDT0000-mdc-ffff94f2504d3000: Connection restored to 192.168.204.115@tcp (at 192.168.204.115@tcp) [12326.979915] Lustre: Unmounted lustre-client [12364.478901] Key type lgssc unregistered [12364.826494] LNet: 195517:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12364.836581] LNetError: 195517:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12364.863561] LNet: Removed LNI 192.168.204.15@tcp [12365.679169] Key type .llcrypt unregistered [12365.682620] Key type ._llcrypt unregistered [12377.604629] Key type ._llcrypt registered [12377.607135] Key type .llcrypt registered [12378.958775] Lustre: DEBUG MARKER: == conf-sanity test 157b: verify allow_register (block new OSTs, allow existing) ========================================================== 18:34:38 (1790807678) [12381.691245] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12381.784545] alg: No test for adler32 (adler32-zlib) [12382.839499] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [12383.101723] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [12384.839881] Key type lgssc registered [12386.321487] Lustre: Echo OBD driver; http://www.lustre.org/ [12438.595790] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12445.547379] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12470.426813] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12472.623271] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [12570.099767] LustreError: lustre-OST0001-osc-ffff94f245c2c800: operation ost_connect to node 192.168.204.115@tcp failed: rc = -19 [12709.262665] Lustre: Unmounted lustre-client [12746.981656] Key type lgssc unregistered [12747.266445] LNet: 201559:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12747.280097] LNetError: 201559:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12747.308192] LNet: Removed LNI 192.168.204.15@tcp [12748.152493] Key type .llcrypt unregistered [12748.154101] Key type ._llcrypt unregistered [12760.015553] Key type ._llcrypt registered [12760.029966] Key type .llcrypt registered [12761.597274] Lustre: DEBUG MARKER: == conf-sanity test 160: MGC updates failnodes from all participants ========================================================== 18:41:01 (1790808061) [12797.414833] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12797.453215] alg: No test for adler32 (adler32-zlib) [12798.484225] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [12798.711616] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [12800.367289] Key type lgssc registered [12801.931945] Lustre: Echo OBD driver; http://www.lustre.org/ [12900.707432] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12907.171748] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12943.810565] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12992.400056] Key type lgssc unregistered [12992.634988] LNet: 206561:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12992.655044] LNetError: 206561:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12992.716566] LNet: Removed LNI 192.168.204.15@tcp [12993.739264] Key type .llcrypt unregistered [12993.742540] Key type ._llcrypt unregistered [13104.743545] Key type ._llcrypt registered [13104.752418] Key type .llcrypt registered [13105.345819] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13105.371476] alg: No test for adler32 (adler32-zlib) [13106.616455] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [13106.979184] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [13108.727988] Key type lgssc registered [13110.428907] Lustre: Echo OBD driver; http://www.lustre.org/ [13164.778884] Lustre: DEBUG MARKER: == conf-sanity test 161: test '-o mgsname' option ======== 18:47:43 (1790808463) [13215.049723] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13222.826671] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13252.855475] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13254.604513] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [13257.273149] Lustre: Unmounted lustre-client [13257.647542] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [13257.877066] Lustre: Unmounted lustre-client [13267.841053] Lustre: Unmounted lustre-client [13296.760257] Key type lgssc unregistered [13297.006933] LNet: 211554:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13297.022495] LNetError: 211554:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13297.049943] LNet: Removed LNI 192.168.204.15@tcp [13297.858065] Key type .llcrypt unregistered [13297.860166] Key type ._llcrypt unregistered [13308.572242] Key type ._llcrypt registered [13308.578461] Key type .llcrypt registered [13310.269757] Lustre: DEBUG MARKER: == conf-sanity test 162: replace nids with -o noclient === 18:50:09 (1790808609) [13314.032591] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13314.078946] alg: No test for adler32 (adler32-zlib) [13315.238200] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [13315.583565] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [13317.279519] Key type lgssc registered [13318.818571] Lustre: Echo OBD driver; http://www.lustre.org/ [13374.882970] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13380.851308] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13405.010420] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13406.843723] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [13414.980291] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13416.843559] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [13422.061894] Lustre: lustre-MDT0000-mdc-ffff94f242e1a800: Connection to lustre-MDT0000 (at 192.168.204.115@tcp) was lost; in progress operations using this service will wait for recovery to complete [13432.298068] Lustre: lustre-MDT0001-mdc-ffff94f242e1a800: Connection to lustre-MDT0001 (at 192.168.204.115@tcp) was lost; in progress operations using this service will wait for recovery to complete [13448.671427] Lustre: 212239:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1790808733/real 1790808733] req@0000000028779811 x1877798935336576/t0(0) o400->MGC192.168.204.115@tcp@192.168.204.115@tcp:26/25 lens 224/224 e 0 to 1 dl 1790808749 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [13448.748174] LustreError: MGC192.168.204.115@tcp: Connection to MGS (at 192.168.204.115@tcp) was lost; in progress operations using this service will fail [13477.490833] Lustre: Evicted from MGS (at 192.168.204.115@tcp) after server handle changed from 0x7876bb5cf1247a38 to 0x7876bb5cf1247bd5 [13477.512428] Lustre: MGC192.168.204.115@tcp: Connection restored to 192.168.204.115@tcp (at 192.168.204.115@tcp) [13515.850540] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13516.935553] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in IDLE state after 0 sec [13517.812635] LustreError: lustre-MDT0000-mdc-ffff94f242e1a800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [13517.834462] Lustre: lustre-MDT0000-mdc-ffff94f242e1a800: Connection restored to 192.168.204.115@tcp (at 192.168.204.115@tcp) [13524.140784] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13525.667556] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [13528.861062] Lustre: Unmounted lustre-client [13554.653310] Key type lgssc unregistered [13554.958314] LNet: 217107:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13554.980871] LNetError: 217107:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13555.011750] LNet: Removed LNI 192.168.204.15@tcp [13555.701741] Key type .llcrypt unregistered [13555.703458] Key type ._llcrypt unregistered [13587.650933] Key type ._llcrypt registered [13587.664307] Key type .llcrypt registered [13588.120888] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13588.137785] alg: No test for adler32 (adler32-zlib) [13589.267828] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [13589.499230] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [13591.207582] Key type lgssc registered [13592.551785] Lustre: Echo OBD driver; http://www.lustre.org/ [13681.865461] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13688.935926] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13716.839631] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13772.979157] Lustre: DEBUG MARKER: == conf-sanity test 164: test expected_clients parameter and max client tracking ========================================================== 18:57:52 (1790809072) [13873.649276] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13881.238872] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13904.641397] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13906.838833] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [13912.003340] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [13913.392649] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [13913.398198] Lustre: Skipped 1 previous similar message [13917.643115] Lustre: Unmounted lustre-client [13919.677359] Lustre: Unmounted lustre-client [13921.951800] Lustre: Unmounted lustre-client [13924.927926] Lustre: Unmounted lustre-client [13924.933050] Lustre: Skipped 1 previous similar message [13935.515720] Lustre: Unmounted lustre-client [13966.033402] Key type lgssc unregistered [13966.447353] LNet: 226296:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13966.455749] LNetError: 226296:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13966.482149] LNet: Removed LNI 192.168.204.15@tcp [13967.296783] Key type .llcrypt unregistered [13967.298937] Key type ._llcrypt unregistered [13980.452464] Key type ._llcrypt registered [13980.455966] Key type .llcrypt registered [13981.903458] Lustre: DEBUG MARKER: == conf-sanity test 165: automatic at_min scaling based on expected clients ========================================================== 19:01:21 (1790809281) [13984.101629] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13984.315830] alg: No test for adler32 (adler32-zlib) [13985.330537] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [13985.599034] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [13987.264721] Key type lgssc registered [13989.293757] Lustre: Echo OBD driver; http://www.lustre.org/ [14045.547074] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14051.716098] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14075.041125] Lustre: DEBUG MARKER: oleg415-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14087.238505] Lustre: Mounted lustre-client - version 2.17.59_2_g696972c [14143.048289] Lustre: Unmounted lustre-client [14176.442251] Key type lgssc unregistered [14176.835336] LNet: 230251:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14176.845656] LNetError: 230251:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14176.900508] LNet: Removed LNI 192.168.204.15@tcp [14178.031323] Key type .llcrypt unregistered [14178.041752] Key type ._llcrypt unregistered [14192.009758] Key type ._llcrypt registered [14192.011616] Key type .llcrypt registered [14194.185753] Lustre: DEBUG MARKER: == conf-sanity test 200a: check CPU partitions =========== 19:04:53 (1790809493) [14210.364431] Key type .llcrypt unregistered [14210.367844] Key type ._llcrypt unregistered [14211.681076] Key type ._llcrypt registered [14211.690887] Key type .llcrypt registered [14212.204424] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 4 [14212.229206] alg: No test for adler32 (adler32-zlib) [14213.300842] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [14213.575562] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [14215.311616] Key type lgssc registered [14216.572631] Lustre: Echo OBD driver; http://www.lustre.org/ [14247.692886] Key type lgssc unregistered [14248.092550] LNet: 232423:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14248.110434] LNetError: 232423:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14248.129023] LNet: Removed LNI 192.168.204.15@tcp [14249.105740] Key type .llcrypt unregistered [14249.110600] Key type ._llcrypt unregistered [14249.691661] Key type ._llcrypt registered [14249.703553] Key type .llcrypt registered [14251.421810] Lustre: DEBUG MARKER: == conf-sanity test 200b: set CPU pattern using core selection ========================================================== 19:05:50 (1790809550) [14267.278577] Key type .llcrypt unregistered [14267.281092] Key type ._llcrypt unregistered [14268.061343] Key type ._llcrypt registered [14268.063260] Key type .llcrypt registered [14268.738977] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14268.762782] alg: No test for adler32 (adler32-zlib) [14270.025219] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [14270.250068] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [14271.944884] Key type lgssc registered [14273.576386] Lustre: Echo OBD driver; http://www.lustre.org/ [14293.151629] Key type lgssc unregistered [14293.437549] LNet: 233989:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14293.452898] LNetError: 233989:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14293.482926] LNet: Removed LNI 192.168.204.15@tcp [14294.294775] Key type .llcrypt unregistered [14294.299658] Key type ._llcrypt unregistered [14302.422996] Key type ._llcrypt registered [14302.434507] Key type .llcrypt registered [14303.036403] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14303.063482] alg: No test for adler32 (adler32-zlib) [14304.296349] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [14304.630222] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [14306.375267] Key type lgssc registered [14308.290893] Lustre: Echo OBD driver; http://www.lustre.org/ [14339.173405] Key type lgssc unregistered [14339.561042] LNet: 235768:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14339.567293] LNetError: 235768:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14339.596406] LNet: Removed LNI 192.168.204.15@tcp [14340.705801] Key type .llcrypt unregistered [14340.709674] Key type ._llcrypt unregistered [14341.548778] Key type ._llcrypt registered [14341.551837] Key type .llcrypt registered [14343.682972] Lustre: DEBUG MARKER: == conf-sanity test 200c: set CPU pattern using NUMA node layout ========================================================== 19:07:22 (1790809642) [14357.882488] Key type .llcrypt unregistered [14357.886095] Key type ._llcrypt unregistered [14359.051651] Key type ._llcrypt registered [14359.065551] Key type .llcrypt registered [14359.833704] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14359.885338] alg: No test for adler32 (adler32-zlib) [14361.003244] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [14361.290625] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [14363.008112] Key type lgssc registered [14365.016141] Lustre: Echo OBD driver; http://www.lustre.org/ [14389.631341] Key type lgssc unregistered [14389.901978] LNet: 237328:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14389.922832] LNetError: 237328:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14389.955678] LNet: Removed LNI 192.168.204.15@tcp [14390.949312] Key type .llcrypt unregistered [14390.956141] Key type ._llcrypt unregistered [14399.406580] Key type ._llcrypt registered [14399.415349] Key type .llcrypt registered [14400.014663] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14400.084221] alg: No test for adler32 (adler32-zlib) [14401.182670] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [14401.424161] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [14403.167282] Key type lgssc registered [14404.720402] Lustre: Echo OBD driver; http://www.lustre.org/ [14423.821751] Key type lgssc unregistered [14424.175799] LNet: 238650:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14424.183492] LNetError: 238650:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14424.210865] LNet: Removed LNI 192.168.204.15@tcp [14425.067575] Key type .llcrypt unregistered [14425.074593] Key type ._llcrypt unregistered [14434.105744] Key type ._llcrypt registered [14434.121937] Key type .llcrypt registered [14434.621706] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14434.675160] alg: No test for adler32 (adler32-zlib) [14436.044674] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [14436.514637] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [14438.399433] Key type lgssc registered [14440.019736] Lustre: Echo OBD driver; http://www.lustre.org/ [14460.322515] Key type lgssc unregistered [14460.607682] LNet: 239969:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14460.612886] LNetError: 239969:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14460.647822] LNet: Removed LNI 192.168.204.15@tcp [14461.780746] Key type .llcrypt unregistered [14461.784446] Key type ._llcrypt unregistered [14472.813372] Key type ._llcrypt registered [14472.824392] Key type .llcrypt registered [14473.434580] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14473.452358] alg: No test for adler32 (adler32-zlib) [14474.817319] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [14475.134455] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [14476.952257] Key type lgssc registered [14479.166214] Lustre: Echo OBD driver; http://www.lustre.org/ [14511.615246] Key type lgssc unregistered [14512.120229] LNet: 241755:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14512.127412] LNetError: 241755:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14512.164203] LNet: Removed LNI 192.168.204.15@tcp [14513.245099] Key type .llcrypt unregistered [14513.250862] Key type ._llcrypt unregistered [14513.863752] Key type ._llcrypt registered [14513.872860] Key type .llcrypt registered [14515.780661] Lustre: DEBUG MARKER: == conf-sanity test 200d: set CPU pattern to exclude only CPU 1 ========================================================== 19:10:14 (1790809814) [14529.777507] Key type .llcrypt unregistered [14529.783620] Key type ._llcrypt unregistered [14530.964120] Key type ._llcrypt registered [14530.989851] Key type .llcrypt registered [14531.642100] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14531.658624] alg: No test for adler32 (adler32-zlib) [14532.843468] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [14533.268493] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [14535.041631] Key type lgssc registered [14536.852749] Lustre: Echo OBD driver; http://www.lustre.org/ [14557.326856] Key type lgssc unregistered [14557.734135] LNet: 243320:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14557.747583] LNetError: 243320:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14557.762857] LNet: Removed LNI 192.168.204.15@tcp [14558.451546] Key type .llcrypt unregistered [14558.456103] Key type ._llcrypt unregistered [14568.781805] Key type ._llcrypt registered [14568.786867] Key type .llcrypt registered [14569.346118] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14569.385899] alg: No test for adler32 (adler32-zlib) [14570.688370] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [14571.259876] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [14573.047785] Key type lgssc registered [14575.173445] Lustre: Echo OBD driver; http://www.lustre.org/ [14596.236212] Key type lgssc unregistered [14596.589851] LNet: 244650:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14596.599702] LNetError: 244650:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14596.642678] LNet: Removed LNI 192.168.204.15@tcp [14597.718245] Key type .llcrypt unregistered [14597.720596] Key type ._llcrypt unregistered [14606.166625] Key type ._llcrypt registered [14606.170258] Key type .llcrypt registered [14606.764960] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14606.780769] alg: No test for adler32 (adler32-zlib) [14607.966846] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [14608.294917] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [14609.983220] Key type lgssc registered [14611.370413] Lustre: Echo OBD driver; http://www.lustre.org/ [14629.363686] Key type lgssc unregistered [14629.597810] LNet: 245971:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14629.604116] LNetError: 245971:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14629.628897] LNet: Removed LNI 192.168.204.15@tcp [14630.312741] Key type .llcrypt unregistered [14630.317585] Key type ._llcrypt unregistered [14640.048786] Key type ._llcrypt registered [14640.072427] Key type .llcrypt registered [14640.557900] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14640.572840] alg: No test for adler32 (adler32-zlib) [14641.732829] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [14642.083515] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [14643.863821] Key type lgssc registered [14645.678578] Lustre: Echo OBD driver; http://www.lustre.org/ [14664.817594] Key type lgssc unregistered [14665.113493] LNet: 247295:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14665.121568] LNetError: 247295:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14665.146286] LNet: Removed LNI 192.168.204.15@tcp [14665.953904] Key type .llcrypt unregistered [14665.960648] Key type ._llcrypt unregistered [14674.462313] Key type ._llcrypt registered [14674.465711] Key type .llcrypt registered [14675.063241] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14675.080683] alg: No test for adler32 (adler32-zlib) [14676.163449] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [14676.397273] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [14678.103270] Key type lgssc registered [14679.803627] Lustre: Echo OBD driver; http://www.lustre.org/ [14698.881741] Key type lgssc unregistered [14699.205435] LNet: 248620:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14699.217455] LNetError: 248620:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14699.240727] LNet: Removed LNI 192.168.204.15@tcp [14699.984394] Key type .llcrypt unregistered [14699.985952] Key type ._llcrypt unregistered [14707.714447] Key type ._llcrypt registered [14707.720230] Key type .llcrypt registered [14708.110341] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14708.128380] alg: No test for adler32 (adler32-zlib) [14709.466299] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [14709.817933] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [14711.583247] Key type lgssc registered [14713.213559] Lustre: Echo OBD driver; http://www.lustre.org/ [14730.606306] Key type lgssc unregistered [14730.976262] LNet: 249939:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14730.987824] LNetError: 249939:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14731.035815] LNet: Removed LNI 192.168.204.15@tcp [14732.534685] Key type .llcrypt unregistered [14732.540623] Key type ._llcrypt unregistered [14775.426636] Key type ._llcrypt registered [14775.434342] Key type .llcrypt registered [14777.763163] Lustre: DEBUG MARKER: == conf-sanity test 200e: set CPU pattern using relative core exclusion ========================================================== 19:14:36 (1790810076) [14795.862398] Key type .llcrypt unregistered [14795.869712] Key type ._llcrypt unregistered [14797.137202] Key type ._llcrypt registered [14797.140370] Key type .llcrypt registered [14798.049206] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14798.080669] alg: No test for adler32 (adler32-zlib) [14799.495471] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [14799.789252] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [14801.511393] Key type lgssc registered [14803.389470] Lustre: Echo OBD driver; http://www.lustre.org/ [14821.209927] Key type lgssc unregistered [14821.519392] LNet: 252273:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14821.538049] LNetError: 252273:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14821.573276] LNet: Removed LNI 192.168.204.15@tcp [14822.273732] Key type .llcrypt unregistered [14822.277459] Key type ._llcrypt unregistered [14830.589882] Key type ._llcrypt registered [14830.593851] Key type .llcrypt registered [14831.186584] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14831.211654] alg: No test for adler32 (adler32-zlib) [14832.473602] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [14832.881142] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [14834.687853] Key type lgssc registered [14836.507783] Lustre: Echo OBD driver; http://www.lustre.org/ [14855.422952] Key type lgssc unregistered [14856.045144] LNet: 253609:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14856.062435] LNetError: 253609:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14856.084476] LNet: Removed LNI 192.168.204.15@tcp [14856.873896] Key type .llcrypt unregistered [14856.877239] Key type ._llcrypt unregistered [14870.453440] Key type ._llcrypt registered [14870.474377] Key type .llcrypt registered [14871.814190] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14871.914166] alg: No test for adler32 (adler32-zlib) [14873.179629] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [14873.538885] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [14875.559439] Key type lgssc registered [14878.216638] Lustre: Echo OBD driver; http://www.lustre.org/ [14903.281825] Key type lgssc unregistered [14903.599925] LNet: 254933:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14903.607562] LNetError: 254933:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14903.626724] LNet: Removed LNI 192.168.204.15@tcp [14904.502345] Key type .llcrypt unregistered [14904.505140] Key type ._llcrypt unregistered [14914.606471] Key type ._llcrypt registered [14914.608021] Key type .llcrypt registered [14915.241590] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14915.249535] alg: No test for adler32 (adler32-zlib) [14916.312815] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [14916.524776] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [14918.175745] Key type lgssc registered [14919.786462] Lustre: Echo OBD driver; http://www.lustre.org/ [14949.409273] Key type lgssc unregistered [14949.739862] LNet: 256741:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14949.746874] LNetError: 256741:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14949.765921] LNet: Removed LNI 192.168.204.15@tcp [14950.512575] Key type .llcrypt unregistered [14950.519909] Key type ._llcrypt unregistered [14951.054760] Key type ._llcrypt registered [14951.058601] Key type .llcrypt registered [14952.427363] Lustre: DEBUG MARKER: == conf-sanity test 250: verify lcfg_erase removes filesystem parameters ========================================================== 19:17:32 (1790810252) [14970.798081] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14970.828748] alg: No test for adler32 (adler32-zlib) [14971.963811] Lustre: Lustre: Build Version: 2.17.59_2_g696972c [14972.314365] LNet: Added LNI 192.168.204.15@tcp [8/256/0/180] [14973.991274] Key type lgssc registered [14975.444181] Lustre: Echo OBD driver; http://www.lustre.org/ [15224.726617] Lustre: DEBUG MARKER: == conf-sanity test 802a: simulate readonly device ======= 19:22:04 (1790810524) [15226.500620] Lustre: DEBUG MARKER: SKIP: conf-sanity test_802a ZFS specific test [15299.837146] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 15018 sec ========= 19:23:19 (1790810599) [15301.919485] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 19:23:21 (1790810601) === [15305.863766] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 19:23:25 (1790810605) === [15347.924987] Key type lgssc unregistered [15348.243799] LNet: 265813:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15348.254929] LNetError: 265813:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15348.279617] LNet: Removed LNI 192.168.204.15@tcp [15349.059884] Key type .llcrypt unregistered [15349.064089] Key type ._llcrypt unregistered