[ 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 460977404 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2400.000 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001011] APIC: Switch to symmetric I/O mode setup [ 0.002287] x2apic enabled [ 0.003025] Switched APIC routing to physical x2apic. [ 0.004018] kvm-guest: setup PV IPIs [ 0.007899] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x22983777dd9, max_idle_ns: 440795300422 ns [ 0.008036] Calibrating delay loop (skipped) preset value.. 4800.00 BogoMIPS (lpj=2400000) [ 0.009014] pid_max: default: 32768 minimum: 301 [ 0.010151] LSM: Security Framework initializing [ 0.011100] Yama: becoming mindful. [ 0.012030] SELinux: Initializing. [ 0.012772] *** VALIDATE selinux *** [ 0.020024] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026023] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027164] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028126] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029130] *** VALIDATE tmpfs *** [ 0.031241] *** VALIDATE proc *** [ 0.033218] *** VALIDATE cgroup *** [ 0.034014] *** VALIDATE cgroup2 *** [ 0.035212] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.036200] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.037012] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.038032] Spectre V2 : User space: Vulnerable [ 0.039009] Speculative Store Bypass: Vulnerable [ 0.042067] debug: unmapping init [mem 0xffffffffaa459000-0xffffffffaa460fff] [ 0.044181] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.045742] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.046029] ... version: 2 [ 0.047012] ... bit width: 48 [ 0.048016] ... generic registers: 4 [ 0.049015] ... value mask: 0000ffffffffffff [ 0.050013] ... max period: 00007fffffffffff [ 0.051018] ... fixed-purpose events: 3 [ 0.052014] ... event mask: 000000070000000f [ 0.054207] rcu: Hierarchical SRCU implementation. [ 0.057020] smp: Bringing up secondary CPUs ... [ 0.058620] x86: Booting SMP configuration: [ 0.059029] .... node #0, CPUs: #1 #2 #3 [ 0.063121] smp: Brought up 1 node, 4 CPUs [ 0.065020] smpboot: Max logical packages: 1 [ 0.066016] smpboot: Total of 4 processors activated (19200.00 BogoMIPS) [ 0.221308] node 0 deferred pages initialised in 152ms [ 0.225140] devtmpfs: initialized [ 0.227281] x86/mm: Memory block size: 128MB [ 0.231237] gcov: version magic: 0x41383552 [ 0.234332] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.235085] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.236294] pinctrl core: initialized pinctrl subsystem [ 0.237306] [ 0.237967] ************************************************************* [ 0.238019] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.239018] ** ** [ 0.240016] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.241020] ** ** [ 0.242016] ** This means that this kernel is built to expose internal ** [ 0.243018] ** IOMMU data structures, which may compromise security on ** [ 0.244022] ** your system. ** [ 0.245021] ** ** [ 0.246023] ** If you see this message and you are not debugging the ** [ 0.247019] ** kernel, report this immediately to your vendor! ** [ 0.248018] ** ** [ 0.249016] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.250020] ************************************************************* [ 0.251614] NET: Registered protocol family 16 [ 0.254943] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.259139] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.263113] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.268129] cpuidle: using governor menu [ 0.269857] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.273481] PCI: Using configuration type 1 for base access [ 0.275233] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.288070] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.290033] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.294212] cryptd: max_cpu_qlen set to 1000 [ 0.298407] ACPI: Added _OSI(Module Device) [ 0.301029] ACPI: Added _OSI(Processor Device) [ 0.302016] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.304020] ACPI: Added _OSI(Processor Aggregator Device) [ 0.309931] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.317780] ACPI: Interpreter enabled [ 0.319111] ACPI: PM: (supports S0 S3 S4 S5) [ 0.321015] ACPI: Using IOAPIC for interrupt routing [ 0.322126] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.326562] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.337838] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.340071] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.342024] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.345110] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.350620] acpiphp: Slot [2] registered [ 0.352244] acpiphp: Slot [5] registered [ 0.353277] acpiphp: Slot [6] registered [ 0.355223] acpiphp: Slot [3] registered [ 0.357126] acpiphp: Slot [4] registered [ 0.358153] acpiphp: Slot [7] registered [ 0.360172] acpiphp: Slot [8] registered [ 0.362184] acpiphp: Slot [9] registered [ 0.364214] acpiphp: Slot [10] registered [ 0.365169] acpiphp: Slot [11] registered [ 0.367145] acpiphp: Slot [12] registered [ 0.368102] acpiphp: Slot [13] registered [ 0.369107] acpiphp: Slot [14] registered [ 0.370099] acpiphp: Slot [15] registered [ 0.372111] acpiphp: Slot [16] registered [ 0.373167] acpiphp: Slot [17] registered [ 0.374195] acpiphp: Slot [18] registered [ 0.375107] acpiphp: Slot [19] registered [ 0.377125] acpiphp: Slot [20] registered [ 0.378125] acpiphp: Slot [21] registered [ 0.380125] acpiphp: Slot [22] registered [ 0.382229] acpiphp: Slot [23] registered [ 0.384193] acpiphp: Slot [24] registered [ 0.385167] acpiphp: Slot [25] registered [ 0.387138] acpiphp: Slot [26] registered [ 0.389138] acpiphp: Slot [27] registered [ 0.390148] acpiphp: Slot [28] registered [ 0.392195] acpiphp: Slot [29] registered [ 0.394145] acpiphp: Slot [30] registered [ 0.396156] acpiphp: Slot [31] registered [ 0.397136] PCI host bridge to bus 0000:00 [ 0.399057] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.402039] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.405049] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.408038] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.411031] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.414038] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.416212] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.419364] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.423730] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.432724] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.438476] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.441034] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.444034] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.447049] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.451636] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.454882] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.458060] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.461896] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.466019] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.477021] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.483019] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.488943] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.495021] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.504016] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.516030] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.525000] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.533019] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.540020] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.554021] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.564041] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.567408] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.569621] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.572386] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.575324] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.579448] iommu: Default domain type: Passthrough [ 0.581722] SCSI subsystem initialized [ 0.584236] ACPI: bus type USB registered [ 0.586151] usbcore: registered new interface driver usbfs [ 0.588167] usbcore: registered new interface driver hub [ 0.590148] usbcore: registered new device driver usb [ 0.592185] pps_core: LinuxPPS API ver. 1 registered [ 0.594023] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.597093] PTP clock support registered [ 0.599192] EDAC MC: Ver: 3.0.0 [ 0.602212] PCI: Using ACPI for IRQ routing [ 0.603952] NetLabel: Initializing [ 0.604014] NetLabel: domain hash size = 128 [ 0.605016] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.606107] NetLabel: unlabeled traffic allowed by default [ 0.608167] vgaarb: loaded [ 0.610265] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.611015] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.615413] clocksource: Switched to clocksource kvm-clock [ 0.742664] VFS: Disk quotas dquot_6.6.0 [ 0.744716] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.747965] *** VALIDATE ramfs *** [ 0.749340] *** VALIDATE hugetlbfs *** [ 0.751511] pnp: PnP ACPI init [ 0.754177] pnp: PnP ACPI: found 6 devices [ 0.772819] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.776464] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.779022] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.781633] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.784255] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.786853] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.790037] NET: Registered protocol family 2 [ 0.792797] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.797797] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.801244] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.806336] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.810329] TCP: Hash tables configured (established 65536 bind 65536) [ 0.813236] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.816229] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.819217] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.822384] NET: Registered protocol family 1 [ 0.825153] RPC: Registered named UNIX socket transport module. [ 0.830535] RPC: Registered udp transport module. [ 0.832378] RPC: Registered tcp transport module. [ 0.833895] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.837949] NET: Registered protocol family 44 [ 0.839869] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.842419] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.844986] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.847509] PCI: CLS 0 bytes, default 64 [ 0.850658] Unpacking initramfs... [ 2.316171] debug: unmapping init [mem 0xffff9c70fcc64000-0xffff9c70fffcffff] [ 2.320306] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.322412] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.325763] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x22983777dd9, max_idle_ns: 440795300422 ns [ 2.832705] Initialise system trusted keyrings [ 2.835952] Key type blacklist registered [ 2.838432] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.848511] zbud: loaded [ 2.852024] *** VALIDATE nfs *** [ 2.853288] *** VALIDATE nfs4 *** [ 2.856418] pstore: using deflate compression [ 2.860255] Platform Keyring initialized [ 2.965270] NET: Registered protocol family 38 [ 2.966833] Key type asymmetric registered [ 2.968208] Asymmetric key parser 'x509' registered [ 2.969759] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.972288] io scheduler mq-deadline registered [ 2.973627] io scheduler kyber registered [ 2.974917] io scheduler bfq registered [ 2.976536] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.978994] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.981353] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.985361] ACPI: Power Button [PWRF] [ 2.991425] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.998355] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.009604] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.039397] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.070182] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.077367] Non-volatile memory driver v1.3 [ 3.080915] Linux agpgart interface v0.103 [ 3.114656] virtio_blk virtio1: [vda] 145904 512-byte logical blocks (74.7 MB/71.2 MiB) [ 3.117360] vda: detected capacity change from 0 to 74702848 [ 3.133908] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.137253] vdb: detected capacity change from 0 to 1073741824 [ 3.145251] libphy: Fixed MDIO Bus: probed [ 3.152558] usbcore: registered new interface driver usbserial_generic [ 3.154872] usbserial: USB Serial support registered for generic [ 3.157078] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.161582] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.163575] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.165855] mousedev: PS/2 mouse device common for all mice [ 3.169305] rtc_cmos 00:05: RTC can wake from S4 [ 3.172055] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.177366] rtc_cmos 00:05: registered as rtc0 [ 3.178933] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.179905] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.184854] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.186874] intel_pstate: CPU model not supported [ 3.195063] hid: raw HID events driver (C) Jiri Kosina [ 3.197301] usbcore: registered new interface driver usbhid [ 3.199140] usbhid: USB HID core driver [ 3.200747] drop_monitor: Initializing network drop monitor service [ 3.203228] Initializing XFRM netlink socket [ 3.205445] NET: Registered protocol family 10 [ 3.208135] Segment Routing with IPv6 [ 3.210079] NET: Registered protocol family 17 [ 3.212828] mpls_gso: MPLS GSO support [ 3.219785] RAS: Correctable Errors collector initialized. [ 3.221885] AVX version of gcm_enc/dec engaged. [ 3.223945] AES CTR mode by8 optimization enabled [ 3.300482] sched_clock: Marking stable (3300453917, 0)->(4221055116, -920601199) [ 3.304518] registered taskstats version 1 [ 3.307023] Loading compiled-in X.509 certificates [ 3.309471] zswap: loaded using pool lzo/zbud [ 3.333362] Key type big_key registered [ 3.347780] Key type encrypted registered [ 3.349355] ima: No TPM chip found, activating TPM-bypass! [ 3.351151] ima: Allocated hash algorithm: sha1 [ 3.352865] ima: No architecture policies found [ 3.354290] evm: Initialising EVM extended attributes: [ 3.356314] evm: security.selinux [ 3.357521] evm: security.ima [ 3.358676] evm: security.capability [ 3.359894] evm: HMAC attrs: 0x1 [ 3.361991] rtc_cmos 00:05: setting system clock to 2026-08-15 21:14:26 UTC (1786828466) [ 3.367507] debug: unmapping init [mem 0xffffffffab403000-0xffffffffab5fffff] [ 3.370456] debug: unmapping init [mem 0xffffffffaa182000-0xffffffffaa458fff] [ 3.379094] Write protecting the kernel read-only data: 28672k [ 3.382502] debug: unmapping init [mem 0xffffffffa8803000-0xffffffffa89fffff] [ 3.385019] debug: unmapping init [mem 0xffffffffa9114000-0xffffffffa91fffff] [ 3.416888] 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.425820] systemd[1]: Detected virtualization kvm. [ 3.427842] systemd[1]: Detected architecture x86-64. [ 3.429521] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.459371] systemd[1]: No hostname configured. [ 3.461551] systemd[1]: Set hostname to . [ 3.463830] random: systemd: uninitialized urandom read (16 bytes read) [ 3.466479] systemd[1]: Initializing machine ID from random generator. [ 3.513405] random: ln: uninitialized urandom read (6 bytes read) [ 3.584596] random: systemd: uninitialized urandom read (16 bytes read) [ 3.587159] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 3.591900] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 3.596936] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ OK ] Listening on udev Kernel Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Initrd Root Device. [ OK ] Listening on Journal Socket. Starting Create Volatile Files and Directories... Starting Apply Kernel Variables... [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Local Encrypted Volumes. Starting Create list of required st…ce nodes for the current kernel... Starting Setup Virtual Console... [ OK ] Reached target Timers. [ OK ] Reached target Paths. [ OK ] Reached target Swap. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Sockets. Starting Journal Service... [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.243167] device-mapper: uevent: version 1.0.3 [ 4.245399] 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 ] Started Hardware RNG Entropy Gatherer Daemon. [ 5.022807] virtio_net virtio0 ens2: renamed from eth0 [ 5.144736] scsi host0: ata_piix [ 5.147096] scsi host1: ata_piix [ 5.148290] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.150267] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 6.317642] random: fast init done [ 8.817231] dracut-initqueue[580]: RTNETLINK answers: File exists [ 9.864425] random: crng init done [ 9.866105] 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.290716] 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... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Swap. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.417781] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.690875] SELinux: Disabled at runtime. [ 11.750976] 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.759361] systemd[1]: Detected virtualization kvm. [ 11.760892] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.283466] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.287145] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.294982] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.299465] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.302953] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.309786] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.313342] systemd[1]: proc-sys-fs-binfmt_misc.automount: Refusing to start, unit to trigger not loaded. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Mounting Huge Pages File System... [ OK ] Listening on initctl Compatibility Named Pipe. Starting Create list of required st…ce nodes for the current kernel... Mounting Kernel Debug File System... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Listening on RPCbind Server Activation Socket. Starting Remount Root and Kernel File Systems... [ OK ] Listening on Process Core Dump Socket. [ OK ] Created slice system-getty.slice. [ OK ] Listening on udev Control Socket. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target rpc_pipefs.target. Starting Apply Kernel Variables... Mounting POSIX Message Queue File System... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-sshd\x2dkeygen.slice. Starting udev Coldplug all Devices... Activating swap /dev/disk/by-label/SWAP... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Reached target RPC Port Mapper. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ 12.474433] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Started Journal Service. [ OK ] Mounted Huge Pages File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Kernel Debug File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ OK ] Started udev Coldplug all Devices. [ 12.714975] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 13.127246] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.135649] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.219813] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.236782] EDAC sbridge: Ver: 1.1.2 [ 14.202959] Key type dns_resolver registered [ 14.508701] NFS: Registering the id_resolver key type [ 14.510418] Key type id_resolver registered [ 14.512030] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... Starting Mark the need to relabel after reboot... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started dnf makecache --timer. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Login Service... [ OK ] Started irqbalance daemon. [ OK ] Started daily update of the root trust anchor for DNSSEC. Starting Restore /run/initramfs on shutdown... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting Network Manager Wait Online... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg460-client login: [ 57.132744] libcfs: loading out-of-tree module taints kernel. [ 57.174989] Key type ._llcrypt registered [ 57.178283] Key type .llcrypt registered [ 57.849803] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 57.863481] alg: No test for adler32 (adler32-zlib) [ 59.486732] Lustre: Lustre: Build Version: 2.17.57_3_g928f38d [ 60.343269] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 62.128125] Key type lgssc registered [ 63.922806] Lustre: Echo OBD driver; http://www.lustre.org/ [ 66.700716] hrtimer: interrupt took 22700010 ns [ 236.116984] Lustre: Mounted lustre-client - version 2.17.57_3_g928f38d [ 240.787516] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 254.809268] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing check_logdir /tmp/testlogs/ [ 260.461307] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing yml_node [ 261.603810] Lustre: lustre-OST0000-osc-ffff9c71585b1000: disconnect after 23s idle [ 265.359843] Lustre: DEBUG MARKER: Client: 2.17.57.3 [ 268.979061] Lustre: DEBUG MARKER: MDS: 2.17.57.3 [ 271.849465] Lustre: DEBUG MARKER: OSS: 2.17.57.3 [ 274.095394] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Sat Aug 15 17:18:55 EDT 2026 [ 292.108133] Lustre: DEBUG MARKER: excepting tests: 32newtarball [ 294.188772] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 298.034894] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 308.419911] Lustre: Unmounted lustre-client [ 424.162191] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 430.298481] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 452.600946] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 493.928751] Lustre: DEBUG MARKER: == conf-sanity test 121: failover MGS ==================== 17:22:34 (1786828954) [ 543.966364] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 549.609787] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 584.100420] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid 1475 0 [ 642.787944] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 648.540787] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 683.676908] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid 1475 0 [ 707.423771] Lustre: DEBUG MARKER: == conf-sanity test 122a: Check OST sequence update ====== 17:26:09 (1786829169) [ 831.854580] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 837.545323] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 861.714420] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 862.401953] Lustre: Mounted lustre-client - version 2.17.57_3_g928f38d [ 885.940768] Lustre: Unmounted lustre-client [ 913.292522] Key type lgssc unregistered [ 913.668875] LNet: 21888:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 913.685293] LNetError: 21888:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 913.726097] LNet: Removed LNI 192.168.204.60@tcp [ 914.663523] Key type .llcrypt unregistered [ 914.673056] Key type ._llcrypt unregistered [ 931.619040] Key type ._llcrypt registered [ 931.636340] Key type .llcrypt registered [ 933.890276] Lustre: DEBUG MARKER: == conf-sanity test 123aa: llog_print works with FIDs and simple names ========================================================== 17:29:54 (1786829394) [ 937.518661] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 937.529239] alg: No test for adler32 (adler32-zlib) [ 938.699538] Lustre: Lustre: Build Version: 2.17.57_3_g928f38d [ 939.103427] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 940.799218] Key type lgssc registered [ 942.126935] Lustre: Echo OBD driver; http://www.lustre.org/ [ 993.837883] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1000.436929] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1021.142850] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1022.813820] Lustre: Mounted lustre-client - version 2.17.57_3_g928f38d [ 1037.593733] Lustre: DEBUG MARKER: == conf-sanity test 123ab: llog_print params output values from set_param -P ========================================================== 17:31:39 (1786829499) [ 1048.793948] Lustre: DEBUG MARKER: == conf-sanity test 123ac: llog_print with --start and --end ========================================================== 17:31:50 (1786829510) [ 1059.850732] Lustre: DEBUG MARKER: == conf-sanity test 123ad: llog_print shows all records == 17:32:01 (1786829521) [ 1072.404155] Lustre: DEBUG MARKER: == conf-sanity test 123ae: llog_cancel can cancel requested record ========================================================== 17:32:13 (1786829533) [ 1095.953972] Lustre: DEBUG MARKER: == conf-sanity test 123af: llog_catlist can show all config files correctly ========================================================== 17:32:37 (1786829557) [ 1111.780302] Lustre: DEBUG MARKER: == conf-sanity test 123ag: llog_print skips values deleted by set_param -P -d ========================================================== 17:32:53 (1786829573) [ 1126.975298] Lustre: DEBUG MARKER: == conf-sanity test 123ah: del_ost cancels config log entries correctly ========================================================== 17:33:08 (1786829588) [ 1137.140438] Lustre: Unmounted lustre-client [ 1137.631307] Lustre: Mounted lustre-client - version 2.17.57_3_g928f38d [ 1139.403657] Lustre: Unmounted lustre-client [ 1177.029482] Key type lgssc unregistered [ 1177.349530] LNet: 30266:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1177.362380] LNetError: 30266:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1177.381195] LNet: Removed LNI 192.168.204.60@tcp [ 1178.207188] Key type .llcrypt unregistered [ 1178.211817] Key type ._llcrypt unregistered [ 1205.972255] Key type ._llcrypt registered [ 1205.974600] Key type .llcrypt registered [ 1206.354707] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1206.411683] alg: No test for adler32 (adler32-zlib) [ 1207.603742] Lustre: Lustre: Build Version: 2.17.57_3_g928f38d [ 1207.904197] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 1209.607195] Key type lgssc registered [ 1210.916295] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1296.732955] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1302.702325] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1326.469558] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1378.151526] Lustre: DEBUG MARKER: == conf-sanity test 123ai: llog_print display all non skipped records ========================================================== 17:37:20 (1786829840) [ 1415.776659] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1421.487681] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1445.532546] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1447.156350] Lustre: Mounted lustre-client - version 2.17.57_3_g928f38d [ 1592.450641] Lustre: DEBUG MARKER: == conf-sanity test 123aj: check permanent TBF rules ===== 17:40:53 (1786830053) [ 1635.696411] Lustre: DEBUG MARKER: == conf-sanity test 123F: clear and reset all parameters using set_param -F ========================================================== 17:41:37 (1786830097) [ 1646.213982] Lustre: Unmounted lustre-client [ 1722.892666] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1728.286542] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1750.102244] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1750.863704] Lustre: Mounted lustre-client - version 2.17.57_3_g928f38d [ 1755.958518] Lustre: Unmounted lustre-client [ 1791.618976] Key type lgssc unregistered [ 1791.918562] LNet: 41595:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1791.931297] LNetError: 41595:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1791.957427] LNet: Removed LNI 192.168.204.60@tcp [ 1793.012660] Key type .llcrypt unregistered [ 1793.020045] Key type ._llcrypt unregistered [ 1808.494874] Key type ._llcrypt registered [ 1808.498540] Key type .llcrypt registered [ 1810.046894] Lustre: DEBUG MARKER: == conf-sanity test 123G: clear and reset all parameters using apply_yaml ========================================================== 17:44:31 (1786830271) [ 1813.255127] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1813.403412] alg: No test for adler32 (adler32-zlib) [ 1814.540880] Lustre: Lustre: Build Version: 2.17.57_3_g928f38d [ 1814.783541] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 1816.464863] Key type lgssc registered [ 1817.474060] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1873.845718] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1879.811368] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1901.308379] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1903.049105] Lustre: Mounted lustre-client - version 2.17.57_3_g928f38d [ 1917.208797] Lustre: Unmounted lustre-client [ 2001.130064] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2008.138570] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2031.333397] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2032.179628] Lustre: Mounted lustre-client - version 2.17.57_3_g928f38d [ 2039.403204] Lustre: Unmounted lustre-client [ 2068.620160] Key type lgssc unregistered [ 2068.899977] LNet: 47157:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2068.908188] LNetError: 47157:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2068.922441] LNet: Removed LNI 192.168.204.60@tcp [ 2069.618418] Key type .llcrypt unregistered [ 2069.621402] Key type ._llcrypt unregistered [ 2085.434382] Key type ._llcrypt registered [ 2085.437518] Key type .llcrypt registered [ 2087.048349] Lustre: DEBUG MARKER: == conf-sanity test 123H: check concurent accesses with 'lctl llog_print ========================================================== 17:49:08 (1786830548) [ 2089.629649] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2089.674719] alg: No test for adler32 (adler32-zlib) [ 2090.826925] Lustre: Lustre: Build Version: 2.17.57_3_g928f38d [ 2091.410601] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 2093.183293] Key type lgssc registered [ 2095.203441] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2152.943112] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2158.938353] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2179.810893] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2181.596714] Lustre: Mounted lustre-client - version 2.17.57_3_g928f38d [ 2294.141955] Lustre: DEBUG MARKER: == conf-sanity test 124: check failover after replace_nids ========================================================== 17:52:35 (1786830755) [ 2295.764427] Lustre: DEBUG MARKER: SKIP: conf-sanity test_124 needs MDT failover setup [ 2297.769500] Lustre: DEBUG MARKER: == conf-sanity test 126: mount in parallel shouldn't cause a crash ========================================================== 17:52:39 (1786830759) [ 2300.091336] Lustre: Unmounted lustre-client [ 2328.138736] Key type lgssc unregistered [ 2328.370075] LNet: 52506:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2328.377734] LNetError: 52506:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2329.448125] LNet: Removed LNI 192.168.204.60@tcp [ 2330.226377] Key type .llcrypt unregistered [ 2330.228244] Key type ._llcrypt unregistered [ 2358.527043] Key type ._llcrypt registered [ 2358.529264] Key type .llcrypt registered [ 2358.949757] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2358.980299] alg: No test for adler32 (adler32-zlib) [ 2360.156908] Lustre: Lustre: Build Version: 2.17.57_3_g928f38d [ 2360.449306] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 2362.143266] Key type lgssc registered [ 2363.583142] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2399.524510] Lustre: DEBUG MARKER: == conf-sanity test 127: direct io overwrite on full ost ========================================================== 17:54:21 (1786830861) [ 2413.100985] Key type lgssc unregistered [ 2413.506830] LNet: 54922:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2413.518914] LNetError: 54922:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2413.540549] LNet: Removed LNI 192.168.204.60@tcp [ 2414.365891] Key type .llcrypt unregistered [ 2414.370207] Key type ._llcrypt unregistered [ 2427.699777] Key type ._llcrypt registered [ 2427.704075] Key type .llcrypt registered [ 2428.411428] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2428.427890] alg: No test for adler32 (adler32-zlib) [ 2429.774273] Lustre: Lustre: Build Version: 2.17.57_3_g928f38d [ 2430.141133] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 2431.880898] Key type lgssc registered [ 2433.272568] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2489.793459] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2496.514688] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2520.586403] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2531.902835] Lustre: Mounted lustre-client - version 2.17.57_3_g928f38d [ 2582.097673] Lustre: DEBUG MARKER: == conf-sanity test 128: Force using remote logs with --nolocallogs ========================================================== 17:57:23 (1786831043) [ 2584.283312] Lustre: DEBUG MARKER: SKIP: conf-sanity test_128 need separate mgs device [ 2586.369748] Lustre: DEBUG MARKER: == conf-sanity test 129: attempt to connect an OST with the same index should fail ========================================================== 17:57:27 (1786831047) [ 2589.799913] Lustre: Unmounted lustre-client [ 2657.848321] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2665.845863] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2745.858345] Lustre: DEBUG MARKER: == conf-sanity test 130: re-register an MDT after writeconf ========================================================== 18:00:07 (1786831207) [ 2827.141305] Lustre: Mounted lustre-client - version 2.17.57_3_g928f38d [ 2834.899968] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 2852.831580] Lustre: lustre-OST0000-osc-ffff9c7145f7d800: disconnect after 22s idle [ 2852.839504] Lustre: lustre-MDT0001-mdc-ffff9c7145f7d800: Connection to lustre-MDT0001 (at 192.168.204.160@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2873.913401] LustreError: 63649:0:(obd_config.c:2103:class_config_llog_handler()) MGC192.168.204.160@tcp: cfg command failed: rc = -17 [ 2873.937462] Lustre: cmd=cf001 0:lustre-MDT0001-mdc 1:mdc 2:lustre-clilmv_UUID [ 2873.937462] [ 2873.953955] LustreError: 62892:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [ 2890.075778] Lustre: DEBUG MARKER: == conf-sanity test 131: MDT backup restore with project ID and inheritance flag ========================================================== 18:02:31 (1786831351) [ 2957.363983] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3049.216481] Lustre: Unmounted lustre-client [ 3206.819504] Lustre: Mounted lustre-client - version 2.17.57_3_g928f38d [ 3211.886161] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3269.308599] Lustre: DEBUG MARKER: == conf-sanity test 132: hsm_actions processed after failover ========================================================== 18:08:50 (1786831730) [ 3270.523242] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3271.120547] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 3271.125188] Lustre: Skipped 1 previous similar message [ 3293.292718] Lustre: Unmounted lustre-client [ 3445.045524] Lustre: DEBUG MARKER: == conf-sanity test 133: stripe QOS: free space balance in a pool ========================================================== 18:11:46 (1786831906) [ 3446.684117] Lustre: DEBUG MARKER: SKIP: conf-sanity test_133 needs >= 4 OSTs [ 3449.049556] Lustre: DEBUG MARKER: == conf-sanity test 134: check_iam works without faults == 18:11:50 (1786831910) [ 3550.318131] Lustre: Mounted lustre-client - version 2.17.57_3_g928f38d [ 3556.278208] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 4263.743195] Lustre: DEBUG MARKER: == conf-sanity test 135: check the behavior when changelog is wrapped around ========================================================== 18:25:25 (1786832725) [ 4265.326586] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4265.331094] Lustre: Skipped 1 previous similar message [ 4275.727284] Lustre: Unmounted lustre-client [ 4419.857253] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4428.218471] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4455.644556] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4456.389221] Lustre: Mounted lustre-client - version 2.17.57_3_g928f38d [ 6405.105919] Lustre: 95372:0:(llog_cat.c:310:llog_cat_process_or_fork()) lustre-MDT0000-mdc-ffff9c7148053000: catlog [0xa:0x5:0x0] crosses index zero [ 6429.413835] Lustre: Unmounted lustre-client [ 6455.053663] Key type lgssc unregistered [ 6455.315813] LNet: 103586:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6455.331733] LNetError: 103586:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6455.346802] LNet: Removed LNI 192.168.204.60@tcp [ 6456.019272] Key type .llcrypt unregistered [ 6456.024730] Key type ._llcrypt unregistered [ 6464.697467] Key type ._llcrypt registered [ 6464.699494] Key type .llcrypt registered [ 6465.964253] Lustre: DEBUG MARKER: == conf-sanity test 136: don't panic with bad obdecho setup ========================================================== 19:02:07 (1786834927) [ 6480.803742] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6480.849222] alg: No test for adler32 (adler32-zlib) [ 6481.953080] Lustre: Lustre: Build Version: 2.17.57_3_g928f38d [ 6482.158544] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 6483.847178] Key type lgssc registered [ 6484.841735] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6559.463880] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6564.742673] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6584.426195] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6586.037246] Lustre: Mounted lustre-client - version 2.17.57_3_g928f38d [ 6600.658535] Lustre: Unmounted lustre-client [ 6713.694875] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6718.198701] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6737.391139] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6778.566780] Lustre: DEBUG MARKER: == conf-sanity test 137: a new MDT should inherit pools, parameters and failnode ========================================================== 19:07:20 (1786835240) [ 6779.806383] Lustre: DEBUG MARKER: SKIP: conf-sanity test_137 needs >= 3 MDTs [ 6781.370154] Lustre: DEBUG MARKER: == conf-sanity test 140: remove_updatelog script actions ========================================================== 19:07:23 (1786835243) [ 6810.520509] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6815.229765] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6831.444732] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6837.297817] Lustre: Mounted lustre-client - version 2.17.57_3_g928f38d [ 6842.351076] Lustre: lustre-MDT0000-mdc-ffff9c7148fe4800: Connection to lustre-MDT0000 (at 192.168.204.160@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6852.583930] Lustre: lustre-MDT0001-mdc-ffff9c7148fe4800: Connection to lustre-MDT0001 (at 192.168.204.160@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6868.703825] Lustre: 104344:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786835315/real 1786835315] req@00000000d7257364 x1873632239365248/t0(0) o400->MGC192.168.204.160@tcp@192.168.204.160@tcp:26/25 lens 224/224 e 0 to 1 dl 1786835331 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6868.724527] LustreError: MGC192.168.204.160@tcp: Connection to MGS (at 192.168.204.160@tcp) was lost; in progress operations using this service will fail [ 6988.781363] Lustre: Evicted from MGS (at 192.168.204.160@tcp) after server handle changed from 0x69724c994be7ceef to 0x69724c994be7e58c [ 6988.788498] Lustre: MGC192.168.204.160@tcp: Connection restored to 192.168.204.160@tcp (at 192.168.204.160@tcp) [ 6998.534195] LustreError: lustre-MDT0000-mdc-ffff9c7148fe4800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 6998.579646] Lustre: lustre-MDT0000-mdc-ffff9c7148fe4800: Connection restored to 192.168.204.160@tcp (at 192.168.204.160@tcp) [ 7005.514796] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7006.748051] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7008.754176] LustreError: lustre-MDT0001-mdc-ffff9c7148fe4800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 7008.781643] Lustre: lustre-MDT0001-mdc-ffff9c7148fe4800: Connection restored to 192.168.204.160@tcp (at 192.168.204.160@tcp) [ 7010.781907] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7011.968542] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7024.594051] Lustre: Unmounted lustre-client [ 7121.554106] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7125.729819] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7143.683746] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7177.914864] Lustre: DEBUG MARKER: == conf-sanity test 150: test setting max_cached_mb to a % ========================================================== 19:14:00 (1786835640) [ 7202.264358] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7206.243168] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7219.936063] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7235.592575] Lustre: Mounted lustre-client - version 2.17.57_3_g928f38d [ 7237.624356] LustreError: 121040: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 [ 7243.131610] Lustre: DEBUG MARKER: == conf-sanity test 151a: damaged local config doesn't prevent mounting ========================================================== 19:15:05 (1786835705) [ 7244.477776] Lustre: Unmounted lustre-client [ 7275.099414] Key type lgssc unregistered [ 7275.304065] LNet: 122051:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7275.308974] LNetError: 122051:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7275.326287] LNet: Removed LNI 192.168.204.60@tcp [ 7275.848740] Key type .llcrypt unregistered [ 7275.853403] Key type ._llcrypt unregistered [ 7285.827250] Key type ._llcrypt registered [ 7285.829406] Key type .llcrypt registered [ 7286.121601] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7286.130169] alg: No test for adler32 (adler32-zlib) [ 7287.148190] Lustre: Lustre: Build Version: 2.17.57_3_g928f38d [ 7287.374102] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 7289.063264] Key type lgssc registered [ 7289.831564] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7353.533219] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7356.930577] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7369.102341] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7458.090339] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7461.792595] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7476.333812] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7507.571205] Lustre: DEBUG MARKER: == conf-sanity test 151b: -ENOSPC doesn't affect mount === 19:19:29 (1786835969) [ 7515.400458] Key type lgssc unregistered [ 7515.616656] LNet: 129246:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7515.620781] LNetError: 129246:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7515.630854] LNet: Removed LNI 192.168.204.60@tcp [ 7516.177209] Key type .llcrypt unregistered [ 7516.180800] Key type ._llcrypt unregistered [ 7526.074498] Key type ._llcrypt registered [ 7526.112079] Key type .llcrypt registered [ 7526.466376] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7526.489936] alg: No test for adler32 (adler32-zlib) [ 7527.507170] Lustre: Lustre: Build Version: 2.17.57_3_g928f38d [ 7527.716505] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 7529.399203] Key type lgssc registered [ 7530.622291] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7566.847807] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7570.638784] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7585.718633] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7664.840601] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7668.613369] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7683.146664] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7712.514509] Lustre: DEBUG MARKER: == conf-sanity test 152: seq allocation error in OSP ===== 19:22:54 (1786836174) [ 7762.257075] Lustre: Mounted lustre-client - version 2.17.57_3_g928f38d [ 7765.688930] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 7771.987476] Lustre: DEBUG MARKER: ADD OST3 [ 7781.019897] Lustre: DEBUG MARKER: STOP OST3 [ 7784.939632] Lustre: lustre-OST0000-osc-ffff9c7142baf000: disconnect after 21s idle [ 7794.389876] Lustre: DEBUG MARKER: START OST3 again [ 7821.303313] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7821.314206] LustreError: 140021:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff9c7142baf000: inode [0x240000bd0:0x1:0x0] mdc close failed: rc = -108 [ 7831.648977] Lustre: Unmounted lustre-client [ 7900.838499] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7904.367502] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7918.453138] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7944.108517] Lustre: DEBUG MARKER: == conf-sanity test 153a: bypass invalid NIDs quickly ==== 19:26:46 (1786836406) [ 8002.780366] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8005.922814] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8019.485540] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8064.902177] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8068.380588] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8080.105562] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8081.204909] LNetError: 129657:0:(lib-move.c:2158:lnet_handle_find_routed_path()) peer 192.168.252.112@tcp2 has no available nets [ 8081.357694] Lustre: Mounted lustre-client - version 2.17.57_3_g928f38d [ 8086.734591] Lustre: Unmounted lustre-client [ 8097.247591] Key type lgssc unregistered [ 8136.991236] LNet: 1 local NIs in recovery (showing 1): 192.168.204.60@tcp [ 8141.410144] LNet: 148970:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8141.419506] LNetError: 148970:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8214.118138] LNet: Removed LNI 192.168.204.60@tcp [ 8214.449353] Key type .llcrypt unregistered [ 8214.451442] Key type ._llcrypt unregistered [ 8224.323484] Key type ._llcrypt registered [ 8224.324864] Key type .llcrypt registered [ 8225.070965] Lustre: DEBUG MARKER: == conf-sanity test 153b: added IPv6 NID support ========= 19:31:27 (1786836687) [ 8234.143238] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8234.158948] alg: No test for adler32 (adler32-zlib) [ 8235.076560] Lustre: Lustre: Build Version: 2.17.57_3_g928f38d [ 8235.203987] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 8236.807319] Key type lgssc registered [ 8237.541849] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8279.009364] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8281.519692] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8291.943219] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8355.776372] Lustre: Mounted lustre-client - version 2.17.57_3_g928f38d [ 8358.497660] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 8362.739836] Lustre: Unmounted lustre-client [ 8362.779699] LNetError: 150149:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::54d3:88ff:fef9:abdb/1023: rc = -22 [ 8362.783185] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 8362.901524] Lustre: Mounted lustre-client - version 2.17.57_3_g928f38d [ 8363.469314] Lustre: Unmounted lustre-client [ 8363.473480] Lustre: Skipped 2 previous similar messages [ 8363.922504] Lustre: Mounted lustre-client - version 2.17.57_3_g928f38d [ 8363.925337] Lustre: Skipped 3 previous similar messages [ 8364.554386] Lustre: Unmounted lustre-client [ 8364.556444] Lustre: Skipped 3 previous similar messages [ 8364.958281] Lustre: 156057: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 [ 8364.968642] LNetError: 150150:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::54d3:88ff:fef9:abdb/1023: rc = -22 [ 8364.975776] LNetError: 150150:0:(lib-socket.c:308:lnet_sock_create()) Skipped 1 previous similar message [ 8364.980096] LNetError: Unexpected error -22 connecting to 2001:db8:85a3::8a2e:370:7334@tcp at host [2001:db8:85a3::8a2e:370:7334]:988 [ 8364.985473] LNetError: Skipped 1 previous similar message [ 8368.816879] Lustre: DEBUG MARKER: == conf-sanity test 153c: don't stuck on unreached NID === 19:33:51 (1786836831) [ 8421.600820] LNet: 1 local NIs in recovery (showing 1): 192.168.204.60@tcp [ 8443.434809] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8446.543266] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8458.332566] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8502.145177] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8504.761399] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8515.765499] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8521.184560] LustreError: 161664:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.252.112@tcp: failed processing log, type 1: rc = -110 [ 8568.031371] LNet: 1 local NIs in recovery (showing 1): 192.168.204.60@tcp [ 8630.495301] LNet: 1 local NIs in recovery (showing 1): 192.168.204.60@tcp [ 8658.911625] 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 [ 8658.923624] Lustre: Unmounted lustre-client [ 8658.926597] Lustre: Skipped 3 previous similar messages [ 8658.941803] LustreError: 161664:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 8687.415306] Key type lgssc unregistered [ 8699.103663] LNet: 1 local NIs in recovery (showing 1): 192.168.204.60@tcp [ 8709.087601] LNet: 162124:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8709.091601] LNetError: 162124:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8779.749992] LNet: Removed LNI 192.168.204.60@tcp [ 8780.097306] Key type .llcrypt unregistered [ 8780.098966] Key type ._llcrypt unregistered [ 8787.075100] Key type ._llcrypt registered [ 8787.077087] Key type .llcrypt registered [ 8787.770473] Lustre: DEBUG MARKER: == conf-sanity test 154: expand .. on rename after MDT backup restore ========================================================== 19:40:50 (1786837250) [ 8796.228546] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8796.237551] alg: No test for adler32 (adler32-zlib) [ 8797.148742] Lustre: Lustre: Build Version: 2.17.57_3_g928f38d [ 8797.280503] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 8798.888569] Key type lgssc registered [ 8799.415643] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8859.172157] Lustre: Mounted lustre-client - version 2.17.57_3_g928f38d [ 8861.905283] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 8871.733848] Lustre: Unmounted lustre-client [ 8958.467421] Lustre: Mounted lustre-client - version 2.17.57_3_g928f38d [ 8961.296664] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 8963.773303] Lustre: Unmounted lustre-client [ 9004.494494] Lustre: DEBUG MARKER: == conf-sanity test 155: gap in seq allocation from ofd after restarting ========================================================== 19:44:27 (1786837467) [ 9043.859566] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9046.153862] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9055.941368] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9107.468663] Lustre: Mounted lustre-client - version 2.17.57_3_g928f38d [ 9110.257955] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9115.446642] Lustre: Unmounted lustre-client [ 9182.710988] Lustre: Mounted lustre-client - version 2.17.57_3_g928f38d [ 9185.447345] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9192.551896] Lustre: DEBUG MARKER: == conf-sanity test 156: root_fid on export consistent with client mount ========================================================== 19:47:35 (1786837655) [ 9192.872832] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 9203.134162] Lustre: Unmounted lustre-client [ 9280.241366] Lustre: Mounted lustre-client - version 2.17.57_3_g928f38d [ 9282.664130] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9284.914094] Lustre: Unmounted lustre-client [ 9287.992763] Lustre: DEBUG MARKER: == conf-sanity test 157a: test allow_register for MDT registration and MGS restart ========================================================== 19:49:10 (1786837750) [ 9329.589990] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9331.421708] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9338.214567] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9348.596602] Lustre: Mounted lustre-client - version 2.17.57_3_g928f38d [ 9348.598873] Lustre: Skipped 1 previous similar message [ 9379.298107] Lustre: lustre-MDT0000-mdc-ffff9c7158733000: Connection to lustre-MDT0000 (at 192.168.204.160@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9389.538226] LustreError: MGC192.168.204.160@tcp: Connection to MGS (at 192.168.204.160@tcp) was lost; in progress operations using this service will fail [ 9389.540461] LustreError: lustre-MDT0000-mdc-ffff9c7158733000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 9389.550844] Lustre: lustre-MDT0000-mdc-ffff9c7158733000: Connection restored to 192.168.204.160@tcp (at 192.168.204.160@tcp) [ 9389.551655] Lustre: Evicted from MGS (at 192.168.204.160@tcp) after server handle changed from 0x2ad92e6a90c370d to 0x2ad92e6a90c3a71 [ 9402.868137] Lustre: Unmounted lustre-client [ 9402.869223] Lustre: Skipped 1 previous similar message [ 9428.394228] Key type lgssc unregistered [ 9428.509534] LNet: 189554:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9428.511960] LNetError: 189554:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9428.521810] LNet: Removed LNI 192.168.204.60@tcp [ 9428.807128] Key type .llcrypt unregistered [ 9428.808346] Key type ._llcrypt unregistered [ 9432.400147] Key type ._llcrypt registered [ 9432.400968] Key type .llcrypt registered [ 9432.881345] Lustre: DEBUG MARKER: == conf-sanity test 157b: verify allow_register (block new OSTs, allow existing) ========================================================== 19:51:35 (1786837895) [ 9433.737291] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9433.744575] alg: No test for adler32 (adler32-zlib) [ 9434.611789] Lustre: Lustre: Build Version: 2.17.57_3_g928f38d [ 9434.703738] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 9436.295171] Key type lgssc registered [ 9436.660073] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9455.839183] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9457.618966] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9464.748638] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9475.087868] Lustre: Mounted lustre-client - version 2.17.57_3_g928f38d [ 9546.491947] Lustre: Unmounted lustre-client [ 9563.936663] Key type lgssc unregistered [ 9564.066633] LNet: 195550:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9564.069106] LNetError: 195550:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9564.079822] LNet: Removed LNI 192.168.204.60@tcp [ 9564.338112] Key type .llcrypt unregistered [ 9564.339484] Key type ._llcrypt unregistered [ 9568.167858] Key type ._llcrypt registered [ 9568.168934] Key type .llcrypt registered [ 9568.688154] Lustre: DEBUG MARKER: == conf-sanity test 160: MGC updates failnodes from all participants ========================================================== 19:53:51 (1786838031) [ 9579.132053] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9579.178174] alg: No test for adler32 (adler32-zlib) [ 9580.049562] Lustre: Lustre: Build Version: 2.17.57_3_g928f38d [ 9580.144904] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 9581.735193] Key type lgssc registered [ 9582.110949] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9613.141292] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9615.006340] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9623.567374] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9644.480656] Key type lgssc unregistered [ 9644.613530] LNet: 200514:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9644.616881] LNetError: 200514:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9644.627325] LNet: Removed LNI 192.168.204.60@tcp [ 9644.891833] Key type .llcrypt unregistered [ 9644.893127] Key type ._llcrypt unregistered [ 9763.017698] Key type ._llcrypt registered [ 9763.019172] Key type .llcrypt registered [ 9763.240508] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9763.245877] alg: No test for adler32 (adler32-zlib) [ 9764.120033] Lustre: Lustre: Build Version: 2.17.57_3_g928f38d [ 9764.223094] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 9765.815165] Key type lgssc registered [ 9766.246568] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9783.533026] Lustre: DEBUG MARKER: == conf-sanity test 161: test '-o mgsname' option ======== 19:57:26 (1786838246) [ 9798.607093] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9800.475103] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9808.523372] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9815.005459] Lustre: Mounted lustre-client - version 2.17.57_3_g928f38d [ 9816.403211] Lustre: Unmounted lustre-client [ 9816.502725] Lustre: Mounted lustre-client - version 2.17.57_3_g928f38d [ 9818.768185] Lustre: Unmounted lustre-client [ 9818.769208] Lustre: Skipped 1 previous similar message [ 9847.092429] Key type lgssc unregistered [ 9847.213581] LNet: 205480:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9847.216957] LNetError: 205480:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9847.225860] LNet: Removed LNI 192.168.204.60@tcp [ 9847.497715] Key type .llcrypt unregistered [ 9847.498949] Key type ._llcrypt unregistered [ 9851.101670] Key type ._llcrypt registered [ 9851.103189] Key type .llcrypt registered [ 9851.562190] Lustre: DEBUG MARKER: == conf-sanity test 162: replace nids with -o noclient === 19:58:34 (1786838314) [ 9852.675077] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9852.680174] alg: No test for adler32 (adler32-zlib) [ 9853.548373] Lustre: Lustre: Build Version: 2.17.57_3_g928f38d [ 9853.638328] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 9855.223197] Key type lgssc registered [ 9855.636863] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9873.302566] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9875.117544] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9881.606062] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9892.336174] Lustre: Mounted lustre-client - version 2.17.57_3_g928f38d [ 9895.436531] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9895.961915] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9897.442299] Lustre: lustre-MDT0000-mdc-ffff9c7148fe1800: Connection to lustre-MDT0000 (at 192.168.204.160@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9902.562348] Lustre: lustre-MDT0001-mdc-ffff9c7148fe1800: Connection to lustre-MDT0001 (at 192.168.204.160@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9907.682129] Lustre: lustre-OST0000-osc-ffff9c7148fe1800: Connection to lustre-OST0000 (at 192.168.204.160@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9917.919136] Lustre: 206155:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786838365/real 1786838365] req@00000000eb54050d x1873635774042752/t0(0) o400->MGC192.168.204.160@tcp@192.168.204.160@tcp:26/25 lens 224/224 e 0 to 1 dl 1786838381 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9917.927973] LustreError: MGC192.168.204.160@tcp: Connection to MGS (at 192.168.204.160@tcp) was lost; in progress operations using this service will fail [ 9928.165350] Lustre: Evicted from MGS (at 192.168.204.160@tcp) after server handle changed from 0xa7418c353a73247f to 0xa7418c353a732711 [ 9928.180354] Lustre: MGC192.168.204.160@tcp: Connection restored to 192.168.204.160@tcp (at 192.168.204.160@tcp) [ 9944.669456] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9947.622866] LustreError: lustre-MDT0000-mdc-ffff9c7148fe1800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 9947.637220] Lustre: lustre-MDT0000-mdc-ffff9c7148fe1800: Connection restored to 192.168.204.160@tcp (at 192.168.204.160@tcp) [ 9949.218918] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 3 sec [ 9954.142404] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9955.495467] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9958.270712] Lustre: Unmounted lustre-client [ 9978.789076] Key type lgssc unregistered [ 9978.925456] LNet: 211023:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9978.929164] LNetError: 211023:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9978.940788] LNet: Removed LNI 192.168.204.60@tcp [ 9979.217075] Key type .llcrypt unregistered [ 9979.218537] Key type ._llcrypt unregistered [ 9990.758212] Key type ._llcrypt registered [ 9990.759625] Key type .llcrypt registered [ 9990.949428] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9990.958990] alg: No test for adler32 (adler32-zlib) [ 9991.906271] Lustre: Lustre: Build Version: 2.17.57_3_g928f38d [ 9992.058669] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 9993.711174] Key type lgssc registered [ 9994.328566] Lustre: Echo OBD driver; http://www.lustre.org/ [10033.398146] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10035.757841] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10045.742886] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10071.128597] Lustre: DEBUG MARKER: == conf-sanity test 164: test expected_clients parameter and max client tracking ========================================================== 20:02:13 (1786838533) [10114.558897] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10117.338728] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10128.656549] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10129.964066] Lustre: Mounted lustre-client - version 2.17.57_3_g928f38d [10132.842970] Lustre: Mounted lustre-client - version 2.17.57_3_g928f38d [10134.131710] Lustre: Mounted lustre-client - version 2.17.57_3_g928f38d [10134.135109] Lustre: Skipped 3 previous similar messages [10135.525758] Lustre: Unmounted lustre-client [10136.239599] Lustre: Unmounted lustre-client [10137.564641] Lustre: Unmounted lustre-client [10137.566410] Lustre: Skipped 1 previous similar message [10142.958657] Lustre: Unmounted lustre-client [10142.962382] Lustre: Skipped 1 previous similar message [10162.161219] Key type lgssc unregistered [10162.311182] LNet: 220175:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10162.314898] LNetError: 220175:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10162.328036] LNet: Removed LNI 192.168.204.60@tcp [10162.665799] Key type .llcrypt unregistered [10162.667057] Key type ._llcrypt unregistered [10167.254270] Key type ._llcrypt registered [10167.256147] Key type .llcrypt registered [10167.908420] Lustre: DEBUG MARKER: == conf-sanity test 200a: check CPU partitions =========== 20:03:50 (1786838630) [10173.031292] Key type .llcrypt unregistered [10173.032619] Key type ._llcrypt unregistered [10173.421462] Key type ._llcrypt registered [10173.423012] Key type .llcrypt registered [10173.619896] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 4 [10173.627562] alg: No test for adler32 (adler32-zlib) [10174.566805] Lustre: Lustre: Build Version: 2.17.57_3_g928f38d [10174.690784] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [10176.303173] Key type lgssc registered [10176.908534] Lustre: Echo OBD driver; http://www.lustre.org/ [10188.159894] Key type lgssc unregistered [10188.303446] LNet: 222334:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10188.307196] LNetError: 222334:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10188.317305] LNet: Removed LNI 192.168.204.60@tcp [10188.668965] Key type .llcrypt unregistered [10188.671599] Key type ._llcrypt unregistered [10188.868885] Key type ._llcrypt registered [10188.870690] Key type .llcrypt registered [10189.566836] Lustre: DEBUG MARKER: == conf-sanity test 200b: set CPU pattern using core selection ========================================================== 20:04:11 (1786838651) [10195.229284] Key type .llcrypt unregistered [10195.230571] Key type ._llcrypt unregistered [10195.675796] Key type ._llcrypt registered [10195.677345] Key type .llcrypt registered [10195.896599] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10195.907722] alg: No test for adler32 (adler32-zlib) [10196.835393] Lustre: Lustre: Build Version: 2.17.57_3_g928f38d [10196.978070] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [10198.599289] Key type lgssc registered [10199.309754] Lustre: Echo OBD driver; http://www.lustre.org/ [10206.542230] Key type lgssc unregistered [10206.689807] LNet: 223891:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10206.693383] LNetError: 223891:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10206.704504] LNet: Removed LNI 192.168.204.60@tcp [10207.009694] Key type .llcrypt unregistered [10207.011328] Key type ._llcrypt unregistered [10209.878881] Key type ._llcrypt registered [10209.880558] Key type .llcrypt registered [10210.064615] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10210.073660] alg: No test for adler32 (adler32-zlib) [10210.988058] Lustre: Lustre: Build Version: 2.17.57_3_g928f38d [10211.113721] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [10212.735267] Key type lgssc registered [10213.366647] Lustre: Echo OBD driver; http://www.lustre.org/ [10224.468341] Key type lgssc unregistered [10224.620921] LNet: 225654:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10224.625224] LNetError: 225654:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10224.633581] LNet: Removed LNI 192.168.204.60@tcp [10224.918329] Key type .llcrypt unregistered [10224.919804] Key type ._llcrypt unregistered [10225.127284] Key type ._llcrypt registered [10225.129557] Key type .llcrypt registered [10225.782369] Lustre: DEBUG MARKER: == conf-sanity test 200c: set CPU pattern using NUMA node layout ========================================================== 20:04:48 (1786838688) [10231.246532] Key type .llcrypt unregistered [10231.248783] Key type ._llcrypt unregistered [10231.816187] Key type ._llcrypt registered [10231.817750] Key type .llcrypt registered [10231.909497] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10231.923557] alg: No test for adler32 (adler32-zlib) [10232.852193] Lustre: Lustre: Build Version: 2.17.57_3_g928f38d [10232.982984] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [10234.591251] Key type lgssc registered [10235.256075] Lustre: Echo OBD driver; http://www.lustre.org/ [10243.124287] Key type lgssc unregistered [10243.268824] LNet: 227205:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10243.273769] LNetError: 227205:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10243.283133] LNet: Removed LNI 192.168.204.60@tcp [10243.617591] Key type .llcrypt unregistered [10243.619147] Key type ._llcrypt unregistered [10246.908432] Key type ._llcrypt registered [10246.911589] Key type .llcrypt registered [10247.173904] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10247.181361] alg: No test for adler32 (adler32-zlib) [10248.149326] Lustre: Lustre: Build Version: 2.17.57_3_g928f38d [10248.291339] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [10249.919325] Key type lgssc registered [10250.589866] Lustre: Echo OBD driver; http://www.lustre.org/ [10258.595687] Key type lgssc unregistered [10258.757557] LNet: 228512:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10258.760481] LNetError: 228512:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10258.769511] LNet: Removed LNI 192.168.204.60@tcp [10259.088108] Key type .llcrypt unregistered [10259.090159] Key type ._llcrypt unregistered [10262.531250] Key type ._llcrypt registered [10262.532846] Key type .llcrypt registered [10262.734530] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10262.745588] alg: No test for adler32 (adler32-zlib) [10263.671409] Lustre: Lustre: Build Version: 2.17.57_3_g928f38d [10263.807884] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [10265.423144] Key type lgssc registered [10266.115779] Lustre: Echo OBD driver; http://www.lustre.org/ [10273.891706] Key type lgssc unregistered [10274.052420] LNet: 229817:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10274.056189] LNetError: 229817:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10274.071223] LNet: Removed LNI 192.168.204.60@tcp [10274.423093] Key type .llcrypt unregistered [10274.424174] Key type ._llcrypt unregistered [10277.526863] Key type ._llcrypt registered [10277.527953] Key type .llcrypt registered [10277.746176] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10277.764055] alg: No test for adler32 (adler32-zlib) [10278.679498] Lustre: Lustre: Build Version: 2.17.57_3_g928f38d [10278.821574] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [10280.423206] Key type lgssc registered [10281.068265] Lustre: Echo OBD driver; http://www.lustre.org/ [10292.242110] Key type lgssc unregistered [10292.372903] LNet: 231585:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10292.377124] LNetError: 231585:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10292.384975] LNet: Removed LNI 192.168.204.60@tcp [10292.715681] Key type .llcrypt unregistered [10292.717477] Key type ._llcrypt unregistered [10292.949957] Key type ._llcrypt registered [10292.952064] Key type .llcrypt registered [10293.583350] Lustre: DEBUG MARKER: == conf-sanity test 200d: set CPU pattern to exclude only CPU 1 ========================================================== 20:05:55 (1786838755) [10298.777257] Key type .llcrypt unregistered [10298.778919] Key type ._llcrypt unregistered [10299.135683] Key type ._llcrypt registered [10299.140266] Key type .llcrypt registered [10299.367093] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10299.374032] alg: No test for adler32 (adler32-zlib) [10300.275942] Lustre: Lustre: Build Version: 2.17.57_3_g928f38d [10300.399700] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [10301.999237] Key type lgssc registered [10302.556419] Lustre: Echo OBD driver; http://www.lustre.org/ [10309.258957] Key type lgssc unregistered [10309.390672] LNet: 233141:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10309.393912] LNetError: 233141:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10309.400567] LNet: Removed LNI 192.168.204.60@tcp [10309.676355] Key type .llcrypt unregistered [10309.677830] Key type ._llcrypt unregistered [10312.735312] Key type ._llcrypt registered [10312.737411] Key type .llcrypt registered [10312.896576] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10312.903418] alg: No test for adler32 (adler32-zlib) [10313.811613] Lustre: Lustre: Build Version: 2.17.57_3_g928f38d [10313.948678] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [10315.583192] Key type lgssc registered [10316.165556] Lustre: Echo OBD driver; http://www.lustre.org/ [10323.422669] Key type lgssc unregistered [10323.580415] LNet: 234456:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10323.586593] LNetError: 234456:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10323.600880] LNet: Removed LNI 192.168.204.60@tcp [10323.961895] Key type .llcrypt unregistered [10323.964485] Key type ._llcrypt unregistered [10326.767586] Key type ._llcrypt registered [10326.771308] Key type .llcrypt registered [10326.977847] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10326.986813] alg: No test for adler32 (adler32-zlib) [10327.910890] Lustre: Lustre: Build Version: 2.17.57_3_g928f38d [10328.033450] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [10329.639268] Key type lgssc registered [10330.226135] Lustre: Echo OBD driver; http://www.lustre.org/ [10336.901292] Key type lgssc unregistered [10337.052788] LNet: 235763:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10337.059406] LNetError: 235763:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10337.072933] LNet: Removed LNI 192.168.204.60@tcp [10337.404858] Key type .llcrypt unregistered [10337.410985] Key type ._llcrypt unregistered [10340.052994] Key type ._llcrypt registered [10340.054908] Key type .llcrypt registered [10340.276396] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10340.283074] alg: No test for adler32 (adler32-zlib) [10341.174201] Lustre: Lustre: Build Version: 2.17.57_3_g928f38d [10341.273589] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [10342.871253] Key type lgssc registered [10343.425909] Lustre: Echo OBD driver; http://www.lustre.org/ [10350.516232] Key type lgssc unregistered [10350.650758] LNet: 237073:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10350.653829] LNetError: 237073:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10350.663989] LNet: Removed LNI 192.168.204.60@tcp [10350.945755] Key type .llcrypt unregistered [10350.947923] Key type ._llcrypt unregistered [10353.887221] Key type ._llcrypt registered [10353.888899] Key type .llcrypt registered [10354.054188] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10354.061248] alg: No test for adler32 (adler32-zlib) [10354.975323] Lustre: Lustre: Build Version: 2.17.57_3_g928f38d [10355.112849] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [10356.735163] Key type lgssc registered [10357.348405] Lustre: Echo OBD driver; http://www.lustre.org/ [10364.149573] Key type lgssc unregistered [10364.285562] LNet: 238385:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10364.289889] LNetError: 238385:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10364.299373] LNet: Removed LNI 192.168.204.60@tcp [10364.602710] Key type .llcrypt unregistered [10364.604334] Key type ._llcrypt unregistered [10367.235365] Key type ._llcrypt registered [10367.236884] Key type .llcrypt registered [10367.371729] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10367.377531] alg: No test for adler32 (adler32-zlib) [10368.275058] Lustre: Lustre: Build Version: 2.17.57_3_g928f38d [10368.381261] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [10369.983181] Key type lgssc registered [10370.450409] Lustre: Echo OBD driver; http://www.lustre.org/ [10376.827736] Key type lgssc unregistered [10376.958983] LNet: 239690:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10376.963162] LNetError: 239690:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10376.975755] LNet: Removed LNI 192.168.204.60@tcp [10377.242088] Key type .llcrypt unregistered [10377.243596] Key type ._llcrypt unregistered [10387.188196] Key type ._llcrypt registered [10387.189526] Key type .llcrypt registered [10387.858949] Lustre: DEBUG MARKER: == conf-sanity test 200e: set CPU pattern using relative core exclusion ========================================================== 20:07:30 (1786838850) [10392.997679] Key type .llcrypt unregistered [10392.999358] Key type ._llcrypt unregistered [10393.347849] Key type ._llcrypt registered [10393.351431] Key type .llcrypt registered [10393.551514] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10393.557738] alg: No test for adler32 (adler32-zlib) [10394.462422] Lustre: Lustre: Build Version: 2.17.57_3_g928f38d [10394.593107] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [10396.207396] Key type lgssc registered [10396.766620] Lustre: Echo OBD driver; http://www.lustre.org/ [10403.861977] Key type lgssc unregistered [10403.997799] LNet: 242016:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10404.002644] LNetError: 242016:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10404.019750] LNet: Removed LNI 192.168.204.60@tcp [10404.350793] Key type .llcrypt unregistered [10404.352174] Key type ._llcrypt unregistered [10407.319862] Key type ._llcrypt registered [10407.321664] Key type .llcrypt registered [10407.515242] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10407.523282] alg: No test for adler32 (adler32-zlib) [10408.427641] Lustre: Lustre: Build Version: 2.17.57_3_g928f38d [10408.560630] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [10410.175217] Key type lgssc registered [10410.709689] Lustre: Echo OBD driver; http://www.lustre.org/ [10417.479704] Key type lgssc unregistered [10417.605455] LNet: 243334:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10417.607897] LNetError: 243334:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10417.617619] LNet: Removed LNI 192.168.204.60@tcp [10417.896972] Key type .llcrypt unregistered [10417.899076] Key type ._llcrypt unregistered [10420.652818] Key type ._llcrypt registered [10420.655905] Key type .llcrypt registered [10420.885140] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10420.892043] alg: No test for adler32 (adler32-zlib) [10421.790650] Lustre: Lustre: Build Version: 2.17.57_3_g928f38d [10421.902519] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [10423.511154] Key type lgssc registered [10423.986371] Lustre: Echo OBD driver; http://www.lustre.org/ [10430.871458] Key type lgssc unregistered [10430.989434] LNet: 244644:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10430.992375] LNetError: 244644:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10431.001436] LNet: Removed LNI 192.168.204.60@tcp [10431.265054] Key type .llcrypt unregistered [10431.266287] Key type ._llcrypt unregistered [10433.589920] Key type ._llcrypt registered [10433.591514] Key type .llcrypt registered [10433.785393] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10433.791650] alg: No test for adler32 (adler32-zlib) [10434.659289] Lustre: Lustre: Build Version: 2.17.57_3_g928f38d [10434.771446] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [10436.367322] Key type lgssc registered [10436.870460] Lustre: Echo OBD driver; http://www.lustre.org/ [10445.693921] Key type lgssc unregistered [10445.818507] LNet: 246435:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10445.821466] LNetError: 246435:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10445.831077] LNet: Removed LNI 192.168.204.60@tcp [10446.091028] Key type .llcrypt unregistered [10446.092360] Key type ._llcrypt unregistered [10446.272308] Key type ._llcrypt registered [10446.273745] Key type .llcrypt registered [10446.804085] Lustre: DEBUG MARKER: == conf-sanity test 250: verify lcfg_erase removes filesystem parameters ========================================================== 20:08:29 (1786838909) [10453.502568] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10453.529313] alg: No test for adler32 (adler32-zlib) [10454.434559] Lustre: Lustre: Build Version: 2.17.57_3_g928f38d [10454.556886] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [10456.151231] Key type lgssc registered [10456.712212] Lustre: Echo OBD driver; http://www.lustre.org/ [10576.756340] Lustre: DEBUG MARKER: == conf-sanity test 802a: simulate readonly device ======= 20:10:39 (1786839039) [10577.472209] Lustre: DEBUG MARKER: SKIP: conf-sanity test_802a ZFS specific test [10600.394319] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 10325 sec ========= 20:11:02 (1786839062) [10601.029740] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 20:11:03 (1786839063) === [10602.348971] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 20:11:04 (1786839064) === [10615.004671] Key type lgssc unregistered [10615.139603] LNet: 255468:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10615.143048] LNetError: 255468:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10615.150967] LNet: Removed LNI 192.168.204.60@tcp [10615.424120] Key type .llcrypt unregistered [10615.425901] Key type ._llcrypt unregistered