[ 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 430962021 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, 524584K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001011] APIC: Switch to symmetric I/O mode setup [ 0.003049] x2apic enabled [ 0.004008] Switched APIC routing to physical x2apic. [ 0.005014] kvm-guest: setup PV IPIs [ 0.008264] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.009019] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010011] pid_max: default: 32768 minimum: 301 [ 0.011126] LSM: Security Framework initializing [ 0.012049] Yama: becoming mindful. [ 0.013036] SELinux: Initializing. [ 0.014060] *** VALIDATE selinux *** [ 0.022195] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.027433] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.028148] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029101] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030098] *** VALIDATE tmpfs *** [ 0.031420] *** VALIDATE proc *** [ 0.032231] *** VALIDATE cgroup *** [ 0.033009] *** VALIDATE cgroup2 *** [ 0.034259] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035153] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036008] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037027] Spectre V2 : User space: Vulnerable [ 0.038008] Speculative Store Bypass: Vulnerable [ 0.041106] debug: unmapping init [mem 0xffffffff9d059000-0xffffffff9d060fff] [ 0.043162] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.044657] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.045023] ... version: 2 [ 0.046011] ... bit width: 48 [ 0.047012] ... generic registers: 4 [ 0.048012] ... value mask: 0000ffffffffffff [ 0.049011] ... max period: 00007fffffffffff [ 0.050012] ... fixed-purpose events: 3 [ 0.051010] ... event mask: 000000070000000f [ 0.052270] rcu: Hierarchical SRCU implementation. [ 0.054382] smp: Bringing up secondary CPUs ... [ 0.055540] x86: Booting SMP configuration: [ 0.056023] .... node #0, CPUs: #1 #2 #3 [ 0.060273] smp: Brought up 1 node, 4 CPUs [ 0.062013] smpboot: Max logical packages: 1 [ 0.063017] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.141021] node 0 deferred pages initialised in 76ms [ 0.143089] devtmpfs: initialized [ 0.144184] x86/mm: Memory block size: 128MB [ 0.146722] gcov: version magic: 0x41383552 [ 0.148306] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.149112] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.150248] pinctrl core: initialized pinctrl subsystem [ 0.151195] [ 0.151951] ************************************************************* [ 0.152013] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.153017] ** ** [ 0.154016] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.155032] ** ** [ 0.156014] ** This means that this kernel is built to expose internal ** [ 0.157014] ** IOMMU data structures, which may compromise security on ** [ 0.158012] ** your system. ** [ 0.159029] ** ** [ 0.160013] ** If you see this message and you are not debugging the ** [ 0.161016] ** kernel, report this immediately to your vendor! ** [ 0.162015] ** ** [ 0.163011] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.164012] ************************************************************* [ 0.165734] NET: Registered protocol family 16 [ 0.166472] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.167077] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.168065] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.170062] cpuidle: using governor menu [ 0.172740] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.174445] PCI: Using configuration type 1 for base access [ 0.177115] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.186134] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.187038] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.189077] cryptd: max_cpu_qlen set to 1000 [ 0.191259] ACPI: Added _OSI(Module Device) [ 0.192015] ACPI: Added _OSI(Processor Device) [ 0.193013] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.194012] ACPI: Added _OSI(Processor Aggregator Device) [ 0.198895] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.200502] ACPI: Interpreter enabled [ 0.201066] ACPI: PM: (supports S0 S3 S4 S5) [ 0.202011] ACPI: Using IOAPIC for interrupt routing [ 0.203100] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.204345] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.213635] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.214042] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.215020] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.216074] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.218305] acpiphp: Slot [2] registered [ 0.219146] acpiphp: Slot [5] registered [ 0.220121] acpiphp: Slot [6] registered [ 0.221111] acpiphp: Slot [3] registered [ 0.222105] acpiphp: Slot [4] registered [ 0.223092] acpiphp: Slot [7] registered [ 0.224092] acpiphp: Slot [8] registered [ 0.225146] acpiphp: Slot [9] registered [ 0.226103] acpiphp: Slot [10] registered [ 0.227082] acpiphp: Slot [11] registered [ 0.228099] acpiphp: Slot [12] registered [ 0.229092] acpiphp: Slot [13] registered [ 0.230090] acpiphp: Slot [14] registered [ 0.231162] acpiphp: Slot [15] registered [ 0.233108] acpiphp: Slot [16] registered [ 0.235115] acpiphp: Slot [17] registered [ 0.237117] acpiphp: Slot [18] registered [ 0.238153] acpiphp: Slot [19] registered [ 0.240093] acpiphp: Slot [20] registered [ 0.241116] acpiphp: Slot [21] registered [ 0.243103] acpiphp: Slot [22] registered [ 0.244097] acpiphp: Slot [23] registered [ 0.246095] acpiphp: Slot [24] registered [ 0.247097] acpiphp: Slot [25] registered [ 0.248064] acpiphp: Slot [26] registered [ 0.249100] acpiphp: Slot [27] registered [ 0.251103] acpiphp: Slot [28] registered [ 0.252062] acpiphp: Slot [29] registered [ 0.252965] acpiphp: Slot [30] registered [ 0.254085] acpiphp: Slot [31] registered [ 0.256089] PCI host bridge to bus 0000:00 [ 0.257016] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.259018] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.261029] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.264019] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.267031] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.270026] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.271186] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.273925] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.276315] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.284015] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.288061] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.291018] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.293014] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.296018] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.298633] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.301818] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.304043] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.307833] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.311842] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.322017] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.326012] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.329949] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.342016] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.357015] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.376025] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.386652] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.392018] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.399013] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.413014] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.425150] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.427225] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.429372] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.432427] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.435222] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.438167] iommu: Default domain type: Passthrough [ 0.439388] SCSI subsystem initialized [ 0.441093] ACPI: bus type USB registered [ 0.442083] usbcore: registered new interface driver usbfs [ 0.444085] usbcore: registered new interface driver hub [ 0.446092] usbcore: registered new device driver usb [ 0.448259] pps_core: LinuxPPS API ver. 1 registered [ 0.450018] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.454058] PTP clock support registered [ 0.456125] EDAC MC: Ver: 3.0.0 [ 0.457462] PCI: Using ACPI for IRQ routing [ 0.458892] NetLabel: Initializing [ 0.459011] NetLabel: domain hash size = 128 [ 0.460012] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.461108] NetLabel: unlabeled traffic allowed by default [ 0.463066] vgaarb: loaded [ 0.464313] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.466014] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.473791] clocksource: Switched to clocksource kvm-clock [ 0.578942] VFS: Disk quotas dquot_6.6.0 [ 0.580497] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.582795] *** VALIDATE ramfs *** [ 0.583966] *** VALIDATE hugetlbfs *** [ 0.585609] pnp: PnP ACPI init [ 0.587893] pnp: PnP ACPI: found 6 devices [ 0.612577] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.616606] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.619713] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.621843] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.625347] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.628376] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.631826] NET: Registered protocol family 2 [ 0.634707] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.639781] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.643567] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.650820] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.654670] TCP: Hash tables configured (established 65536 bind 65536) [ 0.657656] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.661312] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.664645] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.668191] NET: Registered protocol family 1 [ 0.670771] RPC: Registered named UNIX socket transport module. [ 0.676418] RPC: Registered udp transport module. [ 0.678524] RPC: Registered tcp transport module. [ 0.680072] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.682280] NET: Registered protocol family 44 [ 0.683669] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.686720] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.689322] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.693347] PCI: CLS 0 bytes, default 64 [ 0.695329] Unpacking initramfs... [ 4.669459] debug: unmapping init [mem 0xffffa08c7cc64000-0xffffa08c7ffcffff] [ 4.682481] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 4.690872] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 4.697409] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 6.806555] Initialise system trusted keyrings [ 6.809101] Key type blacklist registered [ 6.819982] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 6.843056] zbud: loaded [ 6.849163] *** VALIDATE nfs *** [ 6.853964] *** VALIDATE nfs4 *** [ 6.858804] pstore: using deflate compression [ 6.885515] Platform Keyring initialized [ 7.228536] NET: Registered protocol family 38 [ 7.244790] Key type asymmetric registered [ 7.253816] Asymmetric key parser 'x509' registered [ 7.267803] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 7.276769] io scheduler mq-deadline registered [ 7.281332] io scheduler kyber registered [ 7.290961] io scheduler bfq registered [ 7.299447] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 7.306026] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 7.316886] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 7.326578] ACPI: Power Button [PWRF] [ 7.340172] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 7.357797] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 7.385667] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 7.427921] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 7.477626] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 7.493623] Non-volatile memory driver v1.3 [ 7.496919] Linux agpgart interface v0.103 [ 7.605448] virtio_blk virtio1: [vda] 146008 512-byte logical blocks (74.8 MB/71.3 MiB) [ 7.613839] vda: detected capacity change from 0 to 74756096 [ 7.667825] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 7.686913] vdb: detected capacity change from 0 to 1073741824 [ 7.716497] libphy: Fixed MDIO Bus: probed [ 7.798216] usbcore: registered new interface driver usbserial_generic [ 7.814693] usbserial: USB Serial support registered for generic [ 7.823055] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 7.843123] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 7.853086] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 7.868898] mousedev: PS/2 mouse device common for all mice [ 7.884654] rtc_cmos 00:05: RTC can wake from S4 [ 7.898536] rtc_cmos 00:05: registered as rtc0 [ 7.900272] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 7.912425] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 7.916792] intel_pstate: CPU model not supported [ 7.930981] hid: raw HID events driver (C) Jiri Kosina [ 7.952430] usbcore: registered new interface driver usbhid [ 7.953968] usbhid: USB HID core driver [ 7.965140] drop_monitor: Initializing network drop monitor service [ 7.976294] Initializing XFRM netlink socket [ 7.981902] NET: Registered protocol family 10 [ 7.990636] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 7.990925] Segment Routing with IPv6 [ 8.008432] NET: Registered protocol family 17 [ 8.013161] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 8.022736] mpls_gso: MPLS GSO support [ 8.041907] RAS: Correctable Errors collector initialized. [ 8.050305] AVX version of gcm_enc/dec engaged. [ 8.059169] AES CTR mode by8 optimization enabled [ 8.337166] sched_clock: Marking stable (8337127568, 0)->(9298450240, -961322672) [ 8.362431] registered taskstats version 1 [ 8.369316] Loading compiled-in X.509 certificates [ 8.374571] zswap: loaded using pool lzo/zbud [ 8.452624] Key type big_key registered [ 8.473754] Key type encrypted registered [ 8.475978] ima: No TPM chip found, activating TPM-bypass! [ 8.478918] ima: Allocated hash algorithm: sha1 [ 8.480759] ima: No architecture policies found [ 8.483546] evm: Initialising EVM extended attributes: [ 8.486385] evm: security.selinux [ 8.487978] evm: security.ima [ 8.489635] evm: security.capability [ 8.494897] evm: HMAC attrs: 0x1 [ 8.498657] rtc_cmos 00:05: setting system clock to 2026-08-22 08:24:43 UTC (1787387083) [ 8.509530] debug: unmapping init [mem 0xffffffff9e003000-0xffffffff9e1fffff] [ 8.516358] debug: unmapping init [mem 0xffffffff9cd82000-0xffffffff9d058fff] [ 8.527203] Write protecting the kernel read-only data: 28672k [ 8.537825] debug: unmapping init [mem 0xffffffff9b403000-0xffffffff9b5fffff] [ 8.543441] debug: unmapping init [mem 0xffffffff9bd14000-0xffffffff9bdfffff] [ 8.684693] 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) [ 8.711748] systemd[1]: Detected virtualization kvm. [ 8.714767] systemd[1]: Detected architecture x86-64. [ 8.716748] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 8.774928] systemd[1]: No hostname configured. [ 8.778898] systemd[1]: Set hostname to . [ 8.788835] random: systemd: uninitialized urandom read (16 bytes read) [ 8.802475] systemd[1]: Initializing machine ID from random generator. [ 9.122581] random: systemd: uninitialized urandom read (16 bytes read) [ 9.127921] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 9.139704] random: systemd: uninitialized urandom read (16 bytes read) [ 9.145193] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 9.161817] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ OK ] Listening on Journal Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Slices. [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Reached target Local File Systems. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. Starting Setup Virtual Console... Starting Create Volatile Files and Directories... Starting Apply Kernel Variables... [ OK ] Reached target Timers. [ OK ] Started Memstrack Anylazing Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 10.875192] device-mapper: uevent: version 1.0.3 [ 10.879109] 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 [ 12.755181] virtio_net virtio0 ens2: renamed from eth0 ] Started Hardware RNG Entropy Gatherer Daemon. [ 12.898653] scsi host0: ata_piix [ 12.939241] scsi host1: ata_piix [ 12.946433] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 12.959216] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 18.014659] random: crng init done [ 18.017399] random: 7 urandom warning(s) missed due to ratelimiting [ 18.573731] dracut-initqueue[580]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK [0[ 20.541992] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) m] Reached target Remote File Systems. [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped dracut initqueue hook. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Sockets. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 22.496417] printk: systemd: 25 output lines suppressed due to ratelimiting [ 23.351721] SELinux: Disabled at runtime. [ 23.510099] 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) [ 23.543537] systemd[1]: Detected virtualization kvm. [ 23.551244] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 25.163868] systemd[1]: initrd-switch-root.service: Succeeded. [ 25.174920] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 25.197820] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 25.207511] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 25.216782] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 25.233274] systemd[1]: Starting Journal Service... Starting Journal Service... [ 25.261526] systemd[1]: Mounting Huge Pages File System... Mounting Huge Pages File System... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Listening on Process Core Dump Socket. [ OK ] Reached target rpc_pipefs.target. Mounting POSIX Message Queue File System... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on udev Kernel Socket. [ OK ] Created slice User and Session Slice. [ OK ] Created slice system-serial\x2dgetty.slice. Starting Remount Root and Kernel File Systems... [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... Starting Apply Kernel Variables... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Reached target Slices. [ OK ] Listening on udev Control Socket. [ 25.770116] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Starting udev Coldplug all Devices... [ OK ] Created slice system-getty.slice. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. Mounting Kernel Debug File System... [ OK ] Started Journal Service. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Kernel Debug File System. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started udev Coldplug all Devices. [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Mounted /mnt. [ 26.815036] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 27.690037] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 27.729870] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 28.171270] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 28.233160] EDAC sbridge: Ver: 1.1.2 [ 31.965384] Key type dns_resolver registered [* ] A start job is running for Configur…-only root support (7s / no limit) [** ] A start job is running for Configur…-only root support (7s / no limit)[ 33.249555] NFS: Registering the id_resolver key type [ 33.251742] Key type id_resolver registered [ 33.256985] Key type id_legacy registered [*** ] A start job is running for Configur…-only root support (8s / no limit) [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ 35.809217] hrtimer: interrupt took 6881115 ns [ 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. [ OK ] Started irqbalance daemon. Starting Login Service... [ OK ] Started D-Bus System Message Bus. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Reached target sshd-keygen.target. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. Starting Restore /run/initramfs on shutdown... Starting Network Manager... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting Network Manager Wait Online... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Started Login Service. [ OK ] Started OpenSSH server daemon. Starting Hostname Service... [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg612-client login: [ 93.807827] libcfs: loading out-of-tree module taints kernel. [ 93.929208] Key type ._llcrypt registered [ 93.931066] Key type .llcrypt registered [ 94.375255] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 94.397679] alg: No test for adler32 (adler32-zlib) [ 95.896830] Lustre: Lustre: Build Version: 2.17.57_45_g4630995 [ 96.686156] LNet: Added LNI 192.168.206.12@tcp [8/256/0/180] [ 98.488145] Key type lgssc registered [ 99.859800] Lustre: Echo OBD driver; http://www.lustre.org/ [ 262.542419] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [ 266.998282] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 286.828415] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing check_logdir /tmp/testlogs/ [ 288.224414] Lustre: lustre-OST0000-osc-ffffa08cca463000: disconnect after 24s idle [ 291.455893] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing yml_node [ 296.659597] Lustre: DEBUG MARKER: Client: 2.17.57.45 [ 299.267740] Lustre: DEBUG MARKER: MDS: 2.17.57.45 [ 301.390096] Lustre: DEBUG MARKER: OSS: 2.17.57.45 [ 302.708840] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Sat Aug 22 04:29:36 EDT 2026 [ 316.497828] Lustre: DEBUG MARKER: excepting tests: 32newtarball [ 317.768277] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 319.980361] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 330.341182] Lustre: Unmounted lustre-client [ 433.552145] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 440.104771] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 463.839883] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 503.800957] Lustre: DEBUG MARKER: == conf-sanity test 121: failover MGS ==================== 04:32:57 (1787387577) [ 547.522967] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 552.123414] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 585.660162] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid 1475 0 [ 634.081926] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 639.209587] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 686.017599] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid 1475 0 [ 708.868549] Lustre: DEBUG MARKER: == conf-sanity test 122a: Check OST sequence update ====== 04:36:21 (1787387781) [ 805.832212] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 811.336494] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 833.397028] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 833.891686] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [ 853.257258] Lustre: Unmounted lustre-client [ 879.529417] Key type lgssc unregistered [ 879.831628] LNet: 21908:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 879.837225] LNetError: 21908:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 879.858519] LNet: Removed LNI 192.168.206.12@tcp [ 880.471239] Key type .llcrypt unregistered [ 880.478929] Key type ._llcrypt unregistered [ 894.727351] Key type ._llcrypt registered [ 894.729710] Key type .llcrypt registered [ 895.788122] Lustre: DEBUG MARKER: == conf-sanity test 123aa: llog_print works with FIDs and simple names ========================================================== 04:39:29 (1787387969) [ 897.478516] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 897.509204] alg: No test for adler32 (adler32-zlib) [ 898.589589] Lustre: Lustre: Build Version: 2.17.57_45_g4630995 [ 898.838237] LNet: Added LNI 192.168.206.12@tcp [8/256/0/180] [ 900.488152] Key type lgssc registered [ 901.770912] Lustre: Echo OBD driver; http://www.lustre.org/ [ 950.119445] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 954.395831] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 974.284661] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 975.987166] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [ 990.135733] Lustre: DEBUG MARKER: == conf-sanity test 123ab: llog_print params output values from set_param -P ========================================================== 04:41:03 (1787388063) [ 1003.340313] Lustre: DEBUG MARKER: == conf-sanity test 123ac: llog_print with --start and --end ========================================================== 04:41:16 (1787388076) [ 1012.207366] Lustre: DEBUG MARKER: == conf-sanity test 123ad: llog_print shows all records == 04:41:25 (1787388085) [ 1023.663260] Lustre: DEBUG MARKER: == conf-sanity test 123ae: llog_cancel can cancel requested record ========================================================== 04:41:37 (1787388097) [ 1048.084962] Lustre: DEBUG MARKER: == conf-sanity test 123af: llog_catlist can show all config files correctly ========================================================== 04:42:01 (1787388121) [ 1063.919690] Lustre: DEBUG MARKER: == conf-sanity test 123ag: llog_print skips values deleted by set_param -P -d ========================================================== 04:42:17 (1787388137) [ 1078.277311] Lustre: DEBUG MARKER: == conf-sanity test 123ah: del_ost cancels config log entries correctly ========================================================== 04:42:31 (1787388151) [ 1086.655893] Lustre: Unmounted lustre-client [ 1086.987087] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [ 1089.081619] Lustre: Unmounted lustre-client [ 1125.126374] Key type lgssc unregistered [ 1125.459397] LNet: 30282:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1125.475553] LNetError: 30282:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1125.508211] LNet: Removed LNI 192.168.206.12@tcp [ 1126.644838] Key type .llcrypt unregistered [ 1126.647485] Key type ._llcrypt unregistered [ 1158.356480] Key type ._llcrypt registered [ 1158.361821] Key type .llcrypt registered [ 1158.754416] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1158.790791] alg: No test for adler32 (adler32-zlib) [ 1159.963942] Lustre: Lustre: Build Version: 2.17.57_45_g4630995 [ 1160.407906] LNet: Added LNI 192.168.206.12@tcp [8/256/0/180] [ 1162.199786] Key type lgssc registered [ 1163.906360] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1243.887961] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1249.676358] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1272.623865] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1314.595894] Lustre: DEBUG MARKER: == conf-sanity test 123ai: llog_print display all non skipped records ========================================================== 04:46:28 (1787388388) [ 1348.075764] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1353.474856] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1373.479965] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1375.212400] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [ 1506.259966] Lustre: DEBUG MARKER: == conf-sanity test 123aj: check permanent TBF rules ===== 04:49:39 (1787388579) [ 1546.020894] Lustre: DEBUG MARKER: == conf-sanity test 123F: clear and reset all parameters using set_param -F ========================================================== 04:50:19 (1787388619) [ 1555.189409] Lustre: Unmounted lustre-client [ 1630.245331] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1635.447650] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1655.613159] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1656.292795] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [ 1661.651412] Lustre: Unmounted lustre-client [ 1686.068981] Key type lgssc unregistered [ 1686.337063] LNet: 41608:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1686.365273] LNetError: 41608:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1686.396423] LNet: Removed LNI 192.168.206.12@tcp [ 1687.208561] Key type .llcrypt unregistered [ 1687.212681] Key type ._llcrypt unregistered [ 1700.596970] Key type ._llcrypt registered [ 1700.599775] Key type .llcrypt registered [ 1701.771944] Lustre: DEBUG MARKER: == conf-sanity test 123G: clear and reset all parameters using apply_yaml ========================================================== 04:52:55 (1787388775) [ 1704.092578] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1704.125124] alg: No test for adler32 (adler32-zlib) [ 1705.209844] Lustre: Lustre: Build Version: 2.17.57_45_g4630995 [ 1705.493991] LNet: Added LNI 192.168.206.12@tcp [8/256/0/180] [ 1707.220958] Key type lgssc registered [ 1708.467649] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1751.873263] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1756.930408] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1775.142606] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1781.278033] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [ 1791.373105] Lustre: Unmounted lustre-client [ 1862.925713] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1868.005907] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1885.740119] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1886.425236] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [ 1891.557436] Lustre: Unmounted lustre-client [ 1915.049125] Key type lgssc unregistered [ 1915.285701] LNet: 47144:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1915.294929] LNetError: 47144:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1915.313710] LNet: Removed LNI 192.168.206.12@tcp [ 1916.035926] Key type .llcrypt unregistered [ 1916.038552] Key type ._llcrypt unregistered [ 1930.227277] Key type ._llcrypt registered [ 1930.228831] Key type .llcrypt registered [ 1931.568528] Lustre: DEBUG MARKER: == conf-sanity test 123H: check concurent accesses with 'lctl llog_print ========================================================== 04:56:45 (1787389005) [ 1933.848617] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1933.860748] alg: No test for adler32 (adler32-zlib) [ 1935.087286] Lustre: Lustre: Build Version: 2.17.57_45_g4630995 [ 1935.344227] LNet: Added LNI 192.168.206.12@tcp [8/256/0/180] [ 1937.048292] Key type lgssc registered [ 1938.353408] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1986.012991] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1991.473050] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2010.885727] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2013.727753] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [ 2103.752456] Lustre: DEBUG MARKER: == conf-sanity test 124: check failover after replace_nids ========================================================== 04:59:37 (1787389177) [ 2104.942098] Lustre: DEBUG MARKER: SKIP: conf-sanity test_124 needs MDT failover setup [ 2106.371017] Lustre: DEBUG MARKER: == conf-sanity test 126: mount in parallel shouldn't cause a crash ========================================================== 04:59:40 (1787389180) [ 2108.358834] Lustre: Unmounted lustre-client [ 2134.444796] Key type lgssc unregistered [ 2134.654909] LNet: 52484:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2134.660972] LNetError: 52484:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2134.695952] LNet: Removed LNI 192.168.206.12@tcp [ 2135.330987] Key type .llcrypt unregistered [ 2135.333147] Key type ._llcrypt unregistered [ 2162.594714] Key type ._llcrypt registered [ 2162.597426] Key type .llcrypt registered [ 2162.760125] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2162.768332] alg: No test for adler32 (adler32-zlib) [ 2163.815269] Lustre: Lustre: Build Version: 2.17.57_45_g4630995 [ 2164.095691] LNet: Added LNI 192.168.206.12@tcp [8/256/0/180] [ 2165.897355] Key type lgssc registered [ 2167.018648] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2196.549879] Lustre: DEBUG MARKER: == conf-sanity test 127: direct io overwrite on full ost ========================================================== 05:01:10 (1787389270) [ 2207.290691] Key type lgssc unregistered [ 2207.546874] LNet: 54911:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2207.556470] LNetError: 54911:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2207.586566] LNet: Removed LNI 192.168.206.12@tcp [ 2208.142191] Key type .llcrypt unregistered [ 2208.146653] Key type ._llcrypt unregistered [ 2217.866114] Key type ._llcrypt registered [ 2217.869255] Key type .llcrypt registered [ 2218.188582] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2218.197683] alg: No test for adler32 (adler32-zlib) [ 2219.251848] Lustre: Lustre: Build Version: 2.17.57_45_g4630995 [ 2219.524987] LNet: Added LNI 192.168.206.12@tcp [8/256/0/180] [ 2221.200223] Key type lgssc registered [ 2222.195204] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2264.090985] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2268.568772] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2286.268547] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2292.264183] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [ 2334.371695] Lustre: DEBUG MARKER: == conf-sanity test 128: Force using remote logs with --nolocallogs ========================================================== 05:03:27 (1787389407) [ 2336.051376] Lustre: DEBUG MARKER: SKIP: conf-sanity test_128 need separate mgs device [ 2337.934879] Lustre: DEBUG MARKER: == conf-sanity test 129: attempt to connect an OST with the same index should fail ========================================================== 05:03:31 (1787389411) [ 2339.766534] Lustre: Unmounted lustre-client [ 2397.935531] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2404.846678] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2463.023126] Lustre: DEBUG MARKER: == conf-sanity test 130: re-register an MDT after writeconf ========================================================== 05:05:36 (1787389536) [ 2534.632630] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [ 2539.108846] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 2550.259584] Lustre: lustre-MDT0001-mdc-ffffa08cc83ba000: Connection to lustre-MDT0001 (at 192.168.206.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2560.480565] Lustre: lustre-OST0000-osc-ffffa08cc83ba000: disconnect after 24s idle [ 2565.615498] LustreError: 63588:0:(obd_config.c:2102:class_config_llog_handler()) MGC192.168.206.112@tcp: cfg command failed: rc = -17 [ 2565.630806] Lustre: cmd=cf001 0:lustre-MDT0001-mdc 1:mdc 2:lustre-clilmv_UUID [ 2565.630806] [ 2565.644802] LustreError: 62854:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [ 2577.275613] Lustre: DEBUG MARKER: == conf-sanity test 131: MDT backup restore with project ID and inheritance flag ========================================================== 05:07:31 (1787389651) [ 2626.277398] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 2701.679194] Lustre: Unmounted lustre-client [ 2838.375589] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [ 2842.915231] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 2889.647385] Lustre: DEBUG MARKER: == conf-sanity test 132: hsm_actions processed after failover ========================================================== 05:12:43 (1787389963) [ 2891.772947] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2892.379899] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 2892.385090] Lustre: Skipped 2 previous similar messages [ 2914.315433] Lustre: Unmounted lustre-client [ 3070.689947] Lustre: DEBUG MARKER: == conf-sanity test 133: stripe QOS: free space balance in a pool ========================================================== 05:15:44 (1787390144) [ 3072.132079] Lustre: DEBUG MARKER: SKIP: conf-sanity test_133 needs >= 4 OSTs [ 3074.103130] Lustre: DEBUG MARKER: == conf-sanity test 134: check_iam works without faults == 05:15:47 (1787390147) [ 3156.485293] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [ 3161.454896] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3784.805880] Lustre: DEBUG MARKER: == conf-sanity test 135: check the behavior when changelog is wrapped around ========================================================== 05:27:38 (1787390858) [ 3786.875532] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3797.222947] Lustre: Unmounted lustre-client [ 3918.815172] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3924.240080] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3946.374758] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3947.049619] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [ 5573.418911] Lustre: 95259:0:(llog_cat.c:310:llog_cat_process_or_fork()) lustre-MDT0000-mdc-ffffa08cc96b6800: catlog [0xa:0x5:0x0] crosses index zero [ 5598.890274] Lustre: Unmounted lustre-client [ 5625.394617] Key type lgssc unregistered [ 5625.676917] LNet: 103415:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5625.684225] LNetError: 103415:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5625.700972] LNet: Removed LNI 192.168.206.12@tcp [ 5626.465148] Key type .llcrypt unregistered [ 5626.472355] Key type ._llcrypt unregistered [ 5637.070722] Key type ._llcrypt registered [ 5637.074228] Key type .llcrypt registered [ 5638.475465] Lustre: DEBUG MARKER: == conf-sanity test 136: don't panic with bad obdecho setup ========================================================== 05:58:31 (1787392711) [ 5658.044447] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5658.060512] alg: No test for adler32 (adler32-zlib) [ 5659.232083] Lustre: Lustre: Build Version: 2.17.57_45_g4630995 [ 5659.517688] LNet: Added LNI 192.168.206.12@tcp [8/256/0/180] [ 5661.296193] Key type lgssc registered [ 5662.488975] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5735.641765] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5740.930789] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5760.820340] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5762.458775] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [ 5779.745398] Lustre: Unmounted lustre-client [ 5905.096422] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5910.549230] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5932.946469] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5967.574807] Lustre: DEBUG MARKER: == conf-sanity test 137: a new MDT should inherit pools, parameters and failnode ========================================================== 06:04:00 (1787393040) [ 5969.110456] Lustre: DEBUG MARKER: SKIP: conf-sanity test_137 needs >= 3 MDTs [ 5971.769454] Lustre: DEBUG MARKER: == conf-sanity test 140: remove_updatelog script actions ========================================================== 06:04:04 (1787393044) [ 6007.689330] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6013.026741] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6033.273562] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6034.072424] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [ 6039.532336] Lustre: lustre-MDT0000-mdc-ffffa08cc709a800: Connection to lustre-MDT0000 (at 192.168.206.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6049.772954] Lustre: lustre-MDT0001-mdc-ffffa08cc709a800: Connection to lustre-MDT0001 (at 192.168.206.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6060.000265] Lustre: 104177:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787393119/real 1787393119] req@00000000b3421219 x1874217123523456/t0(0) o400->MGC192.168.206.112@tcp@192.168.206.112@tcp:26/25 lens 224/224 e 0 to 1 dl 1787393135 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6060.016393] LustreError: MGC192.168.206.112@tcp: Connection to MGS (at 192.168.206.112@tcp) was lost; in progress operations using this service will fail [ 6196.205791] LustreError: lustre-MDT0000-mdc-ffffa08cc709a800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 6196.241660] Lustre: lustre-MDT0000-mdc-ffffa08cc709a800: Connection restored to 192.168.206.112@tcp (at 192.168.206.112@tcp) [ 6201.319949] LustreError: lustre-MDT0001-mdc-ffffa08cc709a800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 6201.331995] Lustre: lustre-MDT0001-mdc-ffffa08cc709a800: Connection restored to 192.168.206.112@tcp (at 192.168.206.112@tcp) [ 6205.435190] Lustre: Evicted from MGS (at 192.168.206.112@tcp) after server handle changed from 0x847ebe02ce4588b0 to 0x847ebe02ce45a0f8 [ 6205.448820] Lustre: MGC192.168.206.112@tcp: Connection restored to 192.168.206.112@tcp (at 192.168.206.112@tcp) [ 6205.692097] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6207.435983] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6213.329869] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6214.794572] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6230.106497] Lustre: Unmounted lustre-client [ 6370.152957] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6376.071628] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6397.592479] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6437.265446] Lustre: DEBUG MARKER: == conf-sanity test 150: test setting max_cached_mb to a % ========================================================== 06:11:51 (1787393511) [ 6466.435393] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6471.643077] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6492.666837] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6493.328189] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [ 6495.719343] LustreError: 120911: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 [ 6502.806836] Lustre: DEBUG MARKER: == conf-sanity test 151a: damaged local config doesn't prevent mounting ========================================================== 06:12:56 (1787393576) [ 6505.286739] Lustre: Unmounted lustre-client [ 6535.054507] Key type lgssc unregistered [ 6535.267424] LNet: 121921:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6535.272704] LNetError: 121921:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6535.287918] LNet: Removed LNI 192.168.206.12@tcp [ 6536.096378] Key type .llcrypt unregistered [ 6536.099494] Key type ._llcrypt unregistered [ 6551.440995] Key type ._llcrypt registered [ 6551.442614] Key type .llcrypt registered [ 6551.915331] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6551.927705] alg: No test for adler32 (adler32-zlib) [ 6552.978584] Lustre: Lustre: Build Version: 2.17.57_45_g4630995 [ 6553.257530] LNet: Added LNI 192.168.206.12@tcp [8/256/0/180] [ 6554.968203] Key type lgssc registered [ 6556.421608] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6647.049250] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6653.378415] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6672.713365] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6796.854639] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6802.730082] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6825.289670] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6866.369957] Lustre: DEBUG MARKER: == conf-sanity test 151b: -ENOSPC doesn't affect mount === 06:18:59 (1787393939) [ 6877.708613] Key type lgssc unregistered [ 6878.003901] LNet: 129143:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6878.016272] LNetError: 129143:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6878.032258] LNet: Removed LNI 192.168.206.12@tcp [ 6878.753560] Key type .llcrypt unregistered [ 6878.760916] Key type ._llcrypt unregistered [ 6891.528734] Key type ._llcrypt registered [ 6891.531864] Key type .llcrypt registered [ 6892.206763] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6892.220106] alg: No test for adler32 (adler32-zlib) [ 6893.247446] Lustre: Lustre: Build Version: 2.17.57_45_g4630995 [ 6893.522835] LNet: Added LNI 192.168.206.12@tcp [8/256/0/180] [ 6895.240831] Key type lgssc registered [ 6896.709748] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6949.715199] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6955.644825] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6977.181702] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7085.978805] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7092.764876] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7118.728733] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7161.589960] Lustre: DEBUG MARKER: == conf-sanity test 152: seq allocation error in OSP ===== 06:23:55 (1787394235) [ 7244.271622] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [ 7249.547740] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 7256.959642] Lustre: DEBUG MARKER: ADD OST3 [ 7268.930821] Lustre: DEBUG MARKER: STOP OST3 [ 7269.859235] Lustre: lustre-OST0000-osc-ffffa08cca6ce000: disconnect after 23s idle [ 7280.620426] Lustre: lustre-OST0003-osc-ffffa08cca6ce000: Connection to lustre-OST0003 (at 192.168.206.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7289.397448] Lustre: DEBUG MARKER: START OST3 again [ 7297.767828] Lustre: lustre-OST0003-osc-ffffa08cca6ce000: Connection restored to 192.168.206.112@tcp (at 192.168.206.112@tcp) [ 7329.064644] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7329.074413] LustreError: 139953:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffffa08cca6ce000: inode [0x240000bd0:0x1:0x0] mdc close failed: rc = -108 [ 7339.435122] Lustre: Unmounted lustre-client [ 7461.759491] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7467.843064] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7494.644114] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7540.873434] Lustre: DEBUG MARKER: == conf-sanity test 153a: bypass invalid NIDs quickly ==== 06:30:14 (1787394614) [ 7628.882964] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7634.346367] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7656.797479] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7727.279337] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7732.918183] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7753.823833] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7756.234873] LNetError: 129555:0:(lib-move.c:2158:lnet_handle_find_routed_path()) peer 192.168.252.112@tcp2 has no available nets [ 7756.616483] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [ 7761.912571] Lustre: Unmounted lustre-client [ 7788.792341] Key type lgssc unregistered [ 7809.056392] LNet: 1 local NIs in recovery (showing 1): 192.168.206.12@tcp [ 7814.688962] LNet: 148940:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7814.726033] LNetError: 148940:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7886.440945] LNet: Removed LNI 192.168.206.12@tcp [ 7887.245455] Key type .llcrypt unregistered [ 7887.249668] Key type ._llcrypt unregistered [ 7902.867176] Key type ._llcrypt registered [ 7902.869946] Key type .llcrypt registered [ 7904.267149] Lustre: DEBUG MARKER: == conf-sanity test 153b: added IPv6 NID support ========= 06:36:17 (1787394977) [ 7922.721191] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7922.753748] alg: No test for adler32 (adler32-zlib) [ 7924.022367] Lustre: Lustre: Build Version: 2.17.57_45_g4630995 [ 7924.381477] LNet: Added LNI 192.168.206.12@tcp [8/256/0/180] [ 7926.144284] Key type lgssc registered [ 7927.578052] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8005.727753] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8011.341782] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8032.350677] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8168.412046] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [ 8174.360043] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 8183.770994] Lustre: Unmounted lustre-client [ 8183.961838] LNetError: 150126:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::549a:5dff:fee0:fe53/1023: rc = -22 [ 8183.968776] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 8184.297490] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [ 8184.457683] Lustre: Unmounted lustre-client [ 8185.463464] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [ 8185.471839] Lustre: Skipped 1 previous similar message [ 8185.657367] Lustre: Unmounted lustre-client [ 8185.661259] Lustre: Skipped 1 previous similar message [ 8187.320564] LNetError: 150127:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::549a:5dff:fee0:fe53/1023: rc = -22 [ 8187.332027] LNetError: 150127:0:(lib-socket.c:308:lnet_sock_create()) Skipped 1 previous similar message [ 8187.337380] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 8187.346115] LNetError: Skipped 1 previous similar message [ 8187.597760] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [ 8187.601088] Lustre: Skipped 3 previous similar messages [ 8187.743807] Lustre: Unmounted lustre-client [ 8187.746938] Lustre: Skipped 3 previous similar messages [ 8188.380797] Lustre: 156070: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 [ 8188.396236] LNetError: 150124:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::549a:5dff:fee0:fe53/1023: rc = -22 [ 8188.419784] LNetError: Unexpected error -22 connecting to 2001:db8:85a3::8a2e:370:7334@tcp at host [2001:db8:85a3::8a2e:370:7334]:988 [ 8188.984762] Lustre: 156101: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 [ 8196.542684] Lustre: DEBUG MARKER: == conf-sanity test 153c: don't stuck on unreached NID === 06:41:10 (1787395270) [ 8238.753255] LNet: 1 local NIs in recovery (showing 1): 192.168.206.12@tcp [ 8316.391217] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8322.342215] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8346.962751] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8417.526751] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8422.704238] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8444.731745] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8450.341814] LustreError: 161696:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.252.112@tcp: failed processing log, type 1: rc = -110 [ 8497.828044] LNet: 1 local NIs in recovery (showing 1): 192.168.206.12@tcp [ 8574.625465] LNet: 1 local NIs in recovery (showing 1): 192.168.206.12@tcp [ 8589.281965] 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 [ 8589.308241] Lustre: Unmounted lustre-client [ 8589.311559] Lustre: Skipped 3 previous similar messages [ 8589.317901] LustreError: 161696:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 8618.280743] Key type lgssc unregistered [ 8632.993512] LNet: 162157:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8633.027334] LNetError: 162157:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8705.766732] LNet: Removed LNI 192.168.206.12@tcp [ 8706.688172] Key type .llcrypt unregistered [ 8706.691151] Key type ._llcrypt unregistered [ 8724.884515] Key type ._llcrypt registered [ 8724.889920] Key type .llcrypt registered [ 8726.524144] Lustre: DEBUG MARKER: == conf-sanity test 154: expand .. on rename after MDT backup restore ========================================================== 06:49:59 (1787395799) [ 8745.635515] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8745.798461] alg: No test for adler32 (adler32-zlib) [ 8747.043165] Lustre: Lustre: Build Version: 2.17.57_45_g4630995 [ 8747.468647] LNet: Added LNI 192.168.206.12@tcp [8/256/0/180] [ 8749.208359] Key type lgssc registered [ 8750.497377] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8884.816699] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [ 8890.740898] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 8906.028672] Lustre: Unmounted lustre-client [ 9070.679701] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [ 9075.980883] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9082.101413] Lustre: Unmounted lustre-client [ 9135.960218] Lustre: DEBUG MARKER: == conf-sanity test 155: gap in seq allocation from ofd after restarting ========================================================== 06:56:49 (1787396209) [ 9230.663965] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9236.895963] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9260.067885] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9385.689123] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [ 9391.758910] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9405.348297] Lustre: Unmounted lustre-client [ 9508.447668] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [ 9513.118492] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9530.090730] Lustre: DEBUG MARKER: == conf-sanity test 156: root_fid on export consistent with client mount ========================================================== 07:03:23 (1787396603) [ 9531.975628] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 9542.333456] Lustre: Unmounted lustre-client [ 9715.118229] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [ 9719.929027] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9727.653412] Lustre: Unmounted lustre-client [ 9727.965412] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [ 9737.047450] Lustre: DEBUG MARKER: == conf-sanity test 157a: test allow_register for MDT registration and MGS restart ========================================================== 07:06:50 (1787396810) [ 9807.216723] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9812.827096] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9833.104030] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9833.817621] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [ 9921.019179] Lustre: lustre-MDT0000-mdc-ffffa08cca6c9800: Connection to lustre-MDT0000 (at 192.168.206.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9931.247928] LustreError: lustre-OST0001-osc-ffffa08cca6c9800: operation ost_connect to node 192.168.206.112@tcp failed: rc = -19 [ 9936.367775] LustreError: MGC192.168.206.112@tcp: Connection to MGS (at 192.168.206.112@tcp) was lost; in progress operations using this service will fail [ 9936.393449] LustreError: lustre-MDT0000-mdc-ffffa08cca6c9800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 9936.405173] Lustre: Evicted from MGS (at 192.168.206.112@tcp) after server handle changed from 0xc35a13f085e9ae7e to 0xc35a13f085e9b1db [ 9936.416514] Lustre: lustre-MDT0000-mdc-ffffa08cca6c9800: Connection restored to 192.168.206.112@tcp (at 192.168.206.112@tcp) [ 9936.426363] Lustre: Skipped 1 previous similar message [ 9977.773923] Lustre: Unmounted lustre-client [ 9977.783068] Lustre: Skipped 1 previous similar message [10005.665290] Key type lgssc unregistered [10006.183935] LNet: 189698:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10006.193039] LNetError: 189698:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10006.221607] LNet: Removed LNI 192.168.206.12@tcp [10007.183135] Key type .llcrypt unregistered [10007.184833] Key type ._llcrypt unregistered [10018.568222] Key type ._llcrypt registered [10018.571120] Key type .llcrypt registered [10020.138743] Lustre: DEBUG MARKER: == conf-sanity test 157b: verify allow_register (block new OSTs, allow existing) ========================================================== 07:11:33 (1787397093) [10022.643691] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10022.673623] alg: No test for adler32 (adler32-zlib) [10023.767172] Lustre: Lustre: Build Version: 2.17.57_45_g4630995 [10024.069199] LNet: Added LNI 192.168.206.12@tcp [8/256/0/180] [10025.795423] Key type lgssc registered [10027.376370] Lustre: Echo OBD driver; http://www.lustre.org/ [10076.568906] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10082.176555] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10103.315537] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10105.105277] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [10202.604253] LustreError: lustre-OST0001-osc-ffffa08cc7eba000: operation ost_connect to node 192.168.206.112@tcp failed: rc = -19 [10309.024238] Lustre: Unmounted lustre-client [10335.638853] Key type lgssc unregistered [10335.996904] LNet: 195730:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10336.002664] LNetError: 195730:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10336.025122] LNet: Removed LNI 192.168.206.12@tcp [10336.845155] Key type .llcrypt unregistered [10336.854410] Key type ._llcrypt unregistered [10348.634401] Key type ._llcrypt registered [10348.635771] Key type .llcrypt registered [10350.253875] Lustre: DEBUG MARKER: == conf-sanity test 160: MGC updates failnodes from all participants ========================================================== 07:17:03 (1787397423) [10380.923672] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10381.101372] alg: No test for adler32 (adler32-zlib) [10382.297312] Lustre: Lustre: Build Version: 2.17.57_45_g4630995 [10382.649850] LNet: Added LNI 192.168.206.12@tcp [8/256/0/180] [10384.377123] Key type lgssc registered [10385.654197] Lustre: Echo OBD driver; http://www.lustre.org/ [10464.290957] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10469.923918] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10491.293267] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10525.939947] Key type lgssc unregistered [10526.188234] LNet: 200709:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10526.193965] LNetError: 200709:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10526.207219] LNet: Removed LNI 192.168.206.12@tcp [10526.792843] Key type .llcrypt unregistered [10526.795330] Key type ._llcrypt unregistered [10640.497859] Key type ._llcrypt registered [10640.500205] Key type .llcrypt registered [10640.805504] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10640.813760] alg: No test for adler32 (adler32-zlib) [10641.857377] Lustre: Lustre: Build Version: 2.17.57_45_g4630995 [10642.058788] LNet: Added LNI 192.168.206.12@tcp [8/256/0/180] [10643.744197] Key type lgssc registered [10644.891903] Lustre: Echo OBD driver; http://www.lustre.org/ [10687.822939] Lustre: DEBUG MARKER: == conf-sanity test 161: test '-o mgsname' option ======== 07:22:41 (1787397761) [10723.001468] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10728.427369] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10751.904654] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10753.491876] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [10755.637654] Lustre: Unmounted lustre-client [10755.991698] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [10756.187674] Lustre: Unmounted lustre-client [10763.663763] Lustre: Unmounted lustre-client [10790.594646] Key type lgssc unregistered [10790.905144] LNet: 205691:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10790.919671] LNetError: 205691:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10790.943556] LNet: Removed LNI 192.168.206.12@tcp [10791.710435] Key type .llcrypt unregistered [10791.711933] Key type ._llcrypt unregistered [10801.089286] Key type ._llcrypt registered [10801.091324] Key type .llcrypt registered [10802.669609] Lustre: DEBUG MARKER: == conf-sanity test 162: replace nids with -o noclient === 07:24:35 (1787397875) [10806.203544] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10806.245430] alg: No test for adler32 (adler32-zlib) [10807.318408] Lustre: Lustre: Build Version: 2.17.57_45_g4630995 [10807.575162] LNet: Added LNI 192.168.206.12@tcp [8/256/0/180] [10809.304183] Key type lgssc registered [10810.682458] Lustre: Echo OBD driver; http://www.lustre.org/ [10861.743617] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10867.764965] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10889.363785] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10892.009951] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [10902.863905] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10904.789333] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10912.742959] Lustre: lustre-MDT0000-mdc-ffffa08cd01a0000: Connection to lustre-MDT0000 (at 192.168.206.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [10923.006472] Lustre: lustre-MDT0001-mdc-ffffa08cd01a0000: Connection to lustre-MDT0001 (at 192.168.206.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [10933.153607] Lustre: 206368:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787397992/real 1787397992] req@00000000a092160b x1874222521522048/t0(0) o400->MGC192.168.206.112@tcp@192.168.206.112@tcp:26/25 lens 224/224 e 0 to 1 dl 1787398008 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10933.183818] LustreError: MGC192.168.206.112@tcp: Connection to MGS (at 192.168.206.112@tcp) was lost; in progress operations using this service will fail [10978.279769] Lustre: Evicted from MGS (at 192.168.206.112@tcp) after server handle changed from 0x2d93b3c0677d9429 to 0x2d93b3c0677d96ad [10978.291096] Lustre: MGC192.168.206.112@tcp: Connection restored to 192.168.206.112@tcp (at 192.168.206.112@tcp) [11001.349632] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11002.700665] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in IDLE state after 0 sec [11007.554776] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11009.000292] LustreError: lustre-MDT0000-mdc-ffffa08cd01a0000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [11009.021740] Lustre: lustre-MDT0000-mdc-ffffa08cd01a0000: Connection restored to 192.168.206.112@tcp (at 192.168.206.112@tcp) [11011.341664] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 2 sec [11013.404365] Lustre: Unmounted lustre-client [11041.525801] Key type lgssc unregistered [11041.729186] LNet: 211251:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11041.736051] LNetError: 211251:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11041.752836] LNet: Removed LNI 192.168.206.12@tcp [11042.532358] Key type .llcrypt unregistered [11042.535362] Key type ._llcrypt unregistered [11069.021898] Key type ._llcrypt registered [11069.027455] Key type .llcrypt registered [11069.696229] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11069.708951] alg: No test for adler32 (adler32-zlib) [11070.788895] Lustre: Lustre: Build Version: 2.17.57_45_g4630995 [11071.108392] LNet: Added LNI 192.168.206.12@tcp [8/256/0/180] [11072.817226] Key type lgssc registered [11074.181059] Lustre: Echo OBD driver; http://www.lustre.org/ [11149.074387] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11154.230973] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11174.901373] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11216.131593] Lustre: DEBUG MARKER: == conf-sanity test 164: test expected_clients parameter and max client tracking ========================================================== 07:31:29 (1787398289) [11302.210968] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11308.210467] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11333.125738] Lustre: DEBUG MARKER: oleg612-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11335.949754] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [11340.887881] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [11342.288752] Lustre: Mounted lustre-client - version 2.17.57_45_g4630995 [11342.297891] Lustre: Skipped 1 previous similar message [11347.306790] Lustre: Unmounted lustre-client [11350.301771] Lustre: Unmounted lustre-client [11353.147641] Lustre: Unmounted lustre-client [11356.517247] Lustre: Unmounted lustre-client [11371.305546] Lustre: Unmounted lustre-client [11371.310374] Lustre: Skipped 1 previous similar message [11398.988815] Key type lgssc unregistered [11399.275848] LNet: 220423:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11399.280468] LNetError: 220423:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11399.295219] LNet: Removed LNI 192.168.206.12@tcp [11399.917860] Key type .llcrypt unregistered [11399.923440] Key type ._llcrypt unregistered [11408.755108] Key type ._llcrypt registered [11408.761637] Key type .llcrypt registered [11410.017910] Lustre: DEBUG MARKER: == conf-sanity test 200a: check CPU partitions =========== 07:34:43 (1787398483) [11421.337283] Key type .llcrypt unregistered [11421.342530] Key type ._llcrypt unregistered [11422.245372] Key type ._llcrypt registered [11422.246757] Key type .llcrypt registered [11422.663773] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 4 [11422.684246] alg: No test for adler32 (adler32-zlib) [11423.964590] Lustre: Lustre: Build Version: 2.17.57_45_g4630995 [11424.311631] LNet: Added LNI 192.168.206.12@tcp [8/256/0/180] [11425.985097] Key type lgssc registered [11426.926478] Lustre: Echo OBD driver; http://www.lustre.org/ [11447.650136] Key type lgssc unregistered [11447.891952] LNet: 222585:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11447.895908] LNetError: 222585:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11447.919551] LNet: Removed LNI 192.168.206.12@tcp [11448.486547] Key type .llcrypt unregistered [11448.497346] Key type ._llcrypt unregistered [11448.974914] Key type ._llcrypt registered [11448.976422] Key type .llcrypt registered [11449.928423] Lustre: DEBUG MARKER: == conf-sanity test 200b: set CPU pattern using core selection ========================================================== 07:35:23 (1787398523) [11460.325674] Key type .llcrypt unregistered [11460.334827] Key type ._llcrypt unregistered [11461.389422] Key type ._llcrypt registered [11461.406864] Key type .llcrypt registered [11461.799742] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11461.812694] alg: No test for adler32 (adler32-zlib) [11463.178952] Lustre: Lustre: Build Version: 2.17.57_45_g4630995 [11463.560725] LNet: Added LNI 192.168.206.12@tcp [8/256/0/180] [11465.296296] Key type lgssc registered [11466.300224] Lustre: Echo OBD driver; http://www.lustre.org/ [11482.111279] Key type lgssc unregistered [11482.303846] LNet: 224147:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11482.311882] LNetError: 224147:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11482.321838] LNet: Removed LNI 192.168.206.12@tcp [11482.872516] Key type .llcrypt unregistered [11482.874653] Key type ._llcrypt unregistered [11489.448929] Key type ._llcrypt registered [11489.452503] Key type .llcrypt registered [11489.889271] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11489.908937] alg: No test for adler32 (adler32-zlib) [11490.961085] Lustre: Lustre: Build Version: 2.17.57_45_g4630995 [11491.214862] LNet: Added LNI 192.168.206.12@tcp [8/256/0/180] [11492.920143] Key type lgssc registered [11494.308475] Lustre: Echo OBD driver; http://www.lustre.org/ [11516.692530] Key type lgssc unregistered [11516.897377] LNet: 225914:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11516.915954] LNetError: 225914:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11516.929986] LNet: Removed LNI 192.168.206.12@tcp [11517.576210] Key type .llcrypt unregistered [11517.578117] Key type ._llcrypt unregistered [11517.965442] Key type ._llcrypt registered [11517.967501] Key type .llcrypt registered [11519.064096] Lustre: DEBUG MARKER: == conf-sanity test 200c: set CPU pattern using NUMA node layout ========================================================== 07:36:32 (1787398592) [11528.739366] Key type .llcrypt unregistered [11528.743955] Key type ._llcrypt unregistered [11529.498847] Key type ._llcrypt registered [11529.552093] Key type .llcrypt registered [11529.778790] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11529.796814] alg: No test for adler32 (adler32-zlib) [11530.862732] Lustre: Lustre: Build Version: 2.17.57_45_g4630995 [11531.117134] LNet: Added LNI 192.168.206.12@tcp [8/256/0/180] [11532.808674] Key type lgssc registered [11533.978910] Lustre: Echo OBD driver; http://www.lustre.org/ [11547.993514] Key type lgssc unregistered [11548.242566] LNet: 227469:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11548.257202] LNetError: 227469:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11548.282676] LNet: Removed LNI 192.168.206.12@tcp [11548.917227] Key type .llcrypt unregistered [11548.920714] Key type ._llcrypt unregistered [11555.874067] Key type ._llcrypt registered [11555.957688] Key type .llcrypt registered [11556.238130] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11556.246369] alg: No test for adler32 (adler32-zlib) [11557.349199] Lustre: Lustre: Build Version: 2.17.57_45_g4630995 [11557.667075] LNet: Added LNI 192.168.206.12@tcp [8/256/0/180] [11559.384196] Key type lgssc registered [11560.690766] Lustre: Echo OBD driver; http://www.lustre.org/ [11576.871725] Key type lgssc unregistered [11577.151690] LNet: 228779:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11577.174603] LNetError: 228779:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11577.205528] LNet: Removed LNI 192.168.206.12@tcp [11577.999802] Key type .llcrypt unregistered [11578.005075] Key type ._llcrypt unregistered [11585.277360] Key type ._llcrypt registered [11585.279374] Key type .llcrypt registered [11585.546700] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11585.564377] alg: No test for adler32 (adler32-zlib) [11586.633713] Lustre: Lustre: Build Version: 2.17.57_45_g4630995 [11586.914193] LNet: Added LNI 192.168.206.12@tcp [8/256/0/180] [11588.643120] Key type lgssc registered [11589.884651] Lustre: Echo OBD driver; http://www.lustre.org/ [11603.726583] Key type lgssc unregistered [11604.011859] LNet: 230086:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11604.019191] LNetError: 230086:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11604.043488] LNet: Removed LNI 192.168.206.12@tcp [11604.684669] Key type .llcrypt unregistered [11604.689392] Key type ._llcrypt unregistered [11611.637796] Key type ._llcrypt registered [11611.649130] Key type .llcrypt registered [11612.006675] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11612.024421] alg: No test for adler32 (adler32-zlib) [11613.123628] Lustre: Lustre: Build Version: 2.17.57_45_g4630995 [11613.385048] LNet: Added LNI 192.168.206.12@tcp [8/256/0/180] [11615.073046] Key type lgssc registered [11616.292139] Lustre: Echo OBD driver; http://www.lustre.org/ [11642.641953] Key type lgssc unregistered [11642.828930] LNet: 231870:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11642.836923] LNetError: 231870:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11642.871036] LNet: Removed LNI 192.168.206.12@tcp [11643.732053] Key type .llcrypt unregistered [11643.739796] Key type ._llcrypt unregistered [11644.185686] Key type ._llcrypt registered [11644.187994] Key type .llcrypt registered [11645.608340] Lustre: DEBUG MARKER: == conf-sanity test 200d: set CPU pattern to exclude only CPU 1 ========================================================== 07:38:39 (1787398719) [11654.260569] Key type .llcrypt unregistered [11654.262193] Key type ._llcrypt unregistered [11654.986452] Key type ._llcrypt registered [11654.988248] Key type .llcrypt registered [11655.225726] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11655.243809] alg: No test for adler32 (adler32-zlib) [11656.320242] Lustre: Lustre: Build Version: 2.17.57_45_g4630995 [11656.565533] LNet: Added LNI 192.168.206.12@tcp [8/256/0/180] [11658.259509] Key type lgssc registered [11659.370914] Lustre: Echo OBD driver; http://www.lustre.org/ [11672.922510] Key type lgssc unregistered [11673.139924] LNet: 233427:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11673.148647] LNetError: 233427:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11673.175837] LNet: Removed LNI 192.168.206.12@tcp [11673.775533] Key type .llcrypt unregistered [11673.779344] Key type ._llcrypt unregistered [11680.457495] Key type ._llcrypt registered [11680.463599] Key type .llcrypt registered [11680.988690] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11681.008643] alg: No test for adler32 (adler32-zlib) [11682.103452] Lustre: Lustre: Build Version: 2.17.57_45_g4630995 [11682.402791] LNet: Added LNI 192.168.206.12@tcp [8/256/0/180] [11684.128208] Key type lgssc registered [11685.346571] Lustre: Echo OBD driver; http://www.lustre.org/ [11701.127722] Key type lgssc unregistered [11701.324292] LNet: 234743:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11701.331829] LNetError: 234743:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11701.353806] LNet: Removed LNI 192.168.206.12@tcp [11701.982835] Key type .llcrypt unregistered [11701.984443] Key type ._llcrypt unregistered [11709.485429] Key type ._llcrypt registered [11709.486857] Key type .llcrypt registered [11709.834296] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11709.847510] alg: No test for adler32 (adler32-zlib) [11711.074749] Lustre: Lustre: Build Version: 2.17.57_45_g4630995 [11711.426952] LNet: Added LNI 192.168.206.12@tcp [8/256/0/180] [11713.200452] Key type lgssc registered [11714.884296] Lustre: Echo OBD driver; http://www.lustre.org/ [11731.944104] Key type lgssc unregistered [11732.192911] LNet: 236053:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11732.197931] LNetError: 236053:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11732.214615] LNet: Removed LNI 192.168.206.12@tcp [11732.917069] Key type .llcrypt unregistered [11732.921106] Key type ._llcrypt unregistered [11741.545393] Key type ._llcrypt registered [11741.546841] Key type .llcrypt registered [11741.983533] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11741.998766] alg: No test for adler32 (adler32-zlib) [11743.059594] Lustre: Lustre: Build Version: 2.17.57_45_g4630995 [11743.265584] LNet: Added LNI 192.168.206.12@tcp [8/256/0/180] [11744.936177] Key type lgssc registered [11746.481503] Lustre: Echo OBD driver; http://www.lustre.org/ [11762.873808] Key type lgssc unregistered [11763.149253] LNet: 237367:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11763.157347] LNetError: 237367:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11763.178719] LNet: Removed LNI 192.168.206.12@tcp [11763.874424] Key type .llcrypt unregistered [11763.879462] Key type ._llcrypt unregistered [11770.848344] Key type ._llcrypt registered [11770.852302] Key type .llcrypt registered [11771.274440] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11771.288465] alg: No test for adler32 (adler32-zlib) [11772.298337] Lustre: Lustre: Build Version: 2.17.57_45_g4630995 [11772.504176] LNet: Added LNI 192.168.206.12@tcp [8/256/0/180] [11774.176323] Key type lgssc registered [11775.217865] Lustre: Echo OBD driver; http://www.lustre.org/ [11788.349217] Key type lgssc unregistered [11788.587433] LNet: 238679:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11788.594607] LNetError: 238679:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11788.611996] LNet: Removed LNI 192.168.206.12@tcp [11789.268270] Key type .llcrypt unregistered [11789.270944] Key type ._llcrypt unregistered [11795.911448] Key type ._llcrypt registered [11795.913320] Key type .llcrypt registered [11796.239173] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11796.250230] alg: No test for adler32 (adler32-zlib) [11797.308852] Lustre: Lustre: Build Version: 2.17.57_45_g4630995 [11797.540453] LNet: Added LNI 192.168.206.12@tcp [8/256/0/180] [11799.200935] Key type lgssc registered [11800.353610] Lustre: Echo OBD driver; http://www.lustre.org/ [11814.267803] Key type lgssc unregistered [11814.504611] LNet: 239990:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11814.511616] LNetError: 239990:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11814.532404] LNet: Removed LNI 192.168.206.12@tcp [11815.081814] Key type .llcrypt unregistered [11815.086960] Key type ._llcrypt unregistered [11835.571856] Key type ._llcrypt registered [11835.575955] Key type .llcrypt registered [11836.727631] Lustre: DEBUG MARKER: == conf-sanity test 200e: set CPU pattern using relative core exclusion ========================================================== 07:41:50 (1787398910) [11848.988810] Key type .llcrypt unregistered [11848.994842] Key type ._llcrypt unregistered [11850.101596] Key type ._llcrypt registered [11850.112818] Key type .llcrypt registered [11850.485299] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11850.507272] alg: No test for adler32 (adler32-zlib) [11851.717464] Lustre: Lustre: Build Version: 2.17.57_45_g4630995 [11851.952666] LNet: Added LNI 192.168.206.12@tcp [8/256/0/180] [11853.672195] Key type lgssc registered [11855.091283] Lustre: Echo OBD driver; http://www.lustre.org/ [11871.461037] Key type lgssc unregistered [11871.754277] LNet: 242321:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11871.769618] LNetError: 242321:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11871.783549] LNet: Removed LNI 192.168.206.12@tcp [11872.747592] Key type .llcrypt unregistered [11872.753479] Key type ._llcrypt unregistered [11880.461803] Key type ._llcrypt registered [11880.469267] Key type .llcrypt registered [11880.916199] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11880.952359] alg: No test for adler32 (adler32-zlib) [11882.002697] Lustre: Lustre: Build Version: 2.17.57_45_g4630995 [11882.186796] LNet: Added LNI 192.168.206.12@tcp [8/256/0/180] [11883.848182] Key type lgssc registered [11884.809534] Lustre: Echo OBD driver; http://www.lustre.org/ [11897.808354] Key type lgssc unregistered [11898.092635] LNet: 243643:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11898.102532] LNetError: 243643:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11898.129252] LNet: Removed LNI 192.168.206.12@tcp [11898.727799] Key type .llcrypt unregistered [11898.729916] Key type ._llcrypt unregistered [11905.456217] Key type ._llcrypt registered [11905.471970] Key type .llcrypt registered [11905.940770] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11905.957592] alg: No test for adler32 (adler32-zlib) [11907.080467] Lustre: Lustre: Build Version: 2.17.57_45_g4630995 [11907.421950] LNet: Added LNI 192.168.206.12@tcp [8/256/0/180] [11909.128263] Key type lgssc registered [11910.434185] Lustre: Echo OBD driver; http://www.lustre.org/ [11925.105148] Key type lgssc unregistered [11925.515725] LNet: 244954:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11925.521239] LNetError: 244954:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11925.534973] LNet: Removed LNI 192.168.206.12@tcp [11926.271482] Key type .llcrypt unregistered [11926.287765] Key type ._llcrypt unregistered [11932.699438] Key type ._llcrypt registered [11932.712446] Key type .llcrypt registered [11933.160191] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11933.180307] alg: No test for adler32 (adler32-zlib) [11934.359966] Lustre: Lustre: Build Version: 2.17.57_45_g4630995 [11934.567703] LNet: Added LNI 192.168.206.12@tcp [8/256/0/180] [11936.224342] Key type lgssc registered [11937.545838] Lustre: Echo OBD driver; http://www.lustre.org/ [11964.169641] Key type lgssc unregistered [11964.390419] LNet: 246751:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11964.394933] LNetError: 246751:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11964.405901] LNet: Removed LNI 192.168.206.12@tcp [11965.126639] Key type .llcrypt unregistered [11965.130279] Key type ._llcrypt unregistered [11965.593796] Key type ._llcrypt registered [11965.595737] Key type .llcrypt registered [11967.049769] Lustre: DEBUG MARKER: == conf-sanity test 250: verify lcfg_erase removes filesystem parameters ========================================================== 07:44:00 (1787399040) [11986.609810] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11986.623949] alg: No test for adler32 (adler32-zlib) [11987.733049] Lustre: Lustre: Build Version: 2.17.57_45_g4630995 [11988.065628] LNet: Added LNI 192.168.206.12@tcp [8/256/0/180] [11989.889521] Key type lgssc registered [11991.362865] Lustre: Echo OBD driver; http://www.lustre.org/ [12236.000165] Lustre: DEBUG MARKER: == conf-sanity test 802a: simulate readonly device ======= 07:48:29 (1787399309) [12237.594942] Lustre: DEBUG MARKER: SKIP: conf-sanity test_802a ZFS specific test [12295.912437] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 11992 sec ========= 07:49:29 (1787399369) [12297.914584] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 07:49:31 (1787399371) === [12301.872856] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 07:49:35 (1787399375) === [12337.827309] Key type lgssc unregistered [12338.084108] LNet: 255819:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12338.093542] LNetError: 255819:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12338.117070] LNet: Removed LNI 192.168.206.12@tcp [12338.856276] Key type .llcrypt unregistered [12338.860155] Key type ._llcrypt unregistered