[ 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 421886988 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.001009] APIC: Switch to symmetric I/O mode setup [ 0.002263] x2apic enabled [ 0.003006] Switched APIC routing to physical x2apic. [ 0.005008] kvm-guest: setup PV IPIs [ 0.007568] ..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.009014] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010010] pid_max: default: 32768 minimum: 301 [ 0.011124] LSM: Security Framework initializing [ 0.012049] Yama: becoming mindful. [ 0.013033] SELinux: Initializing. [ 0.014069] *** VALIDATE selinux *** [ 0.023023] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.028629] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.029143] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031064] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.033024] *** VALIDATE tmpfs *** [ 0.034264] *** VALIDATE proc *** [ 0.035353] *** VALIDATE cgroup *** [ 0.036012] *** VALIDATE cgroup2 *** [ 0.037281] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.038163] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.039010] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.040047] Spectre V2 : User space: Vulnerable [ 0.042010] Speculative Store Bypass: Vulnerable [ 0.044805] debug: unmapping init [mem 0xffffffff92a59000-0xffffffff92a60fff] [ 0.046194] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.047823] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.048024] ... version: 2 [ 0.049012] ... bit width: 48 [ 0.050016] ... generic registers: 4 [ 0.051013] ... value mask: 0000ffffffffffff [ 0.052016] ... max period: 00007fffffffffff [ 0.053014] ... fixed-purpose events: 3 [ 0.054012] ... event mask: 000000070000000f [ 0.055317] rcu: Hierarchical SRCU implementation. [ 0.057437] smp: Bringing up secondary CPUs ... [ 0.058579] x86: Booting SMP configuration: [ 0.059018] .... node #0, CPUs: #1 #2 #3 [ 0.062017] smp: Brought up 1 node, 4 CPUs [ 0.064011] smpboot: Max logical packages: 1 [ 0.065017] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.142979] node 0 deferred pages initialised in 76ms [ 0.145532] devtmpfs: initialized [ 0.147276] x86/mm: Memory block size: 128MB [ 0.150063] gcov: version magic: 0x41383552 [ 0.153219] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.154058] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.155211] pinctrl core: initialized pinctrl subsystem [ 0.156111] [ 0.156496] ************************************************************* [ 0.157008] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.158011] ** ** [ 0.159007] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.160007] ** ** [ 0.161007] ** This means that this kernel is built to expose internal ** [ 0.162006] ** IOMMU data structures, which may compromise security on ** [ 0.163008] ** your system. ** [ 0.164009] ** ** [ 0.165008] ** If you see this message and you are not debugging the ** [ 0.166006] ** kernel, report this immediately to your vendor! ** [ 0.167009] ** ** [ 0.168007] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.169008] ************************************************************* [ 0.170517] NET: Registered protocol family 16 [ 0.171368] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.174115] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.177068] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.181092] cpuidle: using governor menu [ 0.182502] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.183332] PCI: Using configuration type 1 for base access [ 0.185098] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.191164] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.192032] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.194185] cryptd: max_cpu_qlen set to 1000 [ 0.197223] ACPI: Added _OSI(Module Device) [ 0.199017] ACPI: Added _OSI(Processor Device) [ 0.201014] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.202012] ACPI: Added _OSI(Processor Aggregator Device) [ 0.207167] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.212349] ACPI: Interpreter enabled [ 0.214072] ACPI: PM: (supports S0 S3 S4 S5) [ 0.215012] ACPI: Using IOAPIC for interrupt routing [ 0.217102] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.220381] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.226000] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.227036] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.228014] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.230078] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.235411] acpiphp: Slot [2] registered [ 0.236099] acpiphp: Slot [5] registered [ 0.238129] acpiphp: Slot [6] registered [ 0.239071] acpiphp: Slot [3] registered [ 0.240060] acpiphp: Slot [4] registered [ 0.241091] acpiphp: Slot [7] registered [ 0.242017] acpiphp: Slot [8] registered [ 0.242991] acpiphp: Slot [9] registered [ 0.244074] acpiphp: Slot [10] registered [ 0.245069] acpiphp: Slot [11] registered [ 0.246063] acpiphp: Slot [12] registered [ 0.247039] acpiphp: Slot [13] registered [ 0.247993] acpiphp: Slot [14] registered [ 0.248068] acpiphp: Slot [15] registered [ 0.249048] acpiphp: Slot [16] registered [ 0.250078] acpiphp: Slot [17] registered [ 0.251034] acpiphp: Slot [18] registered [ 0.252007] acpiphp: Slot [19] registered [ 0.253025] acpiphp: Slot [20] registered [ 0.254010] acpiphp: Slot [21] registered [ 0.255072] acpiphp: Slot [22] registered [ 0.256080] acpiphp: Slot [23] registered [ 0.257057] acpiphp: Slot [24] registered [ 0.258020] acpiphp: Slot [25] registered [ 0.259035] acpiphp: Slot [26] registered [ 0.260087] acpiphp: Slot [27] registered [ 0.261083] acpiphp: Slot [28] registered [ 0.263177] acpiphp: Slot [29] registered [ 0.264096] acpiphp: Slot [30] registered [ 0.265088] acpiphp: Slot [31] registered [ 0.267093] PCI host bridge to bus 0000:00 [ 0.269020] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.271024] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.273075] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.276025] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.278021] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.281023] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.283181] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.286058] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.289437] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.297015] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.301065] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.304020] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.306021] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.308018] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.311301] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.313867] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.316052] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.319888] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.323969] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.331000] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.332000] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.335000] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.343018] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.346015] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.357017] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.365161] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.373017] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.380015] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.396018] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.406277] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.408363] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.410420] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.412491] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.415196] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.419090] iommu: Default domain type: Passthrough [ 0.421374] SCSI subsystem initialized [ 0.422300] ACPI: bus type USB registered [ 0.423058] usbcore: registered new interface driver usbfs [ 0.425048] usbcore: registered new interface driver hub [ 0.426061] usbcore: registered new device driver usb [ 0.427176] pps_core: LinuxPPS API ver. 1 registered [ 0.428006] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.430240] PTP clock support registered [ 0.431113] EDAC MC: Ver: 3.0.0 [ 0.433107] PCI: Using ACPI for IRQ routing [ 0.434555] NetLabel: Initializing [ 0.435008] NetLabel: domain hash size = 128 [ 0.436007] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.438121] NetLabel: unlabeled traffic allowed by default [ 0.439097] vgaarb: loaded [ 0.440263] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.442008] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.450115] clocksource: Switched to clocksource kvm-clock [ 0.557078] VFS: Disk quotas dquot_6.6.0 [ 0.559176] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.561129] *** VALIDATE ramfs *** [ 0.561801] *** VALIDATE hugetlbfs *** [ 0.562827] pnp: PnP ACPI init [ 0.564614] pnp: PnP ACPI: found 6 devices [ 0.590259] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.592608] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.594038] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.595896] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.597896] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.600079] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.602062] NET: Registered protocol family 2 [ 0.604134] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.608727] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.611624] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.615428] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.617655] TCP: Hash tables configured (established 65536 bind 65536) [ 0.619399] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.621523] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.623569] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.625696] NET: Registered protocol family 1 [ 0.627788] RPC: Registered named UNIX socket transport module. [ 0.629149] RPC: Registered udp transport module. [ 0.630745] RPC: Registered tcp transport module. [ 0.632193] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.634216] NET: Registered protocol family 44 [ 0.635784] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.637652] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.639610] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.641662] PCI: CLS 0 bytes, default 64 [ 0.642794] Unpacking initramfs... [ 2.023626] debug: unmapping init [mem 0xffff8c26fcc64000-0xffff8c26fffcffff] [ 2.027362] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.029517] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.032155] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.502214] Initialise system trusted keyrings [ 2.503731] Key type blacklist registered [ 2.505439] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.512836] zbud: loaded [ 2.515094] *** VALIDATE nfs *** [ 2.515768] *** VALIDATE nfs4 *** [ 2.516845] pstore: using deflate compression [ 2.519408] Platform Keyring initialized [ 2.592442] NET: Registered protocol family 38 [ 2.593708] Key type asymmetric registered [ 2.594635] Asymmetric key parser 'x509' registered [ 2.595711] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.597493] io scheduler mq-deadline registered [ 2.598462] io scheduler kyber registered [ 2.599442] io scheduler bfq registered [ 2.600501] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.602297] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.603855] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.605422] ACPI: Power Button [PWRF] [ 2.608597] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.612328] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.618722] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.643464] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.668754] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.672498] Non-volatile memory driver v1.3 [ 2.673562] Linux agpgart interface v0.103 [ 2.698588] virtio_blk virtio1: [vda] 145904 512-byte logical blocks (74.7 MB/71.2 MiB) [ 2.700472] vda: detected capacity change from 0 to 74702848 [ 2.712197] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.713770] vdb: detected capacity change from 0 to 1073741824 [ 2.718680] libphy: Fixed MDIO Bus: probed [ 2.722177] usbcore: registered new interface driver usbserial_generic [ 2.723692] usbserial: USB Serial support registered for generic [ 2.725090] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.728379] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.729508] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.730842] mousedev: PS/2 mouse device common for all mice [ 2.733193] rtc_cmos 00:05: RTC can wake from S4 [ 2.733558] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.736106] rtc_cmos 00:05: registered as rtc0 [ 2.739152] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.740137] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.741782] intel_pstate: CPU model not supported [ 2.745393] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.749776] hid: raw HID events driver (C) Jiri Kosina [ 2.751879] usbcore: registered new interface driver usbhid [ 2.753714] usbhid: USB HID core driver [ 2.755282] drop_monitor: Initializing network drop monitor service [ 2.757631] Initializing XFRM netlink socket [ 2.759599] NET: Registered protocol family 10 [ 2.762787] Segment Routing with IPv6 [ 2.764128] NET: Registered protocol family 17 [ 2.766189] mpls_gso: MPLS GSO support [ 2.771403] RAS: Correctable Errors collector initialized. [ 2.773216] AVX version of gcm_enc/dec engaged. [ 2.774337] AES CTR mode by8 optimization enabled [ 2.848836] sched_clock: Marking stable (2848757154, 0)->(3671321384, -822564230) [ 2.852669] registered taskstats version 1 [ 2.855035] Loading compiled-in X.509 certificates [ 2.857043] zswap: loaded using pool lzo/zbud [ 2.880487] Key type big_key registered [ 2.892824] Key type encrypted registered [ 2.894057] ima: No TPM chip found, activating TPM-bypass! [ 2.895443] ima: Allocated hash algorithm: sha1 [ 2.896706] ima: No architecture policies found [ 2.898531] evm: Initialising EVM extended attributes: [ 2.900207] evm: security.selinux [ 2.901441] evm: security.ima [ 2.902553] evm: security.capability [ 2.903804] evm: HMAC attrs: 0x1 [ 2.906124] rtc_cmos 00:05: setting system clock to 2026-08-15 02:16:19 UTC (1786760179) [ 2.912595] debug: unmapping init [mem 0xffffffff93a03000-0xffffffff93bfffff] [ 2.915813] debug: unmapping init [mem 0xffffffff92782000-0xffffffff92a58fff] [ 2.922460] Write protecting the kernel read-only data: 28672k [ 2.925684] debug: unmapping init [mem 0xffffffff90e03000-0xffffffff90ffffff] [ 2.928011] debug: unmapping init [mem 0xffffffff91714000-0xffffffff917fffff] [ 2.956789] 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) [ 2.962330] systemd[1]: Detected virtualization kvm. [ 2.964352] systemd[1]: Detected architecture x86-64. [ 2.966295] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 2.990577] systemd[1]: No hostname configured. [ 2.992311] systemd[1]: Set hostname to . [ 2.994525] random: systemd: uninitialized urandom read (16 bytes read) [ 2.997250] systemd[1]: Initializing machine ID from random generator. [ 3.129241] random: systemd: uninitialized urandom read (16 bytes read) [ 3.131290] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 3.134235] random: systemd: uninitialized urandom read (16 bytes read) [ 3.136395] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 3.141788] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Timers. [ OK ] Reached target Swap. [ OK ] Reached target Local File Systems. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Listening on Journal Socket. Starting Journal Service... [ OK ] Reached target Sockets. Starting Setup Virtual Console... Starting Create Volatile Files and Directories... Starting Apply Kernel Variables... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Initrd Root Device. [ OK ] Started Memstrack Anylazing Service. [ OK ] Started Setup Virtual Console. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 3.651219] device-mapper: uevent: version 1.0.3 [ 3.653566] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 4.268310] virtio_net virtio0 ens2: renamed from eth0 [ 4.360521] scsi host0: ata_piix [ 4.401996] scsi host1: ata_piix [ 4.403613] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.405746] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.683532] dracut-initqueue[581]: RTNETLINK answers: File exists [ 9.409622] random: crng init done [ 9.410876] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 9.673738] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Timers. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Swap. [ OK ] Stopped target Slices. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 10.692723] printk: systemd: 24 output lines suppressed due to ratelimiting [ 10.952910] SELinux: Disabled at runtime. [ 11.010931] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 11.020929] systemd[1]: Detected virtualization kvm. [ 11.022804] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 11.449208] systemd[1]: initrd-switch-root.service: Succeeded. [ 11.451559] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 11.456304] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 11.458542] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 11.460931] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 11.467253] systemd[1]: Starting Journal Service... Starting Journal Service... [ 11.472970] systemd[1]: Mounting POSIX Message Queue File System... Mounting POSIX Message Queue 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. Activating swap /dev/disk/by-label/SWAP... [ OK ] Reached target rpc_pipefs.target. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. Mounting Kernel Debug File System... Starting Remount Root and Kernel File Systems... [ 11.532518] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. Starting Apply Kernel Variables... [ OK ] Stopped target Initrd File Systems. Mounting Huge Pages File System... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Listening on Process Core Dump Socket. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Local Encrypted Volumes. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-getty.slice. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Started Journal Service. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Kernel Debug File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Huge Pages File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 11.898674] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 12.187108] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 12.219174] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 12.276986] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 12.286919] EDAC sbridge: Ver: 1.1.2 [ 13.181499] Key type dns_resolver registered [ 13.489991] NFS: Registering the id_resolver key type [ 13.492321] Key type id_resolver registered [ 13.493984] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Load/Save Random Seed... Starting Create Volatile Files and Directories... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started irqbalance daemon. [ OK ] Started dnf makecache --timer. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Login Service... [ OK ] Reached target sshd-keygen.target. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started OpenSSH server daemon. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg102-client login: [ 57.983570] libcfs: loading out-of-tree module taints kernel. [ 58.287204] Key type ._llcrypt registered [ 58.289152] Key type .llcrypt registered [ 58.813597] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 58.825336] alg: No test for adler32 (adler32-zlib) [ 60.203420] Lustre: Lustre: Build Version: 2.17.57_1_g689e325 [ 61.134941] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 63.019690] Key type lgssc registered [ 64.529789] Lustre: Echo OBD driver; http://www.lustre.org/ [ 216.116272] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [ 221.371805] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 235.172871] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing check_logdir /tmp/testlogs/ [ 240.310473] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing yml_node [ 241.633171] Lustre: lustre-OST0000-osc-ffff8c2750a5e800: disconnect after 23s idle [ 243.959852] Lustre: DEBUG MARKER: Client: 2.17.57.1 [ 246.311439] Lustre: DEBUG MARKER: MDS: 2.17.57.1 [ 248.697180] Lustre: DEBUG MARKER: OSS: 2.17.57.1 [ 250.688552] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Fri Aug 14 22:20:25 EDT 2026 [ 268.241586] Lustre: DEBUG MARKER: excepting tests: 32newtarball [ 270.193714] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 273.139568] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 283.501185] Lustre: Unmounted lustre-client [ 384.835853] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 390.247575] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 415.036888] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 464.892850] Lustre: DEBUG MARKER: == conf-sanity test 121: failover MGS ==================== 22:23:59 (1786760639) [ 508.712749] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 513.818035] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 561.088111] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid 1475 0 [ 588.118016] hrtimer: interrupt took 15050354 ns [ 606.012659] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 611.750098] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 658.722758] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid 1475 0 [ 684.075511] Lustre: DEBUG MARKER: == conf-sanity test 122a: Check OST sequence update ====== 22:27:38 (1786760858) [ 802.058703] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 807.813135] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 832.627458] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 833.726952] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [ 855.651855] Lustre: Unmounted lustre-client [ 882.506665] Key type lgssc unregistered [ 882.869419] LNet: 21856:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 882.894527] LNetError: 21856:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 882.913331] LNet: Removed LNI 192.168.201.2@tcp [ 883.921161] Key type .llcrypt unregistered [ 883.924548] Key type ._llcrypt unregistered [ 907.516602] Key type ._llcrypt registered [ 907.526431] Key type .llcrypt registered [ 909.393321] Lustre: DEBUG MARKER: == conf-sanity test 123aa: llog_print works with FIDs and simple names ========================================================== 22:31:23 (1786761083) [ 912.005257] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 912.014995] alg: No test for adler32 (adler32-zlib) [ 913.038270] Lustre: Lustre: Build Version: 2.17.57_1_g689e325 [ 913.209584] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 914.986610] Key type lgssc registered [ 917.416782] Lustre: Echo OBD driver; http://www.lustre.org/ [ 977.393124] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 983.171232] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1003.758265] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1006.524566] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [ 1021.097872] Lustre: DEBUG MARKER: == conf-sanity test 123ab: llog_print params output values from set_param -P ========================================================== 22:33:16 (1786761196) [ 1031.676891] Lustre: DEBUG MARKER: == conf-sanity test 123ac: llog_print with --start and --end ========================================================== 22:33:26 (1786761206) [ 1040.511885] Lustre: DEBUG MARKER: == conf-sanity test 123ad: llog_print shows all records == 22:33:35 (1786761215) [ 1052.148719] Lustre: DEBUG MARKER: == conf-sanity test 123ae: llog_cancel can cancel requested record ========================================================== 22:33:47 (1786761227) [ 1077.035876] Lustre: DEBUG MARKER: == conf-sanity test 123af: llog_catlist can show all config files correctly ========================================================== 22:34:12 (1786761252) [ 1091.275221] Lustre: DEBUG MARKER: == conf-sanity test 123ag: llog_print skips values deleted by set_param -P -d ========================================================== 22:34:26 (1786761266) [ 1110.103611] Lustre: DEBUG MARKER: == conf-sanity test 123ah: del_ost cancels config log entries correctly ========================================================== 22:34:44 (1786761284) [ 1124.809294] Lustre: Unmounted lustre-client [ 1125.352712] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [ 1127.352170] Lustre: Unmounted lustre-client [ 1160.054415] Key type lgssc unregistered [ 1160.405759] LNet: 30239:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1160.423474] LNetError: 30239:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1160.448375] LNet: Removed LNI 192.168.201.2@tcp [ 1161.381149] Key type .llcrypt unregistered [ 1161.382987] Key type ._llcrypt unregistered [ 1197.303550] Key type ._llcrypt registered [ 1197.306353] Key type .llcrypt registered [ 1197.799072] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1197.805272] alg: No test for adler32 (adler32-zlib) [ 1198.990858] Lustre: Lustre: Build Version: 2.17.57_1_g689e325 [ 1199.179739] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 1200.864245] Key type lgssc registered [ 1202.346479] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1289.604773] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1296.020906] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1320.081964] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1372.627594] Lustre: DEBUG MARKER: == conf-sanity test 123ai: llog_print display all non skipped records ========================================================== 22:39:08 (1786761548) [ 1408.834187] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1414.739702] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1437.644313] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1440.418157] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [ 1576.403708] Lustre: DEBUG MARKER: == conf-sanity test 123aj: check permanent TBF rules ===== 22:42:31 (1786761751) [ 1618.462612] Lustre: DEBUG MARKER: == conf-sanity test 123F: clear and reset all parameters using set_param -F ========================================================== 22:43:13 (1786761793) [ 1632.758991] Lustre: Unmounted lustre-client [ 1720.022898] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1726.611978] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1750.043893] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1750.771492] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [ 1757.042035] Lustre: Unmounted lustre-client [ 1786.087892] Key type lgssc unregistered [ 1786.396465] LNet: 41574:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1786.416138] LNetError: 41574:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1787.495591] LNet: Removed LNI 192.168.201.2@tcp [ 1788.366337] Key type .llcrypt unregistered [ 1788.368982] Key type ._llcrypt unregistered [ 1805.401944] Key type ._llcrypt registered [ 1805.404174] Key type .llcrypt registered [ 1806.679253] Lustre: DEBUG MARKER: == conf-sanity test 123G: clear and reset all parameters using apply_yaml ========================================================== 22:46:21 (1786761981) [ 1809.166952] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1809.187752] alg: No test for adler32 (adler32-zlib) [ 1810.405919] Lustre: Lustre: Build Version: 2.17.57_1_g689e325 [ 1810.760558] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 1812.480357] Key type lgssc registered [ 1813.759398] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1867.113497] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1873.199525] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1896.293819] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1899.327899] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [ 1914.720596] Lustre: Unmounted lustre-client [ 2001.990932] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2008.964146] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2032.550661] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2033.372888] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [ 2040.034405] Lustre: Unmounted lustre-client [ 2069.442289] Key type lgssc unregistered [ 2069.668420] LNet: 47120:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2069.674799] LNetError: 47120:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2069.701657] LNet: Removed LNI 192.168.201.2@tcp [ 2070.582843] Key type .llcrypt unregistered [ 2070.586820] Key type ._llcrypt unregistered [ 2089.492387] Key type ._llcrypt registered [ 2089.495123] Key type .llcrypt registered [ 2091.383580] Lustre: DEBUG MARKER: == conf-sanity test 123H: check concurent accesses with 'lctl llog_print ========================================================== 22:51:05 (1786762265) [ 2095.354414] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2095.419418] alg: No test for adler32 (adler32-zlib) [ 2096.781259] Lustre: Lustre: Build Version: 2.17.57_1_g689e325 [ 2097.287805] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 2098.984274] Key type lgssc registered [ 2100.431459] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2154.917894] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2160.456616] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2186.304740] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2189.104183] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [ 2307.384230] Lustre: DEBUG MARKER: == conf-sanity test 124: check failover after replace_nids ========================================================== 22:54:42 (1786762482) [ 2309.172526] Lustre: DEBUG MARKER: SKIP: conf-sanity test_124 needs MDT failover setup [ 2311.248941] Lustre: DEBUG MARKER: == conf-sanity test 126: mount in parallel shouldn't cause a crash ========================================================== 22:54:46 (1786762486) [ 2313.938047] Lustre: Unmounted lustre-client [ 2342.934987] Key type lgssc unregistered [ 2343.189774] LNet: 52473:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2343.198530] LNetError: 52473:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2343.220285] LNet: Removed LNI 192.168.201.2@tcp [ 2343.975864] Key type .llcrypt unregistered [ 2343.978088] Key type ._llcrypt unregistered [ 2377.419739] Key type ._llcrypt registered [ 2377.425961] Key type .llcrypt registered [ 2378.319778] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2378.369542] alg: No test for adler32 (adler32-zlib) [ 2379.616374] Lustre: Lustre: Build Version: 2.17.57_1_g689e325 [ 2380.053783] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 2381.738868] Key type lgssc registered [ 2383.677159] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2423.474538] Lustre: DEBUG MARKER: == conf-sanity test 127: direct io overwrite on full ost ========================================================== 22:56:38 (1786762598) [ 2439.435805] Key type lgssc unregistered [ 2439.803802] LNet: 54896:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2439.815611] LNetError: 54896:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2439.854499] LNet: Removed LNI 192.168.201.2@tcp [ 2440.727861] Key type .llcrypt unregistered [ 2440.731775] Key type ._llcrypt unregistered [ 2453.547442] Key type ._llcrypt registered [ 2453.562136] Key type .llcrypt registered [ 2454.470236] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2454.504513] alg: No test for adler32 (adler32-zlib) [ 2455.451929] Lustre: Lustre: Build Version: 2.17.57_1_g689e325 [ 2455.641500] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 2457.273308] Key type lgssc registered [ 2458.987059] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2520.317376] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2527.000341] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2551.286392] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2553.103110] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [ 2602.222676] Lustre: DEBUG MARKER: == conf-sanity test 128: Force using remote logs with --nolocallogs ========================================================== 22:59:37 (1786762777) [ 2603.765966] Lustre: DEBUG MARKER: SKIP: conf-sanity test_128 need separate mgs device [ 2605.310253] Lustre: DEBUG MARKER: == conf-sanity test 129: attempt to connect an OST with the same index should fail ========================================================== 22:59:40 (1786762780) [ 2607.370953] Lustre: Unmounted lustre-client [ 2674.795435] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2681.048275] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2755.477615] Lustre: DEBUG MARKER: == conf-sanity test 130: re-register an MDT after writeconf ========================================================== 23:02:10 (1786762930) [ 2842.580853] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [ 2848.290326] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 2863.081939] Lustre: lustre-MDT0001-mdc-ffff8c274540e000: Connection to lustre-MDT0001 (at 192.168.201.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2868.192595] Lustre: lustre-OST0000-osc-ffff8c274540e000: disconnect after 23s idle [ 2878.237200] LustreError: 63588:0:(obd_config.c:2103:class_config_llog_handler()) MGC192.168.201.102@tcp: cfg command failed: rc = -17 [ 2878.247411] Lustre: cmd=cf001 0:lustre-MDT0001-mdc 1:mdc 2:lustre-clilmv_UUID [ 2878.247411] [ 2878.253127] LustreError: 62857:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [ 2892.832423] Lustre: DEBUG MARKER: == conf-sanity test 131: MDT backup restore with project ID and inheritance flag ========================================================== 23:04:28 (1786763068) [ 2948.371283] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3039.250329] Lustre: Unmounted lustre-client [ 3201.281195] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [ 3205.745331] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3253.572857] Lustre: DEBUG MARKER: == conf-sanity test 132: hsm_actions processed after failover ========================================================== 23:10:28 (1786763428) [ 3255.379490] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3256.103880] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 3256.111313] Lustre: Skipped 2 previous similar messages [ 3277.867945] Lustre: Unmounted lustre-client [ 3414.195658] Lustre: DEBUG MARKER: == conf-sanity test 133: stripe QOS: free space balance in a pool ========================================================== 23:13:09 (1786763589) [ 3415.813226] Lustre: DEBUG MARKER: SKIP: conf-sanity test_133 needs >= 4 OSTs [ 3417.326700] Lustre: DEBUG MARKER: == conf-sanity test 134: check_iam works without faults == 23:13:12 (1786763592) [ 3496.702831] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [ 3501.370990] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 4093.496578] Lustre: DEBUG MARKER: == conf-sanity test 135: check the behavior when changelog is wrapped around ========================================================== 23:24:28 (1786764268) [ 4095.508975] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4105.849634] Lustre: Unmounted lustre-client [ 4240.031312] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4244.516723] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4265.072358] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4265.686628] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [ 5852.839093] Lustre: 95090:0:(llog_cat.c:310:llog_cat_process_or_fork()) lustre-MDT0000-mdc-ffff8c2745d10000: catlog [0xa:0x5:0x0] crosses index zero [ 5880.713612] Lustre: Unmounted lustre-client [ 5906.630687] Key type lgssc unregistered [ 5906.915311] LNet: 103239:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5906.931903] LNetError: 103239:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5907.944961] LNet: Removed LNI 192.168.201.2@tcp [ 5908.672253] Key type .llcrypt unregistered [ 5908.673843] Key type ._llcrypt unregistered [ 5919.612367] Key type ._llcrypt registered [ 5919.613942] Key type .llcrypt registered [ 5921.042875] Lustre: DEBUG MARKER: == conf-sanity test 136: don't panic with bad obdecho setup ========================================================== 23:54:56 (1786766096) [ 5937.622264] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5937.729155] alg: No test for adler32 (adler32-zlib) [ 5938.827524] Lustre: Lustre: Build Version: 2.17.57_1_g689e325 [ 5939.009416] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 5940.720229] Key type lgssc registered [ 5942.087957] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6024.521705] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6029.429916] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6053.605983] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6055.395222] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [ 6070.686307] Lustre: Unmounted lustre-client [ 6193.072288] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6199.911922] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6224.829396] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6260.853536] Lustre: DEBUG MARKER: == conf-sanity test 137: a new MDT should inherit pools, parameters and failnode ========================================================== 00:00:35 (1786766435) [ 6262.720168] Lustre: DEBUG MARKER: SKIP: conf-sanity test_137 needs >= 3 MDTs [ 6264.796425] Lustre: DEBUG MARKER: == conf-sanity test 140: remove_updatelog script actions ========================================================== 00:00:39 (1786766439) [ 6303.630153] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6309.109280] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6332.060546] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6332.786626] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [ 6348.292543] Lustre: lustre-MDT0000-mdc-ffff8c2766a58000: Connection to lustre-MDT0000 (at 192.168.201.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6353.383841] Lustre: lustre-MDT0001-mdc-ffff8c2766a58000: Connection to lustre-MDT0001 (at 192.168.201.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6363.616118] Lustre: 103997:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786766524/real 1786766524] req@00000000791dcdd8 x1873560065878656/t0(0) o400->MGC192.168.201.102@tcp@192.168.201.102@tcp:26/25 lens 224/224 e 0 to 1 dl 1786766540 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6363.639305] LustreError: MGC192.168.201.102@tcp: Connection to MGS (at 192.168.201.102@tcp) was lost; in progress operations using this service will fail [ 6499.322773] LustreError: lustre-MDT0000-mdc-ffff8c2766a58000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 6499.348705] Lustre: lustre-MDT0000-mdc-ffff8c2766a58000: Connection restored to 192.168.201.102@tcp (at 192.168.201.102@tcp) [ 6504.439859] LustreError: lustre-MDT0001-mdc-ffff8c2766a58000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 6504.487837] Lustre: lustre-MDT0001-mdc-ffff8c2766a58000: Connection restored to 192.168.201.102@tcp (at 192.168.201.102@tcp) [ 6508.353984] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6508.614785] Lustre: Evicted from MGS (at 192.168.201.102@tcp) after server handle changed from 0xca1b514994f8f473 to 0xca1b514994f90cc2 [ 6508.635532] Lustre: MGC192.168.201.102@tcp: Connection restored to 192.168.201.102@tcp (at 192.168.201.102@tcp) [ 6509.809676] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6516.120357] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6517.969672] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6531.881688] Lustre: Unmounted lustre-client [ 6661.369435] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6667.700967] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6693.006654] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6746.568812] Lustre: DEBUG MARKER: == conf-sanity test 150: test setting max_cached_mb to a % ========================================================== 00:08:41 (1786766921) [ 6785.228444] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6792.972260] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6817.006260] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6817.690470] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [ 6823.257093] LustreError: 120731: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 [ 6830.646755] Lustre: DEBUG MARKER: == conf-sanity test 151a: damaged local config doesn't prevent mounting ========================================================== 00:10:05 (1786767005) [ 6831.928798] Lustre: Unmounted lustre-client [ 6858.816424] Key type lgssc unregistered [ 6859.080361] LNet: 121740:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6859.086952] LNetError: 121740:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6859.108057] LNet: Removed LNI 192.168.201.2@tcp [ 6859.712231] Key type .llcrypt unregistered [ 6859.714116] Key type ._llcrypt unregistered [ 6875.467644] Key type ._llcrypt registered [ 6875.471502] Key type .llcrypt registered [ 6875.750412] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6875.764742] alg: No test for adler32 (adler32-zlib) [ 6876.915864] Lustre: Lustre: Build Version: 2.17.57_1_g689e325 [ 6877.229238] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 6878.896586] Key type lgssc registered [ 6880.772747] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6970.611581] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6978.331893] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7002.913621] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7127.505711] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7135.672547] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7162.669168] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7211.877642] Lustre: DEBUG MARKER: == conf-sanity test 151b: -ENOSPC doesn't affect mount === 00:16:26 (1786767386) [ 7222.004648] Key type lgssc unregistered [ 7222.306133] LNet: 128963:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7222.315606] LNetError: 128963:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7222.336629] LNet: Removed LNI 192.168.201.2@tcp [ 7222.984728] Key type .llcrypt unregistered [ 7222.986200] Key type ._llcrypt unregistered [ 7237.208185] Key type ._llcrypt registered [ 7237.215978] Key type .llcrypt registered [ 7237.781837] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7237.813114] alg: No test for adler32 (adler32-zlib) [ 7239.019739] Lustre: Lustre: Build Version: 2.17.57_1_g689e325 [ 7239.348512] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 7241.080269] Key type lgssc registered [ 7242.593777] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7300.226895] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7306.147132] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7333.806477] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7464.925386] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7470.988984] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7496.989606] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7541.472303] Lustre: DEBUG MARKER: == conf-sanity test 152: seq allocation error in OSP ===== 00:21:56 (1786767716) [ 7638.043611] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [ 7644.495972] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 7653.998612] Lustre: DEBUG MARKER: ADD OST3 [ 7663.589076] Lustre: lustre-OST0000-osc-ffff8c2750a5b000: disconnect after 23s idle [ 7667.588644] Lustre: DEBUG MARKER: STOP OST3 [ 7681.008524] Lustre: lustre-OST0003-osc-ffff8c2750a5b000: Connection to lustre-OST0003 (at 192.168.201.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7690.768901] Lustre: DEBUG MARKER: START OST3 again [ 7701.804840] Lustre: lustre-OST0003-osc-ffff8c2750a5b000: Connection restored to 192.168.201.102@tcp (at 192.168.201.102@tcp) [ 7733.369126] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7733.381500] LustreError: 139787:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff8c2750a5b000: inode [0x240000bd0:0x1:0x0] mdc close failed: rc = -108 [ 7743.778892] Lustre: Unmounted lustre-client [ 7868.109728] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7875.549614] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7901.147505] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7938.611393] Lustre: DEBUG MARKER: == conf-sanity test 153a: bypass invalid NIDs quickly ==== 00:28:33 (1786768113) [ 8039.474914] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8045.535251] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8072.102733] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8142.191218] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8147.994264] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8169.708155] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8172.839352] LNetError: 129375:0:(lib-move.c:2158:lnet_handle_find_routed_path()) peer 192.168.252.112@tcp2 has no available nets [ 8173.169422] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [ 8178.662424] Lustre: Unmounted lustre-client [ 8209.237299] Key type lgssc unregistered [ 8224.736272] LNet: 1 local NIs in recovery (showing 1): 192.168.201.2@tcp [ 8232.038454] LNet: 148779:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8232.050059] LNetError: 148779:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8303.792755] LNet: Removed LNI 192.168.201.2@tcp [ 8304.704385] Key type .llcrypt unregistered [ 8304.709442] Key type ._llcrypt unregistered [ 8323.773939] Key type ._llcrypt registered [ 8323.776786] Key type .llcrypt registered [ 8325.393764] Lustre: DEBUG MARKER: == conf-sanity test 153b: added IPv6 NID support ========= 00:35:00 (1786768500) [ 8345.197412] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8345.241772] alg: No test for adler32 (adler32-zlib) [ 8346.243461] Lustre: Lustre: Build Version: 2.17.57_1_g689e325 [ 8346.465770] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 8348.160201] Key type lgssc registered [ 8349.656041] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8446.455551] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8451.719431] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8476.376756] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8607.740388] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [ 8613.214832] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 8622.441687] Lustre: Unmounted lustre-client [ 8622.520194] LNetError: 149961:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::54f4:8dff:fe9e:cfa0/1023: rc = -22 [ 8622.528672] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 8622.827917] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [ 8622.973480] Lustre: Unmounted lustre-client [ 8623.914367] LNetError: 149962:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::54f4:8dff:fe9e:cfa0/1023: rc = -22 [ 8623.928602] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 8624.239341] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [ 8624.244921] Lustre: Skipped 1 previous similar message [ 8624.391587] Lustre: Unmounted lustre-client [ 8624.393081] Lustre: Skipped 1 previous similar message [ 8625.953658] LNetError: 149962:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::54f4:8dff:fe9e:cfa0/1023: rc = -22 [ 8625.959444] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 8626.277254] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [ 8626.280206] Lustre: Skipped 3 previous similar messages [ 8626.434688] Lustre: Unmounted lustre-client [ 8626.445171] Lustre: Skipped 3 previous similar messages [ 8627.075768] Lustre: 155897: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 [ 8627.612863] Lustre: 155926: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 [ 8634.978784] Lustre: DEBUG MARKER: == conf-sanity test 153c: don't stuck on unreached NID === 00:40:10 (1786768810) [ 8685.473128] LNet: 1 local NIs in recovery (showing 1): 192.168.201.2@tcp [ 8755.223946] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8761.666391] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8785.905294] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8866.675103] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8872.138831] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8895.729788] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8901.344668] LustreError: 161527:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.252.112@tcp: failed processing log, type 1: rc = -110 [ 8947.621933] LNet: 1 local NIs in recovery (showing 1): 192.168.201.2@tcp [ 9009.057045] LNet: 1 local NIs in recovery (showing 1): 192.168.201.2@tcp [ 9039.840959] 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 [ 9039.862687] Lustre: Unmounted lustre-client [ 9039.868926] Lustre: Skipped 3 previous similar messages [ 9039.877564] LustreError: 161527:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 9077.948735] Key type lgssc unregistered [ 9080.736436] LNet: 1 local NIs in recovery (showing 1): 192.168.201.2@tcp [ 9089.504812] LNet: 161993:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9089.509879] LNetError: 161993:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9160.192334] LNet: Removed LNI 192.168.201.2@tcp [ 9161.073345] Key type .llcrypt unregistered [ 9161.080620] Key type ._llcrypt unregistered [ 9181.224181] Key type ._llcrypt registered [ 9181.226683] Key type .llcrypt registered [ 9182.632934] Lustre: DEBUG MARKER: == conf-sanity test 154: expand .. on rename after MDT backup restore ========================================================== 00:49:17 (1786769357) [ 9205.349778] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9205.460095] alg: No test for adler32 (adler32-zlib) [ 9206.860617] Lustre: Lustre: Build Version: 2.17.57_1_g689e325 [ 9207.368677] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [ 9209.232231] Key type lgssc registered [ 9211.784828] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9363.231386] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [ 9368.501253] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9387.790191] Lustre: Unmounted lustre-client [ 9562.366798] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [ 9567.892681] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9576.862125] Lustre: Unmounted lustre-client [ 9630.835485] Lustre: DEBUG MARKER: == conf-sanity test 155: gap in seq allocation from ofd after restarting ========================================================== 00:56:45 (1786769805) [ 9723.203521] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9729.025589] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9753.278401] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9875.357889] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [ 9880.653715] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9893.634338] Lustre: Unmounted lustre-client [10005.120953] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [10010.205962] Lustre: DEBUG MARKER: Using TIMEOUT=20 [10027.397522] Lustre: DEBUG MARKER: == conf-sanity test 156: root_fid on export consistent with client mount ========================================================== 01:03:22 (1786770202) [10028.703221] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [10039.039838] Lustre: Unmounted lustre-client [10238.742176] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [10243.387558] Lustre: DEBUG MARKER: Using TIMEOUT=20 [10251.241094] Lustre: Unmounted lustre-client [10251.820879] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [10260.506931] Lustre: Unmounted lustre-client [10262.354188] Lustre: DEBUG MARKER: == conf-sanity test 157a: test allow_register for MDT registration and MGS restart ========================================================== 01:07:17 (1786770437) [10337.688596] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10344.154470] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10366.084520] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10367.134271] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [10464.237348] Lustre: lustre-MDT0000-mdc-ffff8c2745a0b000: Connection to lustre-MDT0000 (at 192.168.201.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [10464.265393] LustreError: lustre-OST0001-osc-ffff8c2745a0b000: operation ost_connect to node 192.168.201.102@tcp failed: rc = -19 [10479.602862] LustreError: MGC192.168.201.102@tcp: Connection to MGS (at 192.168.201.102@tcp) was lost; in progress operations using this service will fail [10479.627366] LustreError: lustre-MDT0000-mdc-ffff8c2745a0b000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [10479.638621] Lustre: Evicted from MGS (at 192.168.201.102@tcp) after server handle changed from 0xf081910ea1fb2f8f to 0xf081910ea1fb3259 [10479.661859] Lustre: MGC192.168.201.102@tcp: Connection restored to 192.168.201.102@tcp (at 192.168.201.102@tcp) [10479.678237] Lustre: Skipped 1 previous similar message [10533.453743] Lustre: Unmounted lustre-client [10572.960790] Key type lgssc unregistered [10573.196382] LNet: 189545:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10573.223399] LNetError: 189545:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10573.249411] LNet: Removed LNI 192.168.201.2@tcp [10573.836138] Key type .llcrypt unregistered [10573.839485] Key type ._llcrypt unregistered [10589.381556] Key type ._llcrypt registered [10589.386478] Key type .llcrypt registered [10590.852747] Lustre: DEBUG MARKER: == conf-sanity test 157b: verify allow_register (block new OSTs, allow existing) ========================================================== 01:12:45 (1786770765) [10594.912474] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10594.948029] alg: No test for adler32 (adler32-zlib) [10596.376057] Lustre: Lustre: Build Version: 2.17.57_1_g689e325 [10596.933253] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [10598.779433] Key type lgssc registered [10600.335910] Lustre: Echo OBD driver; http://www.lustre.org/ [10659.812962] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10667.768915] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10689.877525] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10700.889529] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [10798.065687] LustreError: lustre-OST0001-osc-ffff8c2758be6800: operation ost_connect to node 192.168.201.102@tcp failed: rc = -19 [10937.992173] Lustre: Unmounted lustre-client [10967.378609] Key type lgssc unregistered [10967.632271] LNet: 195582:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10967.640325] LNetError: 195582:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10967.683063] LNet: Removed LNI 192.168.201.2@tcp [10968.431301] Key type .llcrypt unregistered [10968.432916] Key type ._llcrypt unregistered [10979.334437] Key type ._llcrypt registered [10979.339625] Key type .llcrypt registered [10980.672827] Lustre: DEBUG MARKER: == conf-sanity test 160: MGC updates failnodes from all participants ========================================================== 01:19:15 (1786771155) [11012.872343] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11012.890235] alg: No test for adler32 (adler32-zlib) [11014.157869] Lustre: Lustre: Build Version: 2.17.57_1_g689e325 [11014.451447] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [11016.152196] Key type lgssc registered [11017.556463] Lustre: Echo OBD driver; http://www.lustre.org/ [11104.954143] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11110.905069] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11139.749635] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11181.423664] Key type lgssc unregistered [11181.750263] LNet: 200568:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11181.759298] LNetError: 200568:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11181.777487] LNet: Removed LNI 192.168.201.2@tcp [11182.558557] Key type .llcrypt unregistered [11182.560205] Key type ._llcrypt unregistered [11294.266504] Key type ._llcrypt registered [11294.268822] Key type .llcrypt registered [11294.792561] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11294.803211] alg: No test for adler32 (adler32-zlib) [11295.941841] Lustre: Lustre: Build Version: 2.17.57_1_g689e325 [11296.116197] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [11297.776219] Key type lgssc registered [11299.124457] Lustre: Echo OBD driver; http://www.lustre.org/ [11346.878936] Lustre: DEBUG MARKER: == conf-sanity test 161: test '-o mgsname' option ======== 01:25:21 (1786771521) [11384.160852] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11391.764667] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11418.284670] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11420.120607] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [11422.426545] Lustre: Unmounted lustre-client [11422.834902] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [11423.022397] Lustre: Unmounted lustre-client [11431.036228] Lustre: Unmounted lustre-client [11462.054226] Key type lgssc unregistered [11462.414054] LNet: 205553:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11462.422667] LNetError: 205553:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11462.439700] LNet: Removed LNI 192.168.201.2@tcp [11463.556124] Key type .llcrypt unregistered [11463.562973] Key type ._llcrypt unregistered [11477.447466] Key type ._llcrypt registered [11477.449315] Key type .llcrypt registered [11479.017203] Lustre: DEBUG MARKER: == conf-sanity test 162: replace nids with -o noclient === 01:27:33 (1786771653) [11482.389897] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11482.418909] alg: No test for adler32 (adler32-zlib) [11483.530144] Lustre: Lustre: Build Version: 2.17.57_1_g689e325 [11483.711856] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [11485.386314] Key type lgssc registered [11486.525470] Lustre: Echo OBD driver; http://www.lustre.org/ [11541.810254] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11548.579613] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11571.327033] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11574.834748] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [11582.688316] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11584.530993] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [11590.129304] Lustre: lustre-MDT0000-mdc-ffff8c2745d17000: Connection to lustre-MDT0000 (at 192.168.201.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [11600.364509] Lustre: lustre-MDT0001-mdc-ffff8c2745d17000: Connection to lustre-MDT0001 (at 192.168.201.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [11610.594460] Lustre: 206226:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786771771/real 1786771771] req@000000000f695ba0 x1873565880161536/t0(0) o400->MGC192.168.201.102@tcp@192.168.201.102@tcp:26/25 lens 224/224 e 0 to 1 dl 1786771787 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [11610.641750] LustreError: MGC192.168.201.102@tcp: Connection to MGS (at 192.168.201.102@tcp) was lost; in progress operations using this service will fail [11645.428218] Lustre: Evicted from MGS (at 192.168.201.102@tcp) after server handle changed from 0x835c8defa63878fb to 0x835c8defa6387a8a [11645.443140] Lustre: MGC192.168.201.102@tcp: Connection restored to 192.168.201.102@tcp (at 192.168.201.102@tcp) [11682.265313] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11685.221933] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in IDLE state after 0 sec [11686.409699] LustreError: lustre-MDT0000-mdc-ffff8c2745d17000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [11686.444801] Lustre: lustre-MDT0000-mdc-ffff8c2745d17000: Connection restored to 192.168.201.102@tcp (at 192.168.201.102@tcp) [11694.350477] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11696.752081] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [11701.558927] Lustre: Unmounted lustre-client [11728.216403] Key type lgssc unregistered [11728.446661] LNet: 211101:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11728.453132] LNetError: 211101:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11728.467397] LNet: Removed LNI 192.168.201.2@tcp [11728.950465] Key type .llcrypt unregistered [11728.952715] Key type ._llcrypt unregistered [11758.647961] Key type ._llcrypt registered [11758.665044] Key type .llcrypt registered [11758.931326] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11758.953980] alg: No test for adler32 (adler32-zlib) [11760.183626] Lustre: Lustre: Build Version: 2.17.57_1_g689e325 [11760.481642] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [11762.176326] Key type lgssc registered [11763.151750] Lustre: Echo OBD driver; http://www.lustre.org/ [11843.384447] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11848.337430] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11874.225508] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11927.258627] Lustre: DEBUG MARKER: == conf-sanity test 164: test expected_clients parameter and max client tracking ========================================================== 01:35:02 (1786772102) [12015.715514] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12020.639877] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12047.752785] Lustre: DEBUG MARKER: oleg102-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12049.619948] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [12054.938425] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [12056.251793] Lustre: Mounted lustre-client - version 2.17.57_1_g689e325 [12056.254773] Lustre: Skipped 1 previous similar message [12061.689311] Lustre: Unmounted lustre-client [12062.921209] Lustre: Unmounted lustre-client [12065.533778] Lustre: Unmounted lustre-client [12067.944147] Lustre: Unmounted lustre-client [12081.328246] Lustre: Unmounted lustre-client [12081.338722] Lustre: Skipped 1 previous similar message [12117.573432] Key type lgssc unregistered [12117.854835] LNet: 220277:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12117.858351] LNetError: 220277:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12117.870577] LNet: Removed LNI 192.168.201.2@tcp [12118.604342] Key type .llcrypt unregistered [12118.607651] Key type ._llcrypt unregistered [12130.475973] Key type ._llcrypt registered [12130.479237] Key type .llcrypt registered [12131.811540] Lustre: DEBUG MARKER: == conf-sanity test 200a: check CPU partitions =========== 01:38:26 (1786772306) [12147.122391] Key type .llcrypt unregistered [12147.139482] Key type ._llcrypt unregistered [12148.617585] Key type ._llcrypt registered [12148.627589] Key type .llcrypt registered [12149.405666] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 4 [12149.426797] alg: No test for adler32 (adler32-zlib) [12150.746697] Lustre: Lustre: Build Version: 2.17.57_1_g689e325 [12151.050977] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [12152.824734] Key type lgssc registered [12154.804899] Lustre: Echo OBD driver; http://www.lustre.org/ [12180.719210] Key type lgssc unregistered [12181.010679] LNet: 222443:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12181.018471] LNetError: 222443:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12181.038313] LNet: Removed LNI 192.168.201.2@tcp [12182.014168] Key type .llcrypt unregistered [12182.017927] Key type ._llcrypt unregistered [12182.431338] Key type ._llcrypt registered [12182.438576] Key type .llcrypt registered [12183.706208] Lustre: DEBUG MARKER: == conf-sanity test 200b: set CPU pattern using core selection ========================================================== 01:39:18 (1786772358) [12196.407626] Key type .llcrypt unregistered [12196.409794] Key type ._llcrypt unregistered [12197.176913] Key type ._llcrypt registered [12197.179646] Key type .llcrypt registered [12197.910818] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12197.935087] alg: No test for adler32 (adler32-zlib) [12198.931955] Lustre: Lustre: Build Version: 2.17.57_1_g689e325 [12199.071325] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [12200.760517] Key type lgssc registered [12202.039478] Lustre: Echo OBD driver; http://www.lustre.org/ [12221.784199] Key type lgssc unregistered [12222.180607] LNet: 224004:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12222.196268] LNetError: 224004:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12222.214046] LNet: Removed LNI 192.168.201.2@tcp [12223.264185] Key type .llcrypt unregistered [12223.266606] Key type ._llcrypt unregistered [12233.300619] Key type ._llcrypt registered [12233.304705] Key type .llcrypt registered [12233.869813] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12233.898015] alg: No test for adler32 (adler32-zlib) [12234.971591] Lustre: Lustre: Build Version: 2.17.57_1_g689e325 [12235.455874] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [12237.440446] Key type lgssc registered [12239.659888] Lustre: Echo OBD driver; http://www.lustre.org/ [12271.001151] Key type lgssc unregistered [12271.377897] LNet: 225772:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12271.392717] LNetError: 225772:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12271.416968] LNet: Removed LNI 192.168.201.2@tcp [12272.524150] Key type .llcrypt unregistered [12272.535911] Key type ._llcrypt unregistered [12272.904156] Key type ._llcrypt registered [12272.909290] Key type .llcrypt registered [12275.004407] Lustre: DEBUG MARKER: == conf-sanity test 200c: set CPU pattern using NUMA node layout ========================================================== 01:40:49 (1786772449) [12291.170925] Key type .llcrypt unregistered [12291.173891] Key type ._llcrypt unregistered [12292.379298] Key type ._llcrypt registered [12292.382387] Key type .llcrypt registered [12292.850185] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12292.866249] alg: No test for adler32 (adler32-zlib) [12293.926703] Lustre: Lustre: Build Version: 2.17.57_1_g689e325 [12294.161791] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [12295.840671] Key type lgssc registered [12296.803458] Lustre: Echo OBD driver; http://www.lustre.org/ [12314.100235] Key type lgssc unregistered [12314.487168] LNet: 227328:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12314.496439] LNetError: 227328:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12314.529744] LNet: Removed LNI 192.168.201.2@tcp [12315.492658] Key type .llcrypt unregistered [12315.500051] Key type ._llcrypt unregistered [12324.220472] Key type ._llcrypt registered [12324.227989] Key type .llcrypt registered [12324.730641] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12324.744140] alg: No test for adler32 (adler32-zlib) [12325.843658] Lustre: Lustre: Build Version: 2.17.57_1_g689e325 [12326.166523] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [12327.907249] Key type lgssc registered [12329.284768] Lustre: Echo OBD driver; http://www.lustre.org/ [12348.225552] Key type lgssc unregistered [12348.510039] LNet: 228639:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12348.522450] LNetError: 228639:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12348.553851] LNet: Removed LNI 192.168.201.2@tcp [12349.589080] Key type .llcrypt unregistered [12349.592108] Key type ._llcrypt unregistered [12357.621567] Key type ._llcrypt registered [12357.626893] Key type .llcrypt registered [12357.904867] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12357.916031] alg: No test for adler32 (adler32-zlib) [12359.031522] Lustre: Lustre: Build Version: 2.17.57_1_g689e325 [12359.231064] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [12360.936287] Key type lgssc registered [12362.233410] Lustre: Echo OBD driver; http://www.lustre.org/ [12379.906555] Key type lgssc unregistered [12380.309947] LNet: 229948:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12380.316757] LNetError: 229948:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12380.332490] LNet: Removed LNI 192.168.201.2@tcp [12380.937792] Key type .llcrypt unregistered [12380.939896] Key type ._llcrypt unregistered [12390.394635] Key type ._llcrypt registered [12390.396191] Key type .llcrypt registered [12390.776857] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12390.786769] alg: No test for adler32 (adler32-zlib) [12392.155719] Lustre: Lustre: Build Version: 2.17.57_1_g689e325 [12392.449749] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [12394.200205] Key type lgssc registered [12397.373299] Lustre: Echo OBD driver; http://www.lustre.org/ [12423.178157] Key type lgssc unregistered [12423.428785] LNet: 231723:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12423.438702] LNetError: 231723:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12423.451460] LNet: Removed LNI 192.168.201.2@tcp [12424.097650] Key type .llcrypt unregistered [12424.100798] Key type ._llcrypt unregistered [12424.487240] Key type ._llcrypt registered [12424.488717] Key type .llcrypt registered [12425.830681] Lustre: DEBUG MARKER: == conf-sanity test 200d: set CPU pattern to exclude only CPU 1 ========================================================== 01:43:20 (1786772600) [12438.643641] Key type .llcrypt unregistered [12438.647329] Key type ._llcrypt unregistered [12439.547689] Key type ._llcrypt registered [12439.561241] Key type .llcrypt registered [12440.140764] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12440.158983] alg: No test for adler32 (adler32-zlib) [12441.241312] Lustre: Lustre: Build Version: 2.17.57_1_g689e325 [12441.475037] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [12443.192398] Key type lgssc registered [12444.622797] Lustre: Echo OBD driver; http://www.lustre.org/ [12460.272730] Key type lgssc unregistered [12460.467724] LNet: 233281:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12460.476105] LNetError: 233281:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12460.489966] LNet: Removed LNI 192.168.201.2@tcp [12461.105798] Key type .llcrypt unregistered [12461.111074] Key type ._llcrypt unregistered [12469.409267] Key type ._llcrypt registered [12469.431317] Key type .llcrypt registered [12470.427535] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12470.466633] alg: No test for adler32 (adler32-zlib) [12471.958805] Lustre: Lustre: Build Version: 2.17.57_1_g689e325 [12472.484967] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [12474.360285] Key type lgssc registered [12476.073793] Lustre: Echo OBD driver; http://www.lustre.org/ [12494.356044] Key type lgssc unregistered [12494.683684] LNet: 234599:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12494.695298] LNetError: 234599:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12494.717286] LNet: Removed LNI 192.168.201.2@tcp [12495.622967] Key type .llcrypt unregistered [12495.628107] Key type ._llcrypt unregistered [12505.894754] Key type ._llcrypt registered [12505.924368] Key type .llcrypt registered [12506.535311] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12506.556194] alg: No test for adler32 (adler32-zlib) [12507.672377] Lustre: Lustre: Build Version: 2.17.57_1_g689e325 [12508.081244] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [12509.889935] Key type lgssc registered [12511.779813] Lustre: Echo OBD driver; http://www.lustre.org/ [12530.148480] Key type lgssc unregistered [12530.342808] LNet: 235912:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12530.347754] LNetError: 235912:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12530.358670] LNet: Removed LNI 192.168.201.2@tcp [12530.976807] Key type .llcrypt unregistered [12530.979500] Key type ._llcrypt unregistered [12539.902709] Key type ._llcrypt registered [12539.917524] Key type .llcrypt registered [12540.203258] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12540.214238] alg: No test for adler32 (adler32-zlib) [12541.364910] Lustre: Lustre: Build Version: 2.17.57_1_g689e325 [12541.675623] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [12543.384183] Key type lgssc registered [12544.703585] Lustre: Echo OBD driver; http://www.lustre.org/ [12561.937560] Key type lgssc unregistered [12562.192878] LNet: 237226:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12562.201623] LNetError: 237226:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12562.214816] LNet: Removed LNI 192.168.201.2@tcp [12562.908825] Key type .llcrypt unregistered [12562.911145] Key type ._llcrypt unregistered [12571.822671] Key type ._llcrypt registered [12571.826921] Key type .llcrypt registered [12572.364436] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12572.392109] alg: No test for adler32 (adler32-zlib) [12573.633763] Lustre: Lustre: Build Version: 2.17.57_1_g689e325 [12573.885678] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [12575.609691] Key type lgssc registered [12577.283892] Lustre: Echo OBD driver; http://www.lustre.org/ [12595.350548] Key type lgssc unregistered [12595.594917] LNet: 238541:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12595.604265] LNetError: 238541:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12595.640329] LNet: Removed LNI 192.168.201.2@tcp [12596.491867] Key type .llcrypt unregistered [12596.497410] Key type ._llcrypt unregistered [12605.917917] Key type ._llcrypt registered [12605.932555] Key type .llcrypt registered [12606.646437] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12606.672029] alg: No test for adler32 (adler32-zlib) [12607.813336] Lustre: Lustre: Build Version: 2.17.57_1_g689e325 [12608.158087] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [12609.944176] Key type lgssc registered [12611.659722] Lustre: Echo OBD driver; http://www.lustre.org/ [12630.363486] Key type lgssc unregistered [12630.721685] LNet: 239852:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12630.735227] LNetError: 239852:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12630.751175] LNet: Removed LNI 192.168.201.2@tcp [12631.777332] Key type .llcrypt unregistered [12631.781180] Key type ._llcrypt unregistered [12658.375990] Key type ._llcrypt registered [12658.378553] Key type .llcrypt registered [12659.812083] Lustre: DEBUG MARKER: == conf-sanity test 200e: set CPU pattern using relative core exclusion ========================================================== 01:47:14 (1786772834) [12673.839436] Key type .llcrypt unregistered [12673.844827] Key type ._llcrypt unregistered [12675.100926] Key type ._llcrypt registered [12675.102534] Key type .llcrypt registered [12675.537977] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12675.554965] alg: No test for adler32 (adler32-zlib) [12676.755027] Lustre: Lustre: Build Version: 2.17.57_1_g689e325 [12677.040636] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [12678.736781] Key type lgssc registered [12680.295538] Lustre: Echo OBD driver; http://www.lustre.org/ [12698.567718] Key type lgssc unregistered [12698.920560] LNet: 242184:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12698.926513] LNetError: 242184:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12698.945448] LNet: Removed LNI 192.168.201.2@tcp [12699.635368] Key type .llcrypt unregistered [12699.637847] Key type ._llcrypt unregistered [12707.035415] Key type ._llcrypt registered [12707.045358] Key type .llcrypt registered [12707.654658] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12707.664661] alg: No test for adler32 (adler32-zlib) [12708.725764] Lustre: Lustre: Build Version: 2.17.57_1_g689e325 [12708.986893] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [12710.673337] Key type lgssc registered [12711.918642] Lustre: Echo OBD driver; http://www.lustre.org/ [12730.085443] Key type lgssc unregistered [12730.506160] LNet: 243509:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12730.520701] LNetError: 243509:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12730.554166] LNet: Removed LNI 192.168.201.2@tcp [12731.263346] Key type .llcrypt unregistered [12731.265152] Key type ._llcrypt unregistered [12739.877988] Key type ._llcrypt registered [12739.890347] Key type .llcrypt registered [12740.239679] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12740.254444] alg: No test for adler32 (adler32-zlib) [12741.401130] Lustre: Lustre: Build Version: 2.17.57_1_g689e325 [12741.605545] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [12743.304216] Key type lgssc registered [12744.883580] Lustre: Echo OBD driver; http://www.lustre.org/ [12763.876561] Key type lgssc unregistered [12764.096690] LNet: 244824:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12764.113102] LNetError: 244824:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12764.135503] LNet: Removed LNI 192.168.201.2@tcp [12764.971080] Key type .llcrypt unregistered [12764.973409] Key type ._llcrypt unregistered [12774.599634] Key type ._llcrypt registered [12774.601711] Key type .llcrypt registered [12775.050416] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12775.073536] alg: No test for adler32 (adler32-zlib) [12776.214400] Lustre: Lustre: Build Version: 2.17.57_1_g689e325 [12776.484840] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [12778.200172] Key type lgssc registered [12779.556723] Lustre: Echo OBD driver; http://www.lustre.org/ [12804.282694] Key type lgssc unregistered [12804.515651] LNet: 246622:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12804.526962] LNetError: 246622:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12804.539205] LNet: Removed LNI 192.168.201.2@tcp [12805.415543] Key type .llcrypt unregistered [12805.419964] Key type ._llcrypt unregistered [12805.895608] Key type ._llcrypt registered [12805.901690] Key type .llcrypt registered [12807.224447] Lustre: DEBUG MARKER: == conf-sanity test 250: verify lcfg_erase removes filesystem parameters ========================================================== 01:49:42 (1786772982) [12826.333231] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12826.393228] alg: No test for adler32 (adler32-zlib) [12827.584100] Lustre: Lustre: Build Version: 2.17.57_1_g689e325 [12827.788594] LNet: Added LNI 192.168.201.2@tcp [8/256/0/180] [12829.472231] Key type lgssc registered [12830.643185] Lustre: Echo OBD driver; http://www.lustre.org/ [13080.500637] Lustre: DEBUG MARKER: == conf-sanity test 802a: simulate readonly device ======= 01:54:15 (1786773255) [13083.581827] Lustre: DEBUG MARKER: SKIP: conf-sanity test_802a ZFS specific test [13152.047943] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 12900 sec ========= 01:55:27 (1786773327) [13153.728640] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 01:55:28 (1786773328) === [13157.465847] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 01:55:32 (1786773332) === [13190.019787] Key type lgssc unregistered [13190.260549] LNet: 255692:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13190.286835] LNetError: 255692:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13190.323409] LNet: Removed LNI 192.168.201.2@tcp [13191.194656] Key type .llcrypt unregistered [13191.196667] Key type ._llcrypt unregistered