[ 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 464529664 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2895288K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001020] APIC: Switch to symmetric I/O mode setup [ 0.003288] x2apic enabled [ 0.004010] Switched APIC routing to physical x2apic. [ 0.005020] kvm-guest: setup PV IPIs [ 0.007805] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008024] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009014] pid_max: default: 32768 minimum: 301 [ 0.010143] LSM: Security Framework initializing [ 0.012065] Yama: becoming mindful. [ 0.013042] SELinux: Initializing. [ 0.014067] *** VALIDATE selinux *** [ 0.023141] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.028462] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.029177] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030121] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.032137] *** VALIDATE tmpfs *** [ 0.034014] *** VALIDATE proc *** [ 0.035296] *** VALIDATE cgroup *** [ 0.036014] *** VALIDATE cgroup2 *** [ 0.037296] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.039050] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.040000] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.040042] Spectre V2 : User space: Vulnerable [ 0.041012] Speculative Store Bypass: Vulnerable [ 0.044000] debug: unmapping init [mem 0xffffffffa4259000-0xffffffffa4260fff] [ 0.045208] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.046764] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.047030] ... version: 2 [ 0.048013] ... bit width: 48 [ 0.049012] ... generic registers: 4 [ 0.050013] ... value mask: 0000ffffffffffff [ 0.051017] ... max period: 00007fffffffffff [ 0.052018] ... fixed-purpose events: 3 [ 0.053012] ... event mask: 000000070000000f [ 0.054344] rcu: Hierarchical SRCU implementation. [ 0.056489] smp: Bringing up secondary CPUs ... [ 0.057590] x86: Booting SMP configuration: [ 0.058031] .... node #0, CPUs: #1 #2 #3 [ 0.061392] smp: Brought up 1 node, 4 CPUs [ 0.063015] smpboot: Max logical packages: 1 [ 0.064019] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.145018] node 0 deferred pages initialised in 79ms [ 0.148179] devtmpfs: initialized [ 0.149184] x86/mm: Memory block size: 128MB [ 0.151000] gcov: version magic: 0x41383552 [ 0.154274] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.155054] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.157311] pinctrl core: initialized pinctrl subsystem [ 0.158139] [ 0.158584] ************************************************************* [ 0.161020] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.163009] ** ** [ 0.165019] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.166009] ** ** [ 0.168010] ** This means that this kernel is built to expose internal ** [ 0.170008] ** IOMMU data structures, which may compromise security on ** [ 0.172016] ** your system. ** [ 0.174009] ** ** [ 0.177011] ** If you see this message and you are not debugging the ** [ 0.179055] ** kernel, report this immediately to your vendor! ** [ 0.182018] ** ** [ 0.186017] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.188014] ************************************************************* [ 0.190572] NET: Registered protocol family 16 [ 0.193506] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.196065] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.199087] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.202558] cpuidle: using governor menu [ 0.204774] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.207437] PCI: Using configuration type 1 for base access [ 0.209124] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.218113] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.221036] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.223176] cryptd: max_cpu_qlen set to 1000 [ 0.225260] ACPI: Added _OSI(Module Device) [ 0.227014] ACPI: Added _OSI(Processor Device) [ 0.229012] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.231019] ACPI: Added _OSI(Processor Aggregator Device) [ 0.236000] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.241383] ACPI: Interpreter enabled [ 0.243086] ACPI: PM: (supports S0 S3 S4 S5) [ 0.244033] ACPI: Using IOAPIC for interrupt routing [ 0.246144] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.248389] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.258738] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.261055] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.264025] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.268083] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.273385] acpiphp: Slot [2] registered [ 0.275093] acpiphp: Slot [5] registered [ 0.276103] acpiphp: Slot [6] registered [ 0.277106] acpiphp: Slot [3] registered [ 0.279083] acpiphp: Slot [4] registered [ 0.280054] acpiphp: Slot [7] registered [ 0.281000] acpiphp: Slot [8] registered [ 0.281000] acpiphp: Slot [9] registered [ 0.283083] acpiphp: Slot [10] registered [ 0.284057] acpiphp: Slot [11] registered [ 0.285074] acpiphp: Slot [12] registered [ 0.287093] acpiphp: Slot [13] registered [ 0.289110] acpiphp: Slot [14] registered [ 0.290069] acpiphp: Slot [15] registered [ 0.291099] acpiphp: Slot [16] registered [ 0.293099] acpiphp: Slot [17] registered [ 0.294089] acpiphp: Slot [18] registered [ 0.295096] acpiphp: Slot [19] registered [ 0.296058] acpiphp: Slot [20] registered [ 0.297074] acpiphp: Slot [21] registered [ 0.297902] acpiphp: Slot [22] registered [ 0.299054] acpiphp: Slot [23] registered [ 0.301100] acpiphp: Slot [24] registered [ 0.303134] acpiphp: Slot [25] registered [ 0.304126] acpiphp: Slot [26] registered [ 0.306115] acpiphp: Slot [27] registered [ 0.308094] acpiphp: Slot [28] registered [ 0.310104] acpiphp: Slot [29] registered [ 0.311105] acpiphp: Slot [30] registered [ 0.312104] acpiphp: Slot [31] registered [ 0.314105] PCI host bridge to bus 0000:00 [ 0.315021] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.318033] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.321024] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.324027] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.327027] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.329030] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.332259] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.335284] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.339717] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.347372] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.350062] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.354021] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.356018] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.359023] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.361511] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.364858] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.367045] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.371000] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.376016] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.383020] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.387014] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.391406] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.395016] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.400021] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.410014] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.419510] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.427011] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.436018] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.454023] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.463944] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.467426] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.470416] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.473433] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.477301] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.482779] iommu: Default domain type: Passthrough [ 0.484469] SCSI subsystem initialized [ 0.485114] ACPI: bus type USB registered [ 0.486093] usbcore: registered new interface driver usbfs [ 0.487083] usbcore: registered new interface driver hub [ 0.488097] usbcore: registered new device driver usb [ 0.490175] pps_core: LinuxPPS API ver. 1 registered [ 0.491006] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.493098] PTP clock support registered [ 0.495211] EDAC MC: Ver: 3.0.0 [ 0.498196] PCI: Using ACPI for IRQ routing [ 0.499839] NetLabel: Initializing [ 0.501009] NetLabel: domain hash size = 128 [ 0.501982] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.503077] NetLabel: unlabeled traffic allowed by default [ 0.505142] vgaarb: loaded [ 0.506316] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.508014] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.513924] clocksource: Switched to clocksource kvm-clock [ 0.620395] VFS: Disk quotas dquot_6.6.0 [ 0.622076] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.624767] *** VALIDATE ramfs *** [ 0.626135] *** VALIDATE hugetlbfs *** [ 0.627449] pnp: PnP ACPI init [ 0.629669] pnp: PnP ACPI: found 6 devices [ 0.646649] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.650040] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.651943] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.653971] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.655881] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.658039] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.660936] NET: Registered protocol family 2 [ 0.663357] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.668279] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.672411] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.677968] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.682159] TCP: Hash tables configured (established 65536 bind 65536) [ 0.684935] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.688386] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.691735] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.695167] NET: Registered protocol family 1 [ 0.698661] RPC: Registered named UNIX socket transport module. [ 0.700465] RPC: Registered udp transport module. [ 0.701896] RPC: Registered tcp transport module. [ 0.703181] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.705062] NET: Registered protocol family 44 [ 0.706495] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.708084] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.709791] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.711658] PCI: CLS 0 bytes, default 64 [ 0.712811] Unpacking initramfs... [ 2.145679] debug: unmapping init [mem 0xffff9c1d3cc64000-0xffff9c1d3ffcffff] [ 2.148154] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.150139] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.153335] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.627974] Initialise system trusted keyrings [ 2.629017] Key type blacklist registered [ 2.630117] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.636714] zbud: loaded [ 2.639267] *** VALIDATE nfs *** [ 2.640049] *** VALIDATE nfs4 *** [ 2.641342] pstore: using deflate compression [ 2.643769] Platform Keyring initialized [ 2.735087] NET: Registered protocol family 38 [ 2.736468] Key type asymmetric registered [ 2.738380] Asymmetric key parser 'x509' registered [ 2.740462] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.742971] io scheduler mq-deadline registered [ 2.744141] io scheduler kyber registered [ 2.746087] io scheduler bfq registered [ 2.747498] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.749081] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.750655] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.752283] ACPI: Power Button [PWRF] [ 2.756368] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.760617] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.771456] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.799631] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.826047] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.829427] Non-volatile memory driver v1.3 [ 2.830581] Linux agpgart interface v0.103 [ 2.859115] virtio_blk virtio1: [vda] 146000 512-byte logical blocks (74.8 MB/71.3 MiB) [ 2.861122] vda: detected capacity change from 0 to 74752000 [ 2.874661] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.877739] vdb: detected capacity change from 0 to 1073741824 [ 2.883289] libphy: Fixed MDIO Bus: probed [ 2.899306] usbcore: registered new interface driver usbserial_generic [ 2.902662] usbserial: USB Serial support registered for generic [ 2.905708] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.911133] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.913483] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.916426] mousedev: PS/2 mouse device common for all mice [ 2.919573] rtc_cmos 00:05: RTC can wake from S4 [ 2.922697] rtc_cmos 00:05: registered as rtc0 [ 2.924583] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.927297] intel_pstate: CPU model not supported [ 2.929861] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.932063] hid: raw HID events driver (C) Jiri Kosina [ 2.937954] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.939042] usbcore: registered new interface driver usbhid [ 2.944194] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.944558] usbhid: USB HID core driver [ 2.949561] drop_monitor: Initializing network drop monitor service [ 2.951373] Initializing XFRM netlink socket [ 2.953520] NET: Registered protocol family 10 [ 2.956589] Segment Routing with IPv6 [ 2.957836] NET: Registered protocol family 17 [ 2.959191] mpls_gso: MPLS GSO support [ 2.963911] RAS: Correctable Errors collector initialized. [ 2.966459] AVX version of gcm_enc/dec engaged. [ 2.968152] AES CTR mode by8 optimization enabled [ 3.044247] sched_clock: Marking stable (3044223647, 0)->(3994682687, -950459040) [ 3.048524] registered taskstats version 1 [ 3.050112] Loading compiled-in X.509 certificates [ 3.052496] zswap: loaded using pool lzo/zbud [ 3.079471] Key type big_key registered [ 3.091469] Key type encrypted registered [ 3.093243] ima: No TPM chip found, activating TPM-bypass! [ 3.095316] ima: Allocated hash algorithm: sha1 [ 3.097040] ima: No architecture policies found [ 3.098778] evm: Initialising EVM extended attributes: [ 3.100356] evm: security.selinux [ 3.101661] evm: security.ima [ 3.102816] evm: security.capability [ 3.104076] evm: HMAC attrs: 0x1 [ 3.106254] rtc_cmos 00:05: setting system clock to 2026-08-19 04:49:54 UTC (1787114994) [ 3.112948] debug: unmapping init [mem 0xffffffffa5203000-0xffffffffa53fffff] [ 3.116167] debug: unmapping init [mem 0xffffffffa3f82000-0xffffffffa4258fff] [ 3.124161] Write protecting the kernel read-only data: 28672k [ 3.127291] debug: unmapping init [mem 0xffffffffa2603000-0xffffffffa27fffff] [ 3.130042] debug: unmapping init [mem 0xffffffffa2f14000-0xffffffffa2ffffff] [ 3.160447] 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.168231] systemd[1]: Detected virtualization kvm. [ 3.170247] systemd[1]: Detected architecture x86-64. [ 3.172468] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.198130] systemd[1]: No hostname configured. [ 3.200407] systemd[1]: Set hostname to . [ 3.202271] random: systemd: uninitialized urandom read (16 bytes read) [ 3.204754] systemd[1]: Initializing machine ID from random generator. [ 3.252355] random: ln: uninitialized urandom read (6 bytes read) [ 3.342648] random: systemd: uninitialized urandom read (16 bytes read) [ 3.345899] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 3.351458] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 3.360573] systemd[1]: Starting Journal Service... Starting Journal Service... [ OK ] Reached target Initrd Root Device. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Timers. Starting Setup Virtual Console... [ OK ] Reached target Slices. [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Local File Systems. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. Starting Apply Kernel Variables... Starting Create Volatile Files and Directories... [ OK ] Reached target Swap. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Journal Service. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 3.938153] device-mapper: uevent: version 1.0.3 [ 3.940977] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 4.546953] virtio_net virtio0 ens2: renamed from eth0 [ 4.636874] scsi host0: ata_piix [ 4.687959] scsi host1: ata_piix [ 4.689503] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.691713] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.934950] dracut-initqueue[592]: RTNETLINK answers: File exists [ 9.614248] random: crng init done [ 9.615627] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 9.974795] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local File Systems. Stopping udev Kernel Device Manager... [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev 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.252963] printk: systemd: 22 output lines suppressed due to ratelimiting [ 11.534561] SELinux: Disabled at runtime. [ 11.607918] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 11.614503] systemd[1]: Detected virtualization kvm. [ 11.616154] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.141769] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.145589] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.149278] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.153473] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.157500] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.168095] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.192250] systemd[1]: Listening on Process Core Dump Socket. [ OK ] Listening on Process Core Dump Socket. Starting Remount Root and Kernel File Systems... [ OK ] Created slice system-getty.slice. [ OK ] Listening on RPCbind Server Activation Socket. Activating swap /dev/disk/by-label/SWAP... Mounting POSIX Message Queue File System... [ OK ] Reached target RPC Port Mapper. Starting Apply Kernel Variables... [ OK ] Reached target rpc_pipefs.target. [ 12.271183] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Mounting Huge Pages File System... [ OK ] Stopped target Switch Root. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Mounting Kernel Debug File System... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on udev Kernel Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Stopped target Initrd Root File System. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Stopped target Initrd File Systems. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Started Journal Service. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Configure read-only root support... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Mounted /mnt. [ OK ] Started udev Coldplug all Devices. [ 12.741252] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 13.068452] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.156054] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.247458] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.276939] EDAC sbridge: Ver: 1.1.2 [ 14.799682] Key type dns_resolver registered [ 15.112542] NFS: Registering the id_resolver key type [ 15.114295] Key type id_resolver registered [ 15.116068] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... Starting Rebuild Dynamic Linker Cache... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting 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 Cleanup of Temporary Directories. [ OK ] Started dnf makecache --timer. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ 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. Starting Login Service... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Reached target sshd-keygen.target. Starting Network Manager... [ 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 GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Dynamic System Tuning 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 OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ 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 oleg146-client login: [ 44.043206] libcfs: loading out-of-tree module taints kernel. [ 44.170628] Key type ._llcrypt registered [ 44.172436] Key type .llcrypt registered [ 44.348331] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 44.355068] alg: No test for adler32 (adler32-zlib) [ 45.394754] Lustre: Lustre: Build Version: 2.17.56_3_g13af17f [ 45.751095] LNet: Added LNI 192.168.201.46@tcp [8/256/0/180] [ 47.391208] Key type lgssc registered [ 48.102633] Lustre: Echo OBD driver; http://www.lustre.org/ [ 156.458404] hrtimer: interrupt took 5632085 ns [ 224.077854] Lustre: Mounted lustre-client [ 229.871976] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 244.403548] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing check_logdir /tmp/testlogs/ [ 249.823225] Lustre: lustre-OST0000-osc-ffff9c1d8379d800: disconnect after 24s idle [ 250.761429] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing yml_node [ 254.980725] Lustre: DEBUG MARKER: Client: 2.17.56.3 [ 257.424166] Lustre: DEBUG MARKER: MDS: 2.17.56.3 [ 260.203512] Lustre: DEBUG MARKER: OSS: 2.17.56.3 [ 261.651833] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Wed Aug 19 00:54:11 EDT 2026 [ 279.418640] Lustre: DEBUG MARKER: excepting tests: 32newtarball [ 281.479202] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 285.220853] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 295.565705] Lustre: Unmounted lustre-client [ 424.880344] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 431.228989] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 457.777344] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 499.554943] Lustre: DEBUG MARKER: == conf-sanity test 121: failover MGS ==================== 00:58:09 (1787115489) [ 550.297702] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 557.822440] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 607.726791] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid 1475 0 [ 664.677807] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 671.256517] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 720.129113] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid 1475 0 [ 753.937886] Lustre: DEBUG MARKER: == conf-sanity test 122a: Check OST sequence update ====== 01:02:23 (1787115743) [ 877.081355] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 884.167981] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 909.140886] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 909.825106] Lustre: Mounted lustre-client [ 930.571301] Lustre: Unmounted lustre-client [ 967.427096] Key type lgssc unregistered [ 967.723926] LNet: 21885:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 967.729161] LNetError: 21885:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 967.756444] LNet: Removed LNI 192.168.201.46@tcp [ 968.855159] Key type .llcrypt unregistered [ 968.861202] Key type ._llcrypt unregistered [ 986.014207] Key type ._llcrypt registered [ 986.015979] Key type .llcrypt registered [ 987.386434] Lustre: DEBUG MARKER: == conf-sanity test 123aa: llog_print works with FIDs and simple names ========================================================== 01:06:17 (1787115977) [ 990.006327] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 990.171459] alg: No test for adler32 (adler32-zlib) [ 991.401216] Lustre: Lustre: Build Version: 2.17.56_3_g13af17f [ 991.868386] LNet: Added LNI 192.168.201.46@tcp [8/256/0/180] [ 993.665141] Key type lgssc registered [ 994.927390] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1047.883757] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1053.284291] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1075.010482] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1077.734726] Lustre: Mounted lustre-client [ 1088.377112] Lustre: DEBUG MARKER: == conf-sanity test 123ab: llog_print params output values from set_param -P ========================================================== 01:07:58 (1787116078) [ 1101.110119] Lustre: DEBUG MARKER: == conf-sanity test 123ac: llog_print with --start and --end ========================================================== 01:08:10 (1787116090) [ 1110.220292] Lustre: DEBUG MARKER: == conf-sanity test 123ad: llog_print shows all records == 01:08:20 (1787116100) [ 1121.785888] Lustre: DEBUG MARKER: == conf-sanity test 123ae: llog_cancel can cancel requested record ========================================================== 01:08:31 (1787116111) [ 1144.738624] Lustre: DEBUG MARKER: == conf-sanity test 123af: llog_catlist can show all config files correctly ========================================================== 01:08:54 (1787116134) [ 1159.861992] Lustre: DEBUG MARKER: == conf-sanity test 123ag: llog_print skips values deleted by set_param -P -d ========================================================== 01:09:09 (1787116149) [ 1178.407617] Lustre: DEBUG MARKER: == conf-sanity test 123ah: del_ost cancels config log entries correctly ========================================================== 01:09:27 (1787116167) [ 1192.156044] Lustre: Unmounted lustre-client [ 1192.644675] Lustre: Mounted lustre-client [ 1195.570757] Lustre: Unmounted lustre-client [ 1226.363731] Key type lgssc unregistered [ 1226.687881] LNet: 30252:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1226.703949] LNetError: 30252:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1226.731843] LNet: Removed LNI 192.168.201.46@tcp [ 1227.707709] Key type .llcrypt unregistered [ 1227.712084] Key type ._llcrypt unregistered [ 1261.981487] Key type ._llcrypt registered [ 1261.984538] Key type .llcrypt registered [ 1262.534643] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1262.560690] alg: No test for adler32 (adler32-zlib) [ 1263.838167] Lustre: Lustre: Build Version: 2.17.56_3_g13af17f [ 1264.059805] LNet: Added LNI 192.168.201.46@tcp [8/256/0/180] [ 1265.807505] Key type lgssc registered [ 1267.516097] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1360.762248] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1366.399971] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1389.767824] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1441.370332] Lustre: DEBUG MARKER: == conf-sanity test 123ai: llog_print display all non skipped records ========================================================== 01:13:51 (1787116431) [ 1473.361796] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1479.477419] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1499.856745] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1502.556358] Lustre: Mounted lustre-client [ 1632.221801] Lustre: DEBUG MARKER: == conf-sanity test 123aj: check permanent TBF rules ===== 01:17:02 (1787116622) [ 1674.442789] Lustre: DEBUG MARKER: == conf-sanity test 123F: clear and reset all parameters using set_param -F ========================================================== 01:17:44 (1787116664) [ 1684.782942] Lustre: Unmounted lustre-client [ 1763.314627] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1768.013990] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1785.705245] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1786.499591] Lustre: Mounted lustre-client [ 1791.276126] Lustre: Unmounted lustre-client [ 1819.829660] Key type lgssc unregistered [ 1820.064293] LNet: 41567:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1820.071134] LNetError: 41567:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1820.100336] LNet: Removed LNI 192.168.201.46@tcp [ 1820.836279] Key type .llcrypt unregistered [ 1820.838789] Key type ._llcrypt unregistered [ 1836.218643] Key type ._llcrypt registered [ 1836.220981] Key type .llcrypt registered [ 1837.406315] Lustre: DEBUG MARKER: == conf-sanity test 123G: clear and reset all parameters using apply_yaml ========================================================== 01:20:27 (1787116827) [ 1840.100992] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1840.292560] alg: No test for adler32 (adler32-zlib) [ 1841.635875] Lustre: Lustre: Build Version: 2.17.56_3_g13af17f [ 1841.942231] LNet: Added LNI 192.168.201.46@tcp [8/256/0/180] [ 1843.711142] Key type lgssc registered [ 1845.053417] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1893.761483] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1899.570843] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1922.784791] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1924.855740] Lustre: Mounted lustre-client [ 1936.849781] Lustre: Unmounted lustre-client [ 2026.492976] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2033.398675] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2056.505889] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2057.404509] Lustre: Mounted lustre-client [ 2063.694707] Lustre: Unmounted lustre-client [ 2092.558990] Key type lgssc unregistered [ 2092.796596] LNet: 47099:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2092.803290] LNetError: 47099:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2092.829623] LNet: Removed LNI 192.168.201.46@tcp [ 2093.423703] Key type .llcrypt unregistered [ 2093.425123] Key type ._llcrypt unregistered [ 2109.927405] Key type ._llcrypt registered [ 2109.930146] Key type .llcrypt registered [ 2111.117877] Lustre: DEBUG MARKER: == conf-sanity test 123H: check concurent accesses with 'lctl llog_print ========================================================== 01:25:01 (1787117101) [ 2113.737988] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2113.753663] alg: No test for adler32 (adler32-zlib) [ 2115.010530] Lustre: Lustre: Build Version: 2.17.56_3_g13af17f [ 2115.325252] LNet: Added LNI 192.168.201.46@tcp [8/256/0/180] [ 2117.079243] Key type lgssc registered [ 2118.575740] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2170.164696] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2176.596977] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2201.197540] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2203.119507] Lustre: Mounted lustre-client [ 2312.919682] Lustre: DEBUG MARKER: == conf-sanity test 124: check failover after replace_nids ========================================================== 01:28:22 (1787117302) [ 2314.847988] Lustre: DEBUG MARKER: SKIP: conf-sanity test_124 needs MDT failover setup [ 2317.193745] Lustre: DEBUG MARKER: == conf-sanity test 126: mount in parallel shouldn't cause a crash ========================================================== 01:28:26 (1787117306) [ 2319.013806] Lustre: Unmounted lustre-client [ 2348.235648] Key type lgssc unregistered [ 2348.671402] LNet: 52444:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2348.685286] LNetError: 52444:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2348.726776] LNet: Removed LNI 192.168.201.46@tcp [ 2349.750069] Key type .llcrypt unregistered [ 2349.755704] Key type ._llcrypt unregistered [ 2378.409690] Key type ._llcrypt registered [ 2378.441458] Key type .llcrypt registered [ 2379.145868] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2379.162717] alg: No test for adler32 (adler32-zlib) [ 2380.410519] Lustre: Lustre: Build Version: 2.17.56_3_g13af17f [ 2380.669149] LNet: Added LNI 192.168.201.46@tcp [8/256/0/180] [ 2382.399249] Key type lgssc registered [ 2383.672540] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2417.036211] Lustre: DEBUG MARKER: == conf-sanity test 127: direct io overwrite on full ost ========================================================== 01:30:07 (1787117407) [ 2431.692496] Key type lgssc unregistered [ 2432.033859] LNet: 54851:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2432.047337] LNetError: 54851:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2432.071253] LNet: Removed LNI 192.168.201.46@tcp [ 2433.035808] Key type .llcrypt unregistered [ 2433.040680] Key type ._llcrypt unregistered [ 2449.228273] Key type ._llcrypt registered [ 2449.239000] Key type .llcrypt registered [ 2449.540779] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2449.568583] alg: No test for adler32 (adler32-zlib) [ 2450.699530] Lustre: Lustre: Build Version: 2.17.56_3_g13af17f [ 2451.092413] LNet: Added LNI 192.168.201.46@tcp [8/256/0/180] [ 2452.879253] Key type lgssc registered [ 2454.193203] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2510.717983] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2517.880935] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2539.251362] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2541.088257] Lustre: Mounted lustre-client [ 2589.973553] Lustre: DEBUG MARKER: == conf-sanity test 128: Force using remote logs with --nolocallogs ========================================================== 01:32:59 (1787117579) [ 2592.211888] Lustre: DEBUG MARKER: SKIP: conf-sanity test_128 need separate mgs device [ 2593.977496] Lustre: DEBUG MARKER: == conf-sanity test 129: attempt to connect an OST with the same index should fail ========================================================== 01:33:04 (1787117584) [ 2595.885450] Lustre: Unmounted lustre-client [ 2664.087318] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2669.124118] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2755.325221] Lustre: DEBUG MARKER: == conf-sanity test 130: re-register an MDT after writeconf ========================================================== 01:35:44 (1787117744) [ 2848.293183] Lustre: Mounted lustre-client [ 2853.360593] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 2873.836941] Lustre: lustre-OST0000-osc-ffff9c1d89628800: disconnect after 23s idle [ 2873.852982] Lustre: lustre-MDT0001-mdc-ffff9c1d89628800: Connection to lustre-MDT0001 (at 192.168.201.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2885.730306] LustreError: 63580:0:(obd_config.c:2103:class_config_llog_handler()) MGC192.168.201.146@tcp: cfg command failed: rc = -17 [ 2885.738627] Lustre: cmd=cf001 0:lustre-MDT0001-mdc 1:mdc 2:lustre-clilmv_UUID [ 2885.738627] [ 2885.747927] LustreError: 62802:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [ 2898.005538] Lustre: DEBUG MARKER: == conf-sanity test 131: MDT backup restore with project ID and inheritance flag ========================================================== 01:38:08 (1787117888) [ 2956.811084] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3038.028373] Lustre: Unmounted lustre-client [ 3204.407337] Lustre: Mounted lustre-client [ 3209.604374] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3258.337835] Lustre: DEBUG MARKER: == conf-sanity test 132: hsm_actions processed after failover ========================================================== 01:44:08 (1787118248) [ 3260.045291] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3260.698585] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 3260.702547] Lustre: Skipped 2 previous similar messages [ 3283.276931] Lustre: Unmounted lustre-client [ 3420.063372] Lustre: DEBUG MARKER: == conf-sanity test 133: stripe QOS: free space balance in a pool ========================================================== 01:46:49 (1787118409) [ 3421.728437] Lustre: DEBUG MARKER: SKIP: conf-sanity test_133 needs >= 4 OSTs [ 3423.813707] Lustre: DEBUG MARKER: == conf-sanity test 134: check_iam works without faults == 01:46:53 (1787118413) [ 3522.376813] Lustre: Mounted lustre-client [ 3527.738396] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 4171.225961] Lustre: DEBUG MARKER: == conf-sanity test 135: check the behavior when changelog is wrapped around ========================================================== 01:59:21 (1787119161) [ 4173.404177] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4183.754512] Lustre: Unmounted lustre-client [ 4327.846743] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4334.034880] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4359.015252] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4359.613410] Lustre: Mounted lustre-client [ 6064.891599] Lustre: 95253:0:(llog_cat.c:310:llog_cat_process_or_fork()) lustre-MDT0000-mdc-ffff9c1d902d6000: catlog [0xa:0x5:0x0] crosses index zero [ 6090.417619] Lustre: Unmounted lustre-client [ 6125.881779] Key type lgssc unregistered [ 6126.131445] LNet: 103410:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6126.144844] LNetError: 103410:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6126.164461] LNet: Removed LNI 192.168.201.46@tcp [ 6126.750306] Key type .llcrypt unregistered [ 6126.754647] Key type ._llcrypt unregistered [ 6136.714828] Key type ._llcrypt registered [ 6136.719117] Key type .llcrypt registered [ 6138.349985] Lustre: DEBUG MARKER: == conf-sanity test 136: don't panic with bad obdecho setup ========================================================== 02:32:07 (1787121127) [ 6159.364787] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6159.380647] alg: No test for adler32 (adler32-zlib) [ 6160.461898] Lustre: Lustre: Build Version: 2.17.56_3_g13af17f [ 6160.740050] LNet: Added LNI 192.168.201.46@tcp [8/256/0/180] [ 6162.423202] Key type lgssc registered [ 6163.519667] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6249.877632] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6255.646342] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6280.975191] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6282.639347] Lustre: Mounted lustre-client [ 6301.407304] Lustre: Unmounted lustre-client [ 6438.768507] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6444.551160] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6467.840608] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6508.064465] Lustre: DEBUG MARKER: == conf-sanity test 137: a new MDT should inherit pools, parameters and failnode ========================================================== 02:38:16 (1787121496) [ 6509.622982] Lustre: DEBUG MARKER: SKIP: conf-sanity test_137 needs >= 3 MDTs [ 6511.351402] Lustre: DEBUG MARKER: == conf-sanity test 140: remove_updatelog script actions ========================================================== 02:38:21 (1787121501) [ 6552.204982] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6560.300232] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6582.934742] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6594.137198] Lustre: Mounted lustre-client [ 6604.298735] Lustre: lustre-MDT0000-mdc-ffff9c1d82c33000: Connection to lustre-MDT0000 (at 192.168.201.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6614.500475] Lustre: lustre-MDT0001-mdc-ffff9c1d82c33000: Connection to lustre-MDT0001 (at 192.168.201.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6624.736086] Lustre: 104171:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787121600/real 1787121600] req@000000005935a371 x1873932349157376/t0(0) o400->MGC192.168.201.146@tcp@192.168.201.146@tcp:26/25 lens 224/224 e 0 to 1 dl 1787121616 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6624.775484] LustreError: MGC192.168.201.146@tcp: Connection to MGS (at 192.168.201.146@tcp) was lost; in progress operations using this service will fail [ 6771.192542] Lustre: Evicted from MGS (at 192.168.201.146@tcp) after server handle changed from 0x17ec4f9e38ba70c6 to 0x17ec4f9e38ba8763 [ 6771.218745] Lustre: MGC192.168.201.146@tcp: Connection restored to 192.168.201.146@tcp (at 192.168.201.146@tcp) [ 6780.914965] Lustre: 113574:0:(mgc_request.c:1899:mgc_process_log()) MGC192.168.201.146@tcp: IR log lustre-cliir failed, not fatal: rc = -2 [ 6786.555663] LustreError: lustre-MDT0000-mdc-ffff9c1d82c33000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 6786.612300] Lustre: lustre-MDT0000-mdc-ffff9c1d82c33000: Connection restored to 192.168.201.146@tcp (at 192.168.201.146@tcp) [ 6789.650950] LustreError: lustre-MDT0001-mdc-ffff9c1d82c33000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 6789.680994] Lustre: lustre-MDT0001-mdc-ffff9c1d82c33000: Connection restored to 192.168.201.146@tcp (at 192.168.201.146@tcp) [ 6797.792945] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6799.737429] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6806.779448] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6808.984604] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6825.597682] Lustre: Unmounted lustre-client [ 6951.089461] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6957.050444] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6979.641873] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7024.997993] Lustre: DEBUG MARKER: == conf-sanity test 150: test setting max_cached_mb to a % ========================================================== 02:46:54 (1787122014) [ 7061.801193] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7067.618922] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7091.414927] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7091.946178] Lustre: Mounted lustre-client [ 7095.290222] LustreError: 120910: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 [ 7103.773328] Lustre: DEBUG MARKER: == conf-sanity test 151a: damaged local config doesn't prevent mounting ========================================================== 02:48:12 (1787122092) [ 7107.731753] Lustre: Unmounted lustre-client [ 7135.680478] Key type lgssc unregistered [ 7135.951356] LNet: 121918:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7135.960558] LNetError: 121918:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7135.986902] LNet: Removed LNI 192.168.201.46@tcp [ 7136.838365] Key type .llcrypt unregistered [ 7136.843370] Key type ._llcrypt unregistered [ 7151.193572] Key type ._llcrypt registered [ 7151.195938] Key type .llcrypt registered [ 7151.428193] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7151.443241] alg: No test for adler32 (adler32-zlib) [ 7152.606858] Lustre: Lustre: Build Version: 2.17.56_3_g13af17f [ 7152.878290] LNet: Added LNI 192.168.201.46@tcp [8/256/0/180] [ 7154.599136] Key type lgssc registered [ 7155.976460] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7248.165489] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7255.316044] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7276.954526] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7401.157712] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7407.392649] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7432.147241] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7471.888350] Lustre: DEBUG MARKER: == conf-sanity test 151b: -ENOSPC doesn't affect mount === 02:54:21 (1787122461) [ 7483.483670] Key type lgssc unregistered [ 7483.760659] LNet: 129137:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7483.767540] LNetError: 129137:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7483.791904] LNet: Removed LNI 192.168.201.46@tcp [ 7484.549768] Key type .llcrypt unregistered [ 7484.552867] Key type ._llcrypt unregistered [ 7498.454543] Key type ._llcrypt registered [ 7498.458529] Key type .llcrypt registered [ 7498.901258] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7498.908566] alg: No test for adler32 (adler32-zlib) [ 7500.203769] Lustre: Lustre: Build Version: 2.17.56_3_g13af17f [ 7500.706495] LNet: Added LNI 192.168.201.46@tcp [8/256/0/180] [ 7502.535175] Key type lgssc registered [ 7504.349948] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7569.628076] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7576.138783] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7599.997124] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7729.328246] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7734.444857] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7756.010655] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7806.312343] Lustre: DEBUG MARKER: == conf-sanity test 152: seq allocation error in OSP ===== 02:59:56 (1787122796) [ 7895.166430] Lustre: Mounted lustre-client [ 7900.542087] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 7912.407782] Lustre: DEBUG MARKER: ADD OST3 [ 7920.608524] Lustre: lustre-OST0000-osc-ffff9c1d89558800: disconnect after 23s idle [ 7926.200767] Lustre: DEBUG MARKER: STOP OST3 [ 7938.541830] Lustre: lustre-OST0003-osc-ffff9c1d89558800: Connection to lustre-OST0003 (at 192.168.201.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7948.078739] Lustre: DEBUG MARKER: START OST3 again [ 7957.726642] Lustre: lustre-OST0003-osc-ffff9c1d89558800: Connection restored to 192.168.201.146@tcp (at 192.168.201.146@tcp) [ 7989.828903] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7989.854863] LustreError: 139975:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff9c1d89558800: inode [0x240000bd0:0x1:0x0] mdc close failed: rc = -108 [ 8000.217609] Lustre: Unmounted lustre-client [ 8135.361906] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8142.042487] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8165.769220] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8211.729898] Lustre: DEBUG MARKER: == conf-sanity test 153a: bypass invalid NIDs quickly ==== 03:06:41 (1787123201) [ 8305.457086] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8311.615383] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8337.026545] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8403.212832] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8409.104492] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8431.970760] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8435.117624] LNetError: 129549:0:(lib-move.c:2158:lnet_handle_find_routed_path()) peer 192.168.252.112@tcp2 has no available nets [ 8435.637541] Lustre: Mounted lustre-client [ 8440.795751] Lustre: Unmounted lustre-client [ 8471.336469] Key type lgssc unregistered [ 8485.983410] LNet: 1 local NIs in recovery (showing 1): 192.168.201.46@tcp [ 8493.089320] LNet: 148966:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8493.103804] LNetError: 148966:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8565.867590] LNet: Removed LNI 192.168.201.46@tcp [ 8566.709240] Key type .llcrypt unregistered [ 8566.716603] Key type ._llcrypt unregistered [ 8582.022626] Key type ._llcrypt registered [ 8582.028798] Key type .llcrypt registered [ 8583.543100] Lustre: DEBUG MARKER: == conf-sanity test 153b: added IPv6 NID support ========= 03:12:53 (1787123573) [ 8605.732841] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8605.749936] alg: No test for adler32 (adler32-zlib) [ 8607.103683] Lustre: Lustre: Build Version: 2.17.56_3_g13af17f [ 8607.584755] LNet: Added LNI 192.168.201.46@tcp [8/256/0/180] [ 8609.383214] Key type lgssc registered [ 8611.199427] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8706.114851] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8711.764530] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8734.484682] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8855.497031] Lustre: Mounted lustre-client [ 8861.288931] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 8871.478215] Lustre: Unmounted lustre-client [ 8871.602233] LNetError: 150149:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::541b:ebff:fed9:59f9/1023: rc = -22 [ 8871.616074] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 8871.963459] Lustre: Mounted lustre-client [ 8872.159191] Lustre: Unmounted lustre-client [ 8872.586466] LNetError: 150148:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::541b:ebff:fed9:59f9/1023: rc = -22 [ 8872.597602] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 8873.221259] Lustre: Mounted lustre-client [ 8873.223747] Lustre: Skipped 2 previous similar messages [ 8873.369136] Lustre: Unmounted lustre-client [ 8873.376221] Lustre: Skipped 2 previous similar messages [ 8874.332536] LNetError: 150149:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::541b:ebff:fed9:59f9/1023: rc = -22 [ 8874.343261] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 8875.321473] Lustre: 156089: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 [ 8875.585168] Lustre: Mounted lustre-client [ 8875.588726] Lustre: Skipped 4 previous similar messages [ 8875.730620] Lustre: Unmounted lustre-client [ 8875.737465] Lustre: Skipped 4 previous similar messages [ 8875.831289] Lustre: 156118: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 [ 8882.162921] Lustre: DEBUG MARKER: == conf-sanity test 153c: don't stuck on unreached NID === 03:17:52 (1787123872) [ 8933.222016] LNet: 1 local NIs in recovery (showing 1): 192.168.201.46@tcp [ 8997.198364] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9002.797370] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9025.186401] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9097.401588] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9104.547343] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9126.574798] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9132.131899] LustreError: 161714:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.252.112@tcp: failed processing log, type 1: rc = -110 [ 9181.024144] LNet: 1 local NIs in recovery (showing 1): 192.168.201.46@tcp [ 9258.847590] LNet: 1 local NIs in recovery (showing 1): 192.168.201.46@tcp [ 9269.215782] 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 [ 9269.225671] Lustre: Unmounted lustre-client [ 9269.227884] Lustre: Skipped 1 previous similar message [ 9269.232865] LustreError: 161714:0:(super25.c:178:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 9305.912489] Key type lgssc unregistered [ 9314.336274] LNet: 162174:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9314.351292] LNetError: 162174:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9389.160383] LNet: Removed LNI 192.168.201.46@tcp [ 9390.154230] Key type .llcrypt unregistered [ 9390.156688] Key type ._llcrypt unregistered [ 9407.140221] Key type ._llcrypt registered [ 9407.143781] Key type .llcrypt registered [ 9408.583619] Lustre: DEBUG MARKER: == conf-sanity test 154: expand .. on rename after MDT backup restore ========================================================== 03:26:38 (1787124398) [ 9426.821317] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9427.009992] alg: No test for adler32 (adler32-zlib) [ 9428.132636] Lustre: Lustre: Build Version: 2.17.56_3_g13af17f [ 9428.446416] LNet: Added LNI 192.168.201.46@tcp [8/256/0/180] [ 9430.151241] Key type lgssc registered [ 9431.758256] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9566.965294] Lustre: Mounted lustre-client [ 9571.724797] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9591.657411] Lustre: Unmounted lustre-client [ 9751.673478] Lustre: Mounted lustre-client [ 9756.743721] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9762.582473] Lustre: Unmounted lustre-client [ 9818.633887] Lustre: DEBUG MARKER: == conf-sanity test 155: gap in seq allocation from ofd after restarting ========================================================== 03:33:28 (1787124808) [ 9906.617559] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9911.895243] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9935.461931] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10053.874141] Lustre: Mounted lustre-client [10058.747570] Lustre: DEBUG MARKER: Using TIMEOUT=20 [10076.191983] Lustre: Unmounted lustre-client [10193.520238] Lustre: Mounted lustre-client [10198.533834] Lustre: DEBUG MARKER: Using TIMEOUT=20 [10218.422205] Lustre: DEBUG MARKER: == conf-sanity test 156: root_fid on export consistent with client mount ========================================================== 03:40:08 (1787125208) [10218.975444] Lustre: lustre-OST0001-osc-ffff9c1d86455000: disconnect after 23s idle [10220.618805] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [10231.013820] Lustre: Unmounted lustre-client [10415.299353] Lustre: Mounted lustre-client [10419.901254] Lustre: DEBUG MARKER: Using TIMEOUT=20 [10428.258918] Lustre: Unmounted lustre-client [10428.940959] Lustre: Mounted lustre-client [10439.174045] Lustre: Unmounted lustre-client [10442.232406] Lustre: DEBUG MARKER: == conf-sanity test 157a: test allow_register for MDT registration and MGS restart ========================================================== 03:43:51 (1787125431) [10515.669175] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10521.778853] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10542.842647] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10543.886295] Lustre: Mounted lustre-client [10641.387174] LustreError: lustre-OST0001-osc-ffff9c1d90ea8800: operation ost_connect to node 192.168.201.146@tcp failed: rc = -19 [10646.515218] Lustre: lustre-MDT0000-mdc-ffff9c1d90ea8800: Connection to lustre-MDT0000 (at 192.168.201.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [10661.868058] LustreError: MGC192.168.201.146@tcp: Connection to MGS (at 192.168.201.146@tcp) was lost; in progress operations using this service will fail [10661.884261] LustreError: lustre-MDT0000-mdc-ffff9c1d90ea8800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [10661.912865] Lustre: lustre-MDT0000-mdc-ffff9c1d90ea8800: Connection restored to 192.168.201.146@tcp (at 192.168.201.146@tcp) [10661.929443] Lustre: Evicted from MGS (at 192.168.201.146@tcp) after server handle changed from 0x3ea9d5d6d5fd53ef to 0x3ea9d5d6d5fd56b9 [10716.133371] Lustre: Unmounted lustre-client [10748.839969] Key type lgssc unregistered [10749.081242] LNet: 189712:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10749.089028] LNetError: 189712:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10749.111434] LNet: Removed LNI 192.168.201.46@tcp [10749.704149] Key type .llcrypt unregistered [10749.706528] Key type ._llcrypt unregistered [10760.006903] Key type ._llcrypt registered [10760.009162] Key type .llcrypt registered [10761.424254] Lustre: DEBUG MARKER: == conf-sanity test 157b: verify allow_register (block new OSTs, allow existing) ========================================================== 03:49:11 (1787125751) [10764.077420] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10764.175344] alg: No test for adler32 (adler32-zlib) [10765.417097] Lustre: Lustre: Build Version: 2.17.56_3_g13af17f [10765.770804] LNet: Added LNI 192.168.201.46@tcp [8/256/0/180] [10767.423297] Key type lgssc registered [10768.664545] Lustre: Echo OBD driver; http://www.lustre.org/ [10822.136628] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10829.036911] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10853.799203] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10855.681903] Lustre: Mounted lustre-client [10953.218151] LustreError: lustre-OST0001-osc-ffff9c1d899fe000: operation ost_connect to node 192.168.201.146@tcp failed: rc = -19 [11087.033959] Lustre: Unmounted lustre-client [11115.501300] Key type lgssc unregistered [11115.771720] LNet: 195744:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11115.778981] LNetError: 195744:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11115.796329] LNet: Removed LNI 192.168.201.46@tcp [11116.686178] Key type .llcrypt unregistered [11116.689554] Key type ._llcrypt unregistered [11127.941217] Key type ._llcrypt registered [11127.942737] Key type .llcrypt registered [11129.276563] Lustre: DEBUG MARKER: == conf-sanity test 160: MGC updates failnodes from all participants ========================================================== 03:55:19 (1787126119) [11162.441358] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11162.468583] alg: No test for adler32 (adler32-zlib) [11163.797360] Lustre: Lustre: Build Version: 2.17.56_3_g13af17f [11164.017594] LNet: Added LNI 192.168.201.46@tcp [8/256/0/180] [11165.743199] Key type lgssc registered [11167.171421] Lustre: Echo OBD driver; http://www.lustre.org/ [11255.392346] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11262.366227] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11287.887331] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11337.946293] Key type lgssc unregistered [11338.216085] LNet: 200725:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11338.225870] LNetError: 200725:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11338.255868] LNet: Removed LNI 192.168.201.46@tcp [11339.203893] Key type .llcrypt unregistered [11339.207148] Key type ._llcrypt unregistered [11446.017677] Key type ._llcrypt registered [11446.031560] Key type .llcrypt registered [11446.525374] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11446.559372] alg: No test for adler32 (adler32-zlib) [11447.784985] Lustre: Lustre: Build Version: 2.17.56_3_g13af17f [11448.061352] LNet: Added LNI 192.168.201.46@tcp [8/256/0/180] [11449.799352] Key type lgssc registered [11451.668485] Lustre: Echo OBD driver; http://www.lustre.org/ [11500.539880] Lustre: DEBUG MARKER: == conf-sanity test 161: test '-o mgsname' option ======== 04:01:30 (1787126490) [11541.326785] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11548.341434] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11575.883455] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11577.754566] Lustre: Mounted lustre-client [11580.197148] Lustre: Unmounted lustre-client [11580.601294] Lustre: Mounted lustre-client [11580.782132] Lustre: Unmounted lustre-client [11588.871162] Lustre: Unmounted lustre-client [11627.226467] Key type lgssc unregistered [11627.476915] LNet: 205714:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11627.487329] LNetError: 205714:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11627.510180] LNet: Removed LNI 192.168.201.46@tcp [11628.201907] Key type .llcrypt unregistered [11628.207459] Key type ._llcrypt unregistered [11639.243752] Key type ._llcrypt registered [11639.249736] Key type .llcrypt registered [11641.084882] Lustre: DEBUG MARKER: == conf-sanity test 162: replace nids with -o noclient === 04:03:50 (1787126630) [11645.406378] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11645.421365] alg: No test for adler32 (adler32-zlib) [11646.665056] Lustre: Lustre: Build Version: 2.17.56_3_g13af17f [11646.942434] LNet: Added LNI 192.168.201.46@tcp [8/256/0/180] [11648.792437] Key type lgssc registered [11650.459734] Lustre: Echo OBD driver; http://www.lustre.org/ [11705.712679] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11711.965991] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11736.023180] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11739.082743] Lustre: Mounted lustre-client [11747.699513] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11750.500758] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [11759.593760] Lustre: lustre-MDT0000-mdc-ffff9c1d82c37800: Connection to lustre-MDT0000 (at 192.168.201.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [11769.858881] Lustre: lustre-MDT0001-mdc-ffff9c1d82c37800: Connection to lustre-MDT0001 (at 192.168.201.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [11781.087725] Lustre: 206404:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787126756/real 1787126756] req@000000008e34c824 x1873938101573376/t0(0) o400->MGC192.168.201.146@tcp@192.168.201.146@tcp:26/25 lens 224/224 e 0 to 1 dl 1787126772 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [11781.124795] LustreError: MGC192.168.201.146@tcp: Connection to MGS (at 192.168.201.146@tcp) was lost; in progress operations using this service will fail [11814.890839] Lustre: Evicted from MGS (at 192.168.201.146@tcp) after server handle changed from 0xc7f23b4b4c64588a to 0xc7f23b4b4c645a19 [11814.910810] Lustre: MGC192.168.201.146@tcp: Connection restored to 192.168.201.146@tcp (at 192.168.201.146@tcp) [11856.890256] LustreError: lustre-MDT0000-mdc-ffff9c1d82c37800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [11856.926163] Lustre: lustre-MDT0000-mdc-ffff9c1d82c37800: Connection restored to 192.168.201.146@tcp (at 192.168.201.146@tcp) [11859.592567] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11861.858643] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in IDLE state after 0 sec [11868.109066] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11869.700607] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [11872.837087] Lustre: Unmounted lustre-client [11897.582401] Key type lgssc unregistered [11897.841773] LNet: 211268:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11897.849487] LNetError: 211268:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11897.861707] LNet: Removed LNI 192.168.201.46@tcp [11898.683515] Key type .llcrypt unregistered [11898.685750] Key type ._llcrypt unregistered [11928.211062] Key type ._llcrypt registered [11928.213292] Key type .llcrypt registered [11928.667652] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11928.678619] alg: No test for adler32 (adler32-zlib) [11929.908538] Lustre: Lustre: Build Version: 2.17.56_3_g13af17f [11930.348908] LNet: Added LNI 192.168.201.46@tcp [8/256/0/180] [11932.175351] Key type lgssc registered [11933.576577] Lustre: Echo OBD driver; http://www.lustre.org/ [12025.033134] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12031.878871] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12057.282427] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12108.346882] Lustre: DEBUG MARKER: == conf-sanity test 164: test expected_clients parameter and max client tracking ========================================================== 04:11:38 (1787127098) [12214.773628] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12220.835344] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12247.697996] Lustre: DEBUG MARKER: oleg146-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12249.788741] Lustre: Mounted lustre-client [12254.860958] Lustre: Mounted lustre-client [12255.899169] Lustre: Mounted lustre-client [12255.900840] Lustre: Skipped 1 previous similar message [12261.642776] Lustre: Unmounted lustre-client [12263.386961] Lustre: Unmounted lustre-client [12266.137976] Lustre: Unmounted lustre-client [12268.203962] Lustre: Unmounted lustre-client [12280.534921] Lustre: Unmounted lustre-client [12280.540643] Lustre: Skipped 1 previous similar message [12323.271329] Key type lgssc unregistered [12323.565172] LNet: 220435:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12323.570690] LNetError: 220435:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12323.591199] LNet: Removed LNI 192.168.201.46@tcp [12324.357966] Key type .llcrypt unregistered [12324.361872] Key type ._llcrypt unregistered [12335.396267] Key type ._llcrypt registered [12335.399753] Key type .llcrypt registered [12336.965222] Lustre: DEBUG MARKER: == conf-sanity test 200a: check CPU partitions =========== 04:15:26 (1787127326) [12350.182541] Key type .llcrypt unregistered [12350.184139] Key type ._llcrypt unregistered [12351.104457] Key type ._llcrypt registered [12351.111652] Key type .llcrypt registered [12351.714547] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 4 [12351.735361] alg: No test for adler32 (adler32-zlib) [12352.810314] Lustre: Lustre: Build Version: 2.17.56_3_g13af17f [12353.103136] LNet: Added LNI 192.168.201.46@tcp [8/256/0/180] [12354.727224] Key type lgssc registered [12355.972210] Lustre: Echo OBD driver; http://www.lustre.org/ [12379.522410] Key type lgssc unregistered [12379.791456] LNet: 222591:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12379.799814] LNetError: 222591:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12379.812491] LNet: Removed LNI 192.168.201.46@tcp [12380.528220] Key type .llcrypt unregistered [12380.541582] Key type ._llcrypt unregistered [12381.075210] Key type ._llcrypt registered [12381.080152] Key type .llcrypt registered [12382.899859] Lustre: DEBUG MARKER: == conf-sanity test 200b: set CPU pattern using core selection ========================================================== 04:16:12 (1787127372) [12395.284419] Key type .llcrypt unregistered [12395.286310] Key type ._llcrypt unregistered [12396.471513] Key type ._llcrypt registered [12396.473035] Key type .llcrypt registered [12397.278160] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12397.294825] alg: No test for adler32 (adler32-zlib) [12398.497360] Lustre: Lustre: Build Version: 2.17.56_3_g13af17f [12398.802558] LNet: Added LNI 192.168.201.46@tcp [8/256/0/180] [12400.551424] Key type lgssc registered [12402.223903] Lustre: Echo OBD driver; http://www.lustre.org/ [12421.085393] Key type lgssc unregistered [12421.228971] LNet: 224145:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12421.241675] LNetError: 224145:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12421.270359] LNet: Removed LNI 192.168.201.46@tcp [12421.831111] Key type .llcrypt unregistered [12421.835224] Key type ._llcrypt unregistered [12431.189613] Key type ._llcrypt registered [12431.193952] Key type .llcrypt registered [12431.487852] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12431.505941] alg: No test for adler32 (adler32-zlib) [12432.512902] Lustre: Lustre: Build Version: 2.17.56_3_g13af17f [12432.709099] LNet: Added LNI 192.168.201.46@tcp [8/256/0/180] [12434.359306] Key type lgssc registered [12436.017125] Lustre: Echo OBD driver; http://www.lustre.org/ [12462.013221] Key type lgssc unregistered [12462.354976] LNet: 225908:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12462.364234] LNetError: 225908:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12462.376249] LNet: Removed LNI 192.168.201.46@tcp [12463.011409] Key type .llcrypt unregistered [12463.013740] Key type ._llcrypt unregistered [12463.311743] Key type ._llcrypt registered [12463.316575] Key type .llcrypt registered [12465.007592] Lustre: DEBUG MARKER: == conf-sanity test 200c: set CPU pattern using NUMA node layout ========================================================== 04:17:34 (1787127454) [12478.327548] Key type .llcrypt unregistered [12478.330608] Key type ._llcrypt unregistered [12479.267031] Key type ._llcrypt registered [12479.273068] Key type .llcrypt registered [12479.784342] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12479.792969] alg: No test for adler32 (adler32-zlib) [12480.850475] Lustre: Lustre: Build Version: 2.17.56_3_g13af17f [12481.043761] LNet: Added LNI 192.168.201.46@tcp [8/256/0/180] [12482.695324] Key type lgssc registered [12484.250663] Lustre: Echo OBD driver; http://www.lustre.org/ [12501.588128] Key type lgssc unregistered [12501.830580] LNet: 227456:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12501.843752] LNetError: 227456:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12501.872826] LNet: Removed LNI 192.168.201.46@tcp [12502.785763] Key type .llcrypt unregistered [12502.790133] Key type ._llcrypt unregistered [12509.923989] Key type ._llcrypt registered [12509.925903] Key type .llcrypt registered [12510.543751] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12510.552663] alg: No test for adler32 (adler32-zlib) [12511.743548] Lustre: Lustre: Build Version: 2.17.56_3_g13af17f [12512.039646] LNet: Added LNI 192.168.201.46@tcp [8/256/0/180] [12513.783691] Key type lgssc registered [12515.506458] Lustre: Echo OBD driver; http://www.lustre.org/ [12532.921941] Key type lgssc unregistered [12533.235088] LNet: 228777:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12533.243271] LNetError: 228777:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12533.263826] LNet: Removed LNI 192.168.201.46@tcp [12534.211806] Key type .llcrypt unregistered [12534.217609] Key type ._llcrypt unregistered [12543.153590] Key type ._llcrypt registered [12543.160357] Key type .llcrypt registered [12543.546098] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12543.577755] alg: No test for adler32 (adler32-zlib) [12544.685468] Lustre: Lustre: Build Version: 2.17.56_3_g13af17f [12545.030450] LNet: Added LNI 192.168.201.46@tcp [8/256/0/180] [12546.770681] Key type lgssc registered [12548.196559] Lustre: Echo OBD driver; http://www.lustre.org/ [12567.448865] Key type lgssc unregistered [12567.788075] LNet: 230084:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12567.792800] LNetError: 230084:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12567.827316] LNet: Removed LNI 192.168.201.46@tcp [12568.844590] Key type .llcrypt unregistered [12568.846808] Key type ._llcrypt unregistered [12577.957555] Key type ._llcrypt registered [12577.961154] Key type .llcrypt registered [12578.480762] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12578.504668] alg: No test for adler32 (adler32-zlib) [12579.681772] Lustre: Lustre: Build Version: 2.17.56_3_g13af17f [12579.992919] LNet: Added LNI 192.168.201.46@tcp [8/256/0/180] [12581.687217] Key type lgssc registered [12582.775561] Lustre: Echo OBD driver; http://www.lustre.org/ [12610.441576] Key type lgssc unregistered [12610.734739] LNet: 231858:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12610.749140] LNetError: 231858:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12610.775468] LNet: Removed LNI 192.168.201.46@tcp [12611.873853] Key type .llcrypt unregistered [12611.875359] Key type ._llcrypt unregistered [12612.812199] Key type ._llcrypt registered [12612.816137] Key type .llcrypt registered [12615.562266] Lustre: DEBUG MARKER: == conf-sanity test 200d: set CPU pattern to exclude only CPU 1 ========================================================== 04:20:03 (1787127603) [12633.690493] Key type .llcrypt unregistered [12633.696798] Key type ._llcrypt unregistered [12635.132143] Key type ._llcrypt registered [12635.180468] Key type .llcrypt registered [12635.580159] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12635.596937] alg: No test for adler32 (adler32-zlib) [12636.807737] Lustre: Lustre: Build Version: 2.17.56_3_g13af17f [12637.120343] LNet: Added LNI 192.168.201.46@tcp [8/256/0/180] [12638.872663] Key type lgssc registered [12640.602385] Lustre: Echo OBD driver; http://www.lustre.org/ [12659.722911] Key type lgssc unregistered [12659.977262] LNet: 233412:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12659.996590] LNetError: 233412:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12660.018250] LNet: Removed LNI 192.168.201.46@tcp [12660.912530] Key type .llcrypt unregistered [12660.914898] Key type ._llcrypt unregistered [12669.469667] Key type ._llcrypt registered [12669.483460] Key type .llcrypt registered [12669.870113] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12669.890720] alg: No test for adler32 (adler32-zlib) [12671.018137] Lustre: Lustre: Build Version: 2.17.56_3_g13af17f [12671.239740] LNet: Added LNI 192.168.201.46@tcp [8/256/0/180] [12672.960077] Key type lgssc registered [12674.538849] Lustre: Echo OBD driver; http://www.lustre.org/ [12691.745839] Key type lgssc unregistered [12691.945275] LNet: 234728:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12691.955407] LNetError: 234728:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12691.972284] LNet: Removed LNI 192.168.201.46@tcp [12692.695757] Key type .llcrypt unregistered [12692.697220] Key type ._llcrypt unregistered [12700.461791] Key type ._llcrypt registered [12700.488231] Key type .llcrypt registered [12700.803767] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12700.815517] alg: No test for adler32 (adler32-zlib) [12701.884470] Lustre: Lustre: Build Version: 2.17.56_3_g13af17f [12702.225322] LNet: Added LNI 192.168.201.46@tcp [8/256/0/180] [12704.015340] Key type lgssc registered [12705.580638] Lustre: Echo OBD driver; http://www.lustre.org/ [12723.909125] Key type lgssc unregistered [12724.145528] LNet: 236038:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12724.151723] LNetError: 236038:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12724.174267] LNet: Removed LNI 192.168.201.46@tcp [12724.914672] Key type .llcrypt unregistered [12724.916400] Key type ._llcrypt unregistered [12733.952486] Key type ._llcrypt registered [12733.957558] Key type .llcrypt registered [12734.515234] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12734.542422] alg: No test for adler32 (adler32-zlib) [12735.778862] Lustre: Lustre: Build Version: 2.17.56_3_g13af17f [12736.106532] LNet: Added LNI 192.168.201.46@tcp [8/256/0/180] [12737.903212] Key type lgssc registered [12739.467144] Lustre: Echo OBD driver; http://www.lustre.org/ [12757.389864] Key type lgssc unregistered [12757.766905] LNet: 237348:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12757.774128] LNetError: 237348:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12757.794665] LNet: Removed LNI 192.168.201.46@tcp [12758.361986] Key type .llcrypt unregistered [12758.364462] Key type ._llcrypt unregistered [12766.323797] Key type ._llcrypt registered [12766.329920] Key type .llcrypt registered [12767.089968] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12767.133980] alg: No test for adler32 (adler32-zlib) [12768.433994] Lustre: Lustre: Build Version: 2.17.56_3_g13af17f [12768.778956] LNet: Added LNI 192.168.201.46@tcp [8/256/0/180] [12770.459941] Key type lgssc registered [12772.111988] Lustre: Echo OBD driver; http://www.lustre.org/ [12788.578734] Key type lgssc unregistered [12788.897880] LNet: 238661:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12788.905892] LNetError: 238661:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12788.920897] LNet: Removed LNI 192.168.201.46@tcp [12789.695378] Key type .llcrypt unregistered [12789.697396] Key type ._llcrypt unregistered [12798.299269] Key type ._llcrypt registered [12798.305029] Key type .llcrypt registered [12798.587898] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12798.609520] alg: No test for adler32 (adler32-zlib) [12799.721268] Lustre: Lustre: Build Version: 2.17.56_3_g13af17f [12799.962897] LNet: Added LNI 192.168.201.46@tcp [8/256/0/180] [12801.623279] Key type lgssc registered [12803.412955] Lustre: Echo OBD driver; http://www.lustre.org/ [12821.835685] Key type lgssc unregistered [12822.163826] LNet: 239967:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12822.178713] LNetError: 239967:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12822.209650] LNet: Removed LNI 192.168.201.46@tcp [12822.961345] Key type .llcrypt unregistered [12822.965412] Key type ._llcrypt unregistered [12848.819596] Key type ._llcrypt registered [12848.827170] Key type .llcrypt registered [12850.321728] Lustre: DEBUG MARKER: == conf-sanity test 200e: set CPU pattern using relative core exclusion ========================================================== 04:23:59 (1787127839) [12864.179300] Key type .llcrypt unregistered [12864.181679] Key type ._llcrypt unregistered [12865.328311] Key type ._llcrypt registered [12865.335792] Key type .llcrypt registered [12865.699762] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12865.711797] alg: No test for adler32 (adler32-zlib) [12866.783911] Lustre: Lustre: Build Version: 2.17.56_3_g13af17f [12867.060872] LNet: Added LNI 192.168.201.46@tcp [8/256/0/180] [12868.777960] Key type lgssc registered [12870.105132] Lustre: Echo OBD driver; http://www.lustre.org/ [12888.652040] Key type lgssc unregistered [12889.040765] LNet: 242289:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12889.053646] LNetError: 242289:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12889.081593] LNet: Removed LNI 192.168.201.46@tcp [12889.812930] Key type .llcrypt unregistered [12889.815418] Key type ._llcrypt unregistered [12897.663933] Key type ._llcrypt registered [12897.682478] Key type .llcrypt registered [12898.028964] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12898.046638] alg: No test for adler32 (adler32-zlib) [12899.181332] Lustre: Lustre: Build Version: 2.17.56_3_g13af17f [12899.570802] LNet: Added LNI 192.168.201.46@tcp [8/256/0/180] [12901.247183] Key type lgssc registered [12902.679591] Lustre: Echo OBD driver; http://www.lustre.org/ [12921.430645] Key type lgssc unregistered [12921.751442] LNet: 243610:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12921.768162] LNetError: 243610:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12921.796952] LNet: Removed LNI 192.168.201.46@tcp [12922.995048] Key type .llcrypt unregistered [12922.998757] Key type ._llcrypt unregistered [12931.600522] Key type ._llcrypt registered [12931.604980] Key type .llcrypt registered [12932.263689] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12932.298452] alg: No test for adler32 (adler32-zlib) [12933.663482] Lustre: Lustre: Build Version: 2.17.56_3_g13af17f [12933.973678] LNet: Added LNI 192.168.201.46@tcp [8/256/0/180] [12935.799216] Key type lgssc registered [12937.142721] Lustre: Echo OBD driver; http://www.lustre.org/ [12955.405565] Key type lgssc unregistered [12955.740928] LNet: 244920:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12955.746338] LNetError: 244920:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12955.772883] LNet: Removed LNI 192.168.201.46@tcp [12956.542463] Key type .llcrypt unregistered [12956.549983] Key type ._llcrypt unregistered [12964.097488] Key type ._llcrypt registered [12964.129348] Key type .llcrypt registered [12964.512746] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12964.529453] alg: No test for adler32 (adler32-zlib) [12965.614272] Lustre: Lustre: Build Version: 2.17.56_3_g13af17f [12965.796196] LNet: Added LNI 192.168.201.46@tcp [8/256/0/180] [12967.455351] Key type lgssc registered [12969.168883] Lustre: Echo OBD driver; http://www.lustre.org/ [12994.036402] Key type lgssc unregistered [12994.317061] LNet: 246713:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12994.325513] LNetError: 246713:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12994.349929] LNet: Removed LNI 192.168.201.46@tcp [12995.003712] Key type .llcrypt unregistered [12995.007988] Key type ._llcrypt unregistered [12995.360291] Key type ._llcrypt registered [12995.361995] Key type .llcrypt registered [12996.816558] Lustre: DEBUG MARKER: == conf-sanity test 250: verify lcfg_erase removes filesystem parameters ========================================================== 04:26:26 (1787127986) [13016.914816] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13016.926526] alg: No test for adler32 (adler32-zlib) [13018.155278] Lustre: Lustre: Build Version: 2.17.56_3_g13af17f [13018.540556] LNet: Added LNI 192.168.201.46@tcp [8/256/0/180] [13020.295232] Key type lgssc registered [13021.954536] Lustre: Echo OBD driver; http://www.lustre.org/ [13275.630472] Lustre: DEBUG MARKER: == conf-sanity test 802a: simulate readonly device ======= 04:31:05 (1787128265) [13277.245842] Lustre: DEBUG MARKER: SKIP: conf-sanity test_802a ZFS specific test [13342.608509] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 13078 sec ========= 04:32:11 (1787128331) [13344.359491] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 04:32:14 (1787128334) === [13347.433822] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 04:32:17 (1787128337) === [13387.624522] Key type lgssc unregistered [13387.875529] LNet: 255783:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13387.885594] LNetError: 255783:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13387.909507] LNet: Removed LNI 192.168.201.46@tcp [13388.744308] Key type .llcrypt unregistered [13388.749795] Key type ._llcrypt unregistered