[ 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 388681358 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, 524584K 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.001010] APIC: Switch to symmetric I/O mode setup [ 0.002369] x2apic enabled [ 0.003011] Switched APIC routing to physical x2apic. [ 0.004018] kvm-guest: setup PV IPIs [ 0.006714] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.007000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.007027] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.008018] pid_max: default: 32768 minimum: 301 [ 0.009121] LSM: Security Framework initializing [ 0.010039] Yama: becoming mindful. [ 0.011022] SELinux: Initializing. [ 0.012075] *** VALIDATE selinux *** [ 0.019758] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.024583] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.025161] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027162] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028100] *** VALIDATE tmpfs *** [ 0.030385] *** VALIDATE proc *** [ 0.031225] *** VALIDATE cgroup *** [ 0.032006] *** VALIDATE cgroup2 *** [ 0.034058] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035139] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036004] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037018] Spectre V2 : User space: Vulnerable [ 0.038003] Speculative Store Bypass: Vulnerable [ 0.040520] debug: unmapping init [mem 0xffffffffa7e59000-0xffffffffa7e60fff] [ 0.042925] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.043479] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.044015] ... version: 2 [ 0.044767] ... bit width: 48 [ 0.045007] ... generic registers: 4 [ 0.046006] ... value mask: 0000ffffffffffff [ 0.047008] ... max period: 00007fffffffffff [ 0.048010] ... fixed-purpose events: 3 [ 0.049006] ... event mask: 000000070000000f [ 0.051223] rcu: Hierarchical SRCU implementation. [ 0.053170] smp: Bringing up secondary CPUs ... [ 0.054510] x86: Booting SMP configuration: [ 0.055023] .... node #0, CPUs: #1 #2 #3 [ 0.064187] smp: Brought up 1 node, 4 CPUs [ 0.066010] smpboot: Max logical packages: 1 [ 0.067024] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.202021] node 0 deferred pages initialised in 131ms [ 0.206008] devtmpfs: initialized [ 0.207200] x86/mm: Memory block size: 128MB [ 0.209000] gcov: version magic: 0x41383552 [ 0.210299] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.211053] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.212453] pinctrl core: initialized pinctrl subsystem [ 0.213173] [ 0.213639] ************************************************************* [ 0.214008] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.215007] ** ** [ 0.216007] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.217000] ** ** [ 0.217000] ** This means that this kernel is built to expose internal ** [ 0.218008] ** IOMMU data structures, which may compromise security on ** [ 0.219000] ** your system. ** [ 0.219000] ** ** [ 0.220009] ** If you see this message and you are not debugging the ** [ 0.222007] ** kernel, report this immediately to your vendor! ** [ 0.223006] ** ** [ 0.224006] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.225007] ************************************************************* [ 0.226634] NET: Registered protocol family 16 [ 0.228388] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.230048] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.232033] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.234034] cpuidle: using governor menu [ 0.235819] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.237713] PCI: Using configuration type 1 for base access [ 0.240156] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.247294] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.249010] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.251094] cryptd: max_cpu_qlen set to 1000 [ 0.253275] ACPI: Added _OSI(Module Device) [ 0.254000] ACPI: Added _OSI(Processor Device) [ 0.255010] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.256010] ACPI: Added _OSI(Processor Aggregator Device) [ 0.259398] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.265481] ACPI: Interpreter enabled [ 0.266042] ACPI: PM: (supports S0 S3 S4 S5) [ 0.267010] ACPI: Using IOAPIC for interrupt routing [ 0.268116] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.270377] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.280870] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.282037] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.284013] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.286091] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.289780] acpiphp: Slot [2] registered [ 0.291089] acpiphp: Slot [5] registered [ 0.292082] acpiphp: Slot [6] registered [ 0.293116] acpiphp: Slot [3] registered [ 0.295078] acpiphp: Slot [4] registered [ 0.296071] acpiphp: Slot [7] registered [ 0.297092] acpiphp: Slot [8] registered [ 0.298082] acpiphp: Slot [9] registered [ 0.299079] acpiphp: Slot [10] registered [ 0.300057] acpiphp: Slot [11] registered [ 0.301060] acpiphp: Slot [12] registered [ 0.302024] acpiphp: Slot [13] registered [ 0.303104] acpiphp: Slot [14] registered [ 0.304058] acpiphp: Slot [15] registered [ 0.305014] acpiphp: Slot [16] registered [ 0.305947] acpiphp: Slot [17] registered [ 0.307065] acpiphp: Slot [18] registered [ 0.308066] acpiphp: Slot [19] registered [ 0.309078] acpiphp: Slot [20] registered [ 0.310115] acpiphp: Slot [21] registered [ 0.311082] acpiphp: Slot [22] registered [ 0.313088] acpiphp: Slot [23] registered [ 0.314129] acpiphp: Slot [24] registered [ 0.315154] acpiphp: Slot [25] registered [ 0.317093] acpiphp: Slot [26] registered [ 0.318172] acpiphp: Slot [27] registered [ 0.319086] acpiphp: Slot [28] registered [ 0.320000] acpiphp: Slot [29] registered [ 0.320000] acpiphp: Slot [30] registered [ 0.322085] acpiphp: Slot [31] registered [ 0.323052] PCI host bridge to bus 0000:00 [ 0.324028] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.327020] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.328016] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.329024] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.331020] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.332013] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.334171] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.335870] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.337211] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.346026] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.351583] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.354027] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.357036] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.360037] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.362356] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.364450] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.366027] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.367714] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.371012] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.379017] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.383563] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.387235] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.392021] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.396019] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.407024] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.416548] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.423017] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.427014] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.438024] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.446378] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.448221] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.450302] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.452332] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.454112] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.457648] iommu: Default domain type: Passthrough [ 0.458490] SCSI subsystem initialized [ 0.460069] ACPI: bus type USB registered [ 0.461022] usbcore: registered new interface driver usbfs [ 0.462036] usbcore: registered new interface driver hub [ 0.463045] usbcore: registered new device driver usb [ 0.464084] pps_core: LinuxPPS API ver. 1 registered [ 0.465005] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.467111] PTP clock support registered [ 0.469000] EDAC MC: Ver: 3.0.0 [ 0.470106] PCI: Using ACPI for IRQ routing [ 0.471685] NetLabel: Initializing [ 0.472000] NetLabel: domain hash size = 128 [ 0.472000] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.474272] NetLabel: unlabeled traffic allowed by default [ 0.476221] vgaarb: loaded [ 0.477392] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.479009] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.483446] clocksource: Switched to clocksource kvm-clock [ 0.610390] VFS: Disk quotas dquot_6.6.0 [ 0.612065] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.614712] *** VALIDATE ramfs *** [ 0.615927] *** VALIDATE hugetlbfs *** [ 0.617633] pnp: PnP ACPI init [ 0.621346] pnp: PnP ACPI: found 6 devices [ 0.638606] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.641075] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.642917] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.644626] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.646374] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.648195] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.650528] NET: Registered protocol family 2 [ 0.652645] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.657071] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.660202] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.664827] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.667742] TCP: Hash tables configured (established 65536 bind 65536) [ 0.670557] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.672606] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.674331] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.676347] NET: Registered protocol family 1 [ 0.678964] RPC: Registered named UNIX socket transport module. [ 0.680921] RPC: Registered udp transport module. [ 0.684539] RPC: Registered tcp transport module. [ 0.686381] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.688583] NET: Registered protocol family 44 [ 0.690500] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.692212] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.693742] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.695312] PCI: CLS 0 bytes, default 64 [ 0.696610] Unpacking initramfs... [ 2.643975] debug: unmapping init [mem 0xffffa0837cc64000-0xffffa0837ffcffff] [ 2.649914] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.653580] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.659245] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 3.289356] Initialise system trusted keyrings [ 3.290863] Key type blacklist registered [ 3.292706] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 3.302441] zbud: loaded [ 3.305630] *** VALIDATE nfs *** [ 3.306803] *** VALIDATE nfs4 *** [ 3.308481] pstore: using deflate compression [ 3.311812] Platform Keyring initialized [ 3.463939] NET: Registered protocol family 38 [ 3.465889] Key type asymmetric registered [ 3.467890] Asymmetric key parser 'x509' registered [ 3.472800] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.478421] io scheduler mq-deadline registered [ 3.482877] io scheduler kyber registered [ 3.485363] io scheduler bfq registered [ 3.487963] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.495715] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.508606] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.515890] ACPI: Power Button [PWRF] [ 3.530857] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.542913] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.556124] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.588870] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.653394] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.659249] Non-volatile memory driver v1.3 [ 3.660736] Linux agpgart interface v0.103 [ 3.687356] virtio_blk virtio1: [vda] 144832 512-byte logical blocks (74.2 MB/70.7 MiB) [ 3.689982] vda: detected capacity change from 0 to 74153984 [ 3.704154] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.706072] vdb: detected capacity change from 0 to 1073741824 [ 3.715700] libphy: Fixed MDIO Bus: probed [ 3.721935] usbcore: registered new interface driver usbserial_generic [ 3.724435] usbserial: USB Serial support registered for generic [ 3.726653] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.730681] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.732550] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.735284] mousedev: PS/2 mouse device common for all mice [ 3.738198] rtc_cmos 00:05: RTC can wake from S4 [ 3.741308] rtc_cmos 00:05: registered as rtc0 [ 3.743027] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.745958] intel_pstate: CPU model not supported [ 3.746554] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.751978] hid: raw HID events driver (C) Jiri Kosina [ 3.753283] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.755291] usbcore: registered new interface driver usbhid [ 3.755299] usbhid: USB HID core driver [ 3.756121] drop_monitor: Initializing network drop monitor service [ 3.756294] Initializing XFRM netlink socket [ 3.756651] NET: Registered protocol family 10 [ 3.764635] Segment Routing with IPv6 [ 3.765903] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.771465] NET: Registered protocol family 17 [ 3.773239] mpls_gso: MPLS GSO support [ 3.778136] RAS: Correctable Errors collector initialized. [ 3.779539] AVX version of gcm_enc/dec engaged. [ 3.780959] AES CTR mode by8 optimization enabled [ 3.859190] sched_clock: Marking stable (3859167766, 0)->(4597614071, -738446305) [ 3.861994] registered taskstats version 1 [ 3.863897] Loading compiled-in X.509 certificates [ 3.865640] zswap: loaded using pool lzo/zbud [ 3.895833] Key type big_key registered [ 3.909681] Key type encrypted registered [ 3.912295] ima: No TPM chip found, activating TPM-bypass! [ 3.915149] ima: Allocated hash algorithm: sha1 [ 3.917275] ima: No architecture policies found [ 3.925587] evm: Initialising EVM extended attributes: [ 3.931986] evm: security.selinux [ 3.932967] evm: security.ima [ 3.934957] evm: security.capability [ 3.936845] evm: HMAC attrs: 0x1 [ 3.941564] rtc_cmos 00:05: setting system clock to 2026-06-26 13:26:16 UTC (1782480376) [ 3.955488] debug: unmapping init [mem 0xffffffffa8e03000-0xffffffffa8ffffff] [ 3.962233] debug: unmapping init [mem 0xffffffffa7b82000-0xffffffffa7e58fff] [ 3.971135] Write protecting the kernel read-only data: 28672k [ 4.047813] debug: unmapping init [mem 0xffffffffa6203000-0xffffffffa63fffff] [ 4.059403] debug: unmapping init [mem 0xffffffffa6b14000-0xffffffffa6bfffff] [ 4.197115] 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) [ 4.212161] systemd[1]: Detected virtualization kvm. [ 4.215862] systemd[1]: Detected architecture x86-64. [ 4.219904] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 4.275869] systemd[1]: No hostname configured. [ 4.278719] systemd[1]: Set hostname to . [ 4.281533] random: systemd: uninitialized urandom read (16 bytes read) [ 4.284682] systemd[1]: Initializing machine ID from random generator. [ 4.525524] random: ln: uninitialized urandom read (6 bytes read) [ 4.786714] random: systemd: uninitialized urandom read (16 bytes read) [ 4.789464] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 4.797858] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 4.803781] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Local File Systems. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Initrd Root Device. [ OK ] Listening on Journal Socket. [ OK ] Reached target Sockets. Starting Apply Kernel Variables... Starting Create list of required st…ce nodes for the current kernel... Starting Create Volatile Files and Directories... Starting Setup Virtual Console... [ OK ] Started Memstrack Anylazing Service. Starting Journal Service... [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 6.809780] device-mapper: uevent: version 1.0.3 [ 6.816987] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 8.407028] virtio_net virtio0 ens2: renamed from eth0 [ 8.426270] random: fast init done [ 9.722425] scsi host0: ata_piix [ 10.016200] scsi host1: ata_piix [ 10.017284] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 10.019448] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 15.102640] random: crng init done [ 15.112927] random: 7 urandom warning(s) missed due to ratelimiting [ 18.284844] dracut-initqueue[595]: RTNETLINK answers: File exists 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... [ 21.137754] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Timers. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 23.974778] printk: systemd: 26 output lines suppressed due to ratelimiting [ 24.724680] SELinux: Disabled at runtime. [ 24.847330] 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) [ 24.855020] systemd[1]: Detected virtualization kvm. [ 24.856648] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 26.081664] systemd[1]: initrd-switch-root.service: Succeeded. [ 26.090183] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 26.098772] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 26.104574] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 26.109687] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 26.122383] systemd[1]: Starting Journal Service... Starting Journal Service... [ 26.139317] systemd[1]: Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on Process Core Dump Socket. Mounting Huge Pages File System... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Listening on udev Control Socket. [ OK ] Stopped target Switch Root. [ OK ] Reached target rpc_pipefs.target. [ OK ] Created slice system-getty.slice. Mounting Kernel Debug File System... [ OK ] Stopped target Initrd File Systems. Mounting POSIX Message Queue File System... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Stopped target Initrd Root File System. Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on initctl Compatibility Named Pipe. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ 26.926432] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Listening on RPCbind Server Activation Socket. Starting Remount Root and Kernel File Systems... [ OK ] Started Forward Password Requests to Wall Directory Watch. Starting Apply Kernel Variables... [ OK ] Reached target RPC Port Mapper. [ OK ] Reached target Local Encrypted Volumes. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Paths. [ OK ] Started Journal Service. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started udev Coldplug all Devices. [ 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 /mnt... Mounting /home/green/git/lustre-release... [ OK ] Mounted /mnt. [ 28.990440] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 30.520187] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 31.429268] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 32.246371] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 33.614163] EDAC sbridge: Ver: 1.1.2 [* ] A start job is running for Configur…-only root support (8s / no limit) [** ] A start job is running for Configur…-only root support (9s / no limit) [*** ] A start job is running for Configur…-only root support (9s / no limit) [ *** ] A start job is running for Configur…only root support (10s / no limit) [ *** ] A start job is running for Configur…only root support (10s / no limit) [ ***] A start job is running for Configur…only root support (11s / no limit) [ **] A start job is running for Configur…only root support (11s / no limit) [ *] A start job is running for Configur…only root support (12s / no limit) [ **] A start job is running for Configur…only root support (12s / no limit)[ 38.731534] Key type dns_resolver registered [ ***] A start job is running for Configur…only root support (12s / no limit)[ 39.278462] NFS: Registering the id_resolver key type [ 39.283841] Key type id_resolver registered [ 39.285704] Key type id_legacy registered [ *** ] A start job is running for Configur…only root support (13s / no limit) [ *** ] A start job is running for Configur…only root support (13s / no limit) [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Load/Save Random Seed... Starting Create Volatile Files and Directories... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [*** ] A start job is running for Rebuild …amic Linker Cache (20s / no limit) [** ] A start job is running for Rebuild …amic Linker Cache (20s / no limit) [ 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 daily update of the root trust anchor for DNSSEC. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... Starting Restore /run/initramfs on shutdown... [ OK ] Reached target sshd-keygen.target. Starting Login Service... [ OK ] Started Restore /run/initramfs on shutdown. [ 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 Login Service. [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ 55.036058] hrtimer: interrupt took 3238399 ns [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg102-client login: [ 165.298370] libcfs: loading out-of-tree module taints kernel. [ 165.460856] Key type ._llcrypt registered [ 165.464853] Key type .llcrypt registered [ 166.038686] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 166.056230] alg: No test for adler32 (adler32-zlib) [ 167.591361] Lustre: Lustre: Build Version: 2.17.54_95_ga6f956b [ 168.719295] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 170.512235] Key type lgssc registered [ 172.114110] Lustre: Echo OBD driver; http://www.lustre.org/ [ 353.192160] Lustre: Mounted lustre-client [ 358.873356] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 378.848197] Lustre: lustre-OST0000-osc-ffffa083d90aa000: disconnect after 23s idle [ 379.735459] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing check_logdir /tmp/testlogs/ [ 386.166320] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing yml_node [ 391.888321] Lustre: DEBUG MARKER: Client: 2.17.54.95 [ 395.140790] Lustre: DEBUG MARKER: MDS: 2.17.54.95 [ 398.557516] Lustre: DEBUG MARKER: OSS: 2.17.54.95 [ 401.032215] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Fri Jun 26 09:32:51 EDT 2026 [ 423.047386] Lustre: DEBUG MARKER: excepting tests: 32newtarball [ 425.206247] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 428.527709] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 438.895382] Lustre: Unmounted lustre-client [ 583.821455] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 591.999435] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 623.064744] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 678.344811] Lustre: DEBUG MARKER: == conf-sanity test 121: failover MGS ==================== 09:37:28 (1782481048) [ 736.844703] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 743.258029] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 791.529318] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid [ 856.378270] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 862.189789] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 910.387744] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid [ 934.368179] Lustre: DEBUG MARKER: == conf-sanity test 122a: Check OST sequence update ====== 09:41:45 (1782481305) [ 1043.164894] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1047.873885] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1069.545155] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1070.260847] Lustre: Mounted lustre-client [ 1091.623730] Lustre: Unmounted lustre-client [ 1119.048204] Key type lgssc unregistered [ 1119.267677] LNet: 21836:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1119.276467] LNetError: 21836:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1119.313086] LNet: Removed LNI 192.168.201.2@tcp [ 1120.029167] Key type .llcrypt unregistered [ 1120.031368] Key type ._llcrypt unregistered [ 1135.545830] Key type ._llcrypt registered [ 1135.547342] Key type .llcrypt registered [ 1136.703711] Lustre: DEBUG MARKER: == conf-sanity test 123aa: llog_print works with FIDs and simple names ========================================================== 09:45:07 (1782481507) [ 1139.372650] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1139.385161] alg: No test for adler32 (adler32-zlib) [ 1140.426223] Lustre: Lustre: Build Version: 2.17.54_95_ga6f956b [ 1140.674747] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 1142.328199] Key type lgssc registered [ 1143.465148] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1188.713386] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1193.524777] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1211.676309] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1214.366201] Lustre: Mounted lustre-client [ 1225.534857] Lustre: DEBUG MARKER: == conf-sanity test 123ab: llog_print params output values from set_param -P ========================================================== 09:46:36 (1782481596) [ 1235.118204] Lustre: DEBUG MARKER: == conf-sanity test 123ac: llog_print with --start and --end ========================================================== 09:46:46 (1782481606) [ 1242.939702] Lustre: DEBUG MARKER: == conf-sanity test 123ad: llog_print shows all records == 09:46:54 (1782481614) [ 1252.884243] Lustre: DEBUG MARKER: == conf-sanity test 123ae: llog_cancel can cancel requested record ========================================================== 09:47:04 (1782481624) [ 1273.833285] Lustre: DEBUG MARKER: == conf-sanity test 123af: llog_catlist can show all config files correctly ========================================================== 09:47:25 (1782481645) [ 1287.382626] Lustre: DEBUG MARKER: == conf-sanity test 123ag: llog_print skips values deleted by set_param -P -d ========================================================== 09:47:38 (1782481658) [ 1300.331384] Lustre: DEBUG MARKER: == conf-sanity test 123ah: del_ost cancels config log entries correctly ========================================================== 09:47:51 (1782481671) [ 1309.907804] Lustre: Unmounted lustre-client [ 1310.275298] Lustre: Mounted lustre-client [ 1312.239906] Lustre: Unmounted lustre-client [ 1338.394954] Key type lgssc unregistered [ 1338.620655] LNet: 30184:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1338.626963] LNetError: 30184:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1338.645536] LNet: Removed LNI 192.168.201.2@tcp [ 1339.132216] Key type .llcrypt unregistered [ 1339.133957] Key type ._llcrypt unregistered [ 1360.908436] Key type ._llcrypt registered [ 1360.916446] Key type .llcrypt registered [ 1361.248695] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1361.262597] alg: No test for adler32 (adler32-zlib) [ 1362.336095] Lustre: Lustre: Build Version: 2.17.54_95_ga6f956b [ 1362.565995] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 1364.248361] Key type lgssc registered [ 1365.486773] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1431.129984] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1434.860638] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1453.443484] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1498.370475] Lustre: DEBUG MARKER: == conf-sanity test 123ai: llog_print display all non skipped records ========================================================== 09:51:09 (1782481869) [ 1526.791459] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1532.232701] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1552.202107] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1554.905866] Lustre: Mounted lustre-client [ 1661.990052] Lustre: DEBUG MARKER: == conf-sanity test 123aj: check permanent TBF rules ===== 09:53:53 (1782482033) [ 1694.406716] Lustre: DEBUG MARKER: == conf-sanity test 123F: clear and reset all parameters using set_param -F ========================================================== 09:54:25 (1782482065) [ 1701.355243] Lustre: Unmounted lustre-client [ 1766.852890] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1771.146354] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1786.643739] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1787.357845] Lustre: Mounted lustre-client [ 1791.299757] Lustre: Unmounted lustre-client [ 1814.390527] Key type lgssc unregistered [ 1814.647131] LNet: 41418:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1814.655186] LNetError: 41418:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1814.669425] LNet: Removed LNI 192.168.201.2@tcp [ 1815.262358] Key type .llcrypt unregistered [ 1815.264343] Key type ._llcrypt unregistered [ 1826.691527] Key type ._llcrypt registered [ 1826.693802] Key type .llcrypt registered [ 1827.552623] Lustre: DEBUG MARKER: == conf-sanity test 123G: clear and reset all parameters using apply_yaml ========================================================== 09:56:39 (1782482199) [ 1829.328767] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1829.362778] alg: No test for adler32 (adler32-zlib) [ 1830.323622] Lustre: Lustre: Build Version: 2.17.54_95_ga6f956b [ 1830.491217] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 1832.128163] Key type lgssc registered [ 1833.073165] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1872.145425] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1877.262338] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1895.411279] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1898.117161] Lustre: Mounted lustre-client [ 1907.369470] Lustre: Unmounted lustre-client [ 1971.162382] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1975.851895] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1991.857483] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1992.498063] Lustre: Mounted lustre-client [ 1996.254959] Lustre: Unmounted lustre-client [ 2027.676356] Key type lgssc unregistered [ 2027.902532] LNet: 46901:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2027.910506] LNetError: 46901:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2027.924734] LNet: Removed LNI 192.168.201.2@tcp [ 2028.385129] Key type .llcrypt unregistered [ 2028.386982] Key type ._llcrypt unregistered [ 2039.647953] Key type ._llcrypt registered [ 2039.649580] Key type .llcrypt registered [ 2040.778715] Lustre: DEBUG MARKER: == conf-sanity test 123H: check concurent accesses with 'lctl llog_print ========================================================== 10:00:12 (1782482412) [ 2042.689266] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2042.706492] alg: No test for adler32 (adler32-zlib) [ 2043.735320] Lustre: Lustre: Build Version: 2.17.54_95_ga6f956b [ 2043.989863] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 2045.640472] Key type lgssc registered [ 2046.437612] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2084.582800] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2088.930206] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2104.740329] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2111.544757] Lustre: Mounted lustre-client [ 2192.932790] Lustre: DEBUG MARKER: == conf-sanity test 124: check failover after replace_nids ========================================================== 10:02:44 (1782482564) [ 2194.173303] Lustre: DEBUG MARKER: SKIP: conf-sanity test_124 needs MDT failover setup [ 2195.437734] Lustre: DEBUG MARKER: == conf-sanity test 126: mount in parallel shouldn't cause a crash ========================================================== 10:02:46 (1782482566) [ 2196.726827] Lustre: Unmounted lustre-client [ 2219.385125] Key type lgssc unregistered [ 2219.551540] LNet: 52215:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2219.557762] LNetError: 52215:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2220.584529] LNet: Removed LNI 192.168.201.2@tcp [ 2221.122234] Key type .llcrypt unregistered [ 2221.124185] Key type ._llcrypt unregistered [ 2239.903133] Key type ._llcrypt registered [ 2239.904916] Key type .llcrypt registered [ 2240.310450] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2240.324076] alg: No test for adler32 (adler32-zlib) [ 2241.331881] Lustre: Lustre: Build Version: 2.17.54_95_ga6f956b [ 2241.553740] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 2243.216339] Key type lgssc registered [ 2244.146834] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2269.001795] Lustre: DEBUG MARKER: == conf-sanity test 127: direct io overwrite on full ost ========================================================== 10:04:00 (1782482640) [ 2277.561465] Key type lgssc unregistered [ 2277.749852] LNet: 54619:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2277.754436] LNetError: 54619:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2277.766836] LNet: Removed LNI 192.168.201.2@tcp [ 2278.218561] Key type .llcrypt unregistered [ 2278.221929] Key type ._llcrypt unregistered [ 2285.685085] Key type ._llcrypt registered [ 2285.686685] Key type .llcrypt registered [ 2286.024752] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2286.071341] alg: No test for adler32 (adler32-zlib) [ 2287.089826] Lustre: Lustre: Build Version: 2.17.54_95_ga6f956b [ 2287.314144] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 2288.968274] Key type lgssc registered [ 2289.809424] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2320.605058] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2324.486583] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2340.207715] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2341.880610] Lustre: Mounted lustre-client [ 2375.978918] Lustre: DEBUG MARKER: == conf-sanity test 128: Force using remote logs with --nolocallogs ========================================================== 10:05:47 (1782482747) [ 2377.492726] Lustre: DEBUG MARKER: SKIP: conf-sanity test_128 need separate mgs device [ 2378.843986] Lustre: DEBUG MARKER: == conf-sanity test 129: attempt to connect an OST with the same index should fail ========================================================== 10:05:50 (1782482750) [ 2379.858938] Lustre: Unmounted lustre-client [ 2428.031544] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2431.179243] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2486.172822] Lustre: DEBUG MARKER: == conf-sanity test 130: re-register an MDT after writeconf ========================================================== 10:07:37 (1782482857) [ 2536.147152] Lustre: Mounted lustre-client [ 2539.794209] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 2546.665877] Lustre: lustre-MDT0001-mdc-ffffa083da257000: Connection to lustre-MDT0001 (at 192.168.201.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2562.016858] Lustre: lustre-OST0000-osc-ffffa083da257000: disconnect after 24s idle [ 2568.383359] LustreError: 63277:0:(obd_config.c:2103:class_config_llog_handler()) MGC192.168.201.102@tcp: cfg command failed: rc = -17 [ 2568.387786] Lustre: cmd=cf001 0:lustre-MDT0001-mdc 1:mdc 2:lustre-clilmv_UUID [ 2568.387786] [ 2568.391935] LustreError: 62508:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [ 2575.240967] Lustre: DEBUG MARKER: == conf-sanity test 131: MDT backup restore with project ID and inheritance flag ========================================================== 10:09:06 (1782482946) [ 2607.400700] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 2667.819704] Lustre: Unmounted lustre-client [ 2760.696449] Lustre: Mounted lustre-client [ 2763.790598] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 2790.875983] Lustre: DEBUG MARKER: == conf-sanity test 132: hsm_actions processed after failover ========================================================== 10:12:42 (1782483162) [ 2791.462560] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2818.564142] Lustre: Unmounted lustre-client [ 2910.578286] Lustre: DEBUG MARKER: == conf-sanity test 133: stripe QOS: free space balance in a pool ========================================================== 10:14:42 (1782483282) [ 2911.400472] Lustre: DEBUG MARKER: SKIP: conf-sanity test_133 needs >= 4 OSTs [ 2912.408963] Lustre: DEBUG MARKER: == conf-sanity test 134: check_iam works without faults == 10:14:44 (1782483284) [ 2959.783495] Lustre: Mounted lustre-client [ 2963.400807] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3308.177937] Lustre: DEBUG MARKER: == conf-sanity test 135: check the behavior when changelog is wrapped around ========================================================== 10:21:19 (1782483679) [ 3308.904485] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3308.907956] Lustre: Skipped 3 previous similar messages [ 3319.255413] Lustre: Unmounted lustre-client [ 3407.983360] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3410.538687] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3422.004791] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3422.278702] Lustre: Mounted lustre-client [ 4456.041352] Lustre: 94502:0:(llog_cat.c:977:llog_cat_process_or_fork()) lustre-MDT0000-mdc-ffffa083c4fdd000: catlog [0xa:0x5:0x0] crosses index zero [ 4472.707449] Lustre: Unmounted lustre-client [ 4492.516666] Key type lgssc unregistered [ 4492.672647] LNet: 102558:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4492.678114] LNetError: 102558:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4492.690482] LNet: Removed LNI 192.168.201.2@tcp [ 4493.098138] Key type .llcrypt unregistered [ 4493.099285] Key type ._llcrypt unregistered [ 4498.636728] Key type ._llcrypt registered [ 4498.638456] Key type .llcrypt registered [ 4499.315720] Lustre: DEBUG MARKER: == conf-sanity test 136: don't panic with bad obdecho setup ========================================================== 10:41:11 (1782484871) [ 4507.662843] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4507.670837] alg: No test for adler32 (adler32-zlib) [ 4508.579156] Lustre: Lustre: Build Version: 2.17.54_95_ga6f956b [ 4508.698023] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 4510.304171] Key type lgssc registered [ 4510.772251] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4596.687792] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4606.817166] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4632.266849] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4634.941641] Lustre: Mounted lustre-client [ 4651.236934] Lustre: Unmounted lustre-client [ 4762.043575] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4765.952657] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4785.248559] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4822.073129] Lustre: DEBUG MARKER: == conf-sanity test 137: a new MDT should inherit pools, parameters and failnode ========================================================== 10:46:33 (1782485193) [ 4823.641827] Lustre: DEBUG MARKER: SKIP: conf-sanity test_137 needs >= 3 MDTs [ 4825.159065] Lustre: DEBUG MARKER: == conf-sanity test 140: remove_updatelog script actions ========================================================== 10:46:36 (1782485196) [ 4856.519348] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4861.793420] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4882.963831] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4883.537094] Lustre: Mounted lustre-client [ 4893.673618] Lustre: lustre-MDT0001-mdc-ffffa083d0e9d000: Connection to lustre-MDT0001 (at 192.168.201.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4910.048693] Lustre: 103317:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1782485266/real 1782485266] req@00000000fe05fa63 x1869070866655744/t0(0) o400->MGC192.168.201.102@tcp@192.168.201.102@tcp:26/25 lens 224/224 e 0 to 1 dl 1782485282 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4910.072882] LustreError: MGC192.168.201.102@tcp: Connection to MGS (at 192.168.201.102@tcp) was lost; in progress operations using this service will fail [ 5040.112284] LustreError: lustre-MDT0000-mdc-ffffa083d0e9d000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 5040.132396] Lustre: lustre-MDT0000-mdc-ffffa083d0e9d000: Connection restored to 192.168.201.102@tcp (at 192.168.201.102@tcp) [ 5045.232654] LustreError: lustre-MDT0001-mdc-ffffa083d0e9d000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 5045.259863] Lustre: lustre-MDT0001-mdc-ffffa083d0e9d000: Connection restored to 192.168.201.102@tcp (at 192.168.201.102@tcp) [ 5047.214941] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5048.106995] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 5051.787929] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5052.977918] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 5055.464141] Lustre: Evicted from MGS (at 192.168.201.102@tcp) after server handle changed from 0xb5b1f60e8011a94c to 0xb5b1f60e8011d823 [ 5055.474938] Lustre: MGC192.168.201.102@tcp: Connection restored to 192.168.201.102@tcp (at 192.168.201.102@tcp) [ 5063.177336] Lustre: Unmounted lustre-client [ 5161.657393] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5165.697590] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5183.659953] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5218.487410] Lustre: DEBUG MARKER: == conf-sanity test 150: test setting max_cached_mb to a % ========================================================== 10:53:09 (1782485589) [ 5238.457397] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5241.843403] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5254.157367] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5254.555158] Lustre: Mounted lustre-client [ 5259.983492] LustreError: 119899: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 [ 5264.299396] Lustre: DEBUG MARKER: == conf-sanity test 151a: damaged local config doesn't prevent mounting ========================================================== 10:53:55 (1782485635) [ 5265.637417] Lustre: Unmounted lustre-client [ 5286.830115] Key type lgssc unregistered [ 5287.005618] LNet: 120904:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5287.009490] LNetError: 120904:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5287.018595] LNet: Removed LNI 192.168.201.2@tcp [ 5287.432890] Key type .llcrypt unregistered [ 5287.434757] Key type ._llcrypt unregistered [ 5296.264647] Key type ._llcrypt registered [ 5296.266363] Key type .llcrypt registered [ 5296.629071] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5296.644144] alg: No test for adler32 (adler32-zlib) [ 5297.584832] Lustre: Lustre: Build Version: 2.17.54_95_ga6f956b [ 5297.736525] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 5299.376170] Key type lgssc registered [ 5300.154836] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5398.353424] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5406.819198] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5431.011291] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5555.758957] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5561.863297] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5584.217915] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5630.262290] Lustre: DEBUG MARKER: == conf-sanity test 151b: -ENOSPC doesn't affect mount === 11:00:01 (1782486001) [ 5645.729678] Key type lgssc unregistered [ 5645.970078] LNet: 128076:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5645.974565] LNetError: 128076:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5645.994882] LNet: Removed LNI 192.168.201.2@tcp [ 5646.628689] Key type .llcrypt unregistered [ 5646.631300] Key type ._llcrypt unregistered [ 5664.378383] Key type ._llcrypt registered [ 5664.383375] Key type .llcrypt registered [ 5665.035673] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5665.058343] alg: No test for adler32 (adler32-zlib) [ 5666.326081] Lustre: Lustre: Build Version: 2.17.54_95_ga6f956b [ 5666.733719] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 5668.584197] Key type lgssc registered [ 5670.199768] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5728.529528] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5735.182981] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5757.294564] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5837.308424] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5840.572763] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5854.984311] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5884.411514] Lustre: DEBUG MARKER: == conf-sanity test 152: seq allocation error in OSP ===== 11:04:16 (1782486256) [ 5934.322954] Lustre: Mounted lustre-client [ 5937.619699] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 5943.070770] Lustre: DEBUG MARKER: ADD OST3 [ 5951.430237] Lustre: DEBUG MARKER: STOP OST3 [ 5959.648686] Lustre: lustre-OST0000-osc-ffffa083d80be000: disconnect after 24s idle [ 5959.670406] Lustre: lustre-OST0003-osc-ffffa083d80be000: Connection to lustre-OST0003 (at 192.168.201.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5960.908393] Lustre: DEBUG MARKER: START OST3 again [ 5966.195578] Lustre: lustre-OST0003-osc-ffffa083d80be000: Connection restored to 192.168.201.102@tcp (at 192.168.201.102@tcp) [ 5985.747868] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5985.756244] LustreError: 138797:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffffa083d80be000: inode [0x240000bd0:0x1:0x0] mdc close failed: rc = -108 [ 5996.038096] Lustre: Unmounted lustre-client [ 6063.017051] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6065.680201] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6078.138129] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6102.317507] Lustre: DEBUG MARKER: == conf-sanity test 153a: bypass invalid NIDs quickly ==== 11:07:54 (1782486474) [ 6148.968670] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6151.615456] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6164.459740] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6204.129326] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6206.876327] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6216.794499] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6218.057760] LNetError: 128489:0:(lib-move.c:2064:lnet_handle_find_routed_path()) peer 192.168.252.112@tcp2 has no available nets [ 6218.218682] Lustre: Mounted lustre-client [ 6228.692527] Lustre: Unmounted lustre-client [ 6256.861902] Key type lgssc unregistered [ 6275.040327] LNet: 1 local NIs in recovery (showing 1): 192.168.201.2@tcp [ 6280.549486] LNet: 147679:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6280.553602] LNetError: 147679:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6350.409249] LNet: Removed LNI 192.168.201.2@tcp [ 6351.365822] Key type .llcrypt unregistered [ 6351.373536] Key type ._llcrypt unregistered [ 6378.425669] Key type ._llcrypt registered [ 6378.428484] Key type .llcrypt registered [ 6379.754587] Lustre: DEBUG MARKER: == conf-sanity test 153b: added IPv6 NID support ========= 11:12:30 (1782486750) [ 6398.936594] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6398.972953] alg: No test for adler32 (adler32-zlib) [ 6400.078819] Lustre: Lustre: Build Version: 2.17.54_95_ga6f956b [ 6400.359911] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 6402.073207] Key type lgssc registered [ 6403.448287] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6481.726655] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6487.740766] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6511.846500] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6618.576752] Lustre: Mounted lustre-client [ 6622.805829] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 6627.869197] Lustre: Unmounted lustre-client [ 6628.018179] LNetError: 148863:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::54f4:8dff:fe9e:cfa0/1023: rc = -22 [ 6628.023308] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 6628.247496] Lustre: Mounted lustre-client [ 6628.385407] Lustre: Unmounted lustre-client [ 6629.418584] Lustre: Mounted lustre-client [ 6629.420062] Lustre: Skipped 2 previous similar messages [ 6629.546453] Lustre: Unmounted lustre-client [ 6629.549398] Lustre: Skipped 2 previous similar messages [ 6630.422529] LNetError: 148863:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::54f4:8dff:fe9e:cfa0/1023: rc = -22 [ 6630.432584] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 6631.221515] Lustre: 154759:0:(obd_mount.c:166: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 [ 6631.799846] Lustre: Mounted lustre-client [ 6631.800950] Lustre: Skipped 5 previous similar messages [ 6631.903446] Lustre: Unmounted lustre-client [ 6631.905239] Lustre: Skipped 5 previous similar messages [ 6636.945829] Lustre: DEBUG MARKER: == conf-sanity test 153c: don't stuck on unreached NID === 11:16:48 (1782487008) [ 6687.073067] LNet: 1 local NIs in recovery (showing 1): 192.168.201.2@tcp [ 6744.838692] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6748.972284] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6764.464467] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6804.068729] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6806.940559] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6816.720220] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6822.048481] LustreError: 160326:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.252.112@tcp: failed processing log, type 1: rc = -110 [ 6872.416261] LNet: 1 local NIs in recovery (showing 1): 192.168.201.2@tcp [ 6948.192310] LNet: 1 local NIs in recovery (showing 1): 192.168.201.2@tcp [ 6959.072497] 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 [ 6959.078174] Lustre: Unmounted lustre-client [ 6959.083286] LustreError: 160326:0:(super25.c:184:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 6977.295309] Key type lgssc unregistered [ 7006.112712] LNet: 160784:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7006.116086] LNetError: 160784:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7079.846520] LNet: Removed LNI 192.168.201.2@tcp [ 7080.097126] Key type .llcrypt unregistered [ 7080.098467] Key type ._llcrypt unregistered [ 7086.093813] Key type ._llcrypt registered [ 7086.095314] Key type .llcrypt registered [ 7086.682559] Lustre: DEBUG MARKER: == conf-sanity test 154: expand .. on rename after MDT backup restore ========================================================== 11:24:18 (1782487458) [ 7093.184981] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7093.208423] alg: No test for adler32 (adler32-zlib) [ 7094.095099] Lustre: Lustre: Build Version: 2.17.54_95_ga6f956b [ 7094.208352] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 7095.800266] Key type lgssc registered [ 7096.273597] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7148.004091] Lustre: Mounted lustre-client [ 7150.555235] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 7157.052173] Lustre: Unmounted lustre-client [ 7209.413577] Lustre: Mounted lustre-client [ 7211.770489] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 7213.433594] Lustre: Unmounted lustre-client [ 7250.634308] Lustre: DEBUG MARKER: == conf-sanity test 155: gap in seq allocation from ofd after restarting ========================================================== 11:27:02 (1782487622) [ 7281.638625] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7283.482144] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7291.626688] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7347.081073] Lustre: Mounted lustre-client [ 7349.533193] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 7353.861957] Lustre: Unmounted lustre-client [ 7528.568026] Lustre: Mounted lustre-client [ 7535.064747] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 7554.019345] Lustre: lustre-OST0001-osc-ffffa083c4fdf000: disconnect after 23s idle [ 7555.011845] Lustre: DEBUG MARKER: == conf-sanity test 156: root_fid on export consistent with client mount ========================================================== 11:32:06 (1782487926) [ 7557.067575] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7567.415437] Lustre: Unmounted lustre-client [ 7761.271202] Lustre: Mounted lustre-client [ 7766.869221] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 7775.287041] Lustre: Unmounted lustre-client [ 7775.848668] Lustre: Mounted lustre-client [ 7785.661371] Lustre: Unmounted lustre-client [ 7787.571570] Lustre: DEBUG MARKER: == conf-sanity test 157a: test allow_register for MDT registration and MGS restart ========================================================== 11:35:58 (1782488158) [ 7858.668660] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7864.959888] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7891.365734] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7892.018718] Lustre: Mounted lustre-client [ 7989.244926] LustreError: lustre-OST0001-osc-ffffa083d0e98000: operation ost_connect to node 192.168.201.102@tcp failed: rc = -19 [ 8004.631096] Lustre: lustre-MDT0000-mdc-ffffa083d0e98000: Connection to lustre-MDT0000 (at 192.168.201.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8025.061425] LustreError: MGC192.168.201.102@tcp: Connection to MGS (at 192.168.201.102@tcp) was lost; in progress operations using this service will fail [ 8025.097554] Lustre: Evicted from MGS (at 192.168.201.102@tcp) after server handle changed from 0x5a238de034981962 to 0x5a238de034981cbf [ 8025.106552] LustreError: lustre-MDT0000-mdc-ffffa083d0e98000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 8025.108327] Lustre: MGC192.168.201.102@tcp: Connection restored to 192.168.201.102@tcp (at 192.168.201.102@tcp) [ 8025.151667] Lustre: Skipped 1 previous similar message [ 8084.886971] Lustre: Unmounted lustre-client [ 8110.190259] Key type lgssc unregistered [ 8110.404888] LNet: 188202:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8110.410235] LNetError: 188202:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8110.422108] LNet: Removed LNI 192.168.201.2@tcp [ 8111.095926] Key type .llcrypt unregistered [ 8111.097745] Key type ._llcrypt unregistered [ 8118.571545] Key type ._llcrypt registered [ 8118.573128] Key type .llcrypt registered [ 8119.881378] Lustre: DEBUG MARKER: == conf-sanity test 157b: verify allow_register (block new OSTs, allow existing) ========================================================== 11:41:30 (1782488490) [ 8121.691872] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8121.751206] alg: No test for adler32 (adler32-zlib) [ 8123.003173] Lustre: Lustre: Build Version: 2.17.54_95_ga6f956b [ 8123.294824] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 8125.008909] Key type lgssc registered [ 8126.314473] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8157.964654] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8160.452411] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8169.986706] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8176.611284] Lustre: Mounted lustre-client [ 8248.008381] Lustre: Unmounted lustre-client [ 8265.390099] Key type lgssc unregistered [ 8265.520463] LNet: 194174:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8265.523283] LNetError: 194174:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8265.532662] LNet: Removed LNI 192.168.201.2@tcp [ 8265.811158] Key type .llcrypt unregistered [ 8265.812698] Key type ._llcrypt unregistered [ 8269.691347] Key type ._llcrypt registered [ 8269.692575] Key type .llcrypt registered [ 8270.205936] Lustre: DEBUG MARKER: == conf-sanity test 160: MGC updates failnodes from all participants ========================================================== 11:44:02 (1782488642) [ 8280.481068] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8280.536869] alg: No test for adler32 (adler32-zlib) [ 8281.404597] Lustre: Lustre: Build Version: 2.17.54_95_ga6f956b [ 8281.504048] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 8283.088178] Key type lgssc registered [ 8283.468450] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8312.295801] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8314.059806] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8321.874395] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8342.049779] Key type lgssc unregistered [ 8342.173558] LNet: 199110:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8342.177275] LNetError: 199110:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8342.187384] LNet: Removed LNI 192.168.201.2@tcp [ 8342.447829] Key type .llcrypt unregistered [ 8342.449435] Key type ._llcrypt unregistered [ 8466.566601] Key type ._llcrypt registered [ 8466.586799] Key type .llcrypt registered [ 8466.743764] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8466.751426] alg: No test for adler32 (adler32-zlib) [ 8467.649055] Lustre: Lustre: Build Version: 2.17.54_95_ga6f956b [ 8467.775267] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 8469.377609] Key type lgssc registered [ 8470.248492] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8496.366578] Lustre: DEBUG MARKER: == conf-sanity test 161: test '-o mgsname' option ======== 11:47:48 (1782488868) [ 8520.890193] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8524.416357] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8537.878807] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8539.290067] Lustre: Mounted lustre-client [ 8540.933497] Lustre: Unmounted lustre-client [ 8541.052265] Lustre: Mounted lustre-client [ 8544.751766] Lustre: Unmounted lustre-client [ 8544.753938] Lustre: Skipped 1 previous similar message [ 8567.095367] Key type lgssc unregistered [ 8567.276755] LNet: 204050:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8567.280966] LNetError: 204050:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8567.292358] LNet: Removed LNI 192.168.201.2@tcp [ 8567.689826] Key type .llcrypt unregistered [ 8567.692514] Key type ._llcrypt unregistered [ 8573.678940] Key type ._llcrypt registered [ 8573.679857] Key type .llcrypt registered [ 8574.438132] Lustre: DEBUG MARKER: == conf-sanity test 162: replace nids with -o noclient === 11:49:06 (1782488946) [ 8576.646300] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8576.676991] alg: No test for adler32 (adler32-zlib) [ 8577.639931] Lustre: Lustre: Build Version: 2.17.54_95_ga6f956b [ 8577.829855] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 8579.464246] Key type lgssc registered [ 8580.213439] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8613.165454] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8616.350579] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8629.322904] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8631.972183] Lustre: Mounted lustre-client [ 8641.015243] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8642.098463] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 8647.658831] Lustre: lustre-MDT0000-mdc-ffffa083d0828000: Connection to lustre-MDT0000 (at 192.168.201.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8657.910140] Lustre: lustre-MDT0001-mdc-ffffa083d0828000: Connection to lustre-MDT0001 (at 192.168.201.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8669.152132] Lustre: 204725:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1782489025/real 1782489025] req@000000002bf89216 x1869075133240704/t0(0) o400->MGC192.168.201.102@tcp@192.168.201.102@tcp:26/25 lens 224/224 e 0 to 1 dl 1782489041 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 8669.173644] LustreError: MGC192.168.201.102@tcp: Connection to MGS (at 192.168.201.102@tcp) was lost; in progress operations using this service will fail [ 8687.590410] Lustre: Evicted from MGS (at 192.168.201.102@tcp) after server handle changed from 0x72833ac010fea128 to 0x72833ac010fea2b7 [ 8687.603863] Lustre: MGC192.168.201.102@tcp: Connection restored to 192.168.201.102@tcp (at 192.168.201.102@tcp) [ 8707.355847] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8708.251372] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in IDLE state after 0 sec [ 8710.871971] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8713.189561] LustreError: lustre-MDT0000-mdc-ffffa083d0828000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 8713.204472] Lustre: lustre-MDT0000-mdc-ffffa083d0828000: Connection restored to 192.168.201.102@tcp (at 192.168.201.102@tcp) [ 8715.423322] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 3 sec [ 8716.355180] Lustre: Unmounted lustre-client [ 8763.019291] Key type lgssc unregistered [ 8764.043808] LNet: 209587:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8764.046359] LNetError: 209587:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8764.105612] LNet: Removed LNI 192.168.201.2@tcp [ 8766.293516] Key type .llcrypt unregistered [ 8766.303294] Key type ._llcrypt unregistered [ 8807.519766] Key type ._llcrypt registered [ 8807.525081] Key type .llcrypt registered [ 8807.937517] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8807.945414] alg: No test for adler32 (adler32-zlib) [ 8809.012169] Lustre: Lustre: Build Version: 2.17.54_95_ga6f956b [ 8809.243215] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 8810.904399] Key type lgssc registered [ 8812.240592] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8916.713867] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8923.373927] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8950.265709] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9003.472932] Lustre: DEBUG MARKER: == conf-sanity test 164: test expected_clients parameter and max client tracking ========================================================== 11:56:13 (1782489373) [ 9119.798137] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9126.082211] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9151.338935] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9153.213067] Lustre: Mounted lustre-client [ 9158.440587] Lustre: Mounted lustre-client [ 9159.765294] Lustre: Mounted lustre-client [ 9159.769945] Lustre: Skipped 1 previous similar message [ 9164.128507] Lustre: Unmounted lustre-client [ 9166.848687] Lustre: Unmounted lustre-client [ 9169.474621] Lustre: Unmounted lustre-client [ 9174.027271] Lustre: Unmounted lustre-client [ 9174.029899] Lustre: Skipped 1 previous similar message [ 9184.284040] Lustre: Unmounted lustre-client [ 9215.265267] Key type lgssc unregistered [ 9215.656326] LNet: 218717:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9215.663170] LNetError: 218717:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9215.693449] LNet: Removed LNI 192.168.201.2@tcp [ 9216.442108] Key type .llcrypt unregistered [ 9216.445585] Key type ._llcrypt unregistered [ 9230.767706] Key type ._llcrypt registered [ 9230.770522] Key type .llcrypt registered [ 9232.781922] Lustre: DEBUG MARKER: == conf-sanity test 200a: check CPU partitions =========== 12:00:03 (1782489603) [ 9249.397517] Key type .llcrypt unregistered [ 9249.399558] Key type ._llcrypt unregistered [ 9251.047322] Key type ._llcrypt registered [ 9251.055406] Key type .llcrypt registered [ 9251.572939] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 4 [ 9251.592365] alg: No test for adler32 (adler32-zlib) [ 9252.999433] Lustre: Lustre: Build Version: 2.17.54_95_ga6f956b [ 9253.309576] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 9255.152243] Key type lgssc registered [ 9256.828853] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9287.416331] Key type lgssc unregistered [ 9287.802489] LNet: 220875:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9287.809494] LNetError: 220875:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9287.845820] LNet: Removed LNI 192.168.201.2@tcp [ 9288.969474] Key type .llcrypt unregistered [ 9288.978917] Key type ._llcrypt unregistered [ 9289.756570] Key type ._llcrypt registered [ 9289.760387] Key type .llcrypt registered [ 9292.631352] Lustre: DEBUG MARKER: == conf-sanity test 200b: set CPU pattern using core selection ========================================================== 12:01:01 (1782489661) [ 9309.978669] Key type .llcrypt unregistered [ 9309.989169] Key type ._llcrypt unregistered [ 9311.940347] Key type ._llcrypt registered [ 9312.091515] Key type .llcrypt registered [ 9312.697935] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9312.731185] alg: No test for adler32 (adler32-zlib) [ 9313.739128] Lustre: Lustre: Build Version: 2.17.54_95_ga6f956b [ 9314.014989] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 9315.840202] Key type lgssc registered [ 9317.473921] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9337.080829] Key type lgssc unregistered [ 9337.372680] LNet: 222440:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9337.380228] LNetError: 222440:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9337.391562] LNet: Removed LNI 192.168.201.2@tcp [ 9338.041960] Key type .llcrypt unregistered [ 9338.043741] Key type ._llcrypt unregistered [ 9348.320869] Key type ._llcrypt registered [ 9348.360809] Key type .llcrypt registered [ 9349.070095] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9349.081766] alg: No test for adler32 (adler32-zlib) [ 9350.533068] Lustre: Lustre: Build Version: 2.17.54_95_ga6f956b [ 9350.927606] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 9352.594083] Key type lgssc registered [ 9354.936128] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9389.348371] Key type lgssc unregistered [ 9389.870162] LNet: 224208:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9389.883763] LNetError: 224208:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9389.928930] LNet: Removed LNI 192.168.201.2@tcp [ 9391.006898] Key type .llcrypt unregistered [ 9391.010491] Key type ._llcrypt unregistered [ 9391.732508] Key type ._llcrypt registered [ 9391.736745] Key type .llcrypt registered [ 9393.402919] Lustre: DEBUG MARKER: == conf-sanity test 200c: set CPU pattern using NUMA node layout ========================================================== 12:02:43 (1782489763) [ 9406.763622] Key type .llcrypt unregistered [ 9406.774389] Key type ._llcrypt unregistered [ 9407.889991] Key type ._llcrypt registered [ 9407.895991] Key type .llcrypt registered [ 9408.582593] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9408.595812] alg: No test for adler32 (adler32-zlib) [ 9409.827555] Lustre: Lustre: Build Version: 2.17.54_95_ga6f956b [ 9410.221346] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 9412.017953] Key type lgssc registered [ 9413.707679] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9434.070391] Key type lgssc unregistered [ 9434.342833] LNet: 225757:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9434.358620] LNetError: 225757:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9434.395939] LNet: Removed LNI 192.168.201.2@tcp [ 9435.281584] Key type .llcrypt unregistered [ 9435.283136] Key type ._llcrypt unregistered [ 9447.884056] Key type ._llcrypt registered [ 9447.902852] Key type .llcrypt registered [ 9449.547456] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9449.570412] alg: No test for adler32 (adler32-zlib) [ 9450.748783] Lustre: Lustre: Build Version: 2.17.54_95_ga6f956b [ 9451.119293] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 9452.848208] Key type lgssc registered [ 9454.430937] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9477.313315] Key type lgssc unregistered [ 9477.689200] LNet: 227066:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9477.695357] LNetError: 227066:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9477.724392] LNet: Removed LNI 192.168.201.2@tcp [ 9479.042317] Key type .llcrypt unregistered [ 9479.047631] Key type ._llcrypt unregistered [ 9491.282125] Key type ._llcrypt registered [ 9491.288405] Key type .llcrypt registered [ 9491.622256] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9491.635583] alg: No test for adler32 (adler32-zlib) [ 9492.980810] Lustre: Lustre: Build Version: 2.17.54_95_ga6f956b [ 9493.711525] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 9495.729239] Key type lgssc registered [ 9498.790777] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9524.942394] Key type lgssc unregistered [ 9525.372804] LNet: 228371:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9525.379536] LNetError: 228371:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9525.402849] LNet: Removed LNI 192.168.201.2@tcp [ 9526.541062] Key type .llcrypt unregistered [ 9526.551588] Key type ._llcrypt unregistered [ 9538.343205] Key type ._llcrypt registered [ 9538.345157] Key type .llcrypt registered [ 9539.065163] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9539.094416] alg: No test for adler32 (adler32-zlib) [ 9540.570339] Lustre: Lustre: Build Version: 2.17.54_95_ga6f956b [ 9541.099366] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 9542.928218] Key type lgssc registered [ 9544.482847] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9580.102740] Key type lgssc unregistered [ 9580.348950] LNet: 230140:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9580.358488] LNetError: 230140:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9580.377264] LNet: Removed LNI 192.168.201.2@tcp [ 9581.227629] Key type .llcrypt unregistered [ 9581.230642] Key type ._llcrypt unregistered [ 9581.758521] Key type ._llcrypt registered [ 9581.759833] Key type .llcrypt registered [ 9583.249788] Lustre: DEBUG MARKER: == conf-sanity test 200d: set CPU pattern to exclude only CPU 1 ========================================================== 12:05:54 (1782489954) [ 9597.588398] Key type .llcrypt unregistered [ 9597.597229] Key type ._llcrypt unregistered [ 9599.578697] Key type ._llcrypt registered [ 9599.584621] Key type .llcrypt registered [ 9599.924051] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9599.945900] alg: No test for adler32 (adler32-zlib) [ 9601.358373] Lustre: Lustre: Build Version: 2.17.54_95_ga6f956b [ 9601.767223] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 9603.664796] Key type lgssc registered [ 9606.029842] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9625.336970] Key type lgssc unregistered [ 9625.495751] LNet: 231693:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9625.500438] LNetError: 231693:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9625.516438] LNet: Removed LNI 192.168.201.2@tcp [ 9626.077756] Key type .llcrypt unregistered [ 9626.079780] Key type ._llcrypt unregistered [ 9634.127232] Key type ._llcrypt registered [ 9634.130385] Key type .llcrypt registered [ 9634.378919] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9634.394591] alg: No test for adler32 (adler32-zlib) [ 9635.545502] Lustre: Lustre: Build Version: 2.17.54_95_ga6f956b [ 9635.928284] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 9637.624304] Key type lgssc registered [ 9639.329637] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9659.530611] Key type lgssc unregistered [ 9659.868835] LNet: 233007:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9659.874944] LNetError: 233007:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9659.884413] LNet: Removed LNI 192.168.201.2@tcp [ 9660.633306] Key type .llcrypt unregistered [ 9660.634760] Key type ._llcrypt unregistered [ 9671.325285] Key type ._llcrypt registered [ 9671.331824] Key type .llcrypt registered [ 9671.977813] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9672.010521] alg: No test for adler32 (adler32-zlib) [ 9673.243324] Lustre: Lustre: Build Version: 2.17.54_95_ga6f956b [ 9673.575298] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 9675.344941] Key type lgssc registered [ 9677.398674] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9697.812311] Key type lgssc unregistered [ 9698.057223] LNet: 234315:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9698.066537] LNetError: 234315:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9698.105458] LNet: Removed LNI 192.168.201.2@tcp [ 9698.880886] Key type .llcrypt unregistered [ 9698.884763] Key type ._llcrypt unregistered [ 9707.769490] Key type ._llcrypt registered [ 9707.780858] Key type .llcrypt registered [ 9708.571706] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9708.609040] alg: No test for adler32 (adler32-zlib) [ 9710.001960] Lustre: Lustre: Build Version: 2.17.54_95_ga6f956b [ 9710.629454] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 9712.522516] Key type lgssc registered [ 9714.167358] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9741.132181] Key type lgssc unregistered [ 9741.352648] LNet: 235624:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9741.360759] LNetError: 235624:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9741.388777] LNet: Removed LNI 192.168.201.2@tcp [ 9742.316033] Key type .llcrypt unregistered [ 9742.320830] Key type ._llcrypt unregistered [ 9751.723675] Key type ._llcrypt registered [ 9751.732283] Key type .llcrypt registered [ 9752.471412] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9752.493880] alg: No test for adler32 (adler32-zlib) [ 9753.627752] Lustre: Lustre: Build Version: 2.17.54_95_ga6f956b [ 9753.966710] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 9755.696259] Key type lgssc registered [ 9757.533181] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9777.850633] Key type lgssc unregistered [ 9778.223706] LNet: 236934:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9778.231230] LNetError: 236934:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9778.253629] LNet: Removed LNI 192.168.201.2@tcp [ 9779.043503] Key type .llcrypt unregistered [ 9779.050565] Key type ._llcrypt unregistered [ 9787.585223] Key type ._llcrypt registered [ 9787.588235] Key type .llcrypt registered [ 9788.128765] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9788.140148] alg: No test for adler32 (adler32-zlib) [ 9789.225604] Lustre: Lustre: Build Version: 2.17.54_95_ga6f956b [ 9789.488896] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 9791.201253] Key type lgssc registered [ 9792.548518] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9810.854055] Key type lgssc unregistered [ 9811.126516] LNet: 238240:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9811.130818] LNetError: 238240:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9811.165044] LNet: Removed LNI 192.168.201.2@tcp [ 9811.935715] Key type .llcrypt unregistered [ 9811.937825] Key type ._llcrypt unregistered [ 9840.117398] Key type ._llcrypt registered [ 9840.119692] Key type .llcrypt registered [ 9842.010373] Lustre: DEBUG MARKER: == conf-sanity test 200e: set CPU pattern using relative core exclusion ========================================================== 12:10:12 (1782490212) [ 9856.235569] Key type .llcrypt unregistered [ 9856.238631] Key type ._llcrypt unregistered [ 9857.442957] Key type ._llcrypt registered [ 9857.454476] Key type .llcrypt registered [ 9858.065960] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9858.092363] alg: No test for adler32 (adler32-zlib) [ 9859.272757] Lustre: Lustre: Build Version: 2.17.54_95_ga6f956b [ 9859.651499] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 9861.520485] Key type lgssc registered [ 9863.650798] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9882.498432] Key type lgssc unregistered [ 9882.907582] LNet: 240563:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9882.915927] LNetError: 240563:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9882.939663] LNet: Removed LNI 192.168.201.2@tcp [ 9883.817721] Key type .llcrypt unregistered [ 9883.820472] Key type ._llcrypt unregistered [ 9893.190639] Key type ._llcrypt registered [ 9893.257094] Key type .llcrypt registered [ 9893.799480] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9893.819212] alg: No test for adler32 (adler32-zlib) [ 9894.897758] Lustre: Lustre: Build Version: 2.17.54_95_ga6f956b [ 9895.372568] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 9897.266928] Key type lgssc registered [ 9899.588996] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9922.359881] Key type lgssc unregistered [ 9922.696878] LNet: 241886:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9922.706867] LNetError: 241886:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9922.739084] LNet: Removed LNI 192.168.201.2@tcp [ 9923.736101] Key type .llcrypt unregistered [ 9923.739258] Key type ._llcrypt unregistered [ 9931.991043] Key type ._llcrypt registered [ 9931.992763] Key type .llcrypt registered [ 9932.149742] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9932.157760] alg: No test for adler32 (adler32-zlib) [ 9933.214592] Lustre: Lustre: Build Version: 2.17.54_95_ga6f956b [ 9933.435196] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 9935.121951] Key type lgssc registered [ 9936.539465] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9952.234243] Key type lgssc unregistered [ 9952.633082] LNet: 243195:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9952.652832] LNetError: 243195:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9952.678329] LNet: Removed LNI 192.168.201.2@tcp [ 9953.584521] Key type .llcrypt unregistered [ 9953.589426] Key type ._llcrypt unregistered [ 9962.871069] Key type ._llcrypt registered [ 9962.882689] Key type .llcrypt registered [ 9963.760355] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9963.797710] alg: No test for adler32 (adler32-zlib) [ 9965.125112] Lustre: Lustre: Build Version: 2.17.54_95_ga6f956b [ 9965.524530] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 9967.344176] Key type lgssc registered [ 9968.866460] Lustre: Echo OBD driver; http://www.lustre.org/ [10005.054821] Key type lgssc unregistered [10005.555652] LNet: 244988:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10005.573638] LNetError: 244988:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [10005.600121] LNet: Removed LNI 192.168.201.2@tcp [10006.530769] Key type .llcrypt unregistered [10006.534739] Key type ._llcrypt unregistered [10006.941159] Key type ._llcrypt registered [10006.942775] Key type .llcrypt registered [10008.694299] Lustre: DEBUG MARKER: == conf-sanity test 250: verify lcfg_erase removes filesystem parameters ========================================================== 12:12:59 (1782490379) [10035.790426] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10035.826906] alg: No test for adler32 (adler32-zlib) [10037.102346] Lustre: Lustre: Build Version: 2.17.54_95_ga6f956b [10037.541787] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [10039.208248] Key type lgssc registered [10041.044464] Lustre: Echo OBD driver; http://www.lustre.org/ [10297.161567] Lustre: DEBUG MARKER: == conf-sanity test 802a: simulate readonly device ======= 12:17:48 (1782490668) [10299.467480] Lustre: DEBUG MARKER: SKIP: conf-sanity test_802a ZFS specific test [10369.639929] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 9967 sec ========== 12:19:00 (1782490740) [10371.442395] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 12:19:02 (1782490742) === [10375.030557] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 12:19:05 (1782490745) === [10413.444526] Key type lgssc unregistered [10413.756690] LNet: 254056:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10413.771036] LNetError: 254056:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [10413.797533] LNet: Removed LNI 192.168.201.2@tcp [10414.587878] Key type .llcrypt unregistered [10414.590991] Key type ._llcrypt unregistered