[ 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 484136264 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2576MB 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.001009] APIC: Switch to symmetric I/O mode setup [ 0.002353] x2apic enabled [ 0.003008] Switched APIC routing to physical x2apic. [ 0.004012] kvm-guest: setup PV IPIs [ 0.006809] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.007000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.007029] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.008012] pid_max: default: 32768 minimum: 301 [ 0.009127] LSM: Security Framework initializing [ 0.011021] Yama: becoming mindful. [ 0.011898] SELinux: Initializing. [ 0.012057] *** VALIDATE selinux *** [ 0.020436] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.025124] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.026152] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027102] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028104] *** VALIDATE tmpfs *** [ 0.030253] *** VALIDATE proc *** [ 0.031236] *** VALIDATE cgroup *** [ 0.032007] *** VALIDATE cgroup2 *** [ 0.033293] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.034144] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.035008] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.036033] Spectre V2 : User space: Vulnerable [ 0.037006] Speculative Store Bypass: Vulnerable [ 0.040491] debug: unmapping init [mem 0xffffffff93e59000-0xffffffff93e60fff] [ 0.042160] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.043644] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.044021] ... version: 2 [ 0.045008] ... bit width: 48 [ 0.046010] ... generic registers: 4 [ 0.046985] ... value mask: 0000ffffffffffff [ 0.047017] ... max period: 00007fffffffffff [ 0.048010] ... fixed-purpose events: 3 [ 0.049008] ... event mask: 000000070000000f [ 0.050281] rcu: Hierarchical SRCU implementation. [ 0.052387] smp: Bringing up secondary CPUs ... [ 0.053555] x86: Booting SMP configuration: [ 0.054023] .... node #0, CPUs: #1 #2 #3 [ 0.057510] smp: Brought up 1 node, 4 CPUs [ 0.059019] smpboot: Max logical packages: 1 [ 0.060016] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.138365] node 0 deferred pages initialised in 76ms [ 0.141100] devtmpfs: initialized [ 0.142265] x86/mm: Memory block size: 128MB [ 0.145588] gcov: version magic: 0x41383552 [ 0.149271] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.150152] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.151315] pinctrl core: initialized pinctrl subsystem [ 0.152221] [ 0.152610] ************************************************************* [ 0.154011] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.156011] ** ** [ 0.158010] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.159010] ** ** [ 0.161013] ** This means that this kernel is built to expose internal ** [ 0.163021] ** IOMMU data structures, which may compromise security on ** [ 0.165010] ** your system. ** [ 0.167009] ** ** [ 0.168010] ** If you see this message and you are not debugging the ** [ 0.170010] ** kernel, report this immediately to your vendor! ** [ 0.172011] ** ** [ 0.174010] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.175009] ************************************************************* [ 0.177811] NET: Registered protocol family 16 [ 0.179501] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.182066] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.184074] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.187032] cpuidle: using governor menu [ 0.188829] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.192670] PCI: Using configuration type 1 for base access [ 0.195155] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.209056] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.212093] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.216132] cryptd: max_cpu_qlen set to 1000 [ 0.222346] ACPI: Added _OSI(Module Device) [ 0.224015] ACPI: Added _OSI(Processor Device) [ 0.226012] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.227012] ACPI: Added _OSI(Processor Aggregator Device) [ 0.232102] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.237447] ACPI: Interpreter enabled [ 0.239050] ACPI: PM: (supports S0 S3 S4 S5) [ 0.240024] ACPI: Using IOAPIC for interrupt routing [ 0.242201] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.244345] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.256000] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.259043] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.261023] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.264069] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.269382] acpiphp: Slot [2] registered [ 0.271228] acpiphp: Slot [5] registered [ 0.273171] acpiphp: Slot [6] registered [ 0.277185] acpiphp: Slot [3] registered [ 0.278157] acpiphp: Slot [4] registered [ 0.280144] acpiphp: Slot [7] registered [ 0.282129] acpiphp: Slot [8] registered [ 0.284150] acpiphp: Slot [9] registered [ 0.285149] acpiphp: Slot [10] registered [ 0.287157] acpiphp: Slot [11] registered [ 0.289229] acpiphp: Slot [12] registered [ 0.291130] acpiphp: Slot [13] registered [ 0.293149] acpiphp: Slot [14] registered [ 0.294172] acpiphp: Slot [15] registered [ 0.296130] acpiphp: Slot [16] registered [ 0.298123] acpiphp: Slot [17] registered [ 0.299069] acpiphp: Slot [18] registered [ 0.300093] acpiphp: Slot [19] registered [ 0.302083] acpiphp: Slot [20] registered [ 0.303136] acpiphp: Slot [21] registered [ 0.305112] acpiphp: Slot [22] registered [ 0.306096] acpiphp: Slot [23] registered [ 0.308084] acpiphp: Slot [24] registered [ 0.309182] acpiphp: Slot [25] registered [ 0.311085] acpiphp: Slot [26] registered [ 0.312090] acpiphp: Slot [27] registered [ 0.314150] acpiphp: Slot [28] registered [ 0.315148] acpiphp: Slot [29] registered [ 0.316138] acpiphp: Slot [30] registered [ 0.318168] acpiphp: Slot [31] registered [ 0.319050] PCI host bridge to bus 0000:00 [ 0.320017] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.322022] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.324033] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.326021] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.328019] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.330023] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.331153] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.333890] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.336265] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.343669] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.347055] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.350018] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.352011] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.354029] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.357287] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.360179] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.364053] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.368810] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.373000] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.382664] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.388017] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.395706] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.410016] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.424015] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.443022] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.453059] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.461959] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.470018] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.489032] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.505213] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.506363] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.508346] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.510370] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.512229] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.517127] iommu: Default domain type: Passthrough [ 0.519645] SCSI subsystem initialized [ 0.521137] ACPI: bus type USB registered [ 0.522177] usbcore: registered new interface driver usbfs [ 0.524119] usbcore: registered new interface driver hub [ 0.526114] usbcore: registered new device driver usb [ 0.527198] pps_core: LinuxPPS API ver. 1 registered [ 0.529020] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.532057] PTP clock support registered [ 0.533443] EDAC MC: Ver: 3.0.0 [ 0.535186] PCI: Using ACPI for IRQ routing [ 0.538139] NetLabel: Initializing [ 0.539014] NetLabel: domain hash size = 128 [ 0.540014] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.542113] NetLabel: unlabeled traffic allowed by default [ 0.544378] vgaarb: loaded [ 0.546274] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.547017] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.553407] clocksource: Switched to clocksource kvm-clock [ 0.675499] VFS: Disk quotas dquot_6.6.0 [ 0.677179] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.680028] *** VALIDATE ramfs *** [ 0.681296] *** VALIDATE hugetlbfs *** [ 0.683652] pnp: PnP ACPI init [ 0.686214] pnp: PnP ACPI: found 6 devices [ 0.701803] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.704228] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.706194] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.707680] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.709416] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.711187] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.713235] NET: Registered protocol family 2 [ 0.715153] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.719197] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.721839] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.726116] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.729072] TCP: Hash tables configured (established 65536 bind 65536) [ 0.731236] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.734518] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.737180] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.740341] NET: Registered protocol family 1 [ 0.743186] RPC: Registered named UNIX socket transport module. [ 0.745754] RPC: Registered udp transport module. [ 0.747159] RPC: Registered tcp transport module. [ 0.748557] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.750582] NET: Registered protocol family 44 [ 0.752062] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.753977] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.755799] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.757902] PCI: CLS 0 bytes, default 64 [ 0.759348] Unpacking initramfs... [ 2.376948] debug: unmapping init [mem 0xffff93373cc64000-0xffff93373ffcffff] [ 2.380651] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.382584] software IO TLB: mapped [mem 0x00000000b8c64000-0x00000000bcc64000] (64MB) [ 2.385174] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.925244] Initialise system trusted keyrings [ 2.928650] Key type blacklist registered [ 2.930836] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.944154] zbud: loaded [ 2.948099] *** VALIDATE nfs *** [ 2.949366] *** VALIDATE nfs4 *** [ 2.951458] pstore: using deflate compression [ 2.955959] Platform Keyring initialized [ 3.071585] NET: Registered protocol family 38 [ 3.073740] Key type asymmetric registered [ 3.075245] Asymmetric key parser 'x509' registered [ 3.077751] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.083086] io scheduler mq-deadline registered [ 3.084931] io scheduler kyber registered [ 3.086686] io scheduler bfq registered [ 3.089098] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.093810] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.098227] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.102175] ACPI: Power Button [PWRF] [ 3.108175] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.117673] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.131602] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.159792] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.188407] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.194243] Non-volatile memory driver v1.3 [ 3.196362] Linux agpgart interface v0.103 [ 3.228351] virtio_blk virtio1: [vda] 149952 512-byte logical blocks (76.8 MB/73.2 MiB) [ 3.231915] vda: detected capacity change from 0 to 76775424 [ 3.253559] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.256073] vdb: detected capacity change from 0 to 1073741824 [ 3.262936] libphy: Fixed MDIO Bus: probed [ 3.268172] usbcore: registered new interface driver usbserial_generic [ 3.270556] usbserial: USB Serial support registered for generic [ 3.272949] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.277427] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.278757] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.281758] mousedev: PS/2 mouse device common for all mice [ 3.308509] rtc_cmos 00:05: RTC can wake from S4 [ 3.312198] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.312598] rtc_cmos 00:05: registered as rtc0 [ 3.317610] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.320837] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.322316] intel_pstate: CPU model not supported [ 3.327644] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.332793] hid: raw HID events driver (C) Jiri Kosina [ 3.336284] usbcore: registered new interface driver usbhid [ 3.338044] usbhid: USB HID core driver [ 3.339752] drop_monitor: Initializing network drop monitor service [ 3.341940] Initializing XFRM netlink socket [ 3.343964] NET: Registered protocol family 10 [ 3.346868] Segment Routing with IPv6 [ 3.348198] NET: Registered protocol family 17 [ 3.350650] mpls_gso: MPLS GSO support [ 3.363202] RAS: Correctable Errors collector initialized. [ 3.365665] AVX version of gcm_enc/dec engaged. [ 3.367623] AES CTR mode by8 optimization enabled [ 3.466526] sched_clock: Marking stable (3466428163, 0)->(4339176150, -872747987) [ 3.470511] registered taskstats version 1 [ 3.472859] Loading compiled-in X.509 certificates [ 3.476782] zswap: loaded using pool lzo/zbud [ 3.511371] Key type big_key registered [ 3.528092] Key type encrypted registered [ 3.530307] ima: No TPM chip found, activating TPM-bypass! [ 3.532843] ima: Allocated hash algorithm: sha1 [ 3.534901] ima: No architecture policies found [ 3.537190] evm: Initialising EVM extended attributes: [ 3.539613] evm: security.selinux [ 3.541144] evm: security.ima [ 3.542492] evm: security.capability [ 3.544100] evm: HMAC attrs: 0x1 [ 3.547121] rtc_cmos 00:05: setting system clock to 2026-09-10 17:56:51 UTC (1789063011) [ 3.555608] debug: unmapping init [mem 0xffffffff94e03000-0xffffffff94ffffff] [ 3.559378] debug: unmapping init [mem 0xffffffff93b82000-0xffffffff93e58fff] [ 3.568252] Write protecting the kernel read-only data: 28672k [ 3.572738] debug: unmapping init [mem 0xffffffff92203000-0xffffffff923fffff] [ 3.576231] debug: unmapping init [mem 0xffffffff92b14000-0xffffffff92bfffff] [ 3.621618] 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.632370] systemd[1]: Detected virtualization kvm. [ 3.634447] systemd[1]: Detected architecture x86-64. [ 3.636205] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.660129] systemd[1]: No hostname configured. [ 3.662209] systemd[1]: Set hostname to . [ 3.664470] random: systemd: uninitialized urandom read (16 bytes read) [ 3.667331] systemd[1]: Initializing machine ID from random generator. [ 3.743431] random: ln: uninitialized urandom read (6 bytes read) [ 3.818475] random: systemd: uninitialized urandom read (16 bytes read) [ 3.821420] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ 3.827187] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 3.831729] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Swap. [ OK ] Reached target Initrd Root Device. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Paths. [ OK ] Reached target Local File Systems. [ OK ] Listening on Journal Socket. Starting Create Volatile Files and Directories... Starting Apply Kernel Variables... Starting Setup Virtual Console... [ OK ] Started Memstrack Anylazing Service. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. Starting Journal Service... [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.538669] device-mapper: uevent: version 1.0.3 [ 4.541696] 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 ] [ 5.273984] random: fast init done Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 5.325252] virtio_net virtio0 ens2: renamed from eth0 [ 5.394169] scsi host0: ata_piix [ 5.411928] scsi host1: ata_piix [ 5.413574] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.416288] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.063821] dracut-initqueue[575]: RTNETLINK answers: File exists [ 10.049969] random: crng init done [ 10.051671] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 10.545082] 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 Initrd Root Device. [ 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 Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Swap. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ 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 Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.722803] printk: systemd: 26 output lines suppressed due to ratelimiting [ 12.006374] SELinux: Disabled at runtime. [ 12.064615] 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) [ 12.075347] systemd[1]: Detected virtualization kvm. [ 12.076784] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.733419] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.737265] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.743852] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.748920] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.753695] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.761531] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.776890] systemd[1]: Activating swap /dev/disk/by-label/SWAP... Activating swap /dev/disk/by-label/SWAP... Mounting Huge Pages File System... [ OK ] Listening on udev Control Socket. [ 12.815259] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target rpc_pipefs.target. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Starting Remount Root and Kernel File Systems... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Created slice User and Session Slice. Mounting Kernel Debug File System... Starting Apply Kernel Variables... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Created slice system-getty.slice. Mounting POSIX Message Queue File System... [ OK ] Reached target RPC Port Mapper. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Listening on Process Core Dump Socket. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Stopped target Initrd File Systems. [ OK ] Reached target Slices. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Started Journal Service. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Huge Pages File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted POSIX Message Queue File System. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 13.275826] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 13.645181] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.695775] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.767621] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.780567] EDAC sbridge: Ver: 1.1.2 [ 14.847650] Key type dns_resolver registered [ 15.175184] NFS: Registering the id_resolver key type [ 15.178911] Key type id_resolver registered [ 15.180482] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... 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 RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started dnf makecache --timer. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Reached target sshd-keygen.target. Starting Login Service... Starting Network Manager... [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... [ OK ] Started OpenSSH server daemon. Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg446-client login: [ 53.952288] libcfs: loading out-of-tree module taints kernel. [ 54.179664] Key type ._llcrypt registered [ 54.198584] Key type .llcrypt registered [ 55.004023] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 55.019955] alg: No test for adler32 (adler32-zlib) [ 56.383889] Lustre: Lustre: Build Version: 2.17.58_39_ga794951 [ 57.543255] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 59.471323] Key type lgssc registered [ 61.692164] Lustre: Echo OBD driver; http://www.lustre.org/ [ 61.920077] hrtimer: interrupt took 8064161 ns [ 272.421543] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [ 278.229511] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 296.213903] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing check_logdir /tmp/testlogs/ [ 297.952318] Lustre: lustre-OST0000-osc-ffff933787ef2000: disconnect after 23s idle [ 302.916516] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing yml_node [ 309.153956] Lustre: DEBUG MARKER: Client: 2.17.58.39 [ 312.525782] Lustre: DEBUG MARKER: MDS: 2.17.58.39 [ 315.697190] Lustre: DEBUG MARKER: OSS: 2.17.58.39 [ 318.067441] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Thu Sep 10 14:02:03 EDT 2026 [ 338.144811] Lustre: DEBUG MARKER: excepting tests: 32newtarball [ 339.738622] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 343.154655] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 353.554701] Lustre: Unmounted lustre-client [ 490.834786] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 497.607330] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 530.273214] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 585.383134] Lustre: DEBUG MARKER: == conf-sanity test 121: failover MGS ==================== 14:06:31 (1789063591) [ 642.445868] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 649.331543] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 705.080039] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid 1475 0 [ 770.190617] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 777.745912] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 818.417206] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid 1475 0 [ 845.894725] Lustre: DEBUG MARKER: == conf-sanity test 122a: Check OST sequence update ====== 14:10:51 (1789063851) [ 993.132172] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1000.535877] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1029.619801] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1030.506401] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [ 1056.562244] Lustre: Unmounted lustre-client [ 1097.467257] Key type lgssc unregistered [ 1097.916222] LNet: 21917:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1097.935102] LNetError: 21917:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1097.959105] LNet: Removed LNI 192.168.204.46@tcp [ 1099.196729] Key type .llcrypt unregistered [ 1099.198703] Key type ._llcrypt unregistered [ 1119.200900] Key type ._llcrypt registered [ 1119.207772] Key type .llcrypt registered [ 1121.504709] Lustre: DEBUG MARKER: == conf-sanity test 123aa: llog_print works with FIDs and simple names ========================================================== 14:15:26 (1789064126) [ 1125.599846] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1125.816864] alg: No test for adler32 (adler32-zlib) [ 1127.296485] Lustre: Lustre: Build Version: 2.17.58_39_ga794951 [ 1128.036132] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 1130.015382] Key type lgssc registered [ 1132.151355] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1201.373217] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1209.678807] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1242.903729] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1245.007640] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [ 1263.618337] Lustre: DEBUG MARKER: == conf-sanity test 123ab: llog_print params output values from set_param -P ========================================================== 14:17:49 (1789064269) [ 1279.249883] Lustre: DEBUG MARKER: == conf-sanity test 123ac: llog_print with --start and --end ========================================================== 14:18:05 (1789064285) [ 1291.749215] Lustre: DEBUG MARKER: == conf-sanity test 123ad: llog_print shows all records == 14:18:17 (1789064297) [ 1306.899690] Lustre: DEBUG MARKER: == conf-sanity test 123ae: llog_cancel can cancel requested record ========================================================== 14:18:32 (1789064312) [ 1339.611799] Lustre: DEBUG MARKER: == conf-sanity test 123af: llog_catlist can show all config files correctly ========================================================== 14:19:05 (1789064345) [ 1358.791406] Lustre: DEBUG MARKER: == conf-sanity test 123ag: llog_print skips values deleted by set_param -P -d ========================================================== 14:19:25 (1789064365) [ 1377.758505] Lustre: DEBUG MARKER: == conf-sanity test 123ah: del_ost cancels config log entries correctly ========================================================== 14:19:43 (1789064383) [ 1390.055246] Lustre: Unmounted lustre-client [ 1390.859835] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [ 1394.487925] Lustre: Unmounted lustre-client [ 1427.001419] Key type lgssc unregistered [ 1427.381615] LNet: 30298:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1427.392476] LNetError: 30298:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1427.424928] LNet: Removed LNI 192.168.204.46@tcp [ 1428.435291] Key type .llcrypt unregistered [ 1428.441111] Key type ._llcrypt unregistered [ 1462.974980] Key type ._llcrypt registered [ 1462.985041] Key type .llcrypt registered [ 1463.839697] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1463.865900] alg: No test for adler32 (adler32-zlib) [ 1465.194075] Lustre: Lustre: Build Version: 2.17.58_39_ga794951 [ 1465.455437] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 1467.183479] Key type lgssc registered [ 1468.460545] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1574.587608] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1581.866512] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1609.795765] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1655.051717] Lustre: DEBUG MARKER: == conf-sanity test 123ai: llog_print display all non skipped records ========================================================== 14:24:21 (1789064661) [ 1695.303597] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1702.518430] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1729.223462] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1735.281281] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [ 1897.177170] Lustre: DEBUG MARKER: == conf-sanity test 123aj: check permanent TBF rules ===== 14:28:23 (1789064903) [ 1948.253554] Lustre: DEBUG MARKER: == conf-sanity test 123F: clear and reset all parameters using set_param -F ========================================================== 14:29:14 (1789064954) [ 1960.021852] Lustre: Unmounted lustre-client [ 2050.431705] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2057.971431] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2088.723972] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2089.795075] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [ 2097.222128] Lustre: Unmounted lustre-client [ 2127.630634] Key type lgssc unregistered [ 2127.966332] LNet: 41641:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2127.975666] LNetError: 41641:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2127.994299] LNet: Removed LNI 192.168.204.46@tcp [ 2129.062381] Key type .llcrypt unregistered [ 2129.065291] Key type ._llcrypt unregistered [ 2148.465393] Key type ._llcrypt registered [ 2148.469891] Key type .llcrypt registered [ 2150.401944] Lustre: DEBUG MARKER: == conf-sanity test 123G: clear and reset all parameters using apply_yaml ========================================================== 14:32:36 (1789065156) [ 2153.243705] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2153.380062] alg: No test for adler32 (adler32-zlib) [ 2154.706961] Lustre: Lustre: Build Version: 2.17.58_39_ga794951 [ 2155.138269] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 2157.007921] Key type lgssc registered [ 2158.913504] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2216.361564] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2222.753298] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2249.297576] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2251.288834] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [ 2263.233185] Lustre: Unmounted lustre-client [ 2361.998219] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2369.776968] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2394.923693] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2396.097618] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [ 2402.642742] Lustre: Unmounted lustre-client [ 2441.007387] Key type lgssc unregistered [ 2441.410351] LNet: 47196:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2441.417585] LNetError: 47196:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2441.452500] LNet: Removed LNI 192.168.204.46@tcp [ 2442.375157] Key type .llcrypt unregistered [ 2442.378156] Key type ._llcrypt unregistered [ 2463.453889] Key type ._llcrypt registered [ 2463.456358] Key type .llcrypt registered [ 2465.118995] Lustre: DEBUG MARKER: == conf-sanity test 123H: check concurent accesses with 'lctl llog_print ========================================================== 14:37:51 (1789065471) [ 2468.219003] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2468.230696] alg: No test for adler32 (adler32-zlib) [ 2469.372226] Lustre: Lustre: Build Version: 2.17.58_39_ga794951 [ 2469.643596] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 2471.377629] Key type lgssc registered [ 2473.578674] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2539.616720] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2547.465362] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2573.625920] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2581.051430] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [ 2703.601834] Lustre: DEBUG MARKER: == conf-sanity test 124: check failover after replace_nids ========================================================== 14:41:49 (1789065709) [ 2705.892584] Lustre: DEBUG MARKER: SKIP: conf-sanity test_124 needs MDT failover setup [ 2708.000905] Lustre: DEBUG MARKER: == conf-sanity test 126: mount in parallel shouldn't cause a crash ========================================================== 14:41:54 (1789065714) [ 2710.585021] Lustre: Unmounted lustre-client [ 2757.848796] Key type lgssc unregistered [ 2758.236573] LNet: 52547:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2758.253060] LNetError: 52547:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2758.310389] LNet: Removed LNI 192.168.204.46@tcp [ 2759.609424] Key type .llcrypt unregistered [ 2759.614287] Key type ._llcrypt unregistered [ 2797.368783] Key type ._llcrypt registered [ 2797.373668] Key type .llcrypt registered [ 2797.857638] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2797.907722] alg: No test for adler32 (adler32-zlib) [ 2799.126754] Lustre: Lustre: Build Version: 2.17.58_39_ga794951 [ 2799.584323] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 2801.442734] Key type lgssc registered [ 2803.253553] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2847.337589] Lustre: DEBUG MARKER: == conf-sanity test 127: direct io overwrite on full ost ========================================================== 14:44:12 (1789065852) [ 2864.794820] Key type lgssc unregistered [ 2865.252334] LNet: 54958:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2865.258500] LNetError: 54958:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2865.276213] LNet: Removed LNI 192.168.204.46@tcp [ 2866.368341] Key type .llcrypt unregistered [ 2866.371874] Key type ._llcrypt unregistered [ 2881.571182] Key type ._llcrypt registered [ 2881.576284] Key type .llcrypt registered [ 2882.519084] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2882.568123] alg: No test for adler32 (adler32-zlib) [ 2883.887466] Lustre: Lustre: Build Version: 2.17.58_39_ga794951 [ 2884.364280] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 2886.175812] Key type lgssc registered [ 2888.057828] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2944.018688] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2950.109771] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2980.609042] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2988.128118] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [ 3045.287187] Lustre: DEBUG MARKER: == conf-sanity test 128: Force using remote logs with --nolocallogs ========================================================== 14:47:31 (1789066051) [ 3047.787690] Lustre: DEBUG MARKER: SKIP: conf-sanity test_128 need separate mgs device [ 3050.056655] Lustre: DEBUG MARKER: == conf-sanity test 129: attempt to connect an OST with the same index should fail ========================================================== 14:47:36 (1789066056) [ 3052.861670] Lustre: Unmounted lustre-client [ 3130.249960] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3137.877881] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3224.615467] Lustre: DEBUG MARKER: == conf-sanity test 130: re-register an MDT after writeconf ========================================================== 14:50:30 (1789066230) [ 3321.006310] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [ 3327.078098] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3341.820800] Lustre: lustre-MDT0001-mdc-ffff933783eef000: Connection to lustre-MDT0001 (at 192.168.204.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3346.911690] Lustre: lustre-OST0000-osc-ffff933783eef000: disconnect after 23s idle [ 3359.252617] LustreError: 63655:0:(obd_config.c:2102:class_config_llog_handler()) MGC192.168.204.146@tcp: cfg command failed: rc = -17 [ 3359.270262] Lustre: cmd=cf001 0:lustre-MDT0001-mdc 1:mdc 2:lustre-clilmv_UUID [ 3359.270262] [ 3359.282465] LustreError: 62915:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [ 3375.747445] Lustre: DEBUG MARKER: == conf-sanity test 131: MDT backup restore with project ID and inheritance flag ========================================================== 14:53:01 (1789066381) [ 3451.600554] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3469.791807] Lustre: lustre-OST0000-osc-ffff933783eef000: disconnect after 22s idle [ 3469.802873] Lustre: Skipped 1 previous similar message [ 3550.013037] Lustre: Unmounted lustre-client [ 3720.840393] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [ 3726.740346] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3789.875276] Lustre: DEBUG MARKER: == conf-sanity test 132: hsm_actions processed after failover ========================================================== 14:59:56 (1789066796) [ 3792.828823] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3793.575503] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 3793.595922] Lustre: Skipped 1 previous similar message [ 3812.455410] Lustre: Unmounted lustre-client [ 3967.759400] Lustre: DEBUG MARKER: == conf-sanity test 133: stripe QOS: free space balance in a pool ========================================================== 15:02:53 (1789066973) [ 3969.327389] Lustre: DEBUG MARKER: SKIP: conf-sanity test_133 needs >= 4 OSTs [ 3971.238609] Lustre: DEBUG MARKER: == conf-sanity test 134: check_iam works without faults == 15:02:57 (1789066977) [ 4062.871821] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [ 4068.738396] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 4849.110880] Lustre: DEBUG MARKER: == conf-sanity test 135: check the behavior when changelog is wrapped around ========================================================== 15:17:35 (1789067855) [ 4852.088144] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4852.098442] Lustre: Skipped 1 previous similar message [ 4862.440705] Lustre: Unmounted lustre-client [ 5020.711910] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5026.491115] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5052.831619] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5053.665855] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [ 7229.687625] Lustre: 95334:0:(llog_cat.c:310:llog_cat_process_or_fork()) lustre-MDT0000-mdc-ffff9337887c8800: catlog [0xa:0x5:0x0] crosses index zero [ 7259.432911] Lustre: Unmounted lustre-client [ 7292.948804] Key type lgssc unregistered [ 7293.331413] LNet: 103557:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7293.366216] LNetError: 103557:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7293.416241] LNet: Removed LNI 192.168.204.46@tcp [ 7294.117234] Key type .llcrypt unregistered [ 7294.121975] Key type ._llcrypt unregistered [ 7307.589548] Key type ._llcrypt registered [ 7307.593399] Key type .llcrypt registered [ 7309.564513] Lustre: DEBUG MARKER: == conf-sanity test 136: don't panic with bad obdecho setup ========================================================== 15:58:35 (1789070315) [ 7332.094121] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7332.146490] alg: No test for adler32 (adler32-zlib) [ 7333.312552] Lustre: Lustre: Build Version: 2.17.58_39_ga794951 [ 7333.579008] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 7335.287282] Key type lgssc registered [ 7336.830099] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7433.395604] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7440.327189] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7469.171800] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7472.029924] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [ 7491.297820] Lustre: Unmounted lustre-client [ 7632.343861] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7639.996590] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7669.511696] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7709.792363] Lustre: DEBUG MARKER: == conf-sanity test 137: a new MDT should inherit pools, parameters and failnode ========================================================== 16:05:16 (1789070716) [ 7711.393528] Lustre: DEBUG MARKER: SKIP: conf-sanity test_137 needs >= 3 MDTs [ 7713.549678] Lustre: DEBUG MARKER: == conf-sanity test 140: remove_updatelog script actions ========================================================== 16:05:19 (1789070719) [ 7754.710726] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7761.546837] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7787.757815] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7798.877582] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [ 7809.031317] Lustre: lustre-MDT0000-mdc-ffff93379012b800: Connection to lustre-MDT0000 (at 192.168.204.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7819.248636] Lustre: lustre-MDT0001-mdc-ffff93379012b800: Connection to lustre-MDT0001 (at 192.168.204.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7830.495204] Lustre: 104315:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789070822/real 1789070822] req@00000000e00fb199 x1875976221962368/t0(0) o400->MGC192.168.204.146@tcp@192.168.204.146@tcp:26/25 lens 224/224 e 0 to 1 dl 1789070838 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 7830.543156] LustreError: MGC192.168.204.146@tcp: Connection to MGS (at 192.168.204.146@tcp) was lost; in progress operations using this service will fail [ 7976.941878] Lustre: Evicted from MGS (at 192.168.204.146@tcp) after server handle changed from 0xe2c1ed734ec4c57f to 0xe2c1ed734ec4dc1c [ 7976.953104] Lustre: MGC192.168.204.146@tcp: Connection restored to 192.168.204.146@tcp (at 192.168.204.146@tcp) [ 7986.678343] Lustre: 113717:0:(mgc_request.c:1900:mgc_process_log()) MGC192.168.204.146@tcp: IR log lustre-cliir failed, not fatal: rc = -2 [ 7992.348954] LustreError: lustre-MDT0000-mdc-ffff93379012b800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 7992.389453] Lustre: lustre-MDT0000-mdc-ffff93379012b800: Connection restored to 192.168.204.146@tcp (at 192.168.204.146@tcp) [ 7993.861753] LustreError: lustre-MDT0001-mdc-ffff93379012b800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 7993.878317] Lustre: lustre-MDT0001-mdc-ffff93379012b800: Connection restored to 192.168.204.146@tcp (at 192.168.204.146@tcp) [ 8002.547535] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8004.875901] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 8013.319305] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8015.878306] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 8034.742252] Lustre: Unmounted lustre-client [ 8186.877855] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8193.210642] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8220.210880] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8275.919302] Lustre: DEBUG MARKER: == conf-sanity test 150: test setting max_cached_mb to a % ========================================================== 16:14:42 (1789071282) [ 8317.274206] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8324.754649] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8351.066244] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8357.476757] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [ 8360.238414] LustreError: 121054: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 [ 8370.761693] Lustre: DEBUG MARKER: == conf-sanity test 151a: damaged local config doesn't prevent mounting ========================================================== 16:16:16 (1789071376) [ 8373.132855] Lustre: Unmounted lustre-client [ 8412.492022] Key type lgssc unregistered [ 8412.717824] LNet: 122063:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8412.723546] LNetError: 122063:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8412.739815] LNet: Removed LNI 192.168.204.46@tcp [ 8413.701325] Key type .llcrypt unregistered [ 8413.704305] Key type ._llcrypt unregistered [ 8428.814719] Key type ._llcrypt registered [ 8428.816428] Key type .llcrypt registered [ 8429.251857] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8429.306849] alg: No test for adler32 (adler32-zlib) [ 8430.530956] Lustre: Lustre: Build Version: 2.17.58_39_ga794951 [ 8430.973845] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 8432.928532] Key type lgssc registered [ 8434.389947] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8532.320867] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8538.861879] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8562.712417] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8688.026831] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8694.838159] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8724.770900] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8769.837215] Lustre: DEBUG MARKER: == conf-sanity test 151b: -ENOSPC doesn't affect mount === 16:22:55 (1789071775) [ 8785.559144] Key type lgssc unregistered [ 8786.009206] LNet: 129272:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8786.032312] LNetError: 129272:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8786.085539] LNet: Removed LNI 192.168.204.46@tcp [ 8787.245849] Key type .llcrypt unregistered [ 8787.249550] Key type ._llcrypt unregistered [ 8805.555882] Key type ._llcrypt registered [ 8805.574709] Key type .llcrypt registered [ 8806.065267] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8806.117641] alg: No test for adler32 (adler32-zlib) [ 8807.433550] Lustre: Lustre: Build Version: 2.17.58_39_ga794951 [ 8807.659225] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 8809.368439] Key type lgssc registered [ 8811.066477] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8878.560442] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8885.416232] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8911.144709] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9049.239362] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9056.387730] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9087.431316] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9160.759849] Lustre: DEBUG MARKER: == conf-sanity test 152: seq allocation error in OSP ===== 16:29:25 (1789072165) [ 9258.764557] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [ 9267.837105] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9280.044265] Lustre: DEBUG MARKER: ADD OST3 [ 9284.575648] Lustre: lustre-OST0000-osc-ffff9337887cc800: disconnect after 22s idle [ 9294.267887] Lustre: DEBUG MARKER: STOP OST3 [ 9309.156908] Lustre: lustre-OST0003-osc-ffff9337887cc800: Connection to lustre-OST0003 (at 192.168.204.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9323.243148] Lustre: DEBUG MARKER: START OST3 again [ 9330.137400] Lustre: lustre-OST0003-osc-ffff9337887cc800: Connection restored to 192.168.204.146@tcp (at 192.168.204.146@tcp) [ 9365.831877] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 9365.840175] LustreError: 140090:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff9337887cc800: inode [0x240000bd0:0x1:0x0] mdc close failed: rc = -108 [ 9376.226203] Lustre: Unmounted lustre-client [ 9512.173192] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9519.775933] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9544.536979] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9593.709735] Lustre: DEBUG MARKER: == conf-sanity test 153a: bypass invalid NIDs quickly ==== 16:36:39 (1789072599) [ 9718.858306] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9726.354609] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9755.215366] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9848.328550] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9857.024726] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9890.252621] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9893.759811] LNetError: 129681:0:(lib-move.c:2151:lnet_handle_find_routed_path()) peer 192.168.252.112@tcp2 has no available nets [ 9894.064985] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [ 9899.449464] Lustre: Unmounted lustre-client [ 9932.926222] Key type lgssc unregistered [ 9947.359257] LNet: 1 local NIs in recovery (showing 1): 192.168.204.46@tcp [ 9953.828944] LNet: 149089:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9953.864222] LNetError: 149089:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10024.550789] LNet: Removed LNI 192.168.204.46@tcp [10025.522267] Key type .llcrypt unregistered [10025.523984] Key type ._llcrypt unregistered [10047.951943] Key type ._llcrypt registered [10047.956179] Key type .llcrypt registered [10049.738598] Lustre: DEBUG MARKER: == conf-sanity test 153b: added IPv6 NID support ========= 16:44:15 (1789073055) [10075.652206] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10075.667520] alg: No test for adler32 (adler32-zlib) [10076.805648] Lustre: Lustre: Build Version: 2.17.58_39_ga794951 [10077.174481] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [10078.992390] Key type lgssc registered [10081.085599] Lustre: Echo OBD driver; http://www.lustre.org/ [10198.932535] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10206.644667] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10236.484627] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10406.330881] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [10413.656630] Lustre: DEBUG MARKER: Using TIMEOUT=20 [10425.140640] Lustre: Unmounted lustre-client [10425.252413] LNetError: 150271:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::540b:81ff:fe4d:b3ad/1023: rc = -22 [10425.257457] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [10425.552454] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [10425.734157] Lustre: Unmounted lustre-client [10426.595610] LNetError: 150270:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::540b:81ff:fe4d:b3ad/1023: rc = -22 [10426.627560] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [10426.946409] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [10426.949643] Lustre: Skipped 1 previous similar message [10427.151693] Lustre: Unmounted lustre-client [10427.159784] Lustre: Skipped 1 previous similar message [10429.118541] LNetError: 150270:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::540b:81ff:fe4d:b3ad/1023: rc = -22 [10429.123608] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [10429.443368] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [10429.446480] Lustre: Skipped 3 previous similar messages [10429.640888] Lustre: Unmounted lustre-client [10429.643370] Lustre: Skipped 3 previous similar messages [10430.547215] Lustre: 156234: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 [10431.252283] Lustre: 156264:0:(obd_mount.c:175:lustre_add_mgc_failnodes()) MGC193.168.0.240@tcp: can't add failover NID 5031:db8:85a3:8d3:1319:8a2e:370:7348@tcp, rc = -75 [10431.272309] LNetError: 150270:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::540b:81ff:fe4d:b3ad/1023: rc = -22 [10431.277651] LNetError: 150270:0:(lib-socket.c:308:lnet_sock_create()) Skipped 1 previous similar message [10431.287440] LNetError: Unexpected error -22 connecting to 2001:db8:85a3::8a2e:370:7334@tcp at host [2001:db8:85a3::8a2e:370:7334]:988 [10431.298284] LNetError: Skipped 1 previous similar message [10439.736942] Lustre: DEBUG MARKER: == conf-sanity test 153c: don't stuck on unreached NID === 16:50:45 (1789073445) [10479.717555] LNet: 1 local NIs in recovery (showing 1): 192.168.204.46@tcp [10569.059395] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10576.892820] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10603.810932] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10694.689144] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10703.856775] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10737.829356] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10743.711794] LustreError: 161865:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.252.112@tcp: failed processing log, type 1: rc = -110 [10791.071166] LNet: 1 local NIs in recovery (showing 1): 192.168.204.46@tcp [10867.871206] LNet: 1 local NIs in recovery (showing 1): 192.168.204.46@tcp [10883.040470] 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 [10883.079663] Lustre: Unmounted lustre-client [10883.090909] Lustre: Skipped 3 previous similar messages [10883.095880] LustreError: 161865:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -110 [10912.817769] Key type lgssc unregistered [10925.345970] LNet: 162325:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10925.360787] LNetError: 162325:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10999.154459] LNet: Removed LNI 192.168.204.46@tcp [11000.788189] Key type .llcrypt unregistered [11000.793037] Key type ._llcrypt unregistered [11023.971276] Key type ._llcrypt registered [11023.974733] Key type .llcrypt registered [11025.610770] Lustre: DEBUG MARKER: == conf-sanity test 154: expand .. on rename after MDT backup restore ========================================================== 17:00:31 (1789074031) [11045.353386] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11045.379556] alg: No test for adler32 (adler32-zlib) [11046.545259] Lustre: Lustre: Build Version: 2.17.58_39_ga794951 [11046.974236] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [11048.752713] Key type lgssc registered [11050.763354] Lustre: Echo OBD driver; http://www.lustre.org/ [11221.257271] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [11228.478570] Lustre: DEBUG MARKER: Using TIMEOUT=20 [11247.074763] Lustre: lustre-OST0000-osc-ffff933798f8f000: disconnect after 23s idle [11248.535060] Lustre: Unmounted lustre-client [11412.822118] LustreError: 163518:0:(lmv_obd.c:211:lmv_notify()) activation of lustre-MDT0001_UUID failed: -22 [11412.931223] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [11418.936209] Lustre: DEBUG MARKER: Using TIMEOUT=20 [11427.090124] Lustre: Unmounted lustre-client [11485.947832] Lustre: DEBUG MARKER: == conf-sanity test 155: gap in seq allocation from ofd after restarting ========================================================== 17:08:12 (1789074492) [11594.022639] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11600.371922] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11626.703125] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11753.990212] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [11761.487198] Lustre: DEBUG MARKER: Using TIMEOUT=20 [11779.553905] Lustre: lustre-OST0001-osc-ffff933790005000: disconnect after 23s idle [11779.566564] Lustre: Skipped 1 previous similar message [11781.384052] Lustre: Unmounted lustre-client [11906.675346] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [11911.796954] Lustre: DEBUG MARKER: Using TIMEOUT=20 [11932.141661] Lustre: lustre-OST0001-osc-ffff933782ba2800: disconnect after 24s idle [11935.581825] Lustre: DEBUG MARKER: == conf-sanity test 156: root_fid on export consistent with client mount ========================================================== 17:15:41 (1789074941) [11937.972715] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11937.993390] LustreError: 180765:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff933782ba2800: inode [0x200000bd1:0x1:0x0] mdc close failed: rc = -108 [11948.334930] Lustre: Unmounted lustre-client [12150.993961] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [12157.741784] Lustre: DEBUG MARKER: Using TIMEOUT=20 [12169.108710] Lustre: Unmounted lustre-client [12169.889089] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [12180.415845] Lustre: Unmounted lustre-client [12182.977130] Lustre: DEBUG MARKER: == conf-sanity test 157a: test allow_register for MDT registration and MGS restart ========================================================== 17:19:48 (1789075188) [12250.894643] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12258.372651] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12283.763382] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12295.258395] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [12392.451062] LustreError: lustre-OST0001-osc-ffff933790d0f000: operation ost_connect to node 192.168.204.146@tcp failed: rc = -19 [12412.914205] Lustre: lustre-MDT0000-mdc-ffff933790d0f000: Connection to lustre-MDT0000 (at 192.168.204.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [12428.291336] LustreError: MGC192.168.204.146@tcp: Connection to MGS (at 192.168.204.146@tcp) was lost; in progress operations using this service will fail [12428.321215] LustreError: lustre-MDT0000-mdc-ffff933790d0f000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [12428.368680] Lustre: Evicted from MGS (at 192.168.204.146@tcp) after server handle changed from 0x93c3502ca59154ab to 0x93c3502ca591580f [12428.374105] Lustre: lustre-MDT0000-mdc-ffff933790d0f000: Connection restored to 192.168.204.146@tcp (at 192.168.204.146@tcp) [12428.408419] Lustre: Skipped 1 previous similar message [12494.287227] Lustre: Unmounted lustre-client [12526.903531] Key type lgssc unregistered [12527.197574] LNet: 190015:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12527.209624] LNetError: 190015:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12527.232215] LNet: Removed LNI 192.168.204.46@tcp [12528.246400] Key type .llcrypt unregistered [12528.249618] Key type ._llcrypt unregistered [12543.140722] Key type ._llcrypt registered [12543.142670] Key type .llcrypt registered [12544.812468] Lustre: DEBUG MARKER: == conf-sanity test 157b: verify allow_register (block new OSTs, allow existing) ========================================================== 17:25:50 (1789075550) [12548.116669] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12548.154660] alg: No test for adler32 (adler32-zlib) [12549.303475] Lustre: Lustre: Build Version: 2.17.58_39_ga794951 [12549.640774] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [12551.399291] Key type lgssc registered [12553.974330] Lustre: Echo OBD driver; http://www.lustre.org/ [12618.996245] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12626.872879] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12653.988638] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12660.306774] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [12755.432509] LustreError: lustre-OST0001-osc-ffff9337870af800: operation ost_connect to node 192.168.204.146@tcp failed: rc = -19 [12909.039284] LustreError: lustre-OST0001-osc-ffff9337870af800: operation ost_connect to node 192.168.204.146@tcp failed: rc = -19 [12915.006099] Lustre: Unmounted lustre-client [12950.336064] Key type lgssc unregistered [12950.713067] LNet: 196057:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12950.737472] LNetError: 196057:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12950.770503] LNet: Removed LNI 192.168.204.46@tcp [12951.689162] Key type .llcrypt unregistered [12951.692921] Key type ._llcrypt unregistered [12967.496813] Key type ._llcrypt registered [12967.499964] Key type .llcrypt registered [12969.721835] Lustre: DEBUG MARKER: == conf-sanity test 160: MGC updates failnodes from all participants ========================================================== 17:32:55 (1789075975) [13009.638915] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13009.727987] alg: No test for adler32 (adler32-zlib) [13011.248825] Lustre: Lustre: Build Version: 2.17.58_39_ga794951 [13011.667146] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [13013.439410] Key type lgssc registered [13015.335459] Lustre: Echo OBD driver; http://www.lustre.org/ [13123.500742] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13131.772431] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13163.027967] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13209.652712] Key type lgssc unregistered [13210.131638] LNet: 201043:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13210.146833] LNetError: 201043:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13210.169109] LNet: Removed LNI 192.168.204.46@tcp [13211.017217] Key type .llcrypt unregistered [13211.018860] Key type ._llcrypt unregistered [13322.289631] Key type ._llcrypt registered [13322.292629] Key type .llcrypt registered [13322.859586] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13322.871399] alg: No test for adler32 (adler32-zlib) [13323.989901] Lustre: Lustre: Build Version: 2.17.58_39_ga794951 [13324.402680] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [13326.175468] Key type lgssc registered [13327.931314] Lustre: Echo OBD driver; http://www.lustre.org/ [13386.624305] Lustre: DEBUG MARKER: == conf-sanity test 161: test '-o mgsname' option ======== 17:39:52 (1789076392) [13442.160477] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13448.718631] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13477.199661] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13478.973234] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [13481.091261] Lustre: Unmounted lustre-client [13481.511765] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [13481.757085] Lustre: Unmounted lustre-client [13490.863390] Lustre: Unmounted lustre-client [13530.832945] Key type lgssc unregistered [13531.206836] LNet: 206030:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13531.220584] LNetError: 206030:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13531.246422] LNet: Removed LNI 192.168.204.46@tcp [13531.995698] Key type .llcrypt unregistered [13531.998418] Key type ._llcrypt unregistered [13547.369213] Key type ._llcrypt registered [13547.378433] Key type .llcrypt registered [13550.153942] Lustre: DEBUG MARKER: == conf-sanity test 162: replace nids with -o noclient === 17:42:34 (1789076554) [13557.198194] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13557.227032] alg: No test for adler32 (adler32-zlib) [13558.474776] Lustre: Lustre: Build Version: 2.17.58_39_ga794951 [13558.950604] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [13560.687464] Key type lgssc registered [13562.692964] Lustre: Echo OBD driver; http://www.lustre.org/ [13626.167836] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13634.182702] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13662.333327] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13669.461299] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [13679.802688] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13682.208657] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [13689.832424] Lustre: lustre-MDT0000-mdc-ffff933792e03800: Connection to lustre-MDT0000 (at 192.168.204.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [13705.195985] Lustre: lustre-MDT0001-mdc-ffff933792e03800: Connection to lustre-MDT0001 (at 192.168.204.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [13710.303343] Lustre: 206704:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789076702/real 1789076702] req@000000009fdf730e x1875982749276160/t0(0) o400->MGC192.168.204.146@tcp@192.168.204.146@tcp:26/25 lens 224/224 e 0 to 1 dl 1789076718 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [13710.355529] LustreError: MGC192.168.204.146@tcp: Connection to MGS (at 192.168.204.146@tcp) was lost; in progress operations using this service will fail [13766.659601] Lustre: Evicted from MGS (at 192.168.204.146@tcp) after server handle changed from 0x7382750f9d12710f to 0x7382750f9d1272b3 [13766.681792] Lustre: MGC192.168.204.146@tcp: Connection restored to 192.168.204.146@tcp (at 192.168.204.146@tcp) [13801.962648] LustreError: lustre-MDT0000-mdc-ffff933792e03800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [13802.001555] Lustre: lustre-MDT0000-mdc-ffff933792e03800: Connection restored to 192.168.204.146@tcp (at 192.168.204.146@tcp) [13807.302658] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13809.701397] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in IDLE state after 0 sec [13818.410860] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13820.857376] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [13825.138787] Lustre: Unmounted lustre-client [13858.230101] Key type lgssc unregistered [13858.556573] LNet: 211579:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13858.572851] LNetError: 211579:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13858.612075] LNet: Removed LNI 192.168.204.46@tcp [13859.651905] Key type .llcrypt unregistered [13859.658617] Key type ._llcrypt unregistered [13896.889193] Key type ._llcrypt registered [13896.891786] Key type .llcrypt registered [13897.361159] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13897.383593] alg: No test for adler32 (adler32-zlib) [13898.459898] Lustre: Lustre: Build Version: 2.17.58_39_ga794951 [13898.857975] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [13900.607344] Key type lgssc registered [13902.202358] Lustre: Echo OBD driver; http://www.lustre.org/ [14006.737670] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14015.011040] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14042.906485] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14090.665140] Lustre: DEBUG MARKER: == conf-sanity test 164: test expected_clients parameter and max client tracking ========================================================== 17:51:36 (1789077096) [14205.055304] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14211.402678] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14237.706441] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14239.552702] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [14245.640724] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [14247.513375] Lustre: Mounted lustre-client - version 2.17.58_39_ga794951 [14247.515397] Lustre: Skipped 1 previous similar message [14253.391482] Lustre: Unmounted lustre-client [14255.614339] Lustre: Unmounted lustre-client [14257.615670] Lustre: Unmounted lustre-client [14260.459888] Lustre: Unmounted lustre-client [14280.300991] Lustre: Unmounted lustre-client [14280.303214] Lustre: Skipped 1 previous similar message [14316.554015] Key type lgssc unregistered [14316.932693] LNet: 220755:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14316.949466] LNetError: 220755:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14316.993252] LNet: Removed LNI 192.168.204.46@tcp [14318.235803] Key type .llcrypt unregistered [14318.243434] Key type ._llcrypt unregistered [14331.981950] Key type ._llcrypt registered [14331.985251] Key type .llcrypt registered [14333.707458] Lustre: DEBUG MARKER: == conf-sanity test 200a: check CPU partitions =========== 17:55:39 (1789077339) [14348.640542] Key type .llcrypt unregistered [14348.646601] Key type ._llcrypt unregistered [14349.653342] Key type ._llcrypt registered [14349.660155] Key type .llcrypt registered [14350.244824] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 4 [14350.278970] alg: No test for adler32 (adler32-zlib) [14351.538975] Lustre: Lustre: Build Version: 2.17.58_39_ga794951 [14351.922710] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [14353.663525] Key type lgssc registered [14355.984291] Lustre: Echo OBD driver; http://www.lustre.org/ [14385.585536] Key type lgssc unregistered [14385.845418] LNet: 222905:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14385.859262] LNetError: 222905:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14385.886566] LNet: Removed LNI 192.168.204.46@tcp [14386.508645] Key type .llcrypt unregistered [14386.513951] Key type ._llcrypt unregistered [14386.961954] Key type ._llcrypt registered [14386.964887] Key type .llcrypt registered [14388.308635] Lustre: DEBUG MARKER: == conf-sanity test 200b: set CPU pattern using core selection ========================================================== 17:56:34 (1789077394) [14401.406695] Key type .llcrypt unregistered [14401.408367] Key type ._llcrypt unregistered [14403.184761] Key type ._llcrypt registered [14403.189137] Key type .llcrypt registered [14403.534720] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14403.567712] alg: No test for adler32 (adler32-zlib) [14404.990726] Lustre: Lustre: Build Version: 2.17.58_39_ga794951 [14405.397733] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [14407.207410] Key type lgssc registered [14409.222715] Lustre: Echo OBD driver; http://www.lustre.org/ [14433.385295] Key type lgssc unregistered [14433.699920] LNet: 224449:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14433.707755] LNetError: 224449:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14433.725218] LNet: Removed LNI 192.168.204.46@tcp [14434.456616] Key type .llcrypt unregistered [14434.459573] Key type ._llcrypt unregistered [14442.922138] Key type ._llcrypt registered [14442.933060] Key type .llcrypt registered [14443.409093] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14443.444200] alg: No test for adler32 (adler32-zlib) [14444.695811] Lustre: Lustre: Build Version: 2.17.58_39_ga794951 [14445.096483] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [14446.855364] Key type lgssc registered [14448.980947] Lustre: Echo OBD driver; http://www.lustre.org/ [14481.699204] Key type lgssc unregistered [14482.224193] LNet: 226210:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14482.232162] LNetError: 226210:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14482.243959] LNet: Removed LNI 192.168.204.46@tcp [14483.580501] Key type .llcrypt unregistered [14483.586100] Key type ._llcrypt unregistered [14484.476918] Key type ._llcrypt registered [14484.491363] Key type .llcrypt registered [14487.692479] Lustre: DEBUG MARKER: == conf-sanity test 200c: set CPU pattern using NUMA node layout ========================================================== 17:58:11 (1789077491) [14503.491778] Key type .llcrypt unregistered [14503.496321] Key type ._llcrypt unregistered [14504.290339] Key type ._llcrypt registered [14504.293245] Key type .llcrypt registered [14504.907612] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14504.945829] alg: No test for adler32 (adler32-zlib) [14506.530680] Lustre: Lustre: Build Version: 2.17.58_39_ga794951 [14507.234276] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [14509.031329] Key type lgssc registered [14511.138750] Lustre: Echo OBD driver; http://www.lustre.org/ [14537.129252] Key type lgssc unregistered [14537.385779] LNet: 227752:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14537.404772] LNetError: 227752:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14537.421763] LNet: Removed LNI 192.168.204.46@tcp [14538.321943] Key type .llcrypt unregistered [14538.327968] Key type ._llcrypt unregistered [14547.239374] Key type ._llcrypt registered [14547.242561] Key type .llcrypt registered [14547.553430] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14547.571152] alg: No test for adler32 (adler32-zlib) [14548.933494] Lustre: Lustre: Build Version: 2.17.58_39_ga794951 [14549.369103] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [14551.055488] Key type lgssc registered [14552.682134] Lustre: Echo OBD driver; http://www.lustre.org/ [14575.187454] Key type lgssc unregistered [14575.748580] LNet: 229057:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14575.760090] LNetError: 229057:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14575.799701] LNet: Removed LNI 192.168.204.46@tcp [14576.648636] Key type .llcrypt unregistered [14576.650146] Key type ._llcrypt unregistered [14588.665227] Key type ._llcrypt registered [14588.678757] Key type .llcrypt registered [14589.858628] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14589.942903] alg: No test for adler32 (adler32-zlib) [14591.212772] Lustre: Lustre: Build Version: 2.17.58_39_ga794951 [14591.468197] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [14593.250667] Key type lgssc registered [14595.441395] Lustre: Echo OBD driver; http://www.lustre.org/ [14619.046868] Key type lgssc unregistered [14619.540676] LNet: 230359:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14619.557137] LNetError: 230359:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14619.595540] LNet: Removed LNI 192.168.204.46@tcp [14620.929292] Key type .llcrypt unregistered [14620.934767] Key type ._llcrypt unregistered [14630.802793] Key type ._llcrypt registered [14630.804312] Key type .llcrypt registered [14631.489389] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14631.518393] alg: No test for adler32 (adler32-zlib) [14632.852375] Lustre: Lustre: Build Version: 2.17.58_39_ga794951 [14633.231641] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [14635.031747] Key type lgssc registered [14636.845902] Lustre: Echo OBD driver; http://www.lustre.org/ [14670.014754] Key type lgssc unregistered [14670.553025] LNet: 232138:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14670.576161] LNetError: 232138:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14670.597832] LNet: Removed LNI 192.168.204.46@tcp [14672.243768] Key type .llcrypt unregistered [14672.251712] Key type ._llcrypt unregistered [14672.885659] Key type ._llcrypt registered [14672.892913] Key type .llcrypt registered [14675.429973] Lustre: DEBUG MARKER: == conf-sanity test 200d: set CPU pattern to exclude only CPU 1 ========================================================== 18:01:20 (1789077680) [14691.242660] Key type .llcrypt unregistered [14691.247072] Key type ._llcrypt unregistered [14693.138577] Key type ._llcrypt registered [14693.200573] Key type .llcrypt registered [14693.702852] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14693.720811] alg: No test for adler32 (adler32-zlib) [14694.989600] Lustre: Lustre: Build Version: 2.17.58_39_ga794951 [14695.377539] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [14697.183494] Key type lgssc registered [14699.168631] Lustre: Echo OBD driver; http://www.lustre.org/ [14718.692535] Key type lgssc unregistered [14719.004566] LNet: 233683:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14719.015696] LNetError: 233683:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14719.064842] LNet: Removed LNI 192.168.204.46@tcp [14719.927133] Key type .llcrypt unregistered [14719.941278] Key type ._llcrypt unregistered [14728.273152] Key type ._llcrypt registered [14728.281894] Key type .llcrypt registered [14728.992062] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14729.022842] alg: No test for adler32 (adler32-zlib) [14730.095095] Lustre: Lustre: Build Version: 2.17.58_39_ga794951 [14730.404917] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [14732.159318] Key type lgssc registered [14734.046298] Lustre: Echo OBD driver; http://www.lustre.org/ [14758.007931] Key type lgssc unregistered [14758.314846] LNet: 234996:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14758.338267] LNetError: 234996:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14758.361661] LNet: Removed LNI 192.168.204.46@tcp [14759.265419] Key type .llcrypt unregistered [14759.273718] Key type ._llcrypt unregistered [14767.584520] Key type ._llcrypt registered [14767.588509] Key type .llcrypt registered [14767.845605] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14767.869815] alg: No test for adler32 (adler32-zlib) [14769.366290] Lustre: Lustre: Build Version: 2.17.58_39_ga794951 [14769.783385] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [14771.463308] Key type lgssc registered [14773.632570] Lustre: Echo OBD driver; http://www.lustre.org/ [14795.355559] Key type lgssc unregistered [14795.723896] LNet: 236300:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14795.733647] LNetError: 236300:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14795.752264] LNet: Removed LNI 192.168.204.46@tcp [14796.425846] Key type .llcrypt unregistered [14796.429977] Key type ._llcrypt unregistered [14806.322541] Key type ._llcrypt registered [14806.326719] Key type .llcrypt registered [14806.744573] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14806.758795] alg: No test for adler32 (adler32-zlib) [14808.031964] Lustre: Lustre: Build Version: 2.17.58_39_ga794951 [14808.488876] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [14810.300606] Key type lgssc registered [14812.184871] Lustre: Echo OBD driver; http://www.lustre.org/ [14833.543657] Key type lgssc unregistered [14834.027965] LNet: 237606:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14834.047952] LNetError: 237606:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14834.059211] LNet: Removed LNI 192.168.204.46@tcp [14835.409963] Key type .llcrypt unregistered [14835.413505] Key type ._llcrypt unregistered [14846.589270] Key type ._llcrypt registered [14846.606751] Key type .llcrypt registered [14847.348331] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14847.363986] alg: No test for adler32 (adler32-zlib) [14848.930840] Lustre: Lustre: Build Version: 2.17.58_39_ga794951 [14849.210901] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [14850.927838] Key type lgssc registered [14853.410874] Lustre: Echo OBD driver; http://www.lustre.org/ [14874.417564] Key type lgssc unregistered [14874.835488] LNet: 238931:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14874.843812] LNetError: 238931:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14874.871663] LNet: Removed LNI 192.168.204.46@tcp [14875.678729] Key type .llcrypt unregistered [14875.681871] Key type ._llcrypt unregistered [14886.529640] Key type ._llcrypt registered [14886.535908] Key type .llcrypt registered [14887.023514] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14887.064245] alg: No test for adler32 (adler32-zlib) [14888.336465] Lustre: Lustre: Build Version: 2.17.58_39_ga794951 [14888.761842] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [14890.471263] Key type lgssc registered [14891.937419] Lustre: Echo OBD driver; http://www.lustre.org/ [14915.688600] Key type lgssc unregistered [14916.077224] LNet: 240235:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14916.084307] LNetError: 240235:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14916.094424] LNet: Removed LNI 192.168.204.46@tcp [14917.174616] Key type .llcrypt unregistered [14917.181916] Key type ._llcrypt unregistered [14945.406606] Key type ._llcrypt registered [14945.409552] Key type .llcrypt registered [14947.105883] Lustre: DEBUG MARKER: == conf-sanity test 200e: set CPU pattern using relative core exclusion ========================================================== 18:05:53 (1789077953) [14963.332526] Key type .llcrypt unregistered [14963.334234] Key type ._llcrypt unregistered [14964.760596] Key type ._llcrypt registered [14964.776884] Key type .llcrypt registered [14965.498994] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14965.514473] alg: No test for adler32 (adler32-zlib) [14966.574763] Lustre: Lustre: Build Version: 2.17.58_39_ga794951 [14966.752937] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [14968.439303] Key type lgssc registered [14969.987565] Lustre: Echo OBD driver; http://www.lustre.org/ [14988.419396] Key type lgssc unregistered [14988.721269] LNet: 242546:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14988.730583] LNetError: 242546:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14988.758730] LNet: Removed LNI 192.168.204.46@tcp [14990.106517] Key type .llcrypt unregistered [14990.110219] Key type ._llcrypt unregistered [14999.788822] Key type ._llcrypt registered [14999.827735] Key type .llcrypt registered [15000.119864] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15000.135489] alg: No test for adler32 (adler32-zlib) [15001.418960] Lustre: Lustre: Build Version: 2.17.58_39_ga794951 [15001.709281] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [15003.591608] Key type lgssc registered [15005.475274] Lustre: Echo OBD driver; http://www.lustre.org/ [15024.767351] Key type lgssc unregistered [15025.160343] LNet: 243862:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15025.172925] LNetError: 243862:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15025.206390] LNet: Removed LNI 192.168.204.46@tcp [15026.627298] Key type .llcrypt unregistered [15026.634813] Key type ._llcrypt unregistered [15034.320135] Key type ._llcrypt registered [15034.326029] Key type .llcrypt registered [15034.771992] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15034.796008] alg: No test for adler32 (adler32-zlib) [15035.846708] Lustre: Lustre: Build Version: 2.17.58_39_ga794951 [15036.090074] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [15037.791296] Key type lgssc registered [15039.010306] Lustre: Echo OBD driver; http://www.lustre.org/ [15056.028502] Key type lgssc unregistered [15056.437933] LNet: 245167:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15056.456194] LNetError: 245167:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15056.491513] LNet: Removed LNI 192.168.204.46@tcp [15057.328043] Key type .llcrypt unregistered [15057.332277] Key type ._llcrypt unregistered [15065.747995] Key type ._llcrypt registered [15065.758682] Key type .llcrypt registered [15066.698384] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15066.722401] alg: No test for adler32 (adler32-zlib) [15068.187892] Lustre: Lustre: Build Version: 2.17.58_39_ga794951 [15068.720841] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [15070.656501] Key type lgssc registered [15073.147385] Lustre: Echo OBD driver; http://www.lustre.org/ [15106.636372] Key type lgssc unregistered [15107.075334] LNet: 246958:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15107.088610] LNetError: 246958:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15107.147061] LNet: Removed LNI 192.168.204.46@tcp [15108.057681] Key type .llcrypt unregistered [15108.061829] Key type ._llcrypt unregistered [15108.704280] Key type ._llcrypt registered [15108.706960] Key type .llcrypt registered [15111.073344] Lustre: DEBUG MARKER: == conf-sanity test 250: verify lcfg_erase removes filesystem parameters ========================================================== 18:08:36 (1789078116) [15135.354830] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15135.396881] alg: No test for adler32 (adler32-zlib) [15136.632885] Lustre: Lustre: Build Version: 2.17.58_39_ga794951 [15137.057189] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [15138.769730] Key type lgssc registered [15140.735911] Lustre: Echo OBD driver; http://www.lustre.org/ [15401.769744] Lustre: DEBUG MARKER: == conf-sanity test 802a: simulate readonly device ======= 18:13:28 (1789078408) [15403.726828] Lustre: DEBUG MARKER: SKIP: conf-sanity test_802a ZFS specific test [15467.526612] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 15147 sec ========= 18:14:33 (1789078473) [15469.424770] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 18:14:35 (1789078475) === [15473.444869] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 18:14:39 (1789078479) === [15519.123793] Key type lgssc unregistered [15519.505590] LNet: 256023:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15519.517957] LNetError: 256023:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15519.560189] LNet: Removed LNI 192.168.204.46@tcp [15520.564563] Key type .llcrypt unregistered [15520.570306] Key type ._llcrypt unregistered