[ 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 424497804 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: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001011] APIC: Switch to symmetric I/O mode setup [ 0.003387] x2apic enabled [ 0.004009] Switched APIC routing to physical x2apic. [ 0.005017] kvm-guest: setup PV IPIs [ 0.008000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008036] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009014] pid_max: default: 32768 minimum: 301 [ 0.011019] LSM: Security Framework initializing [ 0.012055] Yama: becoming mindful. [ 0.013047] SELinux: Initializing. [ 0.014077] *** VALIDATE selinux *** [ 0.021816] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026209] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027155] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028099] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029106] *** VALIDATE tmpfs *** [ 0.031231] *** VALIDATE proc *** [ 0.032266] *** VALIDATE cgroup *** [ 0.033013] *** VALIDATE cgroup2 *** [ 0.034272] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035182] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.037003] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.038027] Spectre V2 : User space: Vulnerable [ 0.040004] Speculative Store Bypass: Vulnerable [ 0.042945] debug: unmapping init [mem 0xffffffff95259000-0xffffffff95260fff] [ 0.044791] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.045680] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.046017] ... version: 2 [ 0.046784] ... bit width: 48 [ 0.047020] ... generic registers: 4 [ 0.048014] ... value mask: 0000ffffffffffff [ 0.049015] ... max period: 00007fffffffffff [ 0.050010] ... fixed-purpose events: 3 [ 0.051009] ... event mask: 000000070000000f [ 0.052272] rcu: Hierarchical SRCU implementation. [ 0.054324] smp: Bringing up secondary CPUs ... [ 0.055545] x86: Booting SMP configuration: [ 0.056023] .... node #0, CPUs: #1 #2 #3 [ 0.059510] smp: Brought up 1 node, 4 CPUs [ 0.060935] smpboot: Max logical packages: 1 [ 0.061012] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.278049] node 0 deferred pages initialised in 214ms [ 0.282112] devtmpfs: initialized [ 0.283193] x86/mm: Memory block size: 128MB [ 0.287457] gcov: version magic: 0x41383552 [ 0.291392] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.292097] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.293257] pinctrl core: initialized pinctrl subsystem [ 0.294361] [ 0.295000] ************************************************************* [ 0.297021] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.300013] ** ** [ 0.302017] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.304016] ** ** [ 0.306015] ** This means that this kernel is built to expose internal ** [ 0.309013] ** IOMMU data structures, which may compromise security on ** [ 0.311011] ** your system. ** [ 0.314014] ** ** [ 0.317015] ** If you see this message and you are not debugging the ** [ 0.320013] ** kernel, report this immediately to your vendor! ** [ 0.322013] ** ** [ 0.325015] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.327010] ************************************************************* [ 0.329805] NET: Registered protocol family 16 [ 0.332535] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.335063] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.337060] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.340029] cpuidle: using governor menu [ 0.342846] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.346965] PCI: Using configuration type 1 for base access [ 0.349155] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.360232] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.363049] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.367194] cryptd: max_cpu_qlen set to 1000 [ 0.370793] ACPI: Added _OSI(Module Device) [ 0.372021] ACPI: Added _OSI(Processor Device) [ 0.374020] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.376021] ACPI: Added _OSI(Processor Aggregator Device) [ 0.382562] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.390532] ACPI: Interpreter enabled [ 0.393097] ACPI: PM: (supports S0 S3 S4 S5) [ 0.395019] ACPI: Using IOAPIC for interrupt routing [ 0.397191] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.400457] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.409890] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.412044] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.415022] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.418213] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.423420] acpiphp: Slot [2] registered [ 0.425163] acpiphp: Slot [5] registered [ 0.427149] acpiphp: Slot [6] registered [ 0.429147] acpiphp: Slot [3] registered [ 0.430107] acpiphp: Slot [4] registered [ 0.432109] acpiphp: Slot [7] registered [ 0.434128] acpiphp: Slot [8] registered [ 0.435145] acpiphp: Slot [9] registered [ 0.437122] acpiphp: Slot [10] registered [ 0.438122] acpiphp: Slot [11] registered [ 0.440128] acpiphp: Slot [12] registered [ 0.442118] acpiphp: Slot [13] registered [ 0.443110] acpiphp: Slot [14] registered [ 0.445103] acpiphp: Slot [15] registered [ 0.447124] acpiphp: Slot [16] registered [ 0.448106] acpiphp: Slot [17] registered [ 0.450115] acpiphp: Slot [18] registered [ 0.452137] acpiphp: Slot [19] registered [ 0.453107] acpiphp: Slot [20] registered [ 0.455112] acpiphp: Slot [21] registered [ 0.456145] acpiphp: Slot [22] registered [ 0.458110] acpiphp: Slot [23] registered [ 0.460149] acpiphp: Slot [24] registered [ 0.462141] acpiphp: Slot [25] registered [ 0.464112] acpiphp: Slot [26] registered [ 0.466119] acpiphp: Slot [27] registered [ 0.468186] acpiphp: Slot [28] registered [ 0.470132] acpiphp: Slot [29] registered [ 0.472171] acpiphp: Slot [30] registered [ 0.474225] acpiphp: Slot [31] registered [ 0.476103] PCI host bridge to bus 0000:00 [ 0.478022] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.481027] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.484027] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.488029] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.491033] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.494094] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.496200] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.499155] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.502503] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.511687] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.517503] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.520020] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.523022] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.525028] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.529093] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.532769] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.536052] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.540932] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.547017] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.559027] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.565016] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.571041] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.579019] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.588020] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.610020] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.620000] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.625898] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.630016] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.642016] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.651666] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.655449] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.659395] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.662476] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.666413] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.673040] iommu: Default domain type: Passthrough [ 0.674490] SCSI subsystem initialized [ 0.676131] ACPI: bus type USB registered [ 0.678131] usbcore: registered new interface driver usbfs [ 0.680078] usbcore: registered new interface driver hub [ 0.682081] usbcore: registered new device driver usb [ 0.683186] pps_core: LinuxPPS API ver. 1 registered [ 0.685015] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.689078] PTP clock support registered [ 0.692071] EDAC MC: Ver: 3.0.0 [ 0.694170] PCI: Using ACPI for IRQ routing [ 0.697030] NetLabel: Initializing [ 0.699016] NetLabel: domain hash size = 128 [ 0.700014] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.703113] NetLabel: unlabeled traffic allowed by default [ 0.706125] vgaarb: loaded [ 0.707374] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.710027] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.716528] clocksource: Switched to clocksource kvm-clock [ 0.832758] VFS: Disk quotas dquot_6.6.0 [ 0.837491] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.840367] *** VALIDATE ramfs *** [ 0.841878] *** VALIDATE hugetlbfs *** [ 0.843676] pnp: PnP ACPI init [ 0.846176] pnp: PnP ACPI: found 6 devices [ 0.871679] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.874825] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.877388] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.879852] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.882766] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.885899] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.889783] NET: Registered protocol family 2 [ 0.892384] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.897544] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.901785] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.907295] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.911427] TCP: Hash tables configured (established 65536 bind 65536) [ 0.915173] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.919272] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.922586] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.926224] NET: Registered protocol family 1 [ 0.930699] RPC: Registered named UNIX socket transport module. [ 0.933115] RPC: Registered udp transport module. [ 0.935020] RPC: Registered tcp transport module. [ 0.936823] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.939442] NET: Registered protocol family 44 [ 0.941216] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.943845] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.946088] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.948543] PCI: CLS 0 bytes, default 64 [ 0.950394] Unpacking initramfs... [ 2.370252] debug: unmapping init [mem 0xffff9abd3cc64000-0xffff9abd3ffcffff] [ 2.374766] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.377711] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.380900] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.887085] Initialise system trusted keyrings [ 2.889131] Key type blacklist registered [ 2.891386] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.905117] zbud: loaded [ 2.910385] *** VALIDATE nfs *** [ 2.911679] *** VALIDATE nfs4 *** [ 2.916267] pstore: using deflate compression [ 2.921096] Platform Keyring initialized [ 3.038404] NET: Registered protocol family 38 [ 3.040317] Key type asymmetric registered [ 3.041962] Asymmetric key parser 'x509' registered [ 3.044312] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.047590] io scheduler mq-deadline registered [ 3.049291] io scheduler kyber registered [ 3.051340] io scheduler bfq registered [ 3.053520] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.056047] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.058589] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.061411] ACPI: Power Button [PWRF] [ 3.066984] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.073674] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.083966] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.112211] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.140353] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.145490] Non-volatile memory driver v1.3 [ 3.147594] Linux agpgart interface v0.103 [ 3.191698] virtio_blk virtio1: [vda] 150008 512-byte logical blocks (76.8 MB/73.2 MiB) [ 3.194489] vda: detected capacity change from 0 to 76804096 [ 3.213226] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.216858] vdb: detected capacity change from 0 to 1073741824 [ 3.223935] libphy: Fixed MDIO Bus: probed [ 3.232594] usbcore: registered new interface driver usbserial_generic [ 3.234978] usbserial: USB Serial support registered for generic [ 3.238216] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.241916] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.244027] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.247768] mousedev: PS/2 mouse device common for all mice [ 3.251138] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.253647] rtc_cmos 00:05: RTC can wake from S4 [ 3.257538] rtc_cmos 00:05: registered as rtc0 [ 3.258636] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.259183] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.266211] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.266414] intel_pstate: CPU model not supported [ 3.272227] hid: raw HID events driver (C) Jiri Kosina [ 3.274727] usbcore: registered new interface driver usbhid [ 3.277218] usbhid: USB HID core driver [ 3.279061] drop_monitor: Initializing network drop monitor service [ 3.282027] Initializing XFRM netlink socket [ 3.284603] NET: Registered protocol family 10 [ 3.288849] Segment Routing with IPv6 [ 3.290979] NET: Registered protocol family 17 [ 3.293794] mpls_gso: MPLS GSO support [ 3.300345] RAS: Correctable Errors collector initialized. [ 3.302769] AVX version of gcm_enc/dec engaged. [ 3.305274] AES CTR mode by8 optimization enabled [ 3.394249] sched_clock: Marking stable (3394197994, 0)->(4254272080, -860074086) [ 3.400207] registered taskstats version 1 [ 3.403690] Loading compiled-in X.509 certificates [ 3.406707] zswap: loaded using pool lzo/zbud [ 3.435196] Key type big_key registered [ 3.450781] Key type encrypted registered [ 3.452884] ima: No TPM chip found, activating TPM-bypass! [ 3.455444] ima: Allocated hash algorithm: sha1 [ 3.457924] ima: No architecture policies found [ 3.459354] evm: Initialising EVM extended attributes: [ 3.461231] evm: security.selinux [ 3.462736] evm: security.ima [ 3.464084] evm: security.capability [ 3.465816] evm: HMAC attrs: 0x1 [ 3.468489] rtc_cmos 00:05: setting system clock to 2026-09-15 16:13:37 UTC (1789488817) [ 3.475709] debug: unmapping init [mem 0xffffffff96203000-0xffffffff963fffff] [ 3.478729] debug: unmapping init [mem 0xffffffff94f82000-0xffffffff95258fff] [ 3.488092] Write protecting the kernel read-only data: 28672k [ 3.491981] debug: unmapping init [mem 0xffffffff93603000-0xffffffff937fffff] [ 3.495260] debug: unmapping init [mem 0xffffffff93f14000-0xffffffff93ffffff] [ 3.535032] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 3.544705] systemd[1]: Detected virtualization kvm. [ 3.547157] systemd[1]: Detected architecture x86-64. [ 3.549824] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.576162] systemd[1]: No hostname configured. [ 3.577758] systemd[1]: Set hostname to . [ 3.579517] random: systemd: uninitialized urandom read (16 bytes read) [ 3.581926] systemd[1]: Initializing machine ID from random generator. [ 3.639193] random: ln: uninitialized urandom read (6 bytes read) [ 3.746313] random: systemd: uninitialized urandom read (16 bytes read) [ 3.750506] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 3.756539] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 3.760835] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Swap. [ OK ] Reached target Local File Systems. [ OK ] Listening on Journal Socket. [ OK ] Started Memstrack Anylazing Service. Starting Create list of required st…ce nodes for the current kernel... Starting Apply Kernel Variables... [ OK ] Reached target Sockets. Starting Setup Virtual Console... [ OK ] Reached target Initrd Root Device. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. Starting Journal Service... Starting Create Volatile Files and Directories... [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. [ OK ] Started Create Volatile Files and Directories. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.423118] device-mapper: uevent: version 1.0.3 [ 4.425678] 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... [ 4.960461] random: fast init done [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 5.246610] virtio_net virtio0 ens2: renamed from eth0 [ 5.482189] scsi host0: ata_piix [ 5.512243] scsi host1: ata_piix [ 5.513944] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.517300] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 10.992205] random: crng init done [ 10.996637] random: 7 urandom warning(s) missed due to ratelimiting [ 11.856311] dracut-initqueue[581]: 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... [ 13.494288] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 16.175640] printk: systemd: 26 output lines suppressed due to ratelimiting [ 17.006515] SELinux: Disabled at runtime. [ 17.120475] 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) [ 17.139297] systemd[1]: Detected virtualization kvm. [ 17.146633] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 19.160059] systemd[1]: initrd-switch-root.service: Succeeded. [ 19.176916] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 19.216917] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 19.270136] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 19.291050] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 19.313662] systemd[1]: Starting Journal Service... Starting Journal Service... [ 19.338766] systemd[1]: Activating swap /dev/disk/by-label/SWAP... Activating swap /dev/disk/by-label/SWAP... Mounting Kernel Debug File System... Mounting Huge Pages File System... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ 19.542632] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Mounting POSIX Message Queue File System... Starting Remount Root and Kernel File Systems... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on initctl Compatibility Named Pipe. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target rpc_pipefs.target. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on Process Core Dump Socket. [ OK ] Created slice system-getty.slice. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Stopped target Initrd Root File System. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Starting Apply Kernel Variables... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Started Journal Service. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted POSIX Message Queue File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. Starting Create Static Device Nodes in /dev... Starting Configure read-only root support... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 21.074377] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 22.426876] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 22.471575] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 22.951800] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 23.068504] EDAC sbridge: Ver: 1.1.2 [* ] A start job is running for Configur…-only root support (8s / no limit)[ 27.687860] Key type dns_resolver registered [** ] 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)[ 28.573530] NFS: Registering the id_resolver key type [ 28.577897] Key type id_resolver registered [ 28.579783] Key type id_legacy registered [ *** ] A start job is running for Configur…only root support (10s / no limit) [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started dnf makecache --timer. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Login Service... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Reached target sshd-keygen.target. [ OK ] Started irqbalance daemon. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting System Logging Service... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg426-client login: [ 99.857278] libcfs: loading out-of-tree module taints kernel. [ 99.996753] Key type ._llcrypt registered [ 100.001818] Key type .llcrypt registered [ 100.689751] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 100.722033] alg: No test for adler32 (adler32-zlib) [ 102.590555] Lustre: Lustre: Build Version: 2.17.58_40_gc6c2479 [ 103.339129] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 105.047256] Key type lgssc registered [ 107.377694] Lustre: Echo OBD driver; http://www.lustre.org/ [ 174.191315] hrtimer: interrupt took 2669582 ns [ 286.412686] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [ 292.500334] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 308.402252] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing check_logdir /tmp/testlogs/ [ 312.293391] Lustre: lustre-OST0000-osc-ffff9abd92f08800: disconnect after 23s idle [ 316.014501] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing yml_node [ 321.038767] Lustre: DEBUG MARKER: Client: 2.17.58.40 [ 324.096645] Lustre: DEBUG MARKER: MDS: 2.17.58.40 [ 326.783598] Lustre: DEBUG MARKER: OSS: 2.17.58.40 [ 328.523415] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Tue Sep 15 12:19:01 EDT 2026 [ 348.091841] Lustre: DEBUG MARKER: excepting tests: 32newtarball [ 349.738887] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 352.228937] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 362.542375] Lustre: Unmounted lustre-client [ 480.940707] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 488.134325] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 513.034194] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 550.533738] Lustre: DEBUG MARKER: == conf-sanity test 121: failover MGS ==================== 12:22:43 (1789489363) [ 595.779411] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 603.162374] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 641.799704] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid 1475 0 [ 697.518573] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 703.517854] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 740.227187] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid 1475 0 [ 765.028935] Lustre: DEBUG MARKER: == conf-sanity test 122a: Check OST sequence update ====== 12:26:17 (1789489577) [ 877.123800] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 882.462483] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 906.315114] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 907.282516] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [ 930.536642] Lustre: Unmounted lustre-client [ 967.883784] Key type lgssc unregistered [ 968.253231] LNet: 21869:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 968.265405] LNetError: 21869:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 968.282497] LNet: Removed LNI 192.168.204.26@tcp [ 969.077154] Key type .llcrypt unregistered [ 969.079034] Key type ._llcrypt unregistered [ 985.300741] Key type ._llcrypt registered [ 985.303680] Key type .llcrypt registered [ 986.863848] Lustre: DEBUG MARKER: == conf-sanity test 123aa: llog_print works with FIDs and simple names ========================================================== 12:29:59 (1789489799) [ 989.612347] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 989.668161] alg: No test for adler32 (adler32-zlib) [ 990.955574] Lustre: Lustre: Build Version: 2.17.58_40_gc6c2479 [ 991.352846] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 993.063414] Key type lgssc registered [ 995.032694] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1057.841856] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1065.445783] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1087.890570] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1090.600939] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [ 1102.987421] Lustre: DEBUG MARKER: == conf-sanity test 123ab: llog_print params output values from set_param -P ========================================================== 12:31:55 (1789489915) [ 1114.379809] Lustre: DEBUG MARKER: == conf-sanity test 123ac: llog_print with --start and --end ========================================================== 12:32:06 (1789489926) [ 1124.819276] Lustre: DEBUG MARKER: == conf-sanity test 123ad: llog_print shows all records == 12:32:16 (1789489936) [ 1139.010622] Lustre: DEBUG MARKER: == conf-sanity test 123ae: llog_cancel can cancel requested record ========================================================== 12:32:31 (1789489951) [ 1166.323461] Lustre: DEBUG MARKER: == conf-sanity test 123af: llog_catlist can show all config files correctly ========================================================== 12:32:58 (1789489978) [ 1183.118132] Lustre: DEBUG MARKER: == conf-sanity test 123ag: llog_print skips values deleted by set_param -P -d ========================================================== 12:33:15 (1789489995) [ 1201.195565] Lustre: DEBUG MARKER: == conf-sanity test 123ah: del_ost cancels config log entries correctly ========================================================== 12:33:32 (1789490012) [ 1212.461599] Lustre: Unmounted lustre-client [ 1213.164125] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [ 1216.219152] Lustre: Unmounted lustre-client [ 1252.040323] Key type lgssc unregistered [ 1252.388064] LNet: 30236:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1252.405452] LNetError: 30236:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1252.434286] LNet: Removed LNI 192.168.204.26@tcp [ 1253.671953] Key type .llcrypt unregistered [ 1253.677745] Key type ._llcrypt unregistered [ 1290.242080] Key type ._llcrypt registered [ 1290.246196] Key type .llcrypt registered [ 1290.911805] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1290.942982] alg: No test for adler32 (adler32-zlib) [ 1292.098607] Lustre: Lustre: Build Version: 2.17.58_40_gc6c2479 [ 1292.386804] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 1294.079265] Key type lgssc registered [ 1295.589446] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1387.202238] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1393.586670] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1419.363408] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1468.536847] Lustre: DEBUG MARKER: == conf-sanity test 123ai: llog_print display all non skipped records ========================================================== 12:38:00 (1789490280) [ 1503.286160] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1509.242548] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1532.555948] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1535.669992] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [ 1674.295816] Lustre: DEBUG MARKER: == conf-sanity test 123aj: check permanent TBF rules ===== 12:41:26 (1789490486) [ 1724.193490] Lustre: DEBUG MARKER: == conf-sanity test 123F: clear and reset all parameters using set_param -F ========================================================== 12:42:15 (1789490535) [ 1739.445594] Lustre: Unmounted lustre-client [ 1827.143744] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1834.072542] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1859.317704] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1860.095260] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [ 1866.932972] Lustre: Unmounted lustre-client [ 1906.686685] Key type lgssc unregistered [ 1907.109229] LNet: 41571:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1907.123556] LNetError: 41571:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1907.143103] LNet: Removed LNI 192.168.204.26@tcp [ 1908.051574] Key type .llcrypt unregistered [ 1908.054369] Key type ._llcrypt unregistered [ 1925.848397] Key type ._llcrypt registered [ 1925.851019] Key type .llcrypt registered [ 1927.877830] Lustre: DEBUG MARKER: == conf-sanity test 123G: clear and reset all parameters using apply_yaml ========================================================== 12:45:39 (1789490739) [ 1930.810903] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1930.831675] alg: No test for adler32 (adler32-zlib) [ 1931.808323] Lustre: Lustre: Build Version: 2.17.58_40_gc6c2479 [ 1931.995179] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 1933.775869] Key type lgssc registered [ 1935.039329] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1992.489888] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1998.614839] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2022.451406] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2033.816730] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [ 2048.320051] Lustre: Unmounted lustre-client [ 2136.290825] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2142.914344] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2167.673970] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2169.458907] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [ 2177.194061] Lustre: Unmounted lustre-client [ 2206.956859] Key type lgssc unregistered [ 2207.206191] LNet: 47109:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2207.223192] LNetError: 47109:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2207.247386] LNet: Removed LNI 192.168.204.26@tcp [ 2208.125066] Key type .llcrypt unregistered [ 2208.133355] Key type ._llcrypt unregistered [ 2228.513590] Key type ._llcrypt registered [ 2228.522130] Key type .llcrypt registered [ 2230.304597] Lustre: DEBUG MARKER: == conf-sanity test 123H: check concurent accesses with 'lctl llog_print ========================================================== 12:50:42 (1789491042) [ 2233.222077] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2233.264481] alg: No test for adler32 (adler32-zlib) [ 2234.509188] Lustre: Lustre: Build Version: 2.17.58_40_gc6c2479 [ 2234.730868] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 2236.423318] Key type lgssc registered [ 2237.535799] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2288.090593] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2293.943440] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2314.030969] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2317.012173] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [ 2416.923557] Lustre: DEBUG MARKER: == conf-sanity test 124: check failover after replace_nids ========================================================== 12:53:48 (1789491228) [ 2419.139314] Lustre: DEBUG MARKER: SKIP: conf-sanity test_124 needs MDT failover setup [ 2420.939390] Lustre: DEBUG MARKER: == conf-sanity test 126: mount in parallel shouldn't cause a crash ========================================================== 12:53:53 (1789491233) [ 2423.196882] Lustre: Unmounted lustre-client [ 2449.012870] Key type lgssc unregistered [ 2449.263535] LNet: 52439:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2449.267839] LNetError: 52439:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2449.281390] LNet: Removed LNI 192.168.204.26@tcp [ 2449.966640] Key type .llcrypt unregistered [ 2449.970701] Key type ._llcrypt unregistered [ 2476.963308] Key type ._llcrypt registered [ 2476.974907] Key type .llcrypt registered [ 2477.506136] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2477.525351] alg: No test for adler32 (adler32-zlib) [ 2478.547350] Lustre: Lustre: Build Version: 2.17.58_40_gc6c2479 [ 2478.818786] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 2480.511296] Key type lgssc registered [ 2481.949255] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2514.805800] Lustre: DEBUG MARKER: == conf-sanity test 127: direct io overwrite on full ost ========================================================== 12:55:27 (1789491327) [ 2528.823188] Key type lgssc unregistered [ 2529.143043] LNet: 54843:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2529.161958] LNetError: 54843:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2529.196185] LNet: Removed LNI 192.168.204.26@tcp [ 2530.356109] Key type .llcrypt unregistered [ 2530.357964] Key type ._llcrypt unregistered [ 2547.803591] Key type ._llcrypt registered [ 2547.810303] Key type .llcrypt registered [ 2548.350447] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2548.371930] alg: No test for adler32 (adler32-zlib) [ 2549.454625] Lustre: Lustre: Build Version: 2.17.58_40_gc6c2479 [ 2549.741527] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 2551.479305] Key type lgssc registered [ 2552.756133] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2602.537129] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2608.297168] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2629.114972] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2631.272288] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [ 2676.131066] Lustre: DEBUG MARKER: == conf-sanity test 128: Force using remote logs with --nolocallogs ========================================================== 12:58:08 (1789491488) [ 2677.914733] Lustre: DEBUG MARKER: SKIP: conf-sanity test_128 need separate mgs device [ 2679.945740] Lustre: DEBUG MARKER: == conf-sanity test 129: attempt to connect an OST with the same index should fail ========================================================== 12:58:12 (1789491492) [ 2682.703872] Lustre: Unmounted lustre-client [ 2763.107707] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2772.007391] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2839.249150] Lustre: DEBUG MARKER: == conf-sanity test 130: re-register an MDT after writeconf ========================================================== 13:00:51 (1789491651) [ 2924.805213] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [ 2930.134796] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 2950.623598] Lustre: lustre-OST0000-osc-ffff9abd91155000: disconnect after 23s idle [ 2950.640230] Lustre: lustre-MDT0001-mdc-ffff9abd91155000: Connection to lustre-MDT0001 (at 192.168.204.126@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2964.791448] LustreError: 63565:0:(obd_config.c:2102:class_config_llog_handler()) MGC192.168.204.126@tcp: cfg command failed: rc = -17 [ 2964.808776] Lustre: cmd=cf001 0:lustre-MDT0001-mdc 1:mdc 2:lustre-clilmv_UUID [ 2964.808776] [ 2964.825571] LustreError: 62796:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [ 2977.911504] Lustre: DEBUG MARKER: == conf-sanity test 131: MDT backup restore with project ID and inheritance flag ========================================================== 13:03:10 (1789491790) [ 3037.703405] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3124.651255] Lustre: Unmounted lustre-client [ 3293.589782] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [ 3298.647425] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3355.997884] Lustre: DEBUG MARKER: == conf-sanity test 132: hsm_actions processed after failover ========================================================== 13:09:27 (1789492167) [ 3358.180744] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3377.943716] Lustre: Unmounted lustre-client [ 3538.492592] Lustre: DEBUG MARKER: == conf-sanity test 133: stripe QOS: free space balance in a pool ========================================================== 13:12:31 (1789492351) [ 3540.160865] Lustre: DEBUG MARKER: SKIP: conf-sanity test_133 needs >= 4 OSTs [ 3542.230799] Lustre: DEBUG MARKER: == conf-sanity test 134: check_iam works without faults == 13:12:34 (1789492354) [ 3639.332759] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [ 3645.991087] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 4345.860347] Lustre: DEBUG MARKER: == conf-sanity test 135: check the behavior when changelog is wrapped around ========================================================== 13:25:58 (1789493158) [ 4348.279480] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4348.295971] Lustre: Skipped 3 previous similar messages [ 4358.610922] Lustre: Unmounted lustre-client [ 4491.873971] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4496.639741] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4517.015464] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4517.801060] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [ 6357.804643] Lustre: 95277:0:(llog_cat.c:310:llog_cat_process_or_fork()) lustre-MDT0000-mdc-ffff9abd88ce6800: catlog [0xa:0x5:0x0] crosses index zero [ 6383.781295] Lustre: Unmounted lustre-client [ 6421.128915] Key type lgssc unregistered [ 6421.353553] LNet: 103458:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6421.384457] LNetError: 103458:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6421.408053] LNet: Removed LNI 192.168.204.26@tcp [ 6422.209098] Key type .llcrypt unregistered [ 6422.217869] Key type ._llcrypt unregistered [ 6433.193974] Key type ._llcrypt registered [ 6433.196055] Key type .llcrypt registered [ 6434.627659] Lustre: DEBUG MARKER: == conf-sanity test 136: don't panic with bad obdecho setup ========================================================== 14:00:46 (1789495246) [ 6453.280533] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6453.340626] alg: No test for adler32 (adler32-zlib) [ 6454.440358] Lustre: Lustre: Build Version: 2.17.58_40_gc6c2479 [ 6454.806750] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 6456.623270] Key type lgssc registered [ 6457.980860] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6535.075706] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6540.717801] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6563.705908] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6566.428688] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [ 6583.674756] Lustre: Unmounted lustre-client [ 6709.798308] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6715.486918] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6746.631974] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6791.163818] Lustre: DEBUG MARKER: == conf-sanity test 137: a new MDT should inherit pools, parameters and failnode ========================================================== 14:06:43 (1789495603) [ 6793.529654] Lustre: DEBUG MARKER: SKIP: conf-sanity test_137 needs >= 3 MDTs [ 6795.625715] Lustre: DEBUG MARKER: == conf-sanity test 140: remove_updatelog script actions ========================================================== 14:06:47 (1789495607) [ 6837.059623] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6843.871061] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6866.761348] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6877.758729] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [ 6887.916465] Lustre: lustre-MDT0000-mdc-ffff9abd88c84800: Connection to lustre-MDT0000 (at 192.168.204.126@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6893.032525] Lustre: lustre-MDT0001-mdc-ffff9abd88c84800: Connection to lustre-MDT0001 (at 192.168.204.126@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6903.272071] Lustre: 104225:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789495701/real 1789495701] req@000000000cdc2cc7 x1876421790215552/t0(0) o400->MGC192.168.204.126@tcp@192.168.204.126@tcp:26/25 lens 224/224 e 0 to 1 dl 1789495717 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6903.299462] LustreError: MGC192.168.204.126@tcp: Connection to MGS (at 192.168.204.126@tcp) was lost; in progress operations using this service will fail [ 7038.997268] LustreError: lustre-MDT0000-mdc-ffff9abd88c84800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 7039.048733] Lustre: lustre-MDT0000-mdc-ffff9abd88c84800: Connection restored to 192.168.204.126@tcp (at 192.168.204.126@tcp) [ 7044.075486] LustreError: lustre-MDT0001-mdc-ffff9abd88c84800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 7044.103098] Lustre: lustre-MDT0001-mdc-ffff9abd88c84800: Connection restored to 192.168.204.126@tcp (at 192.168.204.126@tcp) [ 7048.244900] Lustre: Evicted from MGS (at 192.168.204.126@tcp) after server handle changed from 0x820e00009b60374f to 0x820e00009b604f9e [ 7048.255741] Lustre: MGC192.168.204.126@tcp: Connection restored to 192.168.204.126@tcp (at 192.168.204.126@tcp) [ 7048.422247] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7049.834574] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7055.710921] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7057.254579] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7071.818239] Lustre: Unmounted lustre-client [ 7201.098457] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7207.634892] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7237.520403] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7287.213813] Lustre: DEBUG MARKER: == conf-sanity test 150: test setting max_cached_mb to a % ========================================================== 14:14:59 (1789496099) [ 7325.769872] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7332.078207] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7355.004233] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7356.105852] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [ 7362.062726] LustreError: 120940: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 [ 7371.560283] Lustre: DEBUG MARKER: == conf-sanity test 151a: damaged local config doesn't prevent mounting ========================================================== 14:16:23 (1789496183) [ 7373.169431] Lustre: Unmounted lustre-client [ 7402.898514] Key type lgssc unregistered [ 7403.309467] LNet: 121944:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7403.323351] LNetError: 121944:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7403.339133] LNet: Removed LNI 192.168.204.26@tcp [ 7404.189613] Key type .llcrypt unregistered [ 7404.192098] Key type ._llcrypt unregistered [ 7419.913969] Key type ._llcrypt registered [ 7419.942148] Key type .llcrypt registered [ 7420.443302] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7420.459524] alg: No test for adler32 (adler32-zlib) [ 7421.732912] Lustre: Lustre: Build Version: 2.17.58_40_gc6c2479 [ 7422.046173] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7423.728711] Key type lgssc registered [ 7425.594334] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7517.537392] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7524.677605] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7551.801441] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7686.825368] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7693.008438] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7715.207452] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7758.353903] Lustre: DEBUG MARKER: == conf-sanity test 151b: -ENOSPC doesn't affect mount === 14:22:50 (1789496570) [ 7770.061188] Key type lgssc unregistered [ 7770.386188] LNet: 129153:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7770.395440] LNetError: 129153:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7770.426177] LNet: Removed LNI 192.168.204.26@tcp [ 7771.283212] Key type .llcrypt unregistered [ 7771.286617] Key type ._llcrypt unregistered [ 7785.143328] Key type ._llcrypt registered [ 7785.148584] Key type .llcrypt registered [ 7785.825078] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7785.842971] alg: No test for adler32 (adler32-zlib) [ 7787.029324] Lustre: Lustre: Build Version: 2.17.58_40_gc6c2479 [ 7787.412233] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7789.151256] Key type lgssc registered [ 7790.554423] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7847.161573] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7854.186812] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7881.481849] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8005.455390] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8011.337320] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8040.642842] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8094.673463] Lustre: DEBUG MARKER: == conf-sanity test 152: seq allocation error in OSP ===== 14:28:27 (1789496907) [ 8180.254528] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [ 8185.705375] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 8195.474638] Lustre: DEBUG MARKER: ADD OST3 [ 8205.800036] Lustre: lustre-OST0000-osc-ffff9abd83f08800: disconnect after 23s idle [ 8209.821820] Lustre: DEBUG MARKER: STOP OST3 [ 8224.254366] Lustre: lustre-OST0003-osc-ffff9abd83f08800: Connection to lustre-OST0003 (at 192.168.204.126@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8233.793713] Lustre: DEBUG MARKER: START OST3 again [ 8242.542172] Lustre: lustre-OST0003-osc-ffff9abd83f08800: Connection restored to 192.168.204.126@tcp (at 192.168.204.126@tcp) [ 8272.530916] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 8272.540338] LustreError: 139956:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff9abd83f08800: inode [0x240000bd0:0x1:0x0] mdc close failed: rc = -108 [ 8282.889428] Lustre: Unmounted lustre-client [ 8398.469292] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8405.534175] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8430.844935] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8469.340581] Lustre: DEBUG MARKER: == conf-sanity test 153a: bypass invalid NIDs quickly ==== 14:34:41 (1789497281) [ 8567.520892] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8573.520796] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8596.965079] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8679.685975] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8686.191812] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8706.280732] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8708.704772] LNetError: 129561:0:(lib-move.c:2151:lnet_handle_find_routed_path()) peer 192.168.252.112@tcp2 has no available nets [ 8708.946498] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [ 8714.679420] Lustre: Unmounted lustre-client [ 8750.125679] Key type lgssc unregistered [ 8760.223367] LNet: 1 local NIs in recovery (showing 1): 192.168.204.26@tcp [ 8766.816773] LNet: 148927:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8766.832831] LNetError: 148927:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8840.617335] LNet: Removed LNI 192.168.204.26@tcp [ 8841.599178] Key type .llcrypt unregistered [ 8841.601617] Key type ._llcrypt unregistered [ 8859.017728] Key type ._llcrypt registered [ 8859.020800] Key type .llcrypt registered [ 8860.626210] Lustre: DEBUG MARKER: == conf-sanity test 153b: added IPv6 NID support ========= 14:41:12 (1789497672) [ 8879.982453] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8880.006750] alg: No test for adler32 (adler32-zlib) [ 8881.183849] Lustre: Lustre: Build Version: 2.17.58_40_gc6c2479 [ 8881.495497] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 8883.215325] Key type lgssc registered [ 8884.448452] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8972.888933] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8978.011115] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9001.386467] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9131.753280] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [ 9136.597736] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9146.799451] Lustre: Unmounted lustre-client [ 9146.948942] LNetError: 150105:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::547e:c8ff:fe06:3403/1023: rc = -22 [ 9146.963051] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 9147.247556] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [ 9147.408482] Lustre: Unmounted lustre-client [ 9148.055919] LNetError: 150106:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::547e:c8ff:fe06:3403/1023: rc = -22 [ 9148.069392] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 9148.354554] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [ 9148.371728] Lustre: Skipped 1 previous similar message [ 9148.553716] Lustre: Unmounted lustre-client [ 9148.556863] Lustre: Skipped 1 previous similar message [ 9150.686720] LNetError: 150106:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::547e:c8ff:fe06:3403/1023: rc = -22 [ 9150.711844] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 9151.038387] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [ 9151.045674] Lustre: Skipped 3 previous similar messages [ 9151.224963] Lustre: Unmounted lustre-client [ 9151.230328] Lustre: Skipped 3 previous similar messages [ 9152.028301] Lustre: 156046:0:(obd_mount.c:175:lustre_add_mgc_failnodes()) MGC6699:7654::1234:1234:d84@tcp: can't add failover NID 5031:db8:85a3:8d3:1319:8a2e:370:7348@tcp, rc = -75 [ 9152.604231] Lustre: 156074:0:(obd_mount.c:175:lustre_add_mgc_failnodes()) MGC193.168.0.240@tcp: can't add failover NID 5031:db8:85a3:8d3:1319:8a2e:370:7348@tcp, rc = -75 [ 9160.762609] Lustre: DEBUG MARKER: == conf-sanity test 153c: don't stuck on unreached NID === 14:46:12 (1789497972) [ 9206.111773] LNet: 1 local NIs in recovery (showing 1): 192.168.204.26@tcp [ 9275.774885] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9280.955737] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9302.955283] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9385.968823] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9391.469951] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9413.406774] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9418.976342] LustreError: 161662:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.252.112@tcp: failed processing log, type 1: rc = -110 [ 9468.255370] LNet: 1 local NIs in recovery (showing 1): 192.168.204.26@tcp [ 9545.056335] LNet: 1 local NIs in recovery (showing 1): 192.168.204.26@tcp [ 9555.937934] 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 [ 9555.985620] Lustre: Unmounted lustre-client [ 9555.998551] Lustre: Skipped 3 previous similar messages [ 9556.059990] LustreError: 161662:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 9592.027187] Key type lgssc unregistered [ 9601.503856] LNet: 162119:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9601.517684] LNetError: 162119:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9676.333335] LNet: Removed LNI 192.168.204.26@tcp [ 9677.255162] Key type .llcrypt unregistered [ 9677.260607] Key type ._llcrypt unregistered [ 9694.463446] Key type ._llcrypt registered [ 9694.466350] Key type .llcrypt registered [ 9695.796091] Lustre: DEBUG MARKER: == conf-sanity test 154: expand .. on rename after MDT backup restore ========================================================== 14:55:08 (1789498508) [ 9714.513542] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9714.530711] alg: No test for adler32 (adler32-zlib) [ 9715.636486] Lustre: Lustre: Build Version: 2.17.58_40_gc6c2479 [ 9715.876736] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 9717.567346] Key type lgssc registered [ 9719.005525] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9872.243944] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [ 9877.582574] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9896.279127] Lustre: Unmounted lustre-client [10056.525425] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [10061.723099] Lustre: DEBUG MARKER: Using TIMEOUT=20 [10068.366982] Lustre: Unmounted lustre-client [10117.255119] Lustre: DEBUG MARKER: == conf-sanity test 155: gap in seq allocation from ofd after restarting ========================================================== 15:02:09 (1789498929) [10214.280536] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10220.856707] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10245.988868] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10376.688795] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [10382.387917] Lustre: DEBUG MARKER: Using TIMEOUT=20 [10399.461264] Lustre: Unmounted lustre-client [10517.847681] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [10523.857773] Lustre: DEBUG MARKER: Using TIMEOUT=20 [10543.584502] Lustre: lustre-OST0001-osc-ffff9abd9165b000: disconnect after 23s idle [10547.763892] Lustre: DEBUG MARKER: == conf-sanity test 156: root_fid on export consistent with client mount ========================================================== 15:09:20 (1789499360) [10549.617321] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [10549.624496] LustreError: 180540:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff9abd9165b000: inode [0x200000bd1:0x1:0x0] mdc close failed: rc = -108 [10560.014116] Lustre: Unmounted lustre-client [10751.853859] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [10758.028265] Lustre: DEBUG MARKER: Using TIMEOUT=20 [10769.686630] Lustre: Unmounted lustre-client [10770.552947] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [10779.670773] Lustre: Unmounted lustre-client [10781.665809] Lustre: DEBUG MARKER: == conf-sanity test 157a: test allow_register for MDT registration and MGS restart ========================================================== 15:13:13 (1789499593) [10863.652701] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10869.963775] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10891.570674] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10892.313732] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [10989.544837] Lustre: lustre-MDT0000-mdc-ffff9abd91872000: Connection to lustre-MDT0000 (at 192.168.204.126@tcp) was lost; in progress operations using this service will wait for recovery to complete [10989.545630] LustreError: lustre-OST0001-osc-ffff9abd91872000: operation ost_connect to node 192.168.204.126@tcp failed: rc = -19 [11004.906984] LustreError: MGC192.168.204.126@tcp: Connection to MGS (at 192.168.204.126@tcp) was lost; in progress operations using this service will fail [11004.918468] LustreError: lustre-MDT0000-mdc-ffff9abd91872000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [11004.954531] Lustre: Evicted from MGS (at 192.168.204.126@tcp) after server handle changed from 0x2fef1273f066c658 to 0x2fef1273f066c922 [11004.967869] Lustre: lustre-MDT0000-mdc-ffff9abd91872000: Connection restored to 192.168.204.126@tcp (at 192.168.204.126@tcp) [11051.108161] Lustre: Unmounted lustre-client [11093.806993] Key type lgssc unregistered [11094.190831] LNet: 189776:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11094.215509] LNetError: 189776:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11094.250221] LNet: Removed LNI 192.168.204.26@tcp [11094.950201] Key type .llcrypt unregistered [11094.955789] Key type ._llcrypt unregistered [11105.153093] Key type ._llcrypt registered [11105.155257] Key type .llcrypt registered [11106.516722] Lustre: DEBUG MARKER: == conf-sanity test 157b: verify allow_register (block new OSTs, allow existing) ========================================================== 15:18:38 (1789499918) [11108.930570] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11108.944755] alg: No test for adler32 (adler32-zlib) [11110.053329] Lustre: Lustre: Build Version: 2.17.58_40_gc6c2479 [11110.301463] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [11111.984104] Key type lgssc registered [11113.837937] Lustre: Echo OBD driver; http://www.lustre.org/ [11167.855560] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11173.205280] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11193.575536] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11196.492967] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [11293.686634] LustreError: lustre-OST0001-osc-ffff9abda6b28000: operation ost_connect to node 192.168.204.126@tcp failed: rc = -19 [11394.883644] Lustre: Unmounted lustre-client [11422.741667] Key type lgssc unregistered [11422.990782] LNet: 195802:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11423.002994] LNetError: 195802:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11423.034470] LNet: Removed LNI 192.168.204.26@tcp [11423.656170] Key type .llcrypt unregistered [11423.658484] Key type ._llcrypt unregistered [11434.254506] Key type ._llcrypt registered [11434.258589] Key type .llcrypt registered [11435.795711] Lustre: DEBUG MARKER: == conf-sanity test 160: MGC updates failnodes from all participants ========================================================== 15:24:08 (1789500248) [11467.586548] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11467.600776] alg: No test for adler32 (adler32-zlib) [11468.750400] Lustre: Lustre: Build Version: 2.17.58_40_gc6c2479 [11468.968386] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [11470.639969] Key type lgssc registered [11472.260646] Lustre: Echo OBD driver; http://www.lustre.org/ [11563.886880] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11570.243171] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11596.239304] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11633.427904] Key type lgssc unregistered [11633.766052] LNet: 200781:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11633.781429] LNetError: 200781:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11633.820682] LNet: Removed LNI 192.168.204.26@tcp [11634.500666] Key type .llcrypt unregistered [11634.505612] Key type ._llcrypt unregistered [11748.522983] Key type ._llcrypt registered [11748.540437] Key type .llcrypt registered [11748.956356] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11748.970563] alg: No test for adler32 (adler32-zlib) [11749.978578] Lustre: Lustre: Build Version: 2.17.58_40_gc6c2479 [11750.223027] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [11751.903297] Key type lgssc registered [11753.274173] Lustre: Echo OBD driver; http://www.lustre.org/ [11795.619932] Lustre: DEBUG MARKER: == conf-sanity test 161: test '-o mgsname' option ======== 15:30:08 (1789500608) [11833.186515] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11838.858374] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11867.667917] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11870.288894] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [11872.394569] Lustre: Unmounted lustre-client [11872.651274] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [11880.295594] Lustre: Unmounted lustre-client [11880.303625] Lustre: Skipped 1 previous similar message [11907.401673] Key type lgssc unregistered [11907.649892] LNet: 205750:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11907.668168] LNetError: 205750:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11907.692819] LNet: Removed LNI 192.168.204.26@tcp [11908.372300] Key type .llcrypt unregistered [11908.374635] Key type ._llcrypt unregistered [11917.569159] Key type ._llcrypt registered [11917.571615] Key type .llcrypt registered [11918.995271] Lustre: DEBUG MARKER: == conf-sanity test 162: replace nids with -o noclient === 15:32:11 (1789500731) [11923.197149] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11923.286801] alg: No test for adler32 (adler32-zlib) [11924.423797] Lustre: Lustre: Build Version: 2.17.58_40_gc6c2479 [11924.713529] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [11926.415523] Key type lgssc registered [11928.017303] Lustre: Echo OBD driver; http://www.lustre.org/ [11976.512912] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11981.836636] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12001.729392] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12004.538428] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [12012.440467] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12014.007675] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [12020.201111] Lustre: lustre-MDT0000-mdc-ffff9abdb0e09800: Connection to lustre-MDT0000 (at 192.168.204.126@tcp) was lost; in progress operations using this service will wait for recovery to complete [12030.454832] Lustre: lustre-MDT0001-mdc-ffff9abdb0e09800: Connection to lustre-MDT0001 (at 192.168.204.126@tcp) was lost; in progress operations using this service will wait for recovery to complete [12041.697344] Lustre: 206421:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789500839/real 1789500839] req@00000000b2a95bd8 x1876427525854464/t0(0) o400->MGC192.168.204.126@tcp@192.168.204.126@tcp:26/25 lens 224/224 e 0 to 1 dl 1789500855 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [12041.752681] LustreError: MGC192.168.204.126@tcp: Connection to MGS (at 192.168.204.126@tcp) was lost; in progress operations using this service will fail [12091.885711] Lustre: Evicted from MGS (at 192.168.204.126@tcp) after server handle changed from 0x2573e43c7b85837e to 0x2573e43c7b8585fb [12091.893759] Lustre: MGC192.168.204.126@tcp: Connection restored to 192.168.204.126@tcp (at 192.168.204.126@tcp) [12115.754953] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12117.165703] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in IDLE state after 0 sec [12122.690137] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12126.722265] LustreError: lustre-MDT0000-mdc-ffff9abdb0e09800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [12126.754639] Lustre: lustre-MDT0000-mdc-ffff9abdb0e09800: Connection restored to 192.168.204.126@tcp (at 192.168.204.126@tcp) [12128.576444] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 4 sec [12131.356655] Lustre: Unmounted lustre-client [12162.438372] Key type lgssc unregistered [12162.698766] LNet: 211307:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12162.707544] LNetError: 211307:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12162.723162] LNet: Removed LNI 192.168.204.26@tcp [12163.501442] Key type .llcrypt unregistered [12163.505473] Key type ._llcrypt unregistered [12192.838131] Key type ._llcrypt registered [12192.848583] Key type .llcrypt registered [12193.329128] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12193.353408] alg: No test for adler32 (adler32-zlib) [12194.573584] Lustre: Lustre: Build Version: 2.17.58_40_gc6c2479 [12194.811715] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [12196.479285] Key type lgssc registered [12197.752311] Lustre: Echo OBD driver; http://www.lustre.org/ [12292.797486] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12299.411822] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12325.835665] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12370.982492] Lustre: DEBUG MARKER: == conf-sanity test 164: test expected_clients parameter and max client tracking ========================================================== 15:39:43 (1789501183) [12469.277759] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12476.268759] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12503.351932] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12506.308107] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [12512.459333] LustreError: 211907:0:(lmv_obd.c:211:lmv_notify()) activation of lustre-MDT0001_UUID failed: -22 [12512.513594] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [12514.410063] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [12514.420196] Lustre: Skipped 1 previous similar message [12516.425290] Lustre: Mounted lustre-client - version 2.17.58_40_gc6c2479 [12516.428685] Lustre: Skipped 1 previous similar message [12521.125366] Lustre: Unmounted lustre-client [12523.105157] Lustre: Unmounted lustre-client [12525.754416] Lustre: Unmounted lustre-client [12530.241721] Lustre: Unmounted lustre-client [12530.247047] Lustre: Skipped 1 previous similar message [12542.469313] Lustre: Unmounted lustre-client [12583.333607] Key type lgssc unregistered [12583.677193] LNet: 220474:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12583.687125] LNetError: 220474:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12583.737172] LNet: Removed LNI 192.168.204.26@tcp [12584.972441] Key type .llcrypt unregistered [12584.974602] Key type ._llcrypt unregistered [12601.671709] Key type ._llcrypt registered [12601.675986] Key type .llcrypt registered [12603.833675] Lustre: DEBUG MARKER: == conf-sanity test 200a: check CPU partitions =========== 15:43:35 (1789501415) [12619.938401] Key type .llcrypt unregistered [12619.942427] Key type ._llcrypt unregistered [12621.007574] Key type ._llcrypt registered [12621.009865] Key type .llcrypt registered [12621.419210] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 4 [12621.441571] alg: No test for adler32 (adler32-zlib) [12622.635760] Lustre: Lustre: Build Version: 2.17.58_40_gc6c2479 [12622.981753] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [12624.711229] Key type lgssc registered [12626.229503] Lustre: Echo OBD driver; http://www.lustre.org/ [12652.800550] Key type lgssc unregistered [12653.051903] LNet: 222624:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12653.063812] LNetError: 222624:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12653.083327] LNet: Removed LNI 192.168.204.26@tcp [12653.839451] Key type .llcrypt unregistered [12653.844141] Key type ._llcrypt unregistered [12654.209993] Key type ._llcrypt registered [12654.212658] Key type .llcrypt registered [12655.659807] Lustre: DEBUG MARKER: == conf-sanity test 200b: set CPU pattern using core selection ========================================================== 15:44:28 (1789501468) [12668.994353] Key type .llcrypt unregistered [12668.999358] Key type ._llcrypt unregistered [12670.302923] Key type ._llcrypt registered [12670.306057] Key type .llcrypt registered [12670.795252] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12670.813275] alg: No test for adler32 (adler32-zlib) [12672.060119] Lustre: Lustre: Build Version: 2.17.58_40_gc6c2479 [12672.404939] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [12674.207499] Key type lgssc registered [12676.331580] Lustre: Echo OBD driver; http://www.lustre.org/ [12699.500994] Key type lgssc unregistered [12699.866381] LNet: 224169:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12699.881468] LNetError: 224169:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12699.906370] LNet: Removed LNI 192.168.204.26@tcp [12700.915746] Key type .llcrypt unregistered [12700.917972] Key type ._llcrypt unregistered [12710.680131] Key type ._llcrypt registered [12710.730033] Key type .llcrypt registered [12711.278514] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12711.301268] alg: No test for adler32 (adler32-zlib) [12712.573719] Lustre: Lustre: Build Version: 2.17.58_40_gc6c2479 [12712.880926] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [12714.583243] Key type lgssc registered [12716.080137] Lustre: Echo OBD driver; http://www.lustre.org/ [12744.622250] Key type lgssc unregistered [12744.879707] LNet: 225928:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12744.907451] LNetError: 225928:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12744.935423] LNet: Removed LNI 192.168.204.26@tcp [12745.607821] Key type .llcrypt unregistered [12745.611676] Key type ._llcrypt unregistered [12746.021198] Key type ._llcrypt registered [12746.027879] Key type .llcrypt registered [12747.488631] Lustre: DEBUG MARKER: == conf-sanity test 200c: set CPU pattern using NUMA node layout ========================================================== 15:45:59 (1789501559) [12759.373658] Key type .llcrypt unregistered [12759.378473] Key type ._llcrypt unregistered [12760.403886] Key type ._llcrypt registered [12760.409089] Key type .llcrypt registered [12760.736414] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12760.768137] alg: No test for adler32 (adler32-zlib) [12761.906872] Lustre: Lustre: Build Version: 2.17.58_40_gc6c2479 [12762.209444] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [12763.935321] Key type lgssc registered [12765.220497] Lustre: Echo OBD driver; http://www.lustre.org/ [12781.215822] Key type lgssc unregistered [12781.464222] LNet: 227466:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12781.473760] LNetError: 227466:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12781.491261] LNet: Removed LNI 192.168.204.26@tcp [12782.178574] Key type .llcrypt unregistered [12782.183130] Key type ._llcrypt unregistered [12789.354540] Key type ._llcrypt registered [12789.359097] Key type .llcrypt registered [12789.859749] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12789.891057] alg: No test for adler32 (adler32-zlib) [12791.076692] Lustre: Lustre: Build Version: 2.17.58_40_gc6c2479 [12791.415155] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [12793.103281] Key type lgssc registered [12794.250642] Lustre: Echo OBD driver; http://www.lustre.org/ [12809.794432] Key type lgssc unregistered [12810.097530] LNet: 228768:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12810.106061] LNetError: 228768:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12810.127492] LNet: Removed LNI 192.168.204.26@tcp [12811.118549] Key type .llcrypt unregistered [12811.123053] Key type ._llcrypt unregistered [12820.943258] Key type ._llcrypt registered [12820.955852] Key type .llcrypt registered [12821.819368] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12821.831724] alg: No test for adler32 (adler32-zlib) [12823.057857] Lustre: Lustre: Build Version: 2.17.58_40_gc6c2479 [12823.350388] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [12825.127383] Key type lgssc registered [12826.618841] Lustre: Echo OBD driver; http://www.lustre.org/ [12845.173449] Key type lgssc unregistered [12845.510577] LNet: 230068:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12845.519452] LNetError: 230068:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12845.534416] LNet: Removed LNI 192.168.204.26@tcp [12846.241749] Key type .llcrypt unregistered [12846.245950] Key type ._llcrypt unregistered [12854.257307] Key type ._llcrypt registered [12854.266813] Key type .llcrypt registered [12854.541148] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12854.568774] alg: No test for adler32 (adler32-zlib) [12855.707646] Lustre: Lustre: Build Version: 2.17.58_40_gc6c2479 [12855.931805] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [12857.591230] Key type lgssc registered [12858.992343] Lustre: Echo OBD driver; http://www.lustre.org/ [12883.626877] Key type lgssc unregistered [12883.916848] LNet: 231830:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12883.926786] LNetError: 231830:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12883.941622] LNet: Removed LNI 192.168.204.26@tcp [12884.682755] Key type .llcrypt unregistered [12884.685202] Key type ._llcrypt unregistered [12885.097953] Key type ._llcrypt registered [12885.099968] Key type .llcrypt registered [12886.648235] Lustre: DEBUG MARKER: == conf-sanity test 200d: set CPU pattern to exclude only CPU 1 ========================================================== 15:48:18 (1789501698) [12899.496369] Key type .llcrypt unregistered [12899.507959] Key type ._llcrypt unregistered [12900.389094] Key type ._llcrypt registered [12900.392915] Key type .llcrypt registered [12900.876342] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12900.904557] alg: No test for adler32 (adler32-zlib) [12902.029872] Lustre: Lustre: Build Version: 2.17.58_40_gc6c2479 [12902.381613] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [12904.097995] Key type lgssc registered [12905.692041] Lustre: Echo OBD driver; http://www.lustre.org/ [12924.759399] Key type lgssc unregistered [12925.046412] LNet: 233372:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12925.056046] LNetError: 233372:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12925.084805] LNet: Removed LNI 192.168.204.26@tcp [12925.855763] Key type .llcrypt unregistered [12925.861101] Key type ._llcrypt unregistered [12933.265346] Key type ._llcrypt registered [12933.267707] Key type .llcrypt registered [12933.806880] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12933.856991] alg: No test for adler32 (adler32-zlib) [12935.016380] Lustre: Lustre: Build Version: 2.17.58_40_gc6c2479 [12935.237805] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [12936.911301] Key type lgssc registered [12938.304991] Lustre: Echo OBD driver; http://www.lustre.org/ [12954.461871] Key type lgssc unregistered [12954.711644] LNet: 234681:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12954.718258] LNetError: 234681:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12954.731795] LNet: Removed LNI 192.168.204.26@tcp [12955.364739] Key type .llcrypt unregistered [12955.368465] Key type ._llcrypt unregistered [12962.162374] Key type ._llcrypt registered [12962.170842] Key type .llcrypt registered [12962.935972] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12962.946536] alg: No test for adler32 (adler32-zlib) [12964.086233] Lustre: Lustre: Build Version: 2.17.58_40_gc6c2479 [12964.395574] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [12966.152334] Key type lgssc registered [12967.297947] Lustre: Echo OBD driver; http://www.lustre.org/ [12984.637942] Key type lgssc unregistered [12984.881893] LNet: 235985:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12984.894980] LNetError: 235985:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12984.913777] LNet: Removed LNI 192.168.204.26@tcp [12985.975913] Key type .llcrypt unregistered [12985.981206] Key type ._llcrypt unregistered [12996.095107] Key type ._llcrypt registered [12996.104190] Key type .llcrypt registered [12996.594996] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12996.613439] alg: No test for adler32 (adler32-zlib) [12997.798917] Lustre: Lustre: Build Version: 2.17.58_40_gc6c2479 [12998.063595] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [12999.759314] Key type lgssc registered [13001.182531] Lustre: Echo OBD driver; http://www.lustre.org/ [13022.326191] Key type lgssc unregistered [13022.667638] LNet: 237292:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13022.675579] LNetError: 237292:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13022.695647] LNet: Removed LNI 192.168.204.26@tcp [13023.539509] Key type .llcrypt unregistered [13023.544332] Key type ._llcrypt unregistered [13034.160849] Key type ._llcrypt registered [13034.172095] Key type .llcrypt registered [13034.540605] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13034.569035] alg: No test for adler32 (adler32-zlib) [13035.972877] Lustre: Lustre: Build Version: 2.17.58_40_gc6c2479 [13036.514482] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [13038.224886] Key type lgssc registered [13039.916148] Lustre: Echo OBD driver; http://www.lustre.org/ [13057.541961] Key type lgssc unregistered [13057.834290] LNet: 238599:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13057.840549] LNetError: 238599:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13057.865885] LNet: Removed LNI 192.168.204.26@tcp [13058.566768] Key type .llcrypt unregistered [13058.577475] Key type ._llcrypt unregistered [13065.310929] Key type ._llcrypt registered [13065.318054] Key type .llcrypt registered [13065.859930] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13065.877790] alg: No test for adler32 (adler32-zlib) [13067.030557] Lustre: Lustre: Build Version: 2.17.58_40_gc6c2479 [13067.355985] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [13069.111323] Key type lgssc registered [13070.453059] Lustre: Echo OBD driver; http://www.lustre.org/ [13086.904428] Key type lgssc unregistered [13087.187600] LNet: 239901:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13087.198557] LNetError: 239901:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13087.215389] LNet: Removed LNI 192.168.204.26@tcp [13087.858950] Key type .llcrypt unregistered [13087.866610] Key type ._llcrypt unregistered [13116.578033] Key type ._llcrypt registered [13116.580179] Key type .llcrypt registered [13117.859542] Lustre: DEBUG MARKER: == conf-sanity test 200e: set CPU pattern using relative core exclusion ========================================================== 15:52:10 (1789501930) [13132.371326] Key type .llcrypt unregistered [13132.374830] Key type ._llcrypt unregistered [13133.202056] Key type ._llcrypt registered [13133.208899] Key type .llcrypt registered [13133.807248] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13133.818782] alg: No test for adler32 (adler32-zlib) [13134.966263] Lustre: Lustre: Build Version: 2.17.58_40_gc6c2479 [13135.183295] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [13136.879504] Key type lgssc registered [13137.915394] Lustre: Echo OBD driver; http://www.lustre.org/ [13152.154526] Key type lgssc unregistered [13152.465711] LNet: 242208:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13152.476209] LNetError: 242208:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13152.505102] LNet: Removed LNI 192.168.204.26@tcp [13153.229973] Key type .llcrypt unregistered [13153.234562] Key type ._llcrypt unregistered [13161.166929] Key type ._llcrypt registered [13161.170144] Key type .llcrypt registered [13161.935063] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13161.957631] alg: No test for adler32 (adler32-zlib) [13163.242113] Lustre: Lustre: Build Version: 2.17.58_40_gc6c2479 [13163.562712] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [13165.327319] Key type lgssc registered [13166.627912] Lustre: Echo OBD driver; http://www.lustre.org/ [13184.549254] Key type lgssc unregistered [13184.848455] LNet: 243538:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13184.855835] LNetError: 243538:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13184.881801] LNet: Removed LNI 192.168.204.26@tcp [13185.523790] Key type .llcrypt unregistered [13185.529673] Key type ._llcrypt unregistered [13193.916396] Key type ._llcrypt registered [13193.936581] Key type .llcrypt registered [13194.438454] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13194.452793] alg: No test for adler32 (adler32-zlib) [13195.546724] Lustre: Lustre: Build Version: 2.17.58_40_gc6c2479 [13195.732446] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [13197.391383] Key type lgssc registered [13198.853531] Lustre: Echo OBD driver; http://www.lustre.org/ [13215.137940] Key type lgssc unregistered [13215.435522] LNet: 244844:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13215.443799] LNetError: 244844:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13215.468054] LNet: Removed LNI 192.168.204.26@tcp [13216.148920] Key type .llcrypt unregistered [13216.153483] Key type ._llcrypt unregistered [13222.929176] Key type ._llcrypt registered [13222.933327] Key type .llcrypt registered [13223.384778] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13223.404591] alg: No test for adler32 (adler32-zlib) [13224.514967] Lustre: Lustre: Build Version: 2.17.58_40_gc6c2479 [13224.788384] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [13226.471348] Key type lgssc registered [13227.734444] Lustre: Echo OBD driver; http://www.lustre.org/ [13250.574694] Key type lgssc unregistered [13250.842929] LNet: 246635:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13250.855305] LNetError: 246635:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13250.881707] LNet: Removed LNI 192.168.204.26@tcp [13251.572726] Key type .llcrypt unregistered [13251.577375] Key type ._llcrypt unregistered [13252.007786] Key type ._llcrypt registered [13252.012435] Key type .llcrypt registered [13253.241888] Lustre: DEBUG MARKER: == conf-sanity test 250: verify lcfg_erase removes filesystem parameters ========================================================== 15:54:25 (1789502065) [13271.872734] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13271.889090] alg: No test for adler32 (adler32-zlib) [13273.016844] Lustre: Lustre: Build Version: 2.17.58_40_gc6c2479 [13273.239039] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [13274.967273] Key type lgssc registered [13276.294529] Lustre: Echo OBD driver; http://www.lustre.org/ [13518.204228] Lustre: DEBUG MARKER: == conf-sanity test 802a: simulate readonly device ======= 15:58:50 (1789502330) [13520.827599] Lustre: DEBUG MARKER: SKIP: conf-sanity test_802a ZFS specific test [13590.055556] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 13260 sec ========= 16:00:02 (1789502402) [13591.978731] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 16:00:04 (1789502404) === [13595.604575] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 16:00:07 (1789502407) === [13629.560975] Key type lgssc unregistered [13629.878886] LNet: 255690:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13629.892457] LNetError: 255690:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13629.913075] LNet: Removed LNI 192.168.204.26@tcp [13630.544574] Key type .llcrypt unregistered [13630.549388] Key type ._llcrypt unregistered